{"level":"warn","ts":"2026-05-06T01:12:25.613003Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-06T01:12:25.613087Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.161:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.161:2380","--initial-cluster=instance=https://199.19.213.161:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.161:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.161:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-06T01:12:25.613178Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-06T01:12:25.613192Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.161:2380"]} {"level":"info","ts":"2026-05-06T01:12:25.613214Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-06T01:12:25.613873Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.161:2379"]} {"level":"info","ts":"2026-05-06T01:12:25.613965Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.161:2380"],"listen-peer-urls":["https://199.19.213.161:2380"],"advertise-client-urls":["https://199.19.213.161:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.161:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.161:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-06T01:12:25.727887Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"113.614993ms"} {"level":"info","ts":"2026-05-06T01:12:25.737556Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2460b0ba85bf91ab","cluster-id":"c077309761674c46"} {"level":"info","ts":"2026-05-06T01:12:25.737629Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2460b0ba85bf91ab switched to configuration voters=()"} {"level":"info","ts":"2026-05-06T01:12:25.737653Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2460b0ba85bf91ab became follower at term 0"} {"level":"info","ts":"2026-05-06T01:12:25.737662Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2460b0ba85bf91ab [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-06T01:12:25.737676Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2460b0ba85bf91ab became follower at term 1"} {"level":"info","ts":"2026-05-06T01:12:25.737707Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2460b0ba85bf91ab switched to configuration voters=(2621289298283958699)"} {"level":"warn","ts":"2026-05-06T01:12:25.744554Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-06T01:12:25.746011Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-06T01:12:25.7473Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-06T01:12:25.751471Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2460b0ba85bf91ab","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-06T01:12:25.755581Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-06T01:12:25.755768Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-06T01:12:25.755799Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-06T01:12:25.756245Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2460b0ba85bf91ab switched to configuration voters=(2621289298283958699)"} {"level":"info","ts":"2026-05-06T01:12:25.756372Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c077309761674c46","local-member-id":"2460b0ba85bf91ab","added-peer-id":"2460b0ba85bf91ab","added-peer-peer-urls":["https://199.19.213.161:2380"]} {"level":"info","ts":"2026-05-06T01:12:25.754296Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2460b0ba85bf91ab","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-06T01:12:25.759797Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-06T01:12:25.760019Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.161:2380"} {"level":"info","ts":"2026-05-06T01:12:25.760074Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.161:2380"} {"level":"info","ts":"2026-05-06T01:12:25.760095Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-06T01:12:25.760061Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2460b0ba85bf91ab","initial-advertise-peer-urls":["https://199.19.213.161:2380"],"listen-peer-urls":["https://199.19.213.161:2380"],"advertise-client-urls":["https://199.19.213.161:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.161:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-06T01:12:26.138182Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2460b0ba85bf91ab is starting a new election at term 1"} {"level":"info","ts":"2026-05-06T01:12:26.13827Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2460b0ba85bf91ab became pre-candidate at term 1"} {"level":"info","ts":"2026-05-06T01:12:26.13831Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2460b0ba85bf91ab received MsgPreVoteResp from 2460b0ba85bf91ab at term 1"} {"level":"info","ts":"2026-05-06T01:12:26.138332Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2460b0ba85bf91ab became candidate at term 2"} {"level":"info","ts":"2026-05-06T01:12:26.138348Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2460b0ba85bf91ab received MsgVoteResp from 2460b0ba85bf91ab at term 2"} {"level":"info","ts":"2026-05-06T01:12:26.138376Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2460b0ba85bf91ab became leader at term 2"} {"level":"info","ts":"2026-05-06T01:12:26.138402Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2460b0ba85bf91ab elected leader 2460b0ba85bf91ab at term 2"} {"level":"info","ts":"2026-05-06T01:12:26.139638Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T01:12:26.140407Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-06T01:12:26.140396Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2460b0ba85bf91ab","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.161:2379]}","request-path":"/0/members/2460b0ba85bf91ab/attributes","cluster-id":"c077309761674c46","publish-timeout":"7s"} {"level":"info","ts":"2026-05-06T01:12:26.140463Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-06T01:12:26.140653Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c077309761674c46","local-member-id":"2460b0ba85bf91ab","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T01:12:26.140815Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T01:12:26.140849Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T01:12:26.140876Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-06T01:12:26.140886Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-06T01:12:26.144124Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.161:2379"} {"level":"info","ts":"2026-05-06T01:12:26.144169Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-05-06T01:12:31.237227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.647296ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-06T01:12:31.237351Z","caller":"traceutil/trace.go:171","msg":"trace[338785257] linearizableReadLoop","detail":"{readStateIndex:287; appliedIndex:286; }","duration":"457.75187ms","start":"2026-05-06T01:12:30.779583Z","end":"2026-05-06T01:12:31.237335Z","steps":["trace[338785257] 'read index received' (duration: 171.540867ms)","trace[338785257] 'applied index is now lower than readState.Index' (duration: 286.209663ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:12:31.237424Z","caller":"traceutil/trace.go:171","msg":"trace[1312025246] transaction","detail":"{read_only:false; response_revision:281; number_of_response:1; }","duration":"457.856351ms","start":"2026-05-06T01:12:30.779558Z","end":"2026-05-06T01:12:31.237414Z","steps":["trace[1312025246] 'process raft request' (duration: 171.524196ms)","trace[1312025246] 'compare' (duration: 285.516564ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:12:31.237477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:12:30.779532Z","time spent":"457.921263ms","remote":"127.0.0.1:35754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T01:12:31.237841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.245267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:115"} {"level":"info","ts":"2026-05-06T01:12:31.237918Z","caller":"traceutil/trace.go:171","msg":"trace[1083074566] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:281; }","duration":"458.360108ms","start":"2026-05-06T01:12:30.779547Z","end":"2026-05-06T01:12:31.237907Z","steps":["trace[1083074566] 'agreement among raft nodes before linearized reading' (duration: 458.139645ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:12:31.237951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:12:30.779526Z","time spent":"458.416159ms","remote":"127.0.0.1:35514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":138,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"info","ts":"2026-05-06T01:12:31.41124Z","caller":"traceutil/trace.go:171","msg":"trace[1198040788] transaction","detail":"{read_only:false; response_revision:287; number_of_response:1; }","duration":"172.251751ms","start":"2026-05-06T01:12:31.238947Z","end":"2026-05-06T01:12:31.411199Z","steps":["trace[1198040788] 'process raft request' (duration: 172.19974ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:12:31.411346Z","caller":"traceutil/trace.go:171","msg":"trace[24296475] transaction","detail":"{read_only:false; number_of_response:0; response_revision:283; }","duration":"509.627226ms","start":"2026-05-06T01:12:30.901707Z","end":"2026-05-06T01:12:31.411334Z","steps":["trace[24296475] 'process raft request' (duration: 509.294102ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:12:31.411557Z","caller":"traceutil/trace.go:171","msg":"trace[1258241113] transaction","detail":"{read_only:false; response_revision:286; number_of_response:1; }","duration":"509.15058ms","start":"2026-05-06T01:12:30.902375Z","end":"2026-05-06T01:12:31.411525Z","steps":["trace[1258241113] 'process raft request' (duration: 508.721184ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:12:31.411608Z","caller":"traceutil/trace.go:171","msg":"trace[866772155] linearizableReadLoop","detail":"{readStateIndex:294; appliedIndex:287; }","duration":"174.210007ms","start":"2026-05-06T01:12:31.237384Z","end":"2026-05-06T01:12:31.411594Z","steps":["trace[866772155] 'read index received' (duration: 110.991592ms)","trace[866772155] 'applied index is now lower than readState.Index' (duration: 63.217215ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:12:31.411669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:12:30.902364Z","time spent":"509.246891ms","remote":"127.0.0.1:35646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T01:12:31.411762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.342544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:12:31.411721Z","caller":"traceutil/trace.go:171","msg":"trace[1212482109] transaction","detail":"{read_only:false; number_of_response:0; response_revision:282; }","duration":"524.109631ms","start":"2026-05-06T01:12:30.886954Z","end":"2026-05-06T01:12:31.411063Z","steps":["trace[1212482109] 'process raft request' (duration: 523.923508ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:12:31.411847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.96434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T01:12:31.411782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:12:30.901692Z","time spent":"509.698687ms","remote":"127.0.0.1:35646","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T01:12:31.411954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:12:30.886926Z","time spent":"524.944213ms","remote":"127.0.0.1:35644","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T01:12:31.411792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"618.008448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" ","response":"range_response_count:1 size:216"} {"level":"warn","ts":"2026-05-06T01:12:31.411989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.543838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-06T01:12:31.412022Z","caller":"traceutil/trace.go:171","msg":"trace[1356710751] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:287; }","duration":"168.603889ms","start":"2026-05-06T01:12:31.24341Z","end":"2026-05-06T01:12:31.412013Z","steps":["trace[1356710751] 'agreement among raft nodes before linearized reading' (duration: 168.554008ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:12:31.411271Z","caller":"traceutil/trace.go:171","msg":"trace[1555715957] transaction","detail":"{read_only:false; response_revision:283; number_of_response:1; }","duration":"510.020522ms","start":"2026-05-06T01:12:30.901221Z","end":"2026-05-06T01:12:31.411242Z","steps":["trace[1555715957] 'process raft request' (duration: 509.715458ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:12:31.412093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:12:30.901191Z","time spent":"510.871793ms","remote":"127.0.0.1:35646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5882,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T01:12:31.411888Z","caller":"traceutil/trace.go:171","msg":"trace[901409554] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:287; }","duration":"628.013761ms","start":"2026-05-06T01:12:30.783864Z","end":"2026-05-06T01:12:31.411877Z","steps":["trace[901409554] 'agreement among raft nodes before linearized reading' (duration: 627.96407ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:12:31.412183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:12:30.783857Z","time spent":"628.310715ms","remote":"127.0.0.1:35754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"info","ts":"2026-05-06T01:12:31.411824Z","caller":"traceutil/trace.go:171","msg":"trace[1885067120] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:287; }","duration":"403.500326ms","start":"2026-05-06T01:12:31.00831Z","end":"2026-05-06T01:12:31.411811Z","steps":["trace[1885067120] 'agreement among raft nodes before linearized reading' (duration: 403.316573ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:12:31.41225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:12:31.008292Z","time spent":"403.943892ms","remote":"127.0.0.1:35754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-06T01:12:31.411913Z","caller":"traceutil/trace.go:171","msg":"trace[537327132] transaction","detail":"{read_only:false; response_revision:285; number_of_response:1; }","duration":"509.987911ms","start":"2026-05-06T01:12:30.901912Z","end":"2026-05-06T01:12:31.4119Z","steps":["trace[537327132] 'process raft request' (duration: 509.15959ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:12:31.411242Z","caller":"traceutil/trace.go:171","msg":"trace[62233320] transaction","detail":"{read_only:false; response_revision:282; number_of_response:1; }","duration":"627.94715ms","start":"2026-05-06T01:12:30.783254Z","end":"2026-05-06T01:12:31.411201Z","steps":["trace[62233320] 'process raft request' (duration: 565.11141ms)","trace[62233320] 'compare' (duration: 62.278192ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:12:31.412445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:12:30.783226Z","time spent":"629.168047ms","remote":"127.0.0.1:35530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T01:12:31.412019Z","caller":"traceutil/trace.go:171","msg":"trace[1556572052] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler; range_end:; response_count:1; response_revision:287; }","duration":"618.286294ms","start":"2026-05-06T01:12:30.793721Z","end":"2026-05-06T01:12:31.412007Z","steps":["trace[1556572052] 'agreement among raft nodes before linearized reading' (duration: 617.970399ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:12:31.4126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:12:30.793709Z","time spent":"618.880292ms","remote":"127.0.0.1:35666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":239,"request content":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" "} {"level":"warn","ts":"2026-05-06T01:12:31.412362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:12:30.901863Z","time spent":"510.477748ms","remote":"127.0.0.1:35646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2905,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T01:12:31.411935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.566969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-05-06T01:12:31.412811Z","caller":"traceutil/trace.go:171","msg":"trace[775068302] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:287; }","duration":"629.46384ms","start":"2026-05-06T01:12:30.783335Z","end":"2026-05-06T01:12:31.412799Z","steps":["trace[775068302] 'agreement among raft nodes before linearized reading' (duration: 628.572028ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:12:31.412865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:12:30.783323Z","time spent":"629.531852ms","remote":"127.0.0.1:35892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":687,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-05-06T01:12:31.411263Z","caller":"traceutil/trace.go:171","msg":"trace[883009583] transaction","detail":"{read_only:false; response_revision:284; number_of_response:1; }","duration":"509.346523ms","start":"2026-05-06T01:12:30.901867Z","end":"2026-05-06T01:12:31.411213Z","steps":["trace[883009583] 'process raft request' (duration: 509.16993ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:12:31.414309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:12:30.901857Z","time spent":"512.393385ms","remote":"127.0.0.1:35646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3965,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T01:12:57.242759Z","caller":"traceutil/trace.go:171","msg":"trace[1609064998] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"282.223645ms","start":"2026-05-06T01:12:56.960496Z","end":"2026-05-06T01:12:57.242719Z","steps":["trace[1609064998] 'process raft request' (duration: 227.712589ms)","trace[1609064998] 'compare' (duration: 53.690097ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:12:57.242886Z","caller":"traceutil/trace.go:171","msg":"trace[1631075730] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"282.096553ms","start":"2026-05-06T01:12:56.960765Z","end":"2026-05-06T01:12:57.242861Z","steps":["trace[1631075730] 'process raft request' (duration: 281.81721ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:12:57.243028Z","caller":"traceutil/trace.go:171","msg":"trace[521189427] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"280.760177ms","start":"2026-05-06T01:12:56.96226Z","end":"2026-05-06T01:12:57.24302Z","steps":["trace[521189427] 'process raft request' (duration: 280.501784ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:12:57.242866Z","caller":"traceutil/trace.go:171","msg":"trace[1442307045] linearizableReadLoop","detail":"{readStateIndex:551; appliedIndex:548; }","duration":"276.333277ms","start":"2026-05-06T01:12:56.966509Z","end":"2026-05-06T01:12:57.242842Z","steps":["trace[1442307045] 'read index received' (duration: 221.68896ms)","trace[1442307045] 'applied index is now lower than readState.Index' (duration: 54.643237ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:12:57.243315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.777962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1beta1.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:1010"} {"level":"info","ts":"2026-05-06T01:12:57.243353Z","caller":"traceutil/trace.go:171","msg":"trace[1478003290] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1beta1.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:531; }","duration":"276.854124ms","start":"2026-05-06T01:12:56.966485Z","end":"2026-05-06T01:12:57.243339Z","steps":["trace[1478003290] 'agreement among raft nodes before linearized reading' (duration: 276.606891ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:12:57.26622Z","caller":"traceutil/trace.go:171","msg":"trace[713567571] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"165.36139ms","start":"2026-05-06T01:12:57.100822Z","end":"2026-05-06T01:12:57.266183Z","steps":["trace[713567571] 'process raft request' (duration: 165.31172ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:12:57.26645Z","caller":"traceutil/trace.go:171","msg":"trace[1006052115] transaction","detail":"{read_only:false; number_of_response:1; response_revision:531; }","duration":"299.197626ms","start":"2026-05-06T01:12:56.967243Z","end":"2026-05-06T01:12:57.26644Z","steps":["trace[1006052115] 'process raft request' (duration: 298.298775ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:12:57.266547Z","caller":"traceutil/trace.go:171","msg":"trace[986183690] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"289.757219ms","start":"2026-05-06T01:12:56.976783Z","end":"2026-05-06T01:12:57.26654Z","steps":["trace[986183690] 'process raft request' (duration: 289.192452ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:12:57.266629Z","caller":"traceutil/trace.go:171","msg":"trace[1494277365] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"287.751096ms","start":"2026-05-06T01:12:56.978871Z","end":"2026-05-06T01:12:57.266622Z","steps":["trace[1494277365] 'process raft request' (duration: 287.187789ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:12:57.266705Z","caller":"traceutil/trace.go:171","msg":"trace[1553388630] transaction","detail":"{read_only:false; number_of_response:0; response_revision:531; }","duration":"299.334188ms","start":"2026-05-06T01:12:56.967344Z","end":"2026-05-06T01:12:57.266678Z","steps":["trace[1553388630] 'process raft request' (duration: 298.556878ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:12:57.266737Z","caller":"traceutil/trace.go:171","msg":"trace[1067582973] transaction","detail":"{read_only:false; number_of_response:0; response_revision:531; }","duration":"298.741011ms","start":"2026-05-06T01:12:56.967973Z","end":"2026-05-06T01:12:57.266714Z","steps":["trace[1067582973] 'process raft request' (duration: 297.947081ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:12:59.45426Z","caller":"traceutil/trace.go:171","msg":"trace[375989211] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"213.231493ms","start":"2026-05-06T01:12:59.241Z","end":"2026-05-06T01:12:59.454232Z","steps":["trace[375989211] 'process raft request' (duration: 213.050871ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:13:03.705192Z","caller":"traceutil/trace.go:171","msg":"trace[1620031437] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"183.130034ms","start":"2026-05-06T01:13:03.521983Z","end":"2026-05-06T01:13:03.705113Z","steps":["trace[1620031437] 'process raft request' (duration: 178.946678ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:13:04.189346Z","caller":"traceutil/trace.go:171","msg":"trace[1812081721] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"424.483427ms","start":"2026-05-06T01:13:03.764834Z","end":"2026-05-06T01:13:04.189317Z","steps":["trace[1812081721] 'process raft request' (duration: 417.343779ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:13:04.189552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:13:03.764815Z","time spent":"424.608459ms","remote":"127.0.0.1:35726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T01:13:04.189917Z","caller":"traceutil/trace.go:171","msg":"trace[49128228] linearizableReadLoop","detail":"{readStateIndex:647; appliedIndex:646; }","duration":"337.181841ms","start":"2026-05-06T01:13:03.852671Z","end":"2026-05-06T01:13:04.189853Z","steps":["trace[49128228] 'read index received' (duration: 329.416936ms)","trace[49128228] 'applied index is now lower than readState.Index' (duration: 7.762045ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:13:04.190235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.528874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:13:04.190287Z","caller":"traceutil/trace.go:171","msg":"trace[215980963] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:623; }","duration":"337.636515ms","start":"2026-05-06T01:13:03.852637Z","end":"2026-05-06T01:13:04.190274Z","steps":["trace[215980963] 'agreement among raft nodes before linearized reading' (duration: 337.490553ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:13:04.190312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:13:03.852622Z","time spent":"337.683655ms","remote":"127.0.0.1:35452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-06T01:13:04.190606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.047185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-kp7m8\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-06T01:13:04.19063Z","caller":"traceutil/trace.go:171","msg":"trace[1660364474] range","detail":"{range_begin:/registry/pods/kube-system/cilium-kp7m8; range_end:; response_count:1; response_revision:623; }","duration":"291.094237ms","start":"2026-05-06T01:13:03.899529Z","end":"2026-05-06T01:13:04.190624Z","steps":["trace[1660364474] 'agreement among raft nodes before linearized reading' (duration: 291.037866ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:13:04.190746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.641238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-06T01:13:04.190768Z","caller":"traceutil/trace.go:171","msg":"trace[1480706904] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:623; }","duration":"254.703838ms","start":"2026-05-06T01:13:03.936058Z","end":"2026-05-06T01:13:04.190762Z","steps":["trace[1480706904] 'agreement among raft nodes before linearized reading' (duration: 254.665168ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:13:20.491766Z","caller":"traceutil/trace.go:171","msg":"trace[822024098] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"218.440237ms","start":"2026-05-06T01:13:20.273295Z","end":"2026-05-06T01:13:20.491735Z","steps":["trace[822024098] 'process raft request' (duration: 218.275121ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:13:20.492308Z","caller":"traceutil/trace.go:171","msg":"trace[1929938956] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"216.861226ms","start":"2026-05-06T01:13:20.275428Z","end":"2026-05-06T01:13:20.492289Z","steps":["trace[1929938956] 'process raft request' (duration: 216.734971ms)"],"step_count":1}