{"level":"warn","ts":"2026-03-05T23:19:27.984415Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-05T23:19:27.984555Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.140: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://162.253.55.140:2380","--initial-cluster=instance=https://162.253.55.140:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.140:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.140: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-03-05T23:19:27.984664Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-05T23:19:27.984688Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.140:2380"]} {"level":"info","ts":"2026-03-05T23:19:27.984724Z","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-03-05T23:19:27.985926Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.140:2379"]} {"level":"info","ts":"2026-03-05T23:19:27.98606Z","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://162.253.55.140:2380"],"listen-peer-urls":["https://162.253.55.140:2380"],"advertise-client-urls":["https://162.253.55.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.140: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-03-05T23:19:27.991548Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.951415ms"} {"level":"info","ts":"2026-03-05T23:19:27.99775Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"cf809824ab95ac81","cluster-id":"6f89f5fbef18dc61"} {"level":"info","ts":"2026-03-05T23:19:27.997837Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 switched to configuration voters=()"} {"level":"info","ts":"2026-03-05T23:19:27.997862Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 became follower at term 0"} {"level":"info","ts":"2026-03-05T23:19:27.997881Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft cf809824ab95ac81 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-05T23:19:27.99789Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 became follower at term 1"} {"level":"info","ts":"2026-03-05T23:19:27.997934Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 switched to configuration voters=(14952118046135004289)"} {"level":"warn","ts":"2026-03-05T23:19:28.006971Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-05T23:19:28.008484Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-05T23:19:28.009626Z","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-03-05T23:19:28.012353Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"cf809824ab95ac81","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-05T23:19:28.01337Z","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-03-05T23:19:28.013484Z","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-03-05T23:19:28.013546Z","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-03-05T23:19:28.014032Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 switched to configuration voters=(14952118046135004289)"} {"level":"info","ts":"2026-03-05T23:19:28.015027Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"cf809824ab95ac81","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-05T23:19:28.015164Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6f89f5fbef18dc61","local-member-id":"cf809824ab95ac81","added-peer-id":"cf809824ab95ac81","added-peer-peer-urls":["https://162.253.55.140:2380"]} {"level":"info","ts":"2026-03-05T23:19:28.016828Z","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-03-05T23:19:28.016995Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.140:2380"} {"level":"info","ts":"2026-03-05T23:19:28.017061Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.140:2380"} {"level":"info","ts":"2026-03-05T23:19:28.017931Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"cf809824ab95ac81","initial-advertise-peer-urls":["https://162.253.55.140:2380"],"listen-peer-urls":["https://162.253.55.140:2380"],"advertise-client-urls":["https://162.253.55.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-05T23:19:28.017997Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-05T23:19:28.100285Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 is starting a new election at term 1"} {"level":"info","ts":"2026-03-05T23:19:28.10033Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-05T23:19:28.100346Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 received MsgPreVoteResp from cf809824ab95ac81 at term 1"} {"level":"info","ts":"2026-03-05T23:19:28.100361Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 became candidate at term 2"} {"level":"info","ts":"2026-03-05T23:19:28.100366Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 received MsgVoteResp from cf809824ab95ac81 at term 2"} {"level":"info","ts":"2026-03-05T23:19:28.100381Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 became leader at term 2"} {"level":"info","ts":"2026-03-05T23:19:28.100392Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: cf809824ab95ac81 elected leader cf809824ab95ac81 at term 2"} {"level":"info","ts":"2026-03-05T23:19:28.101989Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"cf809824ab95ac81","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.140:2379]}","request-path":"/0/members/cf809824ab95ac81/attributes","cluster-id":"6f89f5fbef18dc61","publish-timeout":"7s"} {"level":"info","ts":"2026-03-05T23:19:28.102021Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-05T23:19:28.101991Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-05T23:19:28.102201Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-05T23:19:28.102856Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6f89f5fbef18dc61","local-member-id":"cf809824ab95ac81","cluster-version":"3.5"} {"level":"info","ts":"2026-03-05T23:19:28.102977Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-05T23:19:28.103021Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-05T23:19:28.104267Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-05T23:19:28.11144Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.140:2379"} {"level":"info","ts":"2026-03-05T23:19:28.111445Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-05T23:19:28.111524Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"warn","ts":"2026-03-05T23:19:46.711298Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12430388796251177354,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-05T23:19:46.914444Z","caller":"traceutil/trace.go:171","msg":"trace[420917344] linearizableReadLoop","detail":"{readStateIndex:417; appliedIndex:416; }","duration":"704.070413ms","start":"2026-03-05T23:19:46.210348Z","end":"2026-03-05T23:19:46.914419Z","steps":["trace[420917344] 'read index received' (duration: 703.909699ms)","trace[420917344] 'applied index is now lower than readState.Index' (duration: 159.564µs)"],"step_count":2} {"level":"warn","ts":"2026-03-05T23:19:46.914667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"704.299768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-03-05T23:19:46.914744Z","caller":"traceutil/trace.go:171","msg":"trace[1231747240] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:405; }","duration":"704.430551ms","start":"2026-03-05T23:19:46.210301Z","end":"2026-03-05T23:19:46.914732Z","steps":["trace[1231747240] 'agreement among raft nodes before linearized reading' (duration: 704.298668ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T23:19:46.914578Z","caller":"traceutil/trace.go:171","msg":"trace[705956260] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"873.343457ms","start":"2026-03-05T23:19:46.04119Z","end":"2026-03-05T23:19:46.914534Z","steps":["trace[705956260] 'process raft request' (duration: 873.083071ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T23:19:46.914785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.748693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-05T23:19:46.914839Z","caller":"traceutil/trace.go:171","msg":"trace[2079635446] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:405; }","duration":"101.820205ms","start":"2026-03-05T23:19:46.813003Z","end":"2026-03-05T23:19:46.914823Z","steps":["trace[2079635446] 'agreement among raft nodes before linearized reading' (duration: 101.711762ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T23:19:46.914862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T23:19:46.210284Z","time spent":"704.489583ms","remote":"127.0.0.1:33052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-05T23:19:46.914869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.514522ms","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-03-05T23:19:46.914955Z","caller":"traceutil/trace.go:171","msg":"trace[130004800] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:405; }","duration":"212.645495ms","start":"2026-03-05T23:19:46.70229Z","end":"2026-03-05T23:19:46.914936Z","steps":["trace[130004800] 'agreement among raft nodes before linearized reading' (duration: 212.500942ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T23:19:46.914871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.095712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-05T23:19:46.91501Z","caller":"traceutil/trace.go:171","msg":"trace[622390440] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:405; }","duration":"538.283207ms","start":"2026-03-05T23:19:46.376717Z","end":"2026-03-05T23:19:46.915001Z","steps":["trace[622390440] 'agreement among raft nodes before linearized reading' (duration: 537.964119ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T23:19:46.915035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T23:19:46.376699Z","time spent":"538.327969ms","remote":"127.0.0.1:33052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-05T23:19:46.915677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T23:19:46.041165Z","time spent":"873.624494ms","remote":"127.0.0.1:33052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-05T23:19:47.072919Z","caller":"traceutil/trace.go:171","msg":"trace[231186407] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"152.977463ms","start":"2026-03-05T23:19:46.919915Z","end":"2026-03-05T23:19:47.072892Z","steps":["trace[231186407] 'process raft request' (duration: 152.797218ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T23:19:47.073203Z","caller":"traceutil/trace.go:171","msg":"trace[1196461225] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"145.561857ms","start":"2026-03-05T23:19:46.927621Z","end":"2026-03-05T23:19:47.073183Z","steps":["trace[1196461225] 'process raft request' (duration: 145.462454ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T23:19:47.073247Z","caller":"traceutil/trace.go:171","msg":"trace[1315045783] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"152.04632ms","start":"2026-03-05T23:19:46.921187Z","end":"2026-03-05T23:19:47.073233Z","steps":["trace[1315045783] 'process raft request' (duration: 151.66437ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T23:19:56.851166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.337631ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-05T23:19:57.191856Z","caller":"traceutil/trace.go:171","msg":"trace[970358859] linearizableReadLoop","detail":"{readStateIndex:479; appliedIndex:478; }","duration":"172.465284ms","start":"2026-03-05T23:19:57.019366Z","end":"2026-03-05T23:19:57.191831Z","steps":["trace[970358859] 'read index received' (duration: 172.226968ms)","trace[970358859] 'applied index is now lower than readState.Index' (duration: 237.176µs)"],"step_count":2} {"level":"info","ts":"2026-03-05T23:19:57.191874Z","caller":"traceutil/trace.go:171","msg":"trace[1260752198] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"195.994646ms","start":"2026-03-05T23:19:56.995859Z","end":"2026-03-05T23:19:57.191854Z","steps":["trace[1260752198] 'process raft request' (duration: 195.820922ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T23:19:57.192239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.640678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-05T23:19:57.192323Z","caller":"traceutil/trace.go:171","msg":"trace[1703890189] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:464; }","duration":"172.986277ms","start":"2026-03-05T23:19:57.019322Z","end":"2026-03-05T23:19:57.192308Z","steps":["trace[1703890189] 'agreement among raft nodes before linearized reading' (duration: 172.606407ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T23:20:03.457658Z","caller":"traceutil/trace.go:171","msg":"trace[1667464915] linearizableReadLoop","detail":"{readStateIndex:498; appliedIndex:497; }","duration":"236.524167ms","start":"2026-03-05T23:20:03.221112Z","end":"2026-03-05T23:20:03.457636Z","steps":["trace[1667464915] 'read index received' (duration: 236.481956ms)","trace[1667464915] 'applied index is now lower than readState.Index' (duration: 41.161µs)"],"step_count":2} {"level":"info","ts":"2026-03-05T23:20:03.457744Z","caller":"traceutil/trace.go:171","msg":"trace[382094409] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"241.924943ms","start":"2026-03-05T23:20:03.21579Z","end":"2026-03-05T23:20:03.457715Z","steps":["trace[382094409] 'process raft request' (duration: 241.732428ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T23:20:03.45787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.740942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-05T23:20:03.45792Z","caller":"traceutil/trace.go:171","msg":"trace[1149478001] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:482; }","duration":"236.855105ms","start":"2026-03-05T23:20:03.221052Z","end":"2026-03-05T23:20:03.457907Z","steps":["trace[1149478001] 'agreement among raft nodes before linearized reading' (duration: 236.66978ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T23:20:03.457943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.673283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-03-05T23:20:03.457948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.533773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-5jnh7\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-03-05T23:20:03.457988Z","caller":"traceutil/trace.go:171","msg":"trace[1941666952] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:482; }","duration":"225.750446ms","start":"2026-03-05T23:20:03.232224Z","end":"2026-03-05T23:20:03.457975Z","steps":["trace[1941666952] 'agreement among raft nodes before linearized reading' (duration: 225.588692ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T23:20:03.457994Z","caller":"traceutil/trace.go:171","msg":"trace[137647351] range","detail":"{range_begin:/registry/pods/kube-system/cilium-5jnh7; range_end:; response_count:1; response_revision:482; }","duration":"108.639626ms","start":"2026-03-05T23:20:03.349342Z","end":"2026-03-05T23:20:03.457982Z","steps":["trace[137647351] 'agreement among raft nodes before linearized reading' (duration: 108.457471ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T23:20:03.457999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.224758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-05T23:20:03.458082Z","caller":"traceutil/trace.go:171","msg":"trace[1073361595] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:482; }","duration":"232.363103ms","start":"2026-03-05T23:20:03.225699Z","end":"2026-03-05T23:20:03.458062Z","steps":["trace[1073361595] 'agreement among raft nodes before linearized reading' (duration: 232.102595ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T23:20:03.625345Z","caller":"traceutil/trace.go:171","msg":"trace[1285362359] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"163.377745ms","start":"2026-03-05T23:20:03.461941Z","end":"2026-03-05T23:20:03.625319Z","steps":["trace[1285362359] 'process raft request' (duration: 132.303472ms)","trace[1285362359] 'compare' (duration: 30.921809ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T23:20:04.105963Z","caller":"traceutil/trace.go:171","msg":"trace[703242911] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"643.655322ms","start":"2026-03-05T23:20:03.462277Z","end":"2026-03-05T23:20:04.105932Z","steps":["trace[703242911] 'process raft request' (duration: 643.413046ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T23:20:04.10615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T23:20:03.462251Z","time spent":"643.803145ms","remote":"127.0.0.1:33052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-05T23:20:04.106228Z","caller":"traceutil/trace.go:171","msg":"trace[96604774] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"643.624381ms","start":"2026-03-05T23:20:03.462575Z","end":"2026-03-05T23:20:04.1062Z","steps":["trace[96604774] 'process raft request' (duration: 643.302423ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T23:20:04.106236Z","caller":"traceutil/trace.go:171","msg":"trace[1035812941] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"632.385648ms","start":"2026-03-05T23:20:03.473831Z","end":"2026-03-05T23:20:04.106217Z","steps":["trace[1035812941] 'process raft request' (duration: 632.260715ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T23:20:04.106347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T23:20:03.462559Z","time spent":"643.718054ms","remote":"127.0.0.1:33052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-05T23:20:04.106351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T23:20:03.473812Z","time spent":"632.47953ms","remote":"127.0.0.1:32962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-05T23:20:04.180324Z","caller":"traceutil/trace.go:171","msg":"trace[60739708] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"487.180061ms","start":"2026-03-05T23:20:03.693117Z","end":"2026-03-05T23:20:04.180297Z","steps":["trace[60739708] 'process raft request' (duration: 466.929351ms)","trace[60739708] 'compare' (duration: 20.060655ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T23:20:04.180582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T23:20:03.693084Z","time spent":"487.383736ms","remote":"127.0.0.1:33052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-05T23:20:04.181037Z","caller":"traceutil/trace.go:171","msg":"trace[321257332] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"293.395369ms","start":"2026-03-05T23:20:03.887129Z","end":"2026-03-05T23:20:04.180524Z","steps":["trace[321257332] 'process raft request' (duration: 293.111752ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T23:20:05.787606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.241612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-05T23:20:05.787689Z","caller":"traceutil/trace.go:171","msg":"trace[1500001022] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:488; }","duration":"325.367845ms","start":"2026-03-05T23:20:05.462303Z","end":"2026-03-05T23:20:05.78767Z","steps":["trace[1500001022] 'range keys from in-memory index tree' (duration: 325.068098ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T23:20:05.787679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.187478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-03-05T23:20:05.787732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T23:20:05.462274Z","time spent":"325.449086ms","remote":"127.0.0.1:33052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-05T23:20:05.787697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T23:20:05.019596Z","time spent":"768.088406ms","remote":"127.0.0.1:50662","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-03-05T23:20:05.787744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.025075738s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-05T23:20:05.787829Z","caller":"traceutil/trace.go:171","msg":"trace[322834161] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:488; }","duration":"1.025208702s","start":"2026-03-05T23:20:04.762599Z","end":"2026-03-05T23:20:05.787808Z","steps":["trace[322834161] 'range keys from in-memory index tree' (duration: 1.024972536s)"],"step_count":1} {"level":"warn","ts":"2026-03-05T23:20:05.787868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T23:20:04.762575Z","time spent":"1.025284874s","remote":"127.0.0.1:32790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-05T23:20:05.787753Z","caller":"traceutil/trace.go:171","msg":"trace[2043886964] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:488; }","duration":"157.310552ms","start":"2026-03-05T23:20:05.630421Z","end":"2026-03-05T23:20:05.787731Z","steps":["trace[2043886964] 'range keys from in-memory index tree' (duration: 156.964663ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T23:20:06.09699Z","caller":"traceutil/trace.go:171","msg":"trace[14799757] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"304.486629ms","start":"2026-03-05T23:20:05.792485Z","end":"2026-03-05T23:20:06.096971Z","steps":["trace[14799757] 'process raft request' (duration: 304.373585ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T23:20:06.097108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T23:20:05.792466Z","time spent":"304.580162ms","remote":"127.0.0.1:33052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-05T23:20:06.127487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.439832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-05T23:20:06.127494Z","caller":"traceutil/trace.go:171","msg":"trace[301949560] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"334.683069ms","start":"2026-03-05T23:20:05.79259Z","end":"2026-03-05T23:20:06.127273Z","steps":["trace[301949560] 'process raft request' (duration: 334.454654ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T23:20:06.127566Z","caller":"traceutil/trace.go:171","msg":"trace[704335080] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:490; }","duration":"328.538444ms","start":"2026-03-05T23:20:05.799008Z","end":"2026-03-05T23:20:06.127547Z","steps":["trace[704335080] 'agreement among raft nodes before linearized reading' (duration: 328.37919ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T23:20:06.127603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T23:20:05.799Z","time spent":"328.595995ms","remote":"127.0.0.1:32790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-05T23:20:06.127195Z","caller":"traceutil/trace.go:171","msg":"trace[1288370283] linearizableReadLoop","detail":"{readStateIndex:506; appliedIndex:504; }","duration":"328.144095ms","start":"2026-03-05T23:20:05.799026Z","end":"2026-03-05T23:20:06.12717Z","steps":["trace[1288370283] 'read index received' (duration: 297.847602ms)","trace[1288370283] 'applied index is now lower than readState.Index' (duration: 30.295433ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T23:20:06.127685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T23:20:05.792563Z","time spent":"335.025747ms","remote":"127.0.0.1:33052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-05T23:20:10.681664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.623867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-03-05T23:20:10.681752Z","caller":"traceutil/trace.go:171","msg":"trace[82647735] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:559; }","duration":"141.774861ms","start":"2026-03-05T23:20:10.539959Z","end":"2026-03-05T23:20:10.681734Z","steps":["trace[82647735] 'range keys from in-memory index tree' (duration: 141.425542ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T23:20:25.594646Z","caller":"traceutil/trace.go:171","msg":"trace[1894684692] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"957.833099ms","start":"2026-03-05T23:20:24.636781Z","end":"2026-03-05T23:20:25.594614Z","steps":["trace[1894684692] 'process raft request' (duration: 957.617403ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T23:20:25.594829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T23:20:24.636757Z","time spent":"957.990962ms","remote":"127.0.0.1:33052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-05T23:20:26.338967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.098076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-05T23:20:26.339045Z","caller":"traceutil/trace.go:171","msg":"trace[448681668] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:680; }","duration":"576.187258ms","start":"2026-03-05T23:20:25.76284Z","end":"2026-03-05T23:20:26.339027Z","steps":["trace[448681668] 'range keys from in-memory index tree' (duration: 575.930921ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T23:20:26.339095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T23:20:25.762762Z","time spent":"576.314661ms","remote":"127.0.0.1:32790","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-03-05T23:20:26.339261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.901926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-05T23:20:26.339335Z","caller":"traceutil/trace.go:171","msg":"trace[1921503099] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:680; }","duration":"312.023249ms","start":"2026-03-05T23:20:26.027295Z","end":"2026-03-05T23:20:26.339318Z","steps":["trace[1921503099] 'range keys from in-memory index tree' (duration: 311.737012ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T23:20:26.339377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T23:20:26.027273Z","time spent":"312.094881ms","remote":"127.0.0.1:33052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-05T23:20:26.339357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.306861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-05T23:20:26.339439Z","caller":"traceutil/trace.go:171","msg":"trace[1108650735] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:680; }","duration":"122.444514ms","start":"2026-03-05T23:20:26.216971Z","end":"2026-03-05T23:20:26.339415Z","steps":["trace[1108650735] 'range keys from in-memory index tree' (duration: 122.160707ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T23:20:26.339481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.225374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-03-05T23:20:26.339624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.396528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-03-05T23:20:26.339668Z","caller":"traceutil/trace.go:171","msg":"trace[686472143] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:680; }","duration":"127.49426ms","start":"2026-03-05T23:20:26.212163Z","end":"2026-03-05T23:20:26.339658Z","steps":["trace[686472143] 'range keys from in-memory index tree' (duration: 127.245244ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T23:20:26.339813Z","caller":"traceutil/trace.go:171","msg":"trace[9416027] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:680; }","duration":"160.596434ms","start":"2026-03-05T23:20:26.179195Z","end":"2026-03-05T23:20:26.339791Z","steps":["trace[9416027] 'range keys from in-memory index tree' (duration: 160.06756ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T23:20:26.589067Z","caller":"traceutil/trace.go:171","msg":"trace[1778065605] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"245.618098ms","start":"2026-03-05T23:20:26.343425Z","end":"2026-03-05T23:20:26.589043Z","steps":["trace[1778065605] 'process raft request' (duration: 245.566887ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T23:20:26.589469Z","caller":"traceutil/trace.go:171","msg":"trace[1329371387] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"246.006438ms","start":"2026-03-05T23:20:26.343423Z","end":"2026-03-05T23:20:26.58943Z","steps":["trace[1329371387] 'process raft request' (duration: 245.426493ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T23:20:26.757738Z","caller":"traceutil/trace.go:171","msg":"trace[1610461493] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"413.956005ms","start":"2026-03-05T23:20:26.343758Z","end":"2026-03-05T23:20:26.757714Z","steps":["trace[1610461493] 'process raft request' (duration: 358.351072ms)","trace[1610461493] 'compare' (duration: 55.460039ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T23:20:26.757884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T23:20:26.34375Z","time spent":"414.056797ms","remote":"127.0.0.1:33052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-05T23:20:26.758045Z","caller":"traceutil/trace.go:171","msg":"trace[343153777] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"414.042826ms","start":"2026-03-05T23:20:26.343966Z","end":"2026-03-05T23:20:26.758009Z","steps":["trace[343153777] 'process raft request' (duration: 413.709648ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T23:20:26.758139Z","caller":"traceutil/trace.go:171","msg":"trace[1570715865] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"414.056466ms","start":"2026-03-05T23:20:26.344048Z","end":"2026-03-05T23:20:26.758105Z","steps":["trace[1570715865] 'process raft request' (duration: 413.898032ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T23:20:26.758233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T23:20:26.343959Z","time spent":"414.18592ms","remote":"127.0.0.1:33052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-05T23:20:26.758292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T23:20:26.344043Z","time spent":"414.17193ms","remote":"127.0.0.1:33052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-05T23:20:51.214524Z","caller":"traceutil/trace.go:171","msg":"trace[1467496729] linearizableReadLoop","detail":"{readStateIndex:782; appliedIndex:781; }","duration":"310.45797ms","start":"2026-03-05T23:20:50.904014Z","end":"2026-03-05T23:20:51.214472Z","steps":["trace[1467496729] 'read index received' (duration: 271.01625ms)","trace[1467496729] 'applied index is now lower than readState.Index' (duration: 39.44016ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T23:20:51.214589Z","caller":"traceutil/trace.go:171","msg":"trace[1966848774] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"312.604394ms","start":"2026-03-05T23:20:50.901935Z","end":"2026-03-05T23:20:51.214539Z","steps":["trace[1966848774] 'process raft request' (duration: 273.154443ms)","trace[1966848774] 'compare' (duration: 39.109883ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T23:20:51.214768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T23:20:50.901917Z","time spent":"312.756578ms","remote":"127.0.0.1:33052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-05T23:20:51.2149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.563558ms","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-03-05T23:20:51.215013Z","caller":"traceutil/trace.go:171","msg":"trace[1249788016] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:755; }","duration":"309.713681ms","start":"2026-03-05T23:20:50.905284Z","end":"2026-03-05T23:20:51.214998Z","steps":["trace[1249788016] 'agreement among raft nodes before linearized reading' (duration: 309.422664ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T23:20:51.215085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T23:20:50.905274Z","time spent":"309.797544ms","remote":"127.0.0.1:33052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-05T23:20:51.214955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.864051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-05T23:20:51.215321Z","caller":"traceutil/trace.go:171","msg":"trace[1851329436] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:755; }","duration":"311.347463ms","start":"2026-03-05T23:20:50.903961Z","end":"2026-03-05T23:20:51.215308Z","steps":["trace[1851329436] 'agreement among raft nodes before linearized reading' (duration: 310.682256ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T23:20:51.215407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T23:20:50.903942Z","time spent":"311.422264ms","remote":"127.0.0.1:33052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-05T23:20:51.416995Z","caller":"traceutil/trace.go:171","msg":"trace[673108043] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"196.524847ms","start":"2026-03-05T23:20:51.22044Z","end":"2026-03-05T23:20:51.416965Z","steps":["trace[673108043] 'process raft request' (duration: 196.370983ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T23:20:53.20183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.388745ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-05T23:20:53.201975Z","caller":"traceutil/trace.go:171","msg":"trace[564036401] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"346.131667ms","start":"2026-03-05T23:20:52.855819Z","end":"2026-03-05T23:20:53.201951Z","steps":["trace[564036401] 'process raft request' (duration: 91.497606ms)","trace[564036401] 'compare' (duration: 254.18978ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T23:20:53.202141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T23:20:52.855782Z","time spent":"346.318423ms","remote":"127.0.0.1:33052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-05T23:20:53.614358Z","caller":"traceutil/trace.go:171","msg":"trace[846227186] linearizableReadLoop","detail":"{readStateIndex:791; appliedIndex:790; }","duration":"193.222125ms","start":"2026-03-05T23:20:53.421107Z","end":"2026-03-05T23:20:53.614329Z","steps":["trace[846227186] 'read index received' (duration: 192.960588ms)","trace[846227186] 'applied index is now lower than readState.Index' (duration: 260.317µs)"],"step_count":2} {"level":"warn","ts":"2026-03-05T23:20:53.614648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.516313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-05T23:20:53.614703Z","caller":"traceutil/trace.go:171","msg":"trace[1538880108] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:763; }","duration":"193.639295ms","start":"2026-03-05T23:20:53.42104Z","end":"2026-03-05T23:20:53.614679Z","steps":["trace[1538880108] 'agreement among raft nodes before linearized reading' (duration: 193.401539ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T23:20:53.614738Z","caller":"traceutil/trace.go:171","msg":"trace[1154645550] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"352.580248ms","start":"2026-03-05T23:20:53.262131Z","end":"2026-03-05T23:20:53.614712Z","steps":["trace[1154645550] 'process raft request' (duration: 352.018024ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T23:20:53.614921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T23:20:53.262109Z","time spent":"352.69422ms","remote":"127.0.0.1:33052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-05T23:21:06.256492Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12430388796251178333,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-05T23:21:06.621521Z","caller":"traceutil/trace.go:171","msg":"trace[1288351933] transaction","detail":"{read_only:false; response_revision:847; number_of_response:1; }","duration":"958.469395ms","start":"2026-03-05T23:21:05.663026Z","end":"2026-03-05T23:21:06.621496Z","steps":["trace[1288351933] 'process raft request' (duration: 958.30374ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T23:21:06.621686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T23:21:05.663007Z","time spent":"958.594747ms","remote":"127.0.0.1:33052","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-03-05T23:21:07.721145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"964.895054ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-05T23:21:07.721344Z","caller":"traceutil/trace.go:171","msg":"trace[1754922630] transaction","detail":"{read_only:false; response_revision:848; number_of_response:1; }","duration":"1.698570398s","start":"2026-03-05T23:21:06.022743Z","end":"2026-03-05T23:21:07.721314Z","steps":["trace[1754922630] 'process raft request' (duration: 733.410058ms)","trace[1754922630] 'compare' (duration: 964.634837ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T23:21:07.721499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T23:21:06.022713Z","time spent":"1.698703812s","remote":"127.0.0.1:33052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-05T23:21:07.721921Z","caller":"traceutil/trace.go:171","msg":"trace[1113296936] linearizableReadLoop","detail":"{readStateIndex:880; appliedIndex:878; }","duration":"1.966404109s","start":"2026-03-05T23:21:05.755487Z","end":"2026-03-05T23:21:07.721891Z","steps":["trace[1113296936] 'read index received' (duration: 865.839011ms)","trace[1113296936] 'applied index is now lower than readState.Index' (duration: 1.100562188s)"],"step_count":2} {"level":"warn","ts":"2026-03-05T23:21:07.763567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999784788s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-05T23:21:07.763603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.960377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-05T23:21:07.763654Z","caller":"traceutil/trace.go:171","msg":"trace[1251878190] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:848; }","duration":"1.999928462s","start":"2026-03-05T23:21:05.763704Z","end":"2026-03-05T23:21:07.763632Z","steps":["trace[1251878190] 'agreement among raft nodes before linearized reading' (duration: 1.999785348s)"],"step_count":1} {"level":"info","ts":"2026-03-05T23:21:07.763688Z","caller":"traceutil/trace.go:171","msg":"trace[779389753] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:848; }","duration":"166.126342ms","start":"2026-03-05T23:21:07.597544Z","end":"2026-03-05T23:21:07.76367Z","steps":["trace[779389753] 'agreement among raft nodes before linearized reading' (duration: 165.957927ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T23:21:07.763709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T23:21:05.763685Z","time spent":"2.000013565s","remote":"127.0.0.1:32790","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-03-05T23:21:07.763737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.01336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-05T23:21:07.763782Z","caller":"traceutil/trace.go:171","msg":"trace[1334537833] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:848; }","duration":"419.122383ms","start":"2026-03-05T23:21:07.344646Z","end":"2026-03-05T23:21:07.763768Z","steps":["trace[1334537833] 'agreement among raft nodes before linearized reading' (duration: 418.948938ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T23:21:07.763813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T23:21:07.34462Z","time spent":"419.186414ms","remote":"127.0.0.1:33052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-05T23:21:07.763815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.602799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-03-05T23:21:07.763828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.008312322s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-05T23:21:07.763908Z","caller":"traceutil/trace.go:171","msg":"trace[1978937215] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:848; }","duration":"2.008462325s","start":"2026-03-05T23:21:05.755427Z","end":"2026-03-05T23:21:07.763889Z","steps":["trace[1978937215] 'agreement among raft nodes before linearized reading' (duration: 1.966561923s)","trace[1978937215] 'range keys from in-memory index tree' (duration: 41.673427ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T23:21:07.763906Z","caller":"traceutil/trace.go:171","msg":"trace[409750371] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:848; }","duration":"504.757893ms","start":"2026-03-05T23:21:07.259122Z","end":"2026-03-05T23:21:07.76388Z","steps":["trace[409750371] 'agreement among raft nodes before linearized reading' (duration: 504.450024ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T23:21:07.763951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T23:21:05.755403Z","time spent":"2.008540707s","remote":"127.0.0.1:33052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-05T23:21:07.763958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T23:21:07.259097Z","time spent":"504.852406ms","remote":"127.0.0.1:33052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-05T23:21:07.763999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"888.496256ms","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-03-05T23:21:07.764035Z","caller":"traceutil/trace.go:171","msg":"trace[1110650632] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:848; }","duration":"888.568957ms","start":"2026-03-05T23:21:06.875454Z","end":"2026-03-05T23:21:07.764022Z","steps":["trace[1110650632] 'agreement among raft nodes before linearized reading' (duration: 888.481755ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T23:21:07.764062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T23:21:06.875432Z","time spent":"888.622789ms","remote":"127.0.0.1:33052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-05T23:21:20.271992Z","caller":"traceutil/trace.go:171","msg":"trace[901726933] linearizableReadLoop","detail":"{readStateIndex:920; appliedIndex:918; }","duration":"141.845948ms","start":"2026-03-05T23:21:20.130127Z","end":"2026-03-05T23:21:20.271973Z","steps":["trace[901726933] 'read index received' (duration: 136.534966ms)","trace[901726933] 'applied index is now lower than readState.Index' (duration: 5.310282ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T23:21:20.272019Z","caller":"traceutil/trace.go:171","msg":"trace[1593718356] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"410.389426ms","start":"2026-03-05T23:21:19.861611Z","end":"2026-03-05T23:21:20.272001Z","steps":["trace[1593718356] 'process raft request' (duration: 405.059823ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T23:21:20.27207Z","caller":"traceutil/trace.go:171","msg":"trace[1023421183] transaction","detail":"{read_only:false; response_revision:885; number_of_response:1; }","duration":"384.622525ms","start":"2026-03-05T23:21:19.887443Z","end":"2026-03-05T23:21:20.272066Z","steps":["trace[1023421183] 'process raft request' (duration: 384.494082ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T23:21:20.272166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T23:21:19.887422Z","time spent":"384.670796ms","remote":"127.0.0.1:33052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-05T23:21:20.272175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T23:21:19.861589Z","time spent":"410.499079ms","remote":"127.0.0.1:33052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-05T23:21:20.272325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.160656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-05T23:21:20.272389Z","caller":"traceutil/trace.go:171","msg":"trace[689567553] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:885; }","duration":"142.296659ms","start":"2026-03-05T23:21:20.130074Z","end":"2026-03-05T23:21:20.272371Z","steps":["trace[689567553] 'agreement among raft nodes before linearized reading' (duration: 142.166016ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T23:21:21.96076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.573923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-05T23:21:21.960859Z","caller":"traceutil/trace.go:171","msg":"trace[544580446] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:907; }","duration":"197.719116ms","start":"2026-03-05T23:21:21.763116Z","end":"2026-03-05T23:21:21.960835Z","steps":["trace[544580446] 'range keys from in-memory index tree' (duration: 197.47917ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T23:21:22.171684Z","caller":"traceutil/trace.go:171","msg":"trace[64169689] transaction","detail":"{read_only:false; response_revision:908; number_of_response:1; }","duration":"205.207023ms","start":"2026-03-05T23:21:21.966449Z","end":"2026-03-05T23:21:22.171656Z","steps":["trace[64169689] 'process raft request' (duration: 205.020099ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T23:21:22.194933Z","caller":"traceutil/trace.go:171","msg":"trace[1815064750] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"227.322573ms","start":"2026-03-05T23:21:21.967579Z","end":"2026-03-05T23:21:22.194902Z","steps":["trace[1815064750] 'process raft request' (duration: 227.150249ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T23:21:22.494023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.860784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-03-05T23:21:22.494036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.489299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-05T23:21:22.49412Z","caller":"traceutil/trace.go:171","msg":"trace[544705884] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:910; }","duration":"217.017108ms","start":"2026-03-05T23:21:22.277081Z","end":"2026-03-05T23:21:22.494099Z","steps":["trace[544705884] 'range keys from in-memory index tree' (duration: 216.589656ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T23:21:22.494395Z","caller":"traceutil/trace.go:171","msg":"trace[729586150] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:910; }","duration":"217.739625ms","start":"2026-03-05T23:21:22.276442Z","end":"2026-03-05T23:21:22.494182Z","steps":["trace[729586150] 'range keys from in-memory index tree' (duration: 217.203422ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T23:21:24.702216Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12430388796251178502,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-05T23:21:25.203135Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12430388796251178502,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-05T23:21:25.230714Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.041166811s","expected-duration":"1s"} {"level":"info","ts":"2026-03-05T23:21:25.231272Z","caller":"traceutil/trace.go:171","msg":"trace[199181430] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"1.041808757s","start":"2026-03-05T23:21:24.189436Z","end":"2026-03-05T23:21:25.231245Z","steps":["trace[199181430] 'process raft request' (duration: 1.041560341s)"],"step_count":1} {"level":"info","ts":"2026-03-05T23:21:25.231242Z","caller":"traceutil/trace.go:171","msg":"trace[1303181761] linearizableReadLoop","detail":"{readStateIndex:957; appliedIndex:956; }","duration":"1.029571883s","start":"2026-03-05T23:21:24.201637Z","end":"2026-03-05T23:21:25.231208Z","steps":["trace[1303181761] 'read index received' (duration: 1.029240375s)","trace[1303181761] 'applied index is now lower than readState.Index' (duration: 330.358µs)"],"step_count":2} {"level":"warn","ts":"2026-03-05T23:21:25.231415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T23:21:24.189415Z","time spent":"1.041910169s","remote":"127.0.0.1:33052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-05T23:21:25.231544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"706.689344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-05T23:21:25.231597Z","caller":"traceutil/trace.go:171","msg":"trace[908096898] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:921; }","duration":"706.801596ms","start":"2026-03-05T23:21:24.524777Z","end":"2026-03-05T23:21:25.231579Z","steps":["trace[908096898] 'agreement among raft nodes before linearized reading' (duration: 706.614111ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T23:21:25.231558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.250369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-05T23:21:25.231641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T23:21:24.524756Z","time spent":"706.875398ms","remote":"127.0.0.1:33052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-05T23:21:25.231671Z","caller":"traceutil/trace.go:171","msg":"trace[852449935] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:921; }","duration":"467.410213ms","start":"2026-03-05T23:21:24.764232Z","end":"2026-03-05T23:21:25.231642Z","steps":["trace[852449935] 'agreement among raft nodes before linearized reading' (duration: 467.238469ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T23:21:25.231738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T23:21:24.764206Z","time spent":"467.518746ms","remote":"127.0.0.1:32790","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-03-05T23:21:25.231812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.809783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-05T23:21:25.231845Z","caller":"traceutil/trace.go:171","msg":"trace[1367966467] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:921; }","duration":"348.867135ms","start":"2026-03-05T23:21:24.882968Z","end":"2026-03-05T23:21:25.231835Z","steps":["trace[1367966467] 'agreement among raft nodes before linearized reading' (duration: 348.803033ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T23:21:25.231867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T23:21:24.882954Z","time spent":"348.908396ms","remote":"127.0.0.1:32946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":4,"response size":30,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-03-05T23:21:25.231969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.406012ms","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-03-05T23:21:25.232013Z","caller":"traceutil/trace.go:171","msg":"trace[412754802] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:921; }","duration":"510.483594ms","start":"2026-03-05T23:21:24.721519Z","end":"2026-03-05T23:21:25.232002Z","steps":["trace[412754802] 'agreement among raft nodes before linearized reading' (duration: 510.369831ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T23:21:25.232054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T23:21:24.721489Z","time spent":"510.554246ms","remote":"127.0.0.1:33052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-05T23:21:25.232247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"676.663607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-05T23:21:25.232313Z","caller":"traceutil/trace.go:171","msg":"trace[513152476] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:921; }","duration":"676.772109ms","start":"2026-03-05T23:21:24.555525Z","end":"2026-03-05T23:21:25.232297Z","steps":["trace[513152476] 'agreement among raft nodes before linearized reading' (duration: 676.547334ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T23:21:25.232363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T23:21:24.555505Z","time spent":"676.847511ms","remote":"127.0.0.1:33052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-05T23:21:25.233297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.031600464s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-05T23:21:25.23343Z","caller":"traceutil/trace.go:171","msg":"trace[533934620] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:921; }","duration":"1.031792008s","start":"2026-03-05T23:21:24.201613Z","end":"2026-03-05T23:21:25.233405Z","steps":["trace[533934620] 'agreement among raft nodes before linearized reading' (duration: 1.029740087s)"],"step_count":1} {"level":"warn","ts":"2026-03-05T23:21:25.23422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T23:21:24.201604Z","time spent":"1.032592759s","remote":"127.0.0.1:33052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-05T23:21:25.868316Z","caller":"traceutil/trace.go:171","msg":"trace[1704420072] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"632.118008ms","start":"2026-03-05T23:21:25.236163Z","end":"2026-03-05T23:21:25.868281Z","steps":["trace[1704420072] 'process raft request' (duration: 554.98517ms)","trace[1704420072] 'compare' (duration: 76.749749ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T23:21:25.868373Z","caller":"traceutil/trace.go:171","msg":"trace[1342337767] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"630.700994ms","start":"2026-03-05T23:21:25.237657Z","end":"2026-03-05T23:21:25.868358Z","steps":["trace[1342337767] 'process raft request' (duration: 630.650883ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T23:21:25.868425Z","caller":"traceutil/trace.go:171","msg":"trace[733309974] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"631.893132ms","start":"2026-03-05T23:21:25.236525Z","end":"2026-03-05T23:21:25.868418Z","steps":["trace[733309974] 'process raft request' (duration: 631.660337ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T23:21:25.868496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T23:21:25.236146Z","time spent":"632.252751ms","remote":"127.0.0.1:33052","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-03-05T23:21:25.868505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T23:21:25.236503Z","time spent":"631.948314ms","remote":"127.0.0.1:33052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-05T23:21:25.868505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T23:21:25.237649Z","time spent":"630.781306ms","remote":"127.0.0.1:33052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-05T23:21:25.868373Z","caller":"traceutil/trace.go:171","msg":"trace[1464622673] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"631.322698ms","start":"2026-03-05T23:21:25.237016Z","end":"2026-03-05T23:21:25.868338Z","steps":["trace[1464622673] 'process raft request' (duration: 631.262307ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T23:21:25.868673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T23:21:25.237005Z","time spent":"631.639396ms","remote":"127.0.0.1:33052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-05T23:21:27.407139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"739.046968ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-05T23:21:27.407315Z","caller":"traceutil/trace.go:171","msg":"trace[1019294899] linearizableReadLoop","detail":"{readStateIndex:963; appliedIndex:962; }","duration":"642.871838ms","start":"2026-03-05T23:21:26.764403Z","end":"2026-03-05T23:21:27.407274Z","steps":["trace[1019294899] 'read index received' (duration: 36.681µs)","trace[1019294899] 'applied index is now lower than readState.Index' (duration: 642.821716ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T23:21:27.407748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.331279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-05T23:21:27.407805Z","caller":"traceutil/trace.go:171","msg":"trace[982533827] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:926; }","duration":"643.449031ms","start":"2026-03-05T23:21:26.76434Z","end":"2026-03-05T23:21:27.407789Z","steps":["trace[982533827] 'agreement among raft nodes before linearized reading' (duration: 643.329388ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T23:21:27.40786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T23:21:26.764317Z","time spent":"643.529754ms","remote":"127.0.0.1:32790","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-03-05T23:21:27.408049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.201483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-05T23:21:27.408135Z","caller":"traceutil/trace.go:171","msg":"trace[378926038] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:926; }","duration":"172.345106ms","start":"2026-03-05T23:21:27.23577Z","end":"2026-03-05T23:21:27.408115Z","steps":["trace[378926038] 'agreement among raft nodes before linearized reading' (duration: 171.949116ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T23:21:28.373168Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12430388796251178515,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-05T23:21:28.873821Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12430388796251178515,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-05T23:21:28.966748Z","caller":"traceutil/trace.go:171","msg":"trace[438660553] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"1.118313089s","start":"2026-03-05T23:21:27.848408Z","end":"2026-03-05T23:21:28.966721Z","steps":["trace[438660553] 'process raft request' (duration: 1.118126165s)"],"step_count":1} {"level":"warn","ts":"2026-03-05T23:21:28.966936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T23:21:27.848379Z","time spent":"1.118454913s","remote":"127.0.0.1:33052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-05T23:21:29.009212Z","caller":"traceutil/trace.go:171","msg":"trace[1098757419] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"877.181673ms","start":"2026-03-05T23:21:28.132001Z","end":"2026-03-05T23:21:29.009183Z","steps":["trace[1098757419] 'process raft request' (duration: 877.020189ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T23:21:29.009345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T23:21:28.131968Z","time spent":"877.311577ms","remote":"127.0.0.1:32862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":779,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-05T23:21:29.009198Z","caller":"traceutil/trace.go:171","msg":"trace[1947451842] linearizableReadLoop","detail":"{readStateIndex:966; appliedIndex:965; }","duration":"1.136416339s","start":"2026-03-05T23:21:27.872751Z","end":"2026-03-05T23:21:29.009167Z","steps":["trace[1947451842] 'read index received' (duration: 1.094870506s)","trace[1947451842] 'applied index is now lower than readState.Index' (duration: 41.544833ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T23:21:29.009433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.136652987s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-05T23:21:29.009505Z","caller":"traceutil/trace.go:171","msg":"trace[424399362] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:929; }","duration":"1.13681385s","start":"2026-03-05T23:21:27.872678Z","end":"2026-03-05T23:21:29.009492Z","steps":["trace[424399362] 'agreement among raft nodes before linearized reading' (duration: 1.136578183s)"],"step_count":1} {"level":"warn","ts":"2026-03-05T23:21:29.009544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T23:21:27.872649Z","time spent":"1.136886352s","remote":"127.0.0.1:33052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-05T23:21:29.00963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"802.716902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-05T23:21:29.009653Z","caller":"traceutil/trace.go:171","msg":"trace[2109867349] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:929; }","duration":"802.759674ms","start":"2026-03-05T23:21:28.206887Z","end":"2026-03-05T23:21:29.009647Z","steps":["trace[2109867349] 'agreement among raft nodes before linearized reading' (duration: 802.707402ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T23:21:29.009672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T23:21:28.206881Z","time spent":"802.787294ms","remote":"127.0.0.1:33052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-03-05T23:21:29.009729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.312371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-05T23:21:29.00974Z","caller":"traceutil/trace.go:171","msg":"trace[1751806120] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:929; }","duration":"247.372043ms","start":"2026-03-05T23:21:28.762364Z","end":"2026-03-05T23:21:29.009736Z","steps":["trace[1751806120] 'agreement among raft nodes before linearized reading' (duration: 247.352482ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T23:21:29.009761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.136717208s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-03-05T23:21:29.00982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.2332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-05T23:21:29.00984Z","caller":"traceutil/trace.go:171","msg":"trace[655450891] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:929; }","duration":"471.285961ms","start":"2026-03-05T23:21:28.538543Z","end":"2026-03-05T23:21:29.009829Z","steps":["trace[655450891] 'agreement among raft nodes before linearized reading' (duration: 471.265901ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T23:21:29.009829Z","caller":"traceutil/trace.go:171","msg":"trace[790448140] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:929; }","duration":"1.136823221s","start":"2026-03-05T23:21:27.872987Z","end":"2026-03-05T23:21:29.00981Z","steps":["trace[790448140] 'agreement among raft nodes before linearized reading' (duration: 1.136572665s)"],"step_count":1} {"level":"warn","ts":"2026-03-05T23:21:29.009858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T23:21:28.538523Z","time spent":"471.332442ms","remote":"127.0.0.1:32790","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-03-05T23:21:29.009916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T23:21:27.872979Z","time spent":"1.136927833s","remote":"127.0.0.1:33052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-05T23:21:29.010156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.137099337s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-03-05T23:21:29.010149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.136211566s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-05T23:21:29.010176Z","caller":"traceutil/trace.go:171","msg":"trace[1334978379] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:929; }","duration":"1.137142509s","start":"2026-03-05T23:21:27.873029Z","end":"2026-03-05T23:21:29.010172Z","steps":["trace[1334978379] 'agreement among raft nodes before linearized reading' (duration: 1.137031716s)"],"step_count":1} {"level":"warn","ts":"2026-03-05T23:21:29.01019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T23:21:27.873017Z","time spent":"1.137170499s","remote":"127.0.0.1:33052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-05T23:21:29.010187Z","caller":"traceutil/trace.go:171","msg":"trace[484380798] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:929; }","duration":"1.136264547s","start":"2026-03-05T23:21:27.873913Z","end":"2026-03-05T23:21:29.010178Z","steps":["trace[484380798] 'agreement among raft nodes before linearized reading' (duration: 1.136141834s)"],"step_count":1} {"level":"warn","ts":"2026-03-05T23:21:29.010216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T23:21:27.873906Z","time spent":"1.136303338s","remote":"127.0.0.1:33052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-05T23:21:29.010407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"805.669355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:471"} {"level":"info","ts":"2026-03-05T23:21:29.010515Z","caller":"traceutil/trace.go:171","msg":"trace[321562650] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:929; }","duration":"805.813739ms","start":"2026-03-05T23:21:28.204659Z","end":"2026-03-05T23:21:29.010473Z","steps":["trace[321562650] 'agreement among raft nodes before linearized reading' (duration: 805.503341ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T23:21:29.010567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T23:21:28.20464Z","time spent":"805.917202ms","remote":"127.0.0.1:33052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":494,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-03-05T23:21:29.132123Z","caller":"traceutil/trace.go:171","msg":"trace[745614612] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"117.053687ms","start":"2026-03-05T23:21:29.015029Z","end":"2026-03-05T23:21:29.132083Z","steps":["trace[745614612] 'process raft request' (duration: 33.646457ms)","trace[745614612] 'compare' (duration: 83.256587ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T23:21:29.228197Z","caller":"traceutil/trace.go:171","msg":"trace[1433322172] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"212.622385ms","start":"2026-03-05T23:21:29.015542Z","end":"2026-03-05T23:21:29.228165Z","steps":["trace[1433322172] 'process raft request' (duration: 212.538013ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T23:21:29.228222Z","caller":"traceutil/trace.go:171","msg":"trace[113558396] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"212.930042ms","start":"2026-03-05T23:21:29.015251Z","end":"2026-03-05T23:21:29.228181Z","steps":["trace[113558396] 'process raft request' (duration: 212.662096ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T23:21:29.228266Z","caller":"traceutil/trace.go:171","msg":"trace[118417768] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"212.793789ms","start":"2026-03-05T23:21:29.01543Z","end":"2026-03-05T23:21:29.228224Z","steps":["trace[118417768] 'process raft request' (duration: 212.612915ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T23:21:29.228537Z","caller":"traceutil/trace.go:171","msg":"trace[1589037288] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"212.930322ms","start":"2026-03-05T23:21:29.01558Z","end":"2026-03-05T23:21:29.22851Z","steps":["trace[1589037288] 'process raft request' (duration: 212.743988ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T23:21:29.228629Z","caller":"traceutil/trace.go:171","msg":"trace[708593597] linearizableReadLoop","detail":"{readStateIndex:972; appliedIndex:971; }","duration":"158.097689ms","start":"2026-03-05T23:21:29.070372Z","end":"2026-03-05T23:21:29.22847Z","steps":["trace[708593597] 'read index received' (duration: 157.996217ms)","trace[708593597] 'applied index is now lower than readState.Index' (duration: 100.342µs)"],"step_count":2} {"level":"warn","ts":"2026-03-05T23:21:29.228549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.1566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-05T23:21:29.228739Z","caller":"traceutil/trace.go:171","msg":"trace[753496318] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:935; }","duration":"158.406717ms","start":"2026-03-05T23:21:29.070319Z","end":"2026-03-05T23:21:29.228726Z","steps":["trace[753496318] 'agreement among raft nodes before linearized reading' (duration: 158.15445ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T23:21:29.2285Z","caller":"traceutil/trace.go:171","msg":"trace[1859252994] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"212.889251ms","start":"2026-03-05T23:21:29.015579Z","end":"2026-03-05T23:21:29.228468Z","steps":["trace[1859252994] 'process raft request' (duration: 212.547333ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T23:21:29.379098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.294354ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-05T23:21:29.379457Z","caller":"traceutil/trace.go:171","msg":"trace[350846291] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"144.989151ms","start":"2026-03-05T23:21:29.234447Z","end":"2026-03-05T23:21:29.379437Z","steps":["trace[350846291] 'process raft request' (duration: 144.891159ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T23:21:29.379463Z","caller":"traceutil/trace.go:171","msg":"trace[393883317] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"282.534937ms","start":"2026-03-05T23:21:29.09688Z","end":"2026-03-05T23:21:29.379415Z","steps":["trace[393883317] 'process raft request' (duration: 167.834352ms)","trace[393883317] 'compare' (duration: 114.15546ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T23:21:29.379628Z","caller":"traceutil/trace.go:171","msg":"trace[1527895246] linearizableReadLoop","detail":"{readStateIndex:973; appliedIndex:972; }","duration":"147.380892ms","start":"2026-03-05T23:21:29.232116Z","end":"2026-03-05T23:21:29.379497Z","steps":["trace[1527895246] 'read index received' (duration: 32.620125ms)","trace[1527895246] 'applied index is now lower than readState.Index' (duration: 114.757257ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T23:21:29.379983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.842193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-03-05T23:21:29.380067Z","caller":"traceutil/trace.go:171","msg":"trace[1065002248] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:937; }","duration":"147.989216ms","start":"2026-03-05T23:21:29.232057Z","end":"2026-03-05T23:21:29.380046Z","steps":["trace[1065002248] 'agreement among raft nodes before linearized reading' (duration: 147.573436ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T23:21:29.613341Z","caller":"traceutil/trace.go:171","msg":"trace[1823009640] linearizableReadLoop","detail":"{readStateIndex:976; appliedIndex:974; }","duration":"119.621549ms","start":"2026-03-05T23:21:29.493672Z","end":"2026-03-05T23:21:29.613294Z","steps":["trace[1823009640] 'read index received' (duration: 49.19769ms)","trace[1823009640] 'applied index is now lower than readState.Index' (duration: 70.422879ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T23:21:29.613517Z","caller":"traceutil/trace.go:171","msg":"trace[902612582] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"228.943539ms","start":"2026-03-05T23:21:29.384559Z","end":"2026-03-05T23:21:29.613503Z","steps":["trace[902612582] 'process raft request' (duration: 228.677052ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T23:21:29.613608Z","caller":"traceutil/trace.go:171","msg":"trace[898120995] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"230.825795ms","start":"2026-03-05T23:21:29.382751Z","end":"2026-03-05T23:21:29.613577Z","steps":["trace[898120995] 'process raft request' (duration: 160.124909ms)","trace[898120995] 'compare' (duration: 70.194323ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T23:21:29.614001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.296545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-05T23:21:29.614142Z","caller":"traceutil/trace.go:171","msg":"trace[354568866] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:939; }","duration":"120.52105ms","start":"2026-03-05T23:21:29.493599Z","end":"2026-03-05T23:21:29.61412Z","steps":["trace[354568866] 'agreement among raft nodes before linearized reading' (duration: 120.152701ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T23:21:29.991711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.766118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-05T23:21:29.991832Z","caller":"traceutil/trace.go:171","msg":"trace[991130829] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:940; }","duration":"227.968233ms","start":"2026-03-05T23:21:29.76384Z","end":"2026-03-05T23:21:29.991809Z","steps":["trace[991130829] 'range keys from in-memory index tree' (duration: 227.676886ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T23:21:31.387575Z","caller":"traceutil/trace.go:171","msg":"trace[455059260] linearizableReadLoop","detail":"{readStateIndex:978; appliedIndex:977; }","duration":"154.842827ms","start":"2026-03-05T23:21:31.232711Z","end":"2026-03-05T23:21:31.387553Z","steps":["trace[455059260] 'read index received' (duration: 154.761235ms)","trace[455059260] 'applied index is now lower than readState.Index' (duration: 80.862µs)"],"step_count":2} {"level":"info","ts":"2026-03-05T23:21:31.387678Z","caller":"traceutil/trace.go:171","msg":"trace[1587261899] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"246.548668ms","start":"2026-03-05T23:21:31.14112Z","end":"2026-03-05T23:21:31.387668Z","steps":["trace[1587261899] 'process raft request' (duration: 246.302662ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T23:21:31.387928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.633713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-05T23:21:31.388001Z","caller":"traceutil/trace.go:171","msg":"trace[1393245192] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:941; }","duration":"154.775467ms","start":"2026-03-05T23:21:31.233209Z","end":"2026-03-05T23:21:31.387985Z","steps":["trace[1393245192] 'agreement among raft nodes before linearized reading' (duration: 154.587482ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T23:21:31.38812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.395601ms","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-03-05T23:21:31.38815Z","caller":"traceutil/trace.go:171","msg":"trace[1716580780] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:941; }","duration":"155.481804ms","start":"2026-03-05T23:21:31.23266Z","end":"2026-03-05T23:21:31.388142Z","steps":["trace[1716580780] 'agreement among raft nodes before linearized reading' (duration: 155.34769ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T23:21:31.38819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.661894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-05T23:21:31.38821Z","caller":"traceutil/trace.go:171","msg":"trace[1258054532] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:941; }","duration":"154.698054ms","start":"2026-03-05T23:21:31.233506Z","end":"2026-03-05T23:21:31.388205Z","steps":["trace[1258054532] 'agreement among raft nodes before linearized reading' (duration: 154.637263ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T23:21:31.542709Z","caller":"traceutil/trace.go:171","msg":"trace[1042139112] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"149.955976ms","start":"2026-03-05T23:21:31.392724Z","end":"2026-03-05T23:21:31.54268Z","steps":["trace[1042139112] 'process raft request' (duration: 93.214569ms)","trace[1042139112] 'compare' (duration: 56.607114ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T23:21:31.762269Z","caller":"traceutil/trace.go:171","msg":"trace[325848496] linearizableReadLoop","detail":"{readStateIndex:981; appliedIndex:979; }","duration":"222.802525ms","start":"2026-03-05T23:21:31.539435Z","end":"2026-03-05T23:21:31.762237Z","steps":["trace[325848496] 'read index received' (duration: 217.070062ms)","trace[325848496] 'applied index is now lower than readState.Index' (duration: 5.731173ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T23:21:31.762311Z","caller":"traceutil/trace.go:171","msg":"trace[1466051882] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"369.494609ms","start":"2026-03-05T23:21:31.392778Z","end":"2026-03-05T23:21:31.762273Z","steps":["trace[1466051882] 'process raft request' (duration: 363.818467ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T23:21:31.762437Z","caller":"traceutil/trace.go:171","msg":"trace[2017986330] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"368.796741ms","start":"2026-03-05T23:21:31.393625Z","end":"2026-03-05T23:21:31.762422Z","steps":["trace[2017986330] 'process raft request' (duration: 368.547864ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T23:21:31.762729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T23:21:31.393601Z","time spent":"368.886733ms","remote":"127.0.0.1:33052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-05T23:21:31.76282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.366629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.140\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-03-05T23:21:31.762874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.956611ms","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-03-05T23:21:31.76296Z","caller":"traceutil/trace.go:171","msg":"trace[309545576] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:944; }","duration":"119.097974ms","start":"2026-03-05T23:21:31.643844Z","end":"2026-03-05T23:21:31.762942Z","steps":["trace[309545576] 'agreement among raft nodes before linearized reading' (duration: 118.822138ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T23:21:31.762902Z","caller":"traceutil/trace.go:171","msg":"trace[10947265] range","detail":"{range_begin:/registry/masterleases/162.253.55.140; range_end:; response_count:1; response_revision:944; }","duration":"223.513852ms","start":"2026-03-05T23:21:31.539369Z","end":"2026-03-05T23:21:31.762883Z","steps":["trace[10947265] 'agreement among raft nodes before linearized reading' (duration: 223.284306ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T23:21:31.763268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T23:21:31.392762Z","time spent":"369.971801ms","remote":"127.0.0.1:33052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-05T23:21:32.04769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.150999ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-03-05T23:21:32.04797Z","caller":"traceutil/trace.go:171","msg":"trace[1539623154] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"280.67765ms","start":"2026-03-05T23:21:31.767271Z","end":"2026-03-05T23:21:32.047949Z","steps":["trace[1539623154] 'process raft request' (duration: 280.556487ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T23:21:32.048212Z","caller":"traceutil/trace.go:171","msg":"trace[1280742723] linearizableReadLoop","detail":"{readStateIndex:982; appliedIndex:981; }","duration":"283.528251ms","start":"2026-03-05T23:21:31.764647Z","end":"2026-03-05T23:21:32.048175Z","steps":["trace[1280742723] 'read index received' (duration: 106.88692ms)","trace[1280742723] 'applied index is now lower than readState.Index' (duration: 176.637091ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T23:21:32.048405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.728856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-05T23:21:32.048493Z","caller":"traceutil/trace.go:171","msg":"trace[1585161464] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:945; }","duration":"283.86266ms","start":"2026-03-05T23:21:31.764616Z","end":"2026-03-05T23:21:32.048479Z","steps":["trace[1585161464] 'agreement among raft nodes before linearized reading' (duration: 283.681105ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T23:21:32.235858Z","caller":"traceutil/trace.go:171","msg":"trace[1976042851] linearizableReadLoop","detail":"{readStateIndex:984; appliedIndex:983; }","duration":"186.232032ms","start":"2026-03-05T23:21:32.049598Z","end":"2026-03-05T23:21:32.23583Z","steps":["trace[1976042851] 'read index received' (duration: 110.819928ms)","trace[1976042851] 'applied index is now lower than readState.Index' (duration: 75.410504ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T23:21:32.235913Z","caller":"traceutil/trace.go:171","msg":"trace[1020138245] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"187.103974ms","start":"2026-03-05T23:21:32.048765Z","end":"2026-03-05T23:21:32.235869Z","steps":["trace[1020138245] 'process raft request' (duration: 111.771082ms)","trace[1020138245] 'compare' (duration: 75.144977ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T23:21:32.236034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.401626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-05T23:21:32.236119Z","caller":"traceutil/trace.go:171","msg":"trace[1671252792] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:946; }","duration":"186.56145ms","start":"2026-03-05T23:21:32.049546Z","end":"2026-03-05T23:21:32.236107Z","steps":["trace[1671252792] 'agreement among raft nodes before linearized reading' (duration: 186.386775ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T23:21:32.669765Z","caller":"traceutil/trace.go:171","msg":"trace[333036403] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"240.701602ms","start":"2026-03-05T23:21:32.429036Z","end":"2026-03-05T23:21:32.669738Z","steps":["trace[333036403] 'process raft request' (duration: 235.878182ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T23:21:38.747773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.635368666s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-03-05T23:21:38.763112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999895399s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-03-05T23:21:38.763193Z","caller":"traceutil/trace.go:171","msg":"trace[208429293] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000035304s","start":"2026-03-05T23:21:36.76314Z","end":"2026-03-05T23:21:38.763175Z","steps":["trace[208429293] 'agreement among raft nodes before linearized reading' (duration: 1.999941381s)"],"step_count":1} {"level":"warn","ts":"2026-03-05T23:21:38.7634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T23:21:36.763115Z","time spent":"2.000271479s","remote":"127.0.0.1:32790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/03/05 23:21:38 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-03-05T23:21:38.809604Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.697480976s","expected-duration":"1s"} {"level":"info","ts":"2026-03-05T23:21:38.81042Z","caller":"traceutil/trace.go:171","msg":"trace[1005389623] linearizableReadLoop","detail":"{readStateIndex:1025; appliedIndex:1023; }","duration":"2.047199711s","start":"2026-03-05T23:21:36.763203Z","end":"2026-03-05T23:21:38.810403Z","steps":["trace[1005389623] 'read index received' (duration: 349.255823ms)","trace[1005389623] 'applied index is now lower than readState.Index' (duration: 1.697943188s)"],"step_count":2} {"level":"warn","ts":"2026-03-05T23:21:38.810751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.496564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-05T23:21:38.810802Z","caller":"traceutil/trace.go:171","msg":"trace[1438703186] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:986; }","duration":"535.583627ms","start":"2026-03-05T23:21:38.275209Z","end":"2026-03-05T23:21:38.810792Z","steps":["trace[1438703186] 'agreement among raft nodes before linearized reading' (duration: 535.448053ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T23:21:38.81083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T23:21:38.275194Z","time spent":"535.628297ms","remote":"127.0.0.1:33052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-05T23:21:38.810843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.170469313s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-05T23:21:38.810912Z","caller":"traceutil/trace.go:171","msg":"trace[516942147] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:986; }","duration":"1.170550646s","start":"2026-03-05T23:21:37.640342Z","end":"2026-03-05T23:21:38.810893Z","steps":["trace[516942147] 'agreement among raft nodes before linearized reading' (duration: 1.170444884s)"],"step_count":1} {"level":"info","ts":"2026-03-05T23:21:38.81097Z","caller":"traceutil/trace.go:171","msg":"trace[1713079712] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"2.237968516s","start":"2026-03-05T23:21:36.572994Z","end":"2026-03-05T23:21:38.810962Z","steps":["trace[1713079712] 'process raft request' (duration: 2.237282979s)"],"step_count":1} {"level":"warn","ts":"2026-03-05T23:21:38.810957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T23:21:37.640329Z","time spent":"1.170619908s","remote":"127.0.0.1:33052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-05T23:21:38.811036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T23:21:36.57296Z","time spent":"2.238034388s","remote":"127.0.0.1:32952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6173,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-05T23:21:38.811309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.767021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-05T23:21:38.811342Z","caller":"traceutil/trace.go:171","msg":"trace[1423252265] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:986; }","duration":"522.372237ms","start":"2026-03-05T23:21:38.288962Z","end":"2026-03-05T23:21:38.811334Z","steps":["trace[1423252265] 'agreement among raft nodes before linearized reading' (duration: 522.322825ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T23:21:38.811363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T23:21:38.28895Z","time spent":"522.408229ms","remote":"127.0.0.1:33082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":28,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-03-05T23:21:38.810742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.648608605s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-03-05T23:21:38.811413Z","caller":"traceutil/trace.go:171","msg":"trace[1939634878] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:986; }","duration":"1.649347925s","start":"2026-03-05T23:21:37.162052Z","end":"2026-03-05T23:21:38.8114Z","steps":["trace[1939634878] 'agreement among raft nodes before linearized reading' (duration: 1.648549524s)"],"step_count":1} {"level":"warn","ts":"2026-03-05T23:21:38.811458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T23:21:37.162028Z","time spent":"1.649419616s","remote":"127.0.0.1:33052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":553,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-03-05T23:21:38.811825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.403099584s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-05T23:21:38.811842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"936.623124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-05T23:21:38.811855Z","caller":"traceutil/trace.go:171","msg":"trace[1246621678] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:986; }","duration":"1.403162046s","start":"2026-03-05T23:21:37.408685Z","end":"2026-03-05T23:21:38.811847Z","steps":["trace[1246621678] 'agreement among raft nodes before linearized reading' (duration: 1.403093524s)"],"step_count":1} {"level":"warn","ts":"2026-03-05T23:21:38.811875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T23:21:37.408666Z","time spent":"1.403204377s","remote":"127.0.0.1:33052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-05T23:21:38.811875Z","caller":"traceutil/trace.go:171","msg":"trace[1114094437] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:986; }","duration":"936.685936ms","start":"2026-03-05T23:21:37.87518Z","end":"2026-03-05T23:21:38.811866Z","steps":["trace[1114094437] 'agreement among raft nodes before linearized reading' (duration: 936.632485ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T23:21:38.811907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T23:21:37.875163Z","time spent":"936.734597ms","remote":"127.0.0.1:32790","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-03-05T23:21:38.812045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.025958465s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-05T23:21:38.81207Z","caller":"traceutil/trace.go:171","msg":"trace[492125617] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:986; }","duration":"1.026010156s","start":"2026-03-05T23:21:37.786054Z","end":"2026-03-05T23:21:38.812064Z","steps":["trace[492125617] 'agreement among raft nodes before linearized reading' (duration: 1.025948845s)"],"step_count":1} {"level":"warn","ts":"2026-03-05T23:21:38.812087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T23:21:37.786038Z","time spent":"1.026044937s","remote":"127.0.0.1:33052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-05T23:21:38.811771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.02579655s","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-03-05T23:21:38.812304Z","caller":"traceutil/trace.go:171","msg":"trace[486098146] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:986; }","duration":"1.026800737s","start":"2026-03-05T23:21:37.785483Z","end":"2026-03-05T23:21:38.812283Z","steps":["trace[486098146] 'agreement among raft nodes before linearized reading' (duration: 1.025402041s)"],"step_count":1} {"level":"warn","ts":"2026-03-05T23:21:38.812618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T23:21:37.785471Z","time spent":"1.027130404s","remote":"127.0.0.1:33052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}