{"level":"warn","ts":"2026-03-24T14:57:09.853916Z","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-24T14:57:09.854034Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.127:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.127:2380","--initial-cluster=instance=https://199.19.213.127:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.127:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.127: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-24T14:57:09.854127Z","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-24T14:57:09.854135Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.127:2380"]} {"level":"info","ts":"2026-03-24T14:57:09.854159Z","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-24T14:57:09.854839Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.127:2379"]} {"level":"info","ts":"2026-03-24T14:57:09.854916Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.127:2380"],"listen-peer-urls":["https://199.19.213.127:2380"],"advertise-client-urls":["https://199.19.213.127:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.127:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.127: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-24T14:57:09.862502Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.587059ms"} {"level":"info","ts":"2026-03-24T14:57:09.87184Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"975f045ff4f5c0fb","cluster-id":"b3df79a2daf9b6ed"} {"level":"info","ts":"2026-03-24T14:57:09.871962Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"975f045ff4f5c0fb switched to configuration voters=()"} {"level":"info","ts":"2026-03-24T14:57:09.871994Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"975f045ff4f5c0fb became follower at term 0"} {"level":"info","ts":"2026-03-24T14:57:09.872015Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 975f045ff4f5c0fb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-24T14:57:09.872049Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"975f045ff4f5c0fb became follower at term 1"} {"level":"info","ts":"2026-03-24T14:57:09.872119Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"975f045ff4f5c0fb switched to configuration voters=(10907441632692781307)"} {"level":"warn","ts":"2026-03-24T14:57:09.882043Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-24T14:57:09.88476Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-24T14:57:09.890112Z","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-24T14:57:09.892944Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"975f045ff4f5c0fb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-24T14:57:09.895104Z","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-24T14:57:09.895176Z","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-24T14:57:09.895186Z","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-24T14:57:09.894996Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"975f045ff4f5c0fb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-24T14:57:09.896152Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"975f045ff4f5c0fb switched to configuration voters=(10907441632692781307)"} {"level":"info","ts":"2026-03-24T14:57:09.89754Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b3df79a2daf9b6ed","local-member-id":"975f045ff4f5c0fb","added-peer-id":"975f045ff4f5c0fb","added-peer-peer-urls":["https://199.19.213.127:2380"]} {"level":"info","ts":"2026-03-24T14:57:09.89835Z","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-24T14:57:09.898519Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.127:2380"} {"level":"info","ts":"2026-03-24T14:57:09.898582Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.127:2380"} {"level":"info","ts":"2026-03-24T14:57:09.899163Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-24T14:57:09.899114Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"975f045ff4f5c0fb","initial-advertise-peer-urls":["https://199.19.213.127:2380"],"listen-peer-urls":["https://199.19.213.127:2380"],"advertise-client-urls":["https://199.19.213.127:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.127:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-24T14:57:10.372987Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"975f045ff4f5c0fb is starting a new election at term 1"} {"level":"info","ts":"2026-03-24T14:57:10.373058Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"975f045ff4f5c0fb became pre-candidate at term 1"} {"level":"info","ts":"2026-03-24T14:57:10.373095Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"975f045ff4f5c0fb received MsgPreVoteResp from 975f045ff4f5c0fb at term 1"} {"level":"info","ts":"2026-03-24T14:57:10.373117Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"975f045ff4f5c0fb became candidate at term 2"} {"level":"info","ts":"2026-03-24T14:57:10.37313Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"975f045ff4f5c0fb received MsgVoteResp from 975f045ff4f5c0fb at term 2"} {"level":"info","ts":"2026-03-24T14:57:10.373148Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"975f045ff4f5c0fb became leader at term 2"} {"level":"info","ts":"2026-03-24T14:57:10.373343Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 975f045ff4f5c0fb elected leader 975f045ff4f5c0fb at term 2"} {"level":"info","ts":"2026-03-24T14:57:10.374587Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"975f045ff4f5c0fb","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.127:2379]}","request-path":"/0/members/975f045ff4f5c0fb/attributes","cluster-id":"b3df79a2daf9b6ed","publish-timeout":"7s"} {"level":"info","ts":"2026-03-24T14:57:10.374641Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-24T14:57:10.374595Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-24T14:57:10.374622Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-24T14:57:10.375308Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-24T14:57:10.375353Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-24T14:57:10.376097Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b3df79a2daf9b6ed","local-member-id":"975f045ff4f5c0fb","cluster-version":"3.5"} {"level":"info","ts":"2026-03-24T14:57:10.376229Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-24T14:57:10.376312Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-24T14:57:10.378294Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.127:2379"} {"level":"info","ts":"2026-03-24T14:57:10.381506Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-24T14:57:34.250931Z","caller":"traceutil/trace.go:171","msg":"trace[496762541] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"254.778464ms","start":"2026-03-24T14:57:33.996097Z","end":"2026-03-24T14:57:34.250875Z","steps":["trace[496762541] 'process raft request' (duration: 254.533788ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:57:37.831718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.729586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-24T14:57:37.831918Z","caller":"traceutil/trace.go:171","msg":"trace[284251336] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:428; }","duration":"261.996973ms","start":"2026-03-24T14:57:37.569897Z","end":"2026-03-24T14:57:37.831894Z","steps":["trace[284251336] 'range keys from in-memory index tree' (duration: 261.613004ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:57:39.564109Z","caller":"traceutil/trace.go:171","msg":"trace[1143921225] linearizableReadLoop","detail":"{readStateIndex:451; appliedIndex:450; }","duration":"229.701102ms","start":"2026-03-24T14:57:39.334385Z","end":"2026-03-24T14:57:39.564086Z","steps":["trace[1143921225] 'read index received' (duration: 229.506848ms)","trace[1143921225] 'applied index is now lower than readState.Index' (duration: 193.114µs)"],"step_count":2} {"level":"warn","ts":"2026-03-24T14:57:39.564259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.862835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-03-24T14:57:39.564311Z","caller":"traceutil/trace.go:171","msg":"trace[1734142833] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:431; }","duration":"229.962988ms","start":"2026-03-24T14:57:39.334334Z","end":"2026-03-24T14:57:39.564297Z","steps":["trace[1734142833] 'agreement among raft nodes before linearized reading' (duration: 229.852835ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:57:39.564592Z","caller":"traceutil/trace.go:171","msg":"trace[184942206] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"246.641744ms","start":"2026-03-24T14:57:39.317908Z","end":"2026-03-24T14:57:39.56455Z","steps":["trace[184942206] 'process raft request' (duration: 246.041291ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:57:39.748379Z","caller":"traceutil/trace.go:171","msg":"trace[541048378] linearizableReadLoop","detail":"{readStateIndex:452; appliedIndex:451; }","duration":"133.905975ms","start":"2026-03-24T14:57:39.614445Z","end":"2026-03-24T14:57:39.748351Z","steps":["trace[541048378] 'read index received' (duration: 131.291737ms)","trace[541048378] 'applied index is now lower than readState.Index' (duration: 2.613188ms)"],"step_count":2} {"level":"info","ts":"2026-03-24T14:57:39.748498Z","caller":"traceutil/trace.go:171","msg":"trace[1486993889] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"177.513555ms","start":"2026-03-24T14:57:39.570929Z","end":"2026-03-24T14:57:39.748443Z","steps":["trace[1486993889] 'process raft request' (duration: 174.912737ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:57:39.7486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.134199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:3 size:12669"} {"level":"info","ts":"2026-03-24T14:57:39.748639Z","caller":"traceutil/trace.go:171","msg":"trace[429233103] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:3; response_revision:432; }","duration":"134.220172ms","start":"2026-03-24T14:57:39.614405Z","end":"2026-03-24T14:57:39.748625Z","steps":["trace[429233103] 'agreement among raft nodes before linearized reading' (duration: 134.108929ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:57:44.776187Z","caller":"traceutil/trace.go:171","msg":"trace[611108373] linearizableReadLoop","detail":"{readStateIndex:528; appliedIndex:527; }","duration":"472.023141ms","start":"2026-03-24T14:57:44.304136Z","end":"2026-03-24T14:57:44.776159Z","steps":["trace[611108373] 'read index received' (duration: 471.915959ms)","trace[611108373] 'applied index is now lower than readState.Index' (duration: 105.132µs)"],"step_count":2} {"level":"info","ts":"2026-03-24T14:57:44.776231Z","caller":"traceutil/trace.go:171","msg":"trace[1789195787] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"493.432962ms","start":"2026-03-24T14:57:44.282775Z","end":"2026-03-24T14:57:44.776208Z","steps":["trace[1789195787] 'process raft request' (duration: 493.202047ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:57:44.776393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.250036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-24T14:57:44.776436Z","caller":"traceutil/trace.go:171","msg":"trace[1132821012] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:507; }","duration":"472.352508ms","start":"2026-03-24T14:57:44.304073Z","end":"2026-03-24T14:57:44.776426Z","steps":["trace[1132821012] 'agreement among raft nodes before linearized reading' (duration: 472.232905ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:57:44.776472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:57:44.304048Z","time spent":"472.41542ms","remote":"127.0.0.1:54020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-24T14:57:44.776847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:57:44.282747Z","time spent":"493.653567ms","remote":"127.0.0.1:53796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3353,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-24T14:57:44.791693Z","caller":"traceutil/trace.go:171","msg":"trace[822953876] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"308.194259ms","start":"2026-03-24T14:57:44.483476Z","end":"2026-03-24T14:57:44.79167Z","steps":["trace[822953876] 'process raft request' (duration: 304.204311ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:57:44.791711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.660368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-24T14:57:44.791776Z","caller":"traceutil/trace.go:171","msg":"trace[843678367] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:508; }","duration":"458.770201ms","start":"2026-03-24T14:57:44.332994Z","end":"2026-03-24T14:57:44.791764Z","steps":["trace[843678367] 'agreement among raft nodes before linearized reading' (duration: 458.635638ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:57:44.791831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:57:44.483449Z","time spent":"308.320301ms","remote":"127.0.0.1:53796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3104,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-24T14:57:44.79184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:57:44.332974Z","time spent":"458.859802ms","remote":"127.0.0.1:53756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-24T14:57:44.791835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.327993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-24T14:57:44.79194Z","caller":"traceutil/trace.go:171","msg":"trace[509511486] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:508; }","duration":"219.489317ms","start":"2026-03-24T14:57:44.572415Z","end":"2026-03-24T14:57:44.791904Z","steps":["trace[509511486] 'agreement among raft nodes before linearized reading' (duration: 219.288162ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:57:44.791962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.638161ms","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-24T14:57:44.792067Z","caller":"traceutil/trace.go:171","msg":"trace[2073604056] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:508; }","duration":"445.768224ms","start":"2026-03-24T14:57:44.346272Z","end":"2026-03-24T14:57:44.792041Z","steps":["trace[2073604056] 'agreement among raft nodes before linearized reading' (duration: 445.498518ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:57:44.791725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.024419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-24T14:57:44.792118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:57:44.34624Z","time spent":"445.869036ms","remote":"127.0.0.1:54020","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-24T14:57:44.792158Z","caller":"traceutil/trace.go:171","msg":"trace[134996992] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:508; }","duration":"110.48964ms","start":"2026-03-24T14:57:44.68164Z","end":"2026-03-24T14:57:44.79213Z","steps":["trace[134996992] 'agreement among raft nodes before linearized reading' (duration: 109.995729ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:57:51.349965Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13905881036702676179,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-24T14:57:51.422177Z","caller":"traceutil/trace.go:171","msg":"trace[2136464044] linearizableReadLoop","detail":"{readStateIndex:565; appliedIndex:564; }","duration":"572.776978ms","start":"2026-03-24T14:57:50.849369Z","end":"2026-03-24T14:57:51.422146Z","steps":["trace[2136464044] 'read index received' (duration: 572.652075ms)","trace[2136464044] 'applied index is now lower than readState.Index' (duration: 124.083µs)"],"step_count":2} {"level":"info","ts":"2026-03-24T14:57:51.422299Z","caller":"traceutil/trace.go:171","msg":"trace[848145033] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"656.741256ms","start":"2026-03-24T14:57:50.765517Z","end":"2026-03-24T14:57:51.422259Z","steps":["trace[848145033] 'process raft request' (duration: 656.46ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:57:51.422468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.84239ms","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-24T14:57:51.422505Z","caller":"traceutil/trace.go:171","msg":"trace[924686521] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:543; }","duration":"552.935122ms","start":"2026-03-24T14:57:50.869559Z","end":"2026-03-24T14:57:51.422494Z","steps":["trace[924686521] 'agreement among raft nodes before linearized reading' (duration: 552.739017ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:57:51.42254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:57:50.869541Z","time spent":"552.991173ms","remote":"127.0.0.1:54020","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":"warn","ts":"2026-03-24T14:57:51.422602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.188998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-24T14:57:51.422722Z","caller":"traceutil/trace.go:171","msg":"trace[1384332771] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:543; }","duration":"573.344151ms","start":"2026-03-24T14:57:50.849352Z","end":"2026-03-24T14:57:51.422696Z","steps":["trace[1384332771] 'agreement among raft nodes before linearized reading' (duration: 572.939702ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:57:51.422779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:57:50.849327Z","time spent":"573.442493ms","remote":"127.0.0.1:54020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-24T14:57:51.422836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.241094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-03-24T14:57:51.42286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:57:50.76549Z","time spent":"656.95855ms","remote":"127.0.0.1:53942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-24T14:57:51.42291Z","caller":"traceutil/trace.go:171","msg":"trace[999763655] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:543; }","duration":"305.341886ms","start":"2026-03-24T14:57:51.117549Z","end":"2026-03-24T14:57:51.422891Z","steps":["trace[999763655] 'agreement among raft nodes before linearized reading' (duration: 305.127752ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:57:51.42296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:57:51.117536Z","time spent":"305.411387ms","remote":"127.0.0.1:54020","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":"info","ts":"2026-03-24T14:57:51.558863Z","caller":"traceutil/trace.go:171","msg":"trace[1796202887] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"131.38303ms","start":"2026-03-24T14:57:51.427456Z","end":"2026-03-24T14:57:51.558839Z","steps":["trace[1796202887] 'process raft request' (duration: 131.241437ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:57:51.714053Z","caller":"traceutil/trace.go:171","msg":"trace[2051302446] linearizableReadLoop","detail":"{readStateIndex:568; appliedIndex:566; }","duration":"141.654016ms","start":"2026-03-24T14:57:51.572378Z","end":"2026-03-24T14:57:51.714032Z","steps":["trace[2051302446] 'read index received' (duration: 102.76716ms)","trace[2051302446] 'applied index is now lower than readState.Index' (duration: 38.885756ms)"],"step_count":2} {"level":"warn","ts":"2026-03-24T14:57:51.714371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.968623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-24T14:57:51.714494Z","caller":"traceutil/trace.go:171","msg":"trace[1881511204] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:546; }","duration":"142.139846ms","start":"2026-03-24T14:57:51.572328Z","end":"2026-03-24T14:57:51.714468Z","steps":["trace[1881511204] 'agreement among raft nodes before linearized reading' (duration: 141.934132ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:57:51.714602Z","caller":"traceutil/trace.go:171","msg":"trace[1028776871] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"286.326237ms","start":"2026-03-24T14:57:51.428266Z","end":"2026-03-24T14:57:51.714592Z","steps":["trace[1028776871] 'process raft request' (duration: 285.729264ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:57:51.714504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.328094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-24T14:57:51.714795Z","caller":"traceutil/trace.go:171","msg":"trace[859657554] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:546; }","duration":"104.646531ms","start":"2026-03-24T14:57:51.610139Z","end":"2026-03-24T14:57:51.714786Z","steps":["trace[859657554] 'agreement among raft nodes before linearized reading' (duration: 104.234592ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:57:51.71497Z","caller":"traceutil/trace.go:171","msg":"trace[1181721993] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"287.044763ms","start":"2026-03-24T14:57:51.427908Z","end":"2026-03-24T14:57:51.714953Z","steps":["trace[1181721993] 'process raft request' (duration: 247.197866ms)","trace[1181721993] 'compare' (duration: 38.770563ms)"],"step_count":2} {"level":"warn","ts":"2026-03-24T14:57:52.094579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.047022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-24T14:57:52.09473Z","caller":"traceutil/trace.go:171","msg":"trace[890398289] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:547; }","duration":"276.242715ms","start":"2026-03-24T14:57:51.818437Z","end":"2026-03-24T14:57:52.094679Z","steps":["trace[890398289] 'range keys from in-memory index tree' (duration: 275.843908ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:57:52.903432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.285078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-24T14:57:52.903513Z","caller":"traceutil/trace.go:171","msg":"trace[1030921050] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:548; }","duration":"332.420191ms","start":"2026-03-24T14:57:52.571073Z","end":"2026-03-24T14:57:52.903494Z","steps":["trace[1030921050] 'range keys from in-memory index tree' (duration: 332.187427ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:57:52.903569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:57:52.571048Z","time spent":"332.512053ms","remote":"127.0.0.1:53756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-24T14:57:53.254216Z","caller":"traceutil/trace.go:171","msg":"trace[227637542] linearizableReadLoop","detail":"{readStateIndex:571; appliedIndex:570; }","duration":"349.201481ms","start":"2026-03-24T14:57:52.904989Z","end":"2026-03-24T14:57:53.254191Z","steps":["trace[227637542] 'read index received' (duration: 349.015537ms)","trace[227637542] 'applied index is now lower than readState.Index' (duration: 185.044µs)"],"step_count":2} {"level":"info","ts":"2026-03-24T14:57:53.254316Z","caller":"traceutil/trace.go:171","msg":"trace[1364080572] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"365.477389ms","start":"2026-03-24T14:57:52.888809Z","end":"2026-03-24T14:57:53.254286Z","steps":["trace[1364080572] 'process raft request' (duration: 365.254484ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:57:53.254355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.342324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-24T14:57:53.25441Z","caller":"traceutil/trace.go:171","msg":"trace[446755647] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:549; }","duration":"349.449306ms","start":"2026-03-24T14:57:52.904951Z","end":"2026-03-24T14:57:53.2544Z","steps":["trace[446755647] 'agreement among raft nodes before linearized reading' (duration: 349.345454ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:57:53.25445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:57:52.904934Z","time spent":"349.507897ms","remote":"127.0.0.1:53770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-24T14:57:53.254458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:57:52.888772Z","time spent":"365.608102ms","remote":"127.0.0.1:54020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-24T14:57:58.321611Z","caller":"traceutil/trace.go:171","msg":"trace[767102251] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"125.832648ms","start":"2026-03-24T14:57:58.195746Z","end":"2026-03-24T14:57:58.321579Z","steps":["trace[767102251] 'process raft request' (duration: 125.597143ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:58:12.263459Z","caller":"traceutil/trace.go:171","msg":"trace[745057316] linearizableReadLoop","detail":"{readStateIndex:715; appliedIndex:714; }","duration":"430.296744ms","start":"2026-03-24T14:58:11.833131Z","end":"2026-03-24T14:58:12.263428Z","steps":["trace[745057316] 'read index received' (duration: 430.057378ms)","trace[745057316] 'applied index is now lower than readState.Index' (duration: 237.826µs)"],"step_count":2} {"level":"info","ts":"2026-03-24T14:58:12.2635Z","caller":"traceutil/trace.go:171","msg":"trace[2019588646] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"581.366646ms","start":"2026-03-24T14:58:11.682086Z","end":"2026-03-24T14:58:12.263453Z","steps":["trace[2019588646] 'process raft request' (duration: 581.158532ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:58:12.264002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:58:11.682055Z","time spent":"581.564592ms","remote":"127.0.0.1:54020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-24T14:58:12.264022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.417226ms","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-24T14:58:12.264068Z","caller":"traceutil/trace.go:171","msg":"trace[1995886937] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:687; }","duration":"429.553738ms","start":"2026-03-24T14:58:11.834503Z","end":"2026-03-24T14:58:12.264057Z","steps":["trace[1995886937] 'agreement among raft nodes before linearized reading' (duration: 429.146459ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:58:12.264054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.640272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-24T14:58:12.26411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:58:11.834479Z","time spent":"429.62113ms","remote":"127.0.0.1:54020","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-24T14:58:12.26416Z","caller":"traceutil/trace.go:171","msg":"trace[2027682252] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:687; }","duration":"430.799176ms","start":"2026-03-24T14:58:11.833335Z","end":"2026-03-24T14:58:12.264134Z","steps":["trace[2027682252] 'agreement among raft nodes before linearized reading' (duration: 430.322386ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:58:12.264209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:58:11.833321Z","time spent":"430.878777ms","remote":"127.0.0.1:54020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-24T14:58:12.264708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.570062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-24T14:58:12.264763Z","caller":"traceutil/trace.go:171","msg":"trace[455945149] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:687; }","duration":"431.689705ms","start":"2026-03-24T14:58:11.833055Z","end":"2026-03-24T14:58:12.264745Z","steps":["trace[455945149] 'agreement among raft nodes before linearized reading' (duration: 430.53502ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:58:12.264802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:58:11.833023Z","time spent":"431.768456ms","remote":"127.0.0.1:54020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-24T14:58:14.08667Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13905881036702676464,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-24T14:58:14.21419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:58:13.562375Z","time spent":"651.809796ms","remote":"127.0.0.1:53782","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-03-24T14:58:14.481449Z","caller":"traceutil/trace.go:171","msg":"trace[1779723859] linearizableReadLoop","detail":"{readStateIndex:722; appliedIndex:719; }","duration":"895.306761ms","start":"2026-03-24T14:58:13.586124Z","end":"2026-03-24T14:58:14.481431Z","steps":["trace[1779723859] 'read index received' (duration: 627.937471ms)","trace[1779723859] 'applied index is now lower than readState.Index' (duration: 267.36837ms)"],"step_count":2} {"level":"info","ts":"2026-03-24T14:58:14.481537Z","caller":"traceutil/trace.go:171","msg":"trace[2066382560] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"678.743908ms","start":"2026-03-24T14:58:13.802785Z","end":"2026-03-24T14:58:14.481529Z","steps":["trace[2066382560] 'process raft request' (duration: 678.594765ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:58:14.4824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:58:13.802759Z","time spent":"679.542326ms","remote":"127.0.0.1:54020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-24T14:58:14.48268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"896.537738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-24T14:58:14.482765Z","caller":"traceutil/trace.go:171","msg":"trace[128742455] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:693; }","duration":"896.6396ms","start":"2026-03-24T14:58:13.58609Z","end":"2026-03-24T14:58:14.48273Z","steps":["trace[128742455] 'agreement among raft nodes before linearized reading' (duration: 895.442723ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:58:14.482806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:58:13.58608Z","time spent":"896.715953ms","remote":"127.0.0.1:53756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-24T14:58:14.482223Z","caller":"traceutil/trace.go:171","msg":"trace[1018110349] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"915.308071ms","start":"2026-03-24T14:58:13.566213Z","end":"2026-03-24T14:58:14.481521Z","steps":["trace[1018110349] 'process raft request' (duration: 915.048125ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:58:14.486261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:58:13.566199Z","time spent":"919.923263ms","remote":"127.0.0.1:53830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":809,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-24T14:58:14.487193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.011556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-24T14:58:14.487305Z","caller":"traceutil/trace.go:171","msg":"trace[1564945508] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:694; }","duration":"209.872667ms","start":"2026-03-24T14:58:14.277397Z","end":"2026-03-24T14:58:14.48727Z","steps":["trace[1564945508] 'agreement among raft nodes before linearized reading' (duration: 209.422137ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:58:14.487373Z","caller":"traceutil/trace.go:171","msg":"trace[1068901847] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"271.855009ms","start":"2026-03-24T14:58:14.215498Z","end":"2026-03-24T14:58:14.487353Z","steps":["trace[1068901847] 'process raft request' (duration: 270.96799ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:58:14.487373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.550465ms","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":"warn","ts":"2026-03-24T14:58:14.48756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.640613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-24T14:58:14.487583Z","caller":"traceutil/trace.go:171","msg":"trace[769158082] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:694; }","duration":"207.829761ms","start":"2026-03-24T14:58:14.279724Z","end":"2026-03-24T14:58:14.487553Z","steps":["trace[769158082] 'agreement among raft nodes before linearized reading' (duration: 206.933871ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:58:14.487696Z","caller":"traceutil/trace.go:171","msg":"trace[1140783961] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:694; }","duration":"210.807467ms","start":"2026-03-24T14:58:14.276845Z","end":"2026-03-24T14:58:14.487653Z","steps":["trace[1140783961] 'agreement among raft nodes before linearized reading' (duration: 210.463789ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:58:14.48831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.024126ms","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-24T14:58:14.488445Z","caller":"traceutil/trace.go:171","msg":"trace[1536436963] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:694; }","duration":"218.098037ms","start":"2026-03-24T14:58:14.270265Z","end":"2026-03-24T14:58:14.488363Z","steps":["trace[1536436963] 'agreement among raft nodes before linearized reading' (duration: 216.346898ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:58:39.123156Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13905881036702676671,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-24T14:58:39.623406Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13905881036702676671,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-24T14:58:39.718422Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.099975041s","expected-duration":"1s"} {"level":"info","ts":"2026-03-24T14:58:39.718935Z","caller":"traceutil/trace.go:171","msg":"trace[1306962711] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"1.100612314s","start":"2026-03-24T14:58:38.618292Z","end":"2026-03-24T14:58:39.718904Z","steps":["trace[1306962711] 'process raft request' (duration: 1.100444591s)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:58:39.719124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:58:38.618192Z","time spent":"1.100833129s","remote":"127.0.0.1:54020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-24T14:58:40.087637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.377491ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-03-24T14:58:40.087786Z","caller":"traceutil/trace.go:171","msg":"trace[1386618113] linearizableReadLoop","detail":"{readStateIndex:803; appliedIndex:801; }","duration":"1.465286816s","start":"2026-03-24T14:58:38.622474Z","end":"2026-03-24T14:58:40.08776Z","steps":["trace[1386618113] 'read index received' (duration: 1.096247775s)","trace[1386618113] 'applied index is now lower than readState.Index' (duration: 369.037591ms)"],"step_count":2} {"level":"warn","ts":"2026-03-24T14:58:40.088037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.465561092s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-24T14:58:40.088074Z","caller":"traceutil/trace.go:171","msg":"trace[1682861705] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:768; }","duration":"1.465663374s","start":"2026-03-24T14:58:38.6224Z","end":"2026-03-24T14:58:40.088064Z","steps":["trace[1682861705] 'agreement among raft nodes before linearized reading' (duration: 1.46547222s)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:58:40.088119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:58:38.622375Z","time spent":"1.465735236s","remote":"127.0.0.1:54020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-24T14:58:40.088209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.455545546s","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-24T14:58:40.088336Z","caller":"traceutil/trace.go:171","msg":"trace[300618888] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:768; }","duration":"1.455757342s","start":"2026-03-24T14:58:38.632546Z","end":"2026-03-24T14:58:40.088303Z","steps":["trace[300618888] 'agreement among raft nodes before linearized reading' (duration: 1.455370353s)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:58:40.088407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:58:38.632518Z","time spent":"1.455874864s","remote":"127.0.0.1:54020","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":"warn","ts":"2026-03-24T14:58:40.088418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.388085334s","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-24T14:58:40.088508Z","caller":"traceutil/trace.go:171","msg":"trace[1228393283] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:768; }","duration":"1.388245539s","start":"2026-03-24T14:58:38.700244Z","end":"2026-03-24T14:58:40.08849Z","steps":["trace[1228393283] 'agreement among raft nodes before linearized reading' (duration: 1.388005553s)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:58:40.088564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:58:38.700223Z","time spent":"1.38832773s","remote":"127.0.0.1:54020","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-24T14:58:40.088554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.106883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-24T14:58:40.088677Z","caller":"traceutil/trace.go:171","msg":"trace[989592673] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:768; }","duration":"515.342588ms","start":"2026-03-24T14:58:39.573303Z","end":"2026-03-24T14:58:40.088645Z","steps":["trace[989592673] 'agreement among raft nodes before linearized reading' (duration: 515.106643ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:58:40.088741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:58:39.573281Z","time spent":"515.449931ms","remote":"127.0.0.1:53756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-24T14:58:40.089254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.453031489s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-24T14:58:40.089346Z","caller":"traceutil/trace.go:171","msg":"trace[1591626228] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:768; }","duration":"1.453175482s","start":"2026-03-24T14:58:38.636148Z","end":"2026-03-24T14:58:40.089324Z","steps":["trace[1591626228] 'agreement among raft nodes before linearized reading' (duration: 1.452119389s)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:58:40.089387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:58:38.636136Z","time spent":"1.453243843s","remote":"127.0.0.1:54020","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":"info","ts":"2026-03-24T14:58:40.373273Z","caller":"traceutil/trace.go:171","msg":"trace[2037593800] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"278.057458ms","start":"2026-03-24T14:58:40.095181Z","end":"2026-03-24T14:58:40.373239Z","steps":["trace[2037593800] 'process raft request' (duration: 277.807213ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:58:40.373562Z","caller":"traceutil/trace.go:171","msg":"trace[2020874846] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"278.264223ms","start":"2026-03-24T14:58:40.095254Z","end":"2026-03-24T14:58:40.373518Z","steps":["trace[2020874846] 'process raft request' (duration: 277.924966ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:58:40.373572Z","caller":"traceutil/trace.go:171","msg":"trace[1360137227] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"276.158567ms","start":"2026-03-24T14:58:40.097384Z","end":"2026-03-24T14:58:40.373543Z","steps":["trace[1360137227] 'process raft request' (duration: 276.033394ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:58:42.324091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.2908ms","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-24T14:58:42.324217Z","caller":"traceutil/trace.go:171","msg":"trace[1370888608] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:773; }","duration":"182.498435ms","start":"2026-03-24T14:58:42.141695Z","end":"2026-03-24T14:58:42.324194Z","steps":["trace[1370888608] 'range keys from in-memory index tree' (duration: 181.982703ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:58:42.324534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.931004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-24T14:58:42.324689Z","caller":"traceutil/trace.go:171","msg":"trace[653082811] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:773; }","duration":"234.134438ms","start":"2026-03-24T14:58:42.09054Z","end":"2026-03-24T14:58:42.324674Z","steps":["trace[653082811] 'count revisions from in-memory index tree' (duration: 233.860032ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:58:42.568373Z","caller":"traceutil/trace.go:171","msg":"trace[1929518403] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"186.437073ms","start":"2026-03-24T14:58:42.381906Z","end":"2026-03-24T14:58:42.568343Z","steps":["trace[1929518403] 'process raft request' (duration: 177.175145ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:58:42.572106Z","caller":"traceutil/trace.go:171","msg":"trace[584618586] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"187.456426ms","start":"2026-03-24T14:58:42.384626Z","end":"2026-03-24T14:58:42.572082Z","steps":["trace[584618586] 'process raft request' (duration: 187.401825ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:58:42.572296Z","caller":"traceutil/trace.go:171","msg":"trace[226841355] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"189.537863ms","start":"2026-03-24T14:58:42.382686Z","end":"2026-03-24T14:58:42.572224Z","steps":["trace[226841355] 'process raft request' (duration: 189.223966ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:58:54.985672Z","caller":"traceutil/trace.go:171","msg":"trace[1300467365] linearizableReadLoop","detail":"{readStateIndex:900; appliedIndex:899; }","duration":"415.62749ms","start":"2026-03-24T14:58:54.570014Z","end":"2026-03-24T14:58:54.985641Z","steps":["trace[1300467365] 'read index received' (duration: 415.467197ms)","trace[1300467365] 'applied index is now lower than readState.Index' (duration: 159.073µs)"],"step_count":2} {"level":"info","ts":"2026-03-24T14:58:54.985785Z","caller":"traceutil/trace.go:171","msg":"trace[1561762777] transaction","detail":"{read_only:false; response_revision:860; number_of_response:1; }","duration":"583.189919ms","start":"2026-03-24T14:58:54.402562Z","end":"2026-03-24T14:58:54.985752Z","steps":["trace[1561762777] 'process raft request' (duration: 582.865552ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:58:54.985904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.876826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-24T14:58:54.985949Z","caller":"traceutil/trace.go:171","msg":"trace[1690369091] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:860; }","duration":"415.966668ms","start":"2026-03-24T14:58:54.56997Z","end":"2026-03-24T14:58:54.985937Z","steps":["trace[1690369091] 'agreement among raft nodes before linearized reading' (duration: 415.844585ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:58:54.985954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:58:54.402537Z","time spent":"583.310631ms","remote":"127.0.0.1:54020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-24T14:58:54.985987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:58:54.56995Z","time spent":"416.02938ms","remote":"127.0.0.1:53770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-24T14:58:54.986297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.271018ms","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":"warn","ts":"2026-03-24T14:58:54.986378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.335514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-24T14:58:54.986468Z","caller":"traceutil/trace.go:171","msg":"trace[94388085] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:860; }","duration":"364.473287ms","start":"2026-03-24T14:58:54.621972Z","end":"2026-03-24T14:58:54.986445Z","steps":["trace[94388085] 'agreement among raft nodes before linearized reading' (duration: 364.088689ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:58:54.986477Z","caller":"traceutil/trace.go:171","msg":"trace[637834502] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:860; }","duration":"358.388011ms","start":"2026-03-24T14:58:54.627982Z","end":"2026-03-24T14:58:54.98637Z","steps":["trace[637834502] 'agreement among raft nodes before linearized reading' (duration: 358.108504ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:58:54.986514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:58:54.621952Z","time spent":"364.55281ms","remote":"127.0.0.1:54020","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-24T14:58:54.986531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:58:54.627974Z","time spent":"358.544754ms","remote":"127.0.0.1:54020","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-24T14:58:54.987024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.909354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-24T14:58:54.98708Z","caller":"traceutil/trace.go:171","msg":"trace[1195850952] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:860; }","duration":"360.029847ms","start":"2026-03-24T14:58:54.627036Z","end":"2026-03-24T14:58:54.987066Z","steps":["trace[1195850952] 'agreement among raft nodes before linearized reading' (duration: 359.064556ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:58:54.987125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:58:54.627014Z","time spent":"360.090948ms","remote":"127.0.0.1:54020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-24T14:58:55.145598Z","caller":"traceutil/trace.go:171","msg":"trace[1554542538] transaction","detail":"{read_only:false; response_revision:862; number_of_response:1; }","duration":"155.219939ms","start":"2026-03-24T14:58:54.99035Z","end":"2026-03-24T14:58:55.14557Z","steps":["trace[1554542538] 'process raft request' (duration: 155.010295ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:58:55.145782Z","caller":"traceutil/trace.go:171","msg":"trace[2012680982] transaction","detail":"{read_only:false; response_revision:863; number_of_response:1; }","duration":"155.016404ms","start":"2026-03-24T14:58:54.990736Z","end":"2026-03-24T14:58:55.145752Z","steps":["trace[2012680982] 'process raft request' (duration: 154.798069ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:59:09.45468Z","caller":"traceutil/trace.go:171","msg":"trace[775925400] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"407.58991ms","start":"2026-03-24T14:59:09.047058Z","end":"2026-03-24T14:59:09.454648Z","steps":["trace[775925400] 'process raft request' (duration: 407.397936ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:59:09.4549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:59:09.047026Z","time spent":"407.760934ms","remote":"127.0.0.1:54020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-24T14:59:09.455092Z","caller":"traceutil/trace.go:171","msg":"trace[1684037958] linearizableReadLoop","detail":"{readStateIndex:942; appliedIndex:942; }","duration":"385.463241ms","start":"2026-03-24T14:59:09.069603Z","end":"2026-03-24T14:59:09.455066Z","steps":["trace[1684037958] 'read index received' (duration: 385.454151ms)","trace[1684037958] 'applied index is now lower than readState.Index' (duration: 7.29µs)"],"step_count":2} {"level":"warn","ts":"2026-03-24T14:59:09.455395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.759827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-03-24T14:59:09.455506Z","caller":"traceutil/trace.go:171","msg":"trace[1826789810] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:900; }","duration":"385.920621ms","start":"2026-03-24T14:59:09.069566Z","end":"2026-03-24T14:59:09.455486Z","steps":["trace[1826789810] 'agreement among raft nodes before linearized reading' (duration: 385.634595ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:59:09.455563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:59:09.069553Z","time spent":"385.985833ms","remote":"127.0.0.1:54020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-24T14:59:10.094414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.217567ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-03-24T14:59:10.094752Z","caller":"traceutil/trace.go:171","msg":"trace[1924984933] linearizableReadLoop","detail":"{readStateIndex:943; appliedIndex:942; }","duration":"639.57211ms","start":"2026-03-24T14:59:09.455157Z","end":"2026-03-24T14:59:10.094729Z","steps":["trace[1924984933] 'read index received' (duration: 151.064596ms)","trace[1924984933] 'applied index is now lower than readState.Index' (duration: 488.503683ms)"],"step_count":2} {"level":"info","ts":"2026-03-24T14:59:10.094927Z","caller":"traceutil/trace.go:171","msg":"trace[967207646] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"635.551658ms","start":"2026-03-24T14:59:09.459336Z","end":"2026-03-24T14:59:10.094887Z","steps":["trace[967207646] 'process raft request' (duration: 635.20639ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:59:10.094993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"878.716511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-03-24T14:59:10.095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.053259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-24T14:59:10.095038Z","caller":"traceutil/trace.go:171","msg":"trace[1536148150] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:901; }","duration":"522.133031ms","start":"2026-03-24T14:59:09.572894Z","end":"2026-03-24T14:59:10.095027Z","steps":["trace[1536148150] 'agreement among raft nodes before linearized reading' (duration: 522.07974ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:59:10.095038Z","caller":"traceutil/trace.go:171","msg":"trace[687282601] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:901; }","duration":"878.829343ms","start":"2026-03-24T14:59:09.216192Z","end":"2026-03-24T14:59:10.095021Z","steps":["trace[687282601] 'agreement among raft nodes before linearized reading' (duration: 878.652379ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:59:10.095065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:59:09.572842Z","time spent":"522.216495ms","remote":"127.0.0.1:53756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-24T14:59:10.095074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:59:09.216163Z","time spent":"878.901526ms","remote":"127.0.0.1:54020","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-24T14:59:10.095112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:59:09.459315Z","time spent":"635.691572ms","remote":"127.0.0.1:54020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-24T14:59:10.095174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"859.147661ms","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-24T14:59:10.095275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.811481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-24T14:59:10.095303Z","caller":"traceutil/trace.go:171","msg":"trace[1141871383] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:901; }","duration":"154.878973ms","start":"2026-03-24T14:59:09.940416Z","end":"2026-03-24T14:59:10.095295Z","steps":["trace[1141871383] 'agreement among raft nodes before linearized reading' (duration: 154.775781ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:59:10.095309Z","caller":"traceutil/trace.go:171","msg":"trace[320525527] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:901; }","duration":"859.348785ms","start":"2026-03-24T14:59:09.235934Z","end":"2026-03-24T14:59:10.095283Z","steps":["trace[320525527] 'agreement among raft nodes before linearized reading' (duration: 858.839783ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:59:10.095393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:59:09.235913Z","time spent":"859.468978ms","remote":"127.0.0.1:54020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-24T14:59:10.228018Z","caller":"traceutil/trace.go:171","msg":"trace[974588944] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"126.647825ms","start":"2026-03-24T14:59:10.101324Z","end":"2026-03-24T14:59:10.227972Z","steps":["trace[974588944] 'process raft request' (duration: 126.584173ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:59:10.228053Z","caller":"traceutil/trace.go:171","msg":"trace[1940869472] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"127.975376ms","start":"2026-03-24T14:59:10.100033Z","end":"2026-03-24T14:59:10.228009Z","steps":["trace[1940869472] 'process raft request' (duration: 77.927877ms)","trace[1940869472] 'compare' (duration: 49.589468ms)"],"step_count":2} {"level":"info","ts":"2026-03-24T14:59:10.22808Z","caller":"traceutil/trace.go:171","msg":"trace[397697198] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"127.928984ms","start":"2026-03-24T14:59:10.100114Z","end":"2026-03-24T14:59:10.228043Z","steps":["trace[397697198] 'process raft request' (duration: 127.688039ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:59:11.647354Z","caller":"traceutil/trace.go:171","msg":"trace[1752826969] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"183.351425ms","start":"2026-03-24T14:59:11.463968Z","end":"2026-03-24T14:59:11.64732Z","steps":["trace[1752826969] 'process raft request' (duration: 183.045898ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:59:12.586434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.990582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-24T14:59:12.586524Z","caller":"traceutil/trace.go:171","msg":"trace[1582962481] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:906; }","duration":"354.133795ms","start":"2026-03-24T14:59:12.23237Z","end":"2026-03-24T14:59:12.586504Z","steps":["trace[1582962481] 'range keys from in-memory index tree' (duration: 353.691623ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:59:12.586569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:59:12.23234Z","time spent":"354.219047ms","remote":"127.0.0.1:54020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-24T14:59:12.586576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.117604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-03-24T14:59:12.586597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.96497ms","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-24T14:59:12.586662Z","caller":"traceutil/trace.go:171","msg":"trace[616177041] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:906; }","duration":"354.314879ms","start":"2026-03-24T14:59:12.232325Z","end":"2026-03-24T14:59:12.58664Z","steps":["trace[616177041] 'range keys from in-memory index tree' (duration: 353.822596ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:59:12.586693Z","caller":"traceutil/trace.go:171","msg":"trace[1994389422] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:906; }","duration":"354.082692ms","start":"2026-03-24T14:59:12.232584Z","end":"2026-03-24T14:59:12.586667Z","steps":["trace[1994389422] 'range keys from in-memory index tree' (duration: 353.796606ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:59:12.586742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:59:12.232299Z","time spent":"354.431352ms","remote":"127.0.0.1:54020","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-24T14:59:12.586783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:59:12.232469Z","time spent":"354.297569ms","remote":"127.0.0.1:54020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-24T14:59:12.695974Z","caller":"traceutil/trace.go:171","msg":"trace[1600637300] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"103.77507ms","start":"2026-03-24T14:59:12.592176Z","end":"2026-03-24T14:59:12.695951Z","steps":["trace[1600637300] 'process raft request' (duration: 103.615436ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:59:12.798049Z","caller":"traceutil/trace.go:171","msg":"trace[1939408239] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"203.407915ms","start":"2026-03-24T14:59:12.594617Z","end":"2026-03-24T14:59:12.798025Z","steps":["trace[1939408239] 'process raft request' (duration: 203.368374ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:59:12.798239Z","caller":"traceutil/trace.go:171","msg":"trace[915112182] transaction","detail":"{read_only:false; response_revision:908; number_of_response:1; }","duration":"203.64071ms","start":"2026-03-24T14:59:12.594584Z","end":"2026-03-24T14:59:12.798225Z","steps":["trace[915112182] 'process raft request' (duration: 192.324405ms)","trace[915112182] 'compare' (duration: 10.961468ms)"],"step_count":2} {"level":"warn","ts":"2026-03-24T14:59:14.381767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.196048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-24T14:59:14.381835Z","caller":"traceutil/trace.go:171","msg":"trace[1422871894] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:909; }","duration":"249.321751ms","start":"2026-03-24T14:59:14.132498Z","end":"2026-03-24T14:59:14.38182Z","steps":["trace[1422871894] 'count revisions from in-memory index tree' (duration: 249.059315ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:59:14.381873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"721.967658ms","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-24T14:59:14.381951Z","caller":"traceutil/trace.go:171","msg":"trace[1931848204] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:909; }","duration":"722.150632ms","start":"2026-03-24T14:59:13.659779Z","end":"2026-03-24T14:59:14.381929Z","steps":["trace[1931848204] 'range keys from in-memory index tree' (duration: 721.771524ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:59:14.382003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:59:13.659743Z","time spent":"722.249874ms","remote":"127.0.0.1:54020","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":"warn","ts":"2026-03-24T14:59:14.38202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.434458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-03-24T14:59:14.382037Z","caller":"traceutil/trace.go:171","msg":"trace[1187012424] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:909; }","duration":"231.481659ms","start":"2026-03-24T14:59:14.15055Z","end":"2026-03-24T14:59:14.382031Z","steps":["trace[1187012424] 'range keys from in-memory index tree' (duration: 231.275705ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:59:14.382202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"818.031883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.127\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-24T14:59:14.382279Z","caller":"traceutil/trace.go:171","msg":"trace[1973301533] range","detail":"{range_begin:/registry/masterleases/199.19.213.127; range_end:; response_count:1; response_revision:909; }","duration":"818.153186ms","start":"2026-03-24T14:59:13.564105Z","end":"2026-03-24T14:59:14.382258Z","steps":["trace[1973301533] 'range keys from in-memory index tree' (duration: 817.857159ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:59:14.382321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:59:13.564085Z","time spent":"818.226377ms","remote":"127.0.0.1:53782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.19.213.127\" "} {"level":"warn","ts":"2026-03-24T14:59:14.382378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"811.897095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-24T14:59:14.382415Z","caller":"traceutil/trace.go:171","msg":"trace[1767129331] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:909; }","duration":"811.972737ms","start":"2026-03-24T14:59:13.57043Z","end":"2026-03-24T14:59:14.382403Z","steps":["trace[1767129331] 'range keys from in-memory index tree' (duration: 811.839334ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:59:14.382442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:59:13.570418Z","time spent":"812.018778ms","remote":"127.0.0.1:53770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-24T14:59:14.808661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:59:14.38393Z","time spent":"424.727086ms","remote":"127.0.0.1:53782","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-03-24T14:59:14.834474Z","caller":"traceutil/trace.go:171","msg":"trace[64745066] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"447.950059ms","start":"2026-03-24T14:59:14.386485Z","end":"2026-03-24T14:59:14.834435Z","steps":["trace[64745066] 'process raft request' (duration: 447.709234ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:59:14.834744Z","caller":"traceutil/trace.go:171","msg":"trace[1806606565] linearizableReadLoop","detail":"{readStateIndex:955; appliedIndex:953; }","duration":"263.092022ms","start":"2026-03-24T14:59:14.571611Z","end":"2026-03-24T14:59:14.834703Z","steps":["trace[1806606565] 'read index received' (duration: 237.106186ms)","trace[1806606565] 'applied index is now lower than readState.Index' (duration: 25.982916ms)"],"step_count":2} {"level":"info","ts":"2026-03-24T14:59:14.834845Z","caller":"traceutil/trace.go:171","msg":"trace[744786914] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"447.293304ms","start":"2026-03-24T14:59:14.387518Z","end":"2026-03-24T14:59:14.834811Z","steps":["trace[744786914] 'process raft request' (duration: 446.867995ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:59:14.835025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:59:14.387508Z","time spent":"447.414357ms","remote":"127.0.0.1:54020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-24T14:59:14.835016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.339087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-24T14:59:14.835131Z","caller":"traceutil/trace.go:171","msg":"trace[373765605] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:911; }","duration":"263.546463ms","start":"2026-03-24T14:59:14.571564Z","end":"2026-03-24T14:59:14.83511Z","steps":["trace[373765605] 'agreement among raft nodes before linearized reading' (duration: 263.329397ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:59:14.835407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:59:14.386467Z","time spent":"448.122723ms","remote":"127.0.0.1:54020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-24T14:59:14.952673Z","caller":"traceutil/trace.go:171","msg":"trace[1329190619] linearizableReadLoop","detail":"{readStateIndex:956; appliedIndex:955; }","duration":"117.799017ms","start":"2026-03-24T14:59:14.834848Z","end":"2026-03-24T14:59:14.952647Z","steps":["trace[1329190619] 'read index received' (duration: 81.009307ms)","trace[1329190619] 'applied index is now lower than readState.Index' (duration: 36.78739ms)"],"step_count":2} {"level":"info","ts":"2026-03-24T14:59:14.952731Z","caller":"traceutil/trace.go:171","msg":"trace[199586986] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"143.026516ms","start":"2026-03-24T14:59:14.809652Z","end":"2026-03-24T14:59:14.952679Z","steps":["trace[199586986] 'process raft request' (duration: 106.198184ms)","trace[199586986] 'compare' (duration: 36.647707ms)"],"step_count":2} {"level":"warn","ts":"2026-03-24T14:59:14.952997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.79782ms","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-24T14:59:14.953042Z","caller":"traceutil/trace.go:171","msg":"trace[1713227682] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:912; }","duration":"252.861042ms","start":"2026-03-24T14:59:14.700169Z","end":"2026-03-24T14:59:14.95303Z","steps":["trace[1713227682] 'agreement among raft nodes before linearized reading' (duration: 252.585685ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:59:14.953372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.081799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-03-24T14:59:14.953525Z","caller":"traceutil/trace.go:171","msg":"trace[976442942] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:912; }","duration":"152.251893ms","start":"2026-03-24T14:59:14.801251Z","end":"2026-03-24T14:59:14.953503Z","steps":["trace[976442942] 'agreement among raft nodes before linearized reading' (duration: 151.933256ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:59:14.95409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.682893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-03-24T14:59:14.954193Z","caller":"traceutil/trace.go:171","msg":"trace[1466857837] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:912; }","duration":"152.837837ms","start":"2026-03-24T14:59:14.801329Z","end":"2026-03-24T14:59:14.954167Z","steps":["trace[1466857837] 'agreement among raft nodes before linearized reading' (duration: 152.54472ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:59:15.105345Z","caller":"traceutil/trace.go:171","msg":"trace[60280266] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"145.974201ms","start":"2026-03-24T14:59:14.959349Z","end":"2026-03-24T14:59:15.105323Z","steps":["trace[60280266] 'process raft request' (duration: 93.316983ms)","trace[60280266] 'compare' (duration: 52.136886ms)"],"step_count":2} {"level":"info","ts":"2026-03-24T14:59:15.105525Z","caller":"traceutil/trace.go:171","msg":"trace[465837028] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"146.057053ms","start":"2026-03-24T14:59:14.959459Z","end":"2026-03-24T14:59:15.105516Z","steps":["trace[465837028] 'process raft request' (duration: 145.494291ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:59:16.180193Z","caller":"traceutil/trace.go:171","msg":"trace[287996202] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"223.720134ms","start":"2026-03-24T14:59:15.956446Z","end":"2026-03-24T14:59:16.180166Z","steps":["trace[287996202] 'process raft request' (duration: 222.407134ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:59:17.102591Z","caller":"traceutil/trace.go:171","msg":"trace[51004038] linearizableReadLoop","detail":"{readStateIndex:962; appliedIndex:961; }","duration":"234.412166ms","start":"2026-03-24T14:59:16.868146Z","end":"2026-03-24T14:59:17.102558Z","steps":["trace[51004038] 'read index received' (duration: 234.15076ms)","trace[51004038] 'applied index is now lower than readState.Index' (duration: 260.146µs)"],"step_count":2} {"level":"info","ts":"2026-03-24T14:59:17.102658Z","caller":"traceutil/trace.go:171","msg":"trace[1862788789] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"260.333259ms","start":"2026-03-24T14:59:16.842284Z","end":"2026-03-24T14:59:17.102618Z","steps":["trace[1862788789] 'process raft request' (duration: 260.084894ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:59:17.10297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.619097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-24T14:59:17.103036Z","caller":"traceutil/trace.go:171","msg":"trace[1345068005] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:918; }","duration":"123.75277ms","start":"2026-03-24T14:59:16.979266Z","end":"2026-03-24T14:59:17.103019Z","steps":["trace[1345068005] 'agreement among raft nodes before linearized reading' (duration: 123.485134ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:59:17.102955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.777484ms","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-24T14:59:17.103193Z","caller":"traceutil/trace.go:171","msg":"trace[2083375043] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:918; }","duration":"235.062989ms","start":"2026-03-24T14:59:16.868111Z","end":"2026-03-24T14:59:17.103174Z","steps":["trace[2083375043] 'agreement among raft nodes before linearized reading' (duration: 234.596809ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:59:17.355113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.259925ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-24T14:59:17.355295Z","caller":"traceutil/trace.go:171","msg":"trace[1965682966] linearizableReadLoop","detail":"{readStateIndex:963; appliedIndex:962; }","duration":"242.515597ms","start":"2026-03-24T14:59:17.112741Z","end":"2026-03-24T14:59:17.355257Z","steps":["trace[1965682966] 'read index received' (duration: 132.188271ms)","trace[1965682966] 'applied index is now lower than readState.Index' (duration: 110.324686ms)"],"step_count":2} {"level":"info","ts":"2026-03-24T14:59:17.355413Z","caller":"traceutil/trace.go:171","msg":"trace[228934063] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"242.844695ms","start":"2026-03-24T14:59:17.112552Z","end":"2026-03-24T14:59:17.355396Z","steps":["trace[228934063] 'process raft request' (duration: 132.16623ms)","trace[228934063] 'compare' (duration: 110.052141ms)"],"step_count":2} {"level":"warn","ts":"2026-03-24T14:59:17.355589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.847914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-24T14:59:17.355652Z","caller":"traceutil/trace.go:171","msg":"trace[802125088] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:919; }","duration":"242.931756ms","start":"2026-03-24T14:59:17.112705Z","end":"2026-03-24T14:59:17.355637Z","steps":["trace[802125088] 'agreement among raft nodes before linearized reading' (duration: 242.68587ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:59:17.544616Z","caller":"traceutil/trace.go:171","msg":"trace[1424884339] linearizableReadLoop","detail":"{readStateIndex:965; appliedIndex:963; }","duration":"189.255868ms","start":"2026-03-24T14:59:17.355333Z","end":"2026-03-24T14:59:17.544589Z","steps":["trace[1424884339] 'read index received' (duration: 102.449841ms)","trace[1424884339] 'applied index is now lower than readState.Index' (duration: 86.804657ms)"],"step_count":2} {"level":"warn","ts":"2026-03-24T14:59:17.545684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.162661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-24T14:59:17.545797Z","caller":"traceutil/trace.go:171","msg":"trace[392103738] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:921; }","duration":"321.335945ms","start":"2026-03-24T14:59:17.224447Z","end":"2026-03-24T14:59:17.545783Z","steps":["trace[392103738] 'agreement among raft nodes before linearized reading' (duration: 320.343283ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:59:17.545966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:59:17.224415Z","time spent":"321.465908ms","remote":"127.0.0.1:53900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":29,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"info","ts":"2026-03-24T14:59:17.546287Z","caller":"traceutil/trace.go:171","msg":"trace[1706988301] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"430.644419ms","start":"2026-03-24T14:59:17.115628Z","end":"2026-03-24T14:59:17.546272Z","steps":["trace[1706988301] 'process raft request' (duration: 428.912541ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:59:17.546475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:59:17.115617Z","time spent":"430.752661ms","remote":"127.0.0.1:54020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-24T14:59:17.54626Z","caller":"traceutil/trace.go:171","msg":"trace[1789983502] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"433.3579ms","start":"2026-03-24T14:59:17.11286Z","end":"2026-03-24T14:59:17.546218Z","steps":["trace[1789983502] 'process raft request' (duration: 344.906596ms)","trace[1789983502] 'compare' (duration: 86.641524ms)"],"step_count":2} {"level":"warn","ts":"2026-03-24T14:59:17.546725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:59:17.112836Z","time spent":"433.862061ms","remote":"127.0.0.1:54020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-24T14:59:17.565215Z","caller":"traceutil/trace.go:171","msg":"trace[1512991548] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"205.324779ms","start":"2026-03-24T14:59:17.359876Z","end":"2026-03-24T14:59:17.565201Z","steps":["trace[1512991548] 'process raft request' (duration: 205.215087ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:59:17.78339Z","caller":"traceutil/trace.go:171","msg":"trace[777540334] linearizableReadLoop","detail":"{readStateIndex:967; appliedIndex:966; }","duration":"198.355883ms","start":"2026-03-24T14:59:17.585008Z","end":"2026-03-24T14:59:17.783364Z","steps":["trace[777540334] 'read index received' (duration: 115.160207ms)","trace[777540334] 'applied index is now lower than readState.Index' (duration: 83.194606ms)"],"step_count":2} {"level":"info","ts":"2026-03-24T14:59:17.783495Z","caller":"traceutil/trace.go:171","msg":"trace[22534649] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"318.203945ms","start":"2026-03-24T14:59:17.465219Z","end":"2026-03-24T14:59:17.783423Z","steps":["trace[22534649] 'process raft request' (duration: 234.925327ms)","trace[22534649] 'compare' (duration: 83.058143ms)"],"step_count":2} {"level":"warn","ts":"2026-03-24T14:59:17.783615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.588838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5184"} {"level":"info","ts":"2026-03-24T14:59:17.783653Z","caller":"traceutil/trace.go:171","msg":"trace[1512604383] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:923; }","duration":"198.68427ms","start":"2026-03-24T14:59:17.584957Z","end":"2026-03-24T14:59:17.783641Z","steps":["trace[1512604383] 'agreement among raft nodes before linearized reading' (duration: 198.500226ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:59:17.783682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:59:17.465198Z","time spent":"318.389619ms","remote":"127.0.0.1:53830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":750,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-24T14:59:18.36738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.997187ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-24T14:59:18.367541Z","caller":"traceutil/trace.go:171","msg":"trace[1289321306] linearizableReadLoop","detail":"{readStateIndex:968; appliedIndex:967; }","duration":"527.603585ms","start":"2026-03-24T14:59:17.839911Z","end":"2026-03-24T14:59:18.367515Z","steps":["trace[1289321306] 'read index received' (duration: 257.378523ms)","trace[1289321306] 'applied index is now lower than readState.Index' (duration: 270.222782ms)"],"step_count":2} {"level":"warn","ts":"2026-03-24T14:59:18.367693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.76722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-24T14:59:18.367747Z","caller":"traceutil/trace.go:171","msg":"trace[1068833814] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:924; }","duration":"527.874852ms","start":"2026-03-24T14:59:17.839858Z","end":"2026-03-24T14:59:18.367732Z","steps":["trace[1068833814] 'agreement among raft nodes before linearized reading' (duration: 527.736219ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:59:18.367843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:59:17.839836Z","time spent":"527.951943ms","remote":"127.0.0.1:50768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":29,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" "} {"level":"info","ts":"2026-03-24T14:59:18.367867Z","caller":"traceutil/trace.go:171","msg":"trace[930005161] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"579.127267ms","start":"2026-03-24T14:59:17.788688Z","end":"2026-03-24T14:59:18.367816Z","steps":["trace[930005161] 'process raft request' (duration: 308.582477ms)","trace[930005161] 'compare' (duration: 269.522166ms)"],"step_count":2} {"level":"warn","ts":"2026-03-24T14:59:18.368043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:59:17.788646Z","time spent":"579.307471ms","remote":"127.0.0.1:53830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":750,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-24T14:59:18.410181Z","caller":"traceutil/trace.go:171","msg":"trace[194217232] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"391.375234ms","start":"2026-03-24T14:59:18.018774Z","end":"2026-03-24T14:59:18.410149Z","steps":["trace[194217232] 'process raft request' (duration: 390.576275ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:59:18.41039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:59:18.018742Z","time spent":"391.540718ms","remote":"127.0.0.1:54020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-24T14:59:18.731774Z","caller":"traceutil/trace.go:171","msg":"trace[2103792510] linearizableReadLoop","detail":"{readStateIndex:970; appliedIndex:969; }","duration":"162.107295ms","start":"2026-03-24T14:59:18.569637Z","end":"2026-03-24T14:59:18.731744Z","steps":["trace[2103792510] 'read index received' (duration: 162.008052ms)","trace[2103792510] 'applied index is now lower than readState.Index' (duration: 97.583µs)"],"step_count":2} {"level":"info","ts":"2026-03-24T14:59:18.731828Z","caller":"traceutil/trace.go:171","msg":"trace[1025611751] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"359.404874ms","start":"2026-03-24T14:59:18.372386Z","end":"2026-03-24T14:59:18.731791Z","steps":["trace[1025611751] 'process raft request' (duration: 359.192708ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:59:18.731954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.297549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-24T14:59:18.731998Z","caller":"traceutil/trace.go:171","msg":"trace[1707101087] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:926; }","duration":"162.402291ms","start":"2026-03-24T14:59:18.569585Z","end":"2026-03-24T14:59:18.731987Z","steps":["trace[1707101087] 'agreement among raft nodes before linearized reading' (duration: 162.267038ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:59:18.732021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:59:18.372355Z","time spent":"359.581897ms","remote":"127.0.0.1:53830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":821,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-24T14:59:19.050368Z","caller":"traceutil/trace.go:171","msg":"trace[1708867704] linearizableReadLoop","detail":"{readStateIndex:971; appliedIndex:970; }","duration":"315.713537ms","start":"2026-03-24T14:59:18.734627Z","end":"2026-03-24T14:59:19.05034Z","steps":["trace[1708867704] 'read index received' (duration: 285.746202ms)","trace[1708867704] 'applied index is now lower than readState.Index' (duration: 29.965525ms)"],"step_count":2} {"level":"warn","ts":"2026-03-24T14:59:19.050683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.040925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-24T14:59:19.050766Z","caller":"traceutil/trace.go:171","msg":"trace[802436626] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:926; }","duration":"316.224588ms","start":"2026-03-24T14:59:18.734523Z","end":"2026-03-24T14:59:19.050748Z","steps":["trace[802436626] 'agreement among raft nodes before linearized reading' (duration: 315.939012ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:59:19.050889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:59:18.734455Z","time spent":"316.425314ms","remote":"127.0.0.1:53770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-24T14:59:19.058905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.50535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-24T14:59:19.05896Z","caller":"traceutil/trace.go:171","msg":"trace[345192084] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:927; }","duration":"259.615232ms","start":"2026-03-24T14:59:18.799332Z","end":"2026-03-24T14:59:19.058947Z","steps":["trace[345192084] 'agreement among raft nodes before linearized reading' (duration: 259.49416ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:59:19.377406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.675622ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-24T14:59:19.377699Z","caller":"traceutil/trace.go:171","msg":"trace[1745823240] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"265.502786ms","start":"2026-03-24T14:59:19.112154Z","end":"2026-03-24T14:59:19.377657Z","steps":["trace[1745823240] 'process raft request' (duration: 120.490426ms)","trace[1745823240] 'compare' (duration: 144.514599ms)"],"step_count":2} {"level":"warn","ts":"2026-03-24T14:59:26.977496Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13905881036702677199,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-24T14:59:27.225946Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.019363416s","expected-duration":"1s"} {"level":"info","ts":"2026-03-24T14:59:27.231972Z","caller":"traceutil/trace.go:171","msg":"trace[2071864302] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"1.025547956s","start":"2026-03-24T14:59:26.20639Z","end":"2026-03-24T14:59:27.231938Z","steps":["trace[2071864302] 'process raft request' (duration: 1.019892388s)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:59:27.232173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:59:26.206361Z","time spent":"1.02570661s","remote":"127.0.0.1:53942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13709,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-24T14:59:27.507286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.162389ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-24T14:59:27.507591Z","caller":"traceutil/trace.go:171","msg":"trace[1822117709] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"1.060813548s","start":"2026-03-24T14:59:26.446639Z","end":"2026-03-24T14:59:27.507452Z","steps":["trace[1822117709] 'process raft request' (duration: 863.352212ms)","trace[1822117709] 'compare' (duration: 197.032886ms)"],"step_count":2} {"level":"info","ts":"2026-03-24T14:59:27.507747Z","caller":"traceutil/trace.go:171","msg":"trace[438528397] linearizableReadLoop","detail":"{readStateIndex:1047; appliedIndex:1045; }","duration":"1.031197594s","start":"2026-03-24T14:59:26.476514Z","end":"2026-03-24T14:59:27.507711Z","steps":["trace[438528397] 'read index received' (duration: 749.668667ms)","trace[438528397] 'applied index is now lower than readState.Index' (duration: 281.527087ms)"],"step_count":2} {"level":"info","ts":"2026-03-24T14:59:27.507969Z","caller":"traceutil/trace.go:171","msg":"trace[1787044493] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"266.475791ms","start":"2026-03-24T14:59:27.241471Z","end":"2026-03-24T14:59:27.507947Z","steps":["trace[1787044493] 'process raft request' (duration: 265.942608ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:59:27.50808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.031544953s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-03-24T14:59:27.50814Z","caller":"traceutil/trace.go:171","msg":"trace[1620356407] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1002; }","duration":"1.031652815s","start":"2026-03-24T14:59:26.476472Z","end":"2026-03-24T14:59:27.508125Z","steps":["trace[1620356407] 'agreement among raft nodes before linearized reading' (duration: 1.03145356s)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:59:27.508199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:59:26.47646Z","time spent":"1.031728247s","remote":"127.0.0.1:54020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":498,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-03-24T14:59:27.508311Z","caller":"traceutil/trace.go:171","msg":"trace[110748649] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"266.595023ms","start":"2026-03-24T14:59:27.241705Z","end":"2026-03-24T14:59:27.5083Z","steps":["trace[110748649] 'process raft request' (duration: 266.03833ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:59:27.508345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"935.983172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-24T14:59:27.508401Z","caller":"traceutil/trace.go:171","msg":"trace[671980978] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1004; }","duration":"936.091255ms","start":"2026-03-24T14:59:26.572288Z","end":"2026-03-24T14:59:27.508379Z","steps":["trace[671980978] 'agreement among raft nodes before linearized reading' (duration: 935.989793ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:59:27.508463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:59:26.572262Z","time spent":"936.189758ms","remote":"127.0.0.1:53756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-24T14:59:27.508578Z","caller":"traceutil/trace.go:171","msg":"trace[944360193] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"257.163263ms","start":"2026-03-24T14:59:27.251398Z","end":"2026-03-24T14:59:27.508561Z","steps":["trace[944360193] 'process raft request' (duration: 256.62997ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:59:27.508903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:59:26.446613Z","time spent":"1.061189796s","remote":"127.0.0.1:54020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-24T14:59:43.870365Z","caller":"traceutil/trace.go:171","msg":"trace[407769135] linearizableReadLoop","detail":"{readStateIndex:1124; appliedIndex:1123; }","duration":"142.131848ms","start":"2026-03-24T14:59:43.72817Z","end":"2026-03-24T14:59:43.870302Z","steps":["trace[407769135] 'read index received' (duration: 84.062411ms)","trace[407769135] 'applied index is now lower than readState.Index' (duration: 58.068077ms)"],"step_count":2} {"level":"warn","ts":"2026-03-24T14:59:43.87072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.536487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-24T14:59:43.870731Z","caller":"traceutil/trace.go:171","msg":"trace[1056700956] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"154.397646ms","start":"2026-03-24T14:59:43.716293Z","end":"2026-03-24T14:59:43.870691Z","steps":["trace[1056700956] 'process raft request' (duration: 95.95059ms)","trace[1056700956] 'compare' (duration: 57.860012ms)"],"step_count":2} {"level":"info","ts":"2026-03-24T14:59:43.870803Z","caller":"traceutil/trace.go:171","msg":"trace[414321007] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1074; }","duration":"142.67289ms","start":"2026-03-24T14:59:43.728115Z","end":"2026-03-24T14:59:43.870788Z","steps":["trace[414321007] 'agreement among raft nodes before linearized reading' (duration: 142.414394ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:59:43.870822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.727078ms","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-24T14:59:43.870872Z","caller":"traceutil/trace.go:171","msg":"trace[1120007533] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1074; }","duration":"105.79013ms","start":"2026-03-24T14:59:43.765067Z","end":"2026-03-24T14:59:43.870857Z","steps":["trace[1120007533] 'agreement among raft nodes before linearized reading' (duration: 105.657597ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:59:43.870892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.572127ms","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-24T14:59:43.870944Z","caller":"traceutil/trace.go:171","msg":"trace[1760541801] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1074; }","duration":"110.651249ms","start":"2026-03-24T14:59:43.760278Z","end":"2026-03-24T14:59:43.87093Z","steps":["trace[1760541801] 'agreement among raft nodes before linearized reading' (duration: 110.449734ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T15:00:06.172751Z","caller":"traceutil/trace.go:171","msg":"trace[2035282036] transaction","detail":"{read_only:false; response_revision:1168; number_of_response:1; }","duration":"105.925193ms","start":"2026-03-24T15:00:06.066795Z","end":"2026-03-24T15:00:06.17272Z","steps":["trace[2035282036] 'process raft request' (duration: 44.31032ms)","trace[2035282036] 'compare' (duration: 61.415338ms)"],"step_count":2} {"level":"info","ts":"2026-03-24T15:00:06.172949Z","caller":"traceutil/trace.go:171","msg":"trace[1462286825] transaction","detail":"{read_only:false; response_revision:1169; number_of_response:1; }","duration":"106.067957ms","start":"2026-03-24T15:00:06.066848Z","end":"2026-03-24T15:00:06.172916Z","steps":["trace[1462286825] 'process raft request' (duration: 105.829901ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T15:02:00.673304Z","caller":"traceutil/trace.go:171","msg":"trace[1947441674] transaction","detail":"{read_only:false; response_revision:1630; number_of_response:1; }","duration":"226.036956ms","start":"2026-03-24T15:02:00.447244Z","end":"2026-03-24T15:02:00.67328Z","steps":["trace[1947441674] 'process raft request' (duration: 225.78246ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T15:02:00.673403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.239802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-24T15:02:00.673291Z","caller":"traceutil/trace.go:171","msg":"trace[324091903] linearizableReadLoop","detail":"{readStateIndex:1707; appliedIndex:1706; }","duration":"103.114269ms","start":"2026-03-24T15:02:00.570134Z","end":"2026-03-24T15:02:00.673249Z","steps":["trace[324091903] 'read index received' (duration: 102.908744ms)","trace[324091903] 'applied index is now lower than readState.Index' (duration: 203.175µs)"],"step_count":2} {"level":"info","ts":"2026-03-24T15:02:00.67347Z","caller":"traceutil/trace.go:171","msg":"trace[1647074709] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1630; }","duration":"103.372935ms","start":"2026-03-24T15:02:00.570078Z","end":"2026-03-24T15:02:00.673451Z","steps":["trace[1647074709] 'agreement among raft nodes before linearized reading' (duration: 103.248012ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T15:02:01.805756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.088088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-24T15:02:01.805831Z","caller":"traceutil/trace.go:171","msg":"trace[267587347] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1636; }","duration":"622.199021ms","start":"2026-03-24T15:02:01.183605Z","end":"2026-03-24T15:02:01.805804Z","steps":["trace[267587347] 'count revisions from in-memory index tree' (duration: 621.962466ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T15:02:01.805855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T15:02:01.183581Z","time spent":"622.268263ms","remote":"127.0.0.1:50918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":123,"response size":31,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-03-24T15:02:02.790634Z","caller":"traceutil/trace.go:171","msg":"trace[1515115835] transaction","detail":"{read_only:false; response_revision:1639; number_of_response:1; }","duration":"107.510914ms","start":"2026-03-24T15:02:02.683082Z","end":"2026-03-24T15:02:02.790593Z","steps":["trace[1515115835] 'process raft request' (duration: 107.303699ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T15:02:02.792701Z","caller":"traceutil/trace.go:171","msg":"trace[886913714] transaction","detail":"{read_only:false; response_revision:1640; number_of_response:1; }","duration":"106.247125ms","start":"2026-03-24T15:02:02.686382Z","end":"2026-03-24T15:02:02.792629Z","steps":["trace[886913714] 'process raft request' (duration: 105.796494ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T15:02:05.17483Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13905881036702678856,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-24T15:02:05.674998Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13905881036702678856,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-24T15:02:06.176106Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13905881036702678856,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-24T15:02:06.495531Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.881984915s","expected-duration":"1s"} {"level":"info","ts":"2026-03-24T15:02:06.496065Z","caller":"traceutil/trace.go:171","msg":"trace[2106989681] linearizableReadLoop","detail":"{readStateIndex:1726; appliedIndex:1725; }","duration":"1.821556996s","start":"2026-03-24T15:02:04.674476Z","end":"2026-03-24T15:02:06.496033Z","steps":["trace[2106989681] 'read index received' (duration: 1.821185807s)","trace[2106989681] 'applied index is now lower than readState.Index' (duration: 369.949µs)"],"step_count":2} {"level":"info","ts":"2026-03-24T15:02:06.496206Z","caller":"traceutil/trace.go:171","msg":"trace[804249661] transaction","detail":"{read_only:false; response_revision:1648; number_of_response:1; }","duration":"1.882766943s","start":"2026-03-24T15:02:04.613428Z","end":"2026-03-24T15:02:06.496195Z","steps":["trace[804249661] 'process raft request' (duration: 1.882361543s)"],"step_count":1} {"level":"warn","ts":"2026-03-24T15:02:06.49634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T15:02:04.613407Z","time spent":"1.882829015s","remote":"127.0.0.1:54020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":519,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-24T15:02:06.496609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.701141428s","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-24T15:02:06.49668Z","caller":"traceutil/trace.go:171","msg":"trace[1949178294] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1648; }","duration":"1.701253921s","start":"2026-03-24T15:02:04.795404Z","end":"2026-03-24T15:02:06.496658Z","steps":["trace[1949178294] 'agreement among raft nodes before linearized reading' (duration: 1.701045806s)"],"step_count":1} {"level":"warn","ts":"2026-03-24T15:02:06.496688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"945.860183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-24T15:02:06.496725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T15:02:04.795382Z","time spent":"1.701333703s","remote":"127.0.0.1:54020","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-24T15:02:06.496726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.957134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-24T15:02:06.49676Z","caller":"traceutil/trace.go:171","msg":"trace[1115754829] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1648; }","duration":"946.017858ms","start":"2026-03-24T15:02:05.550715Z","end":"2026-03-24T15:02:06.496733Z","steps":["trace[1115754829] 'agreement among raft nodes before linearized reading' (duration: 945.849784ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T15:02:06.496792Z","caller":"traceutil/trace.go:171","msg":"trace[463331373] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:1648; }","duration":"291.081148ms","start":"2026-03-24T15:02:06.205701Z","end":"2026-03-24T15:02:06.496782Z","steps":["trace[463331373] 'agreement among raft nodes before linearized reading' (duration: 290.986435ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T15:02:06.496807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T15:02:05.550686Z","time spent":"946.11263ms","remote":"127.0.0.1:46280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-03-24T15:02:06.496936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.649403219s","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-24T15:02:06.496968Z","caller":"traceutil/trace.go:171","msg":"trace[872093857] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1648; }","duration":"1.649454191s","start":"2026-03-24T15:02:04.847505Z","end":"2026-03-24T15:02:06.496959Z","steps":["trace[872093857] 'agreement among raft nodes before linearized reading' (duration: 1.649361979s)"],"step_count":1} {"level":"warn","ts":"2026-03-24T15:02:06.496988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.822506268s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-24T15:02:06.496994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T15:02:04.847491Z","time spent":"1.649497312s","remote":"127.0.0.1:54020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-24T15:02:06.497017Z","caller":"traceutil/trace.go:171","msg":"trace[1835316905] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1648; }","duration":"1.82255996s","start":"2026-03-24T15:02:04.674448Z","end":"2026-03-24T15:02:06.497008Z","steps":["trace[1835316905] 'agreement among raft nodes before linearized reading' (duration: 1.822501069s)"],"step_count":1} {"level":"warn","ts":"2026-03-24T15:02:06.497043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T15:02:04.674437Z","time spent":"1.822599601s","remote":"127.0.0.1:50812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":31,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-03-24T15:02:06.497165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.65070795s","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:532"} {"level":"info","ts":"2026-03-24T15:02:06.497213Z","caller":"traceutil/trace.go:171","msg":"trace[1540398797] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1648; }","duration":"1.650804003s","start":"2026-03-24T15:02:04.8464Z","end":"2026-03-24T15:02:06.497204Z","steps":["trace[1540398797] 'agreement among raft nodes before linearized reading' (duration: 1.65069949s)"],"step_count":1} {"level":"warn","ts":"2026-03-24T15:02:06.497225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.672792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:525"} {"level":"warn","ts":"2026-03-24T15:02:06.497247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T15:02:04.846377Z","time spent":"1.650862324s","remote":"127.0.0.1:54020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-03-24T15:02:06.49728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"925.283367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-24T15:02:06.497297Z","caller":"traceutil/trace.go:171","msg":"trace[1434512937] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1648; }","duration":"925.348589ms","start":"2026-03-24T15:02:05.571944Z","end":"2026-03-24T15:02:06.497293Z","steps":["trace[1434512937] 'agreement among raft nodes before linearized reading' (duration: 925.320888ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T15:02:06.497313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T15:02:05.57193Z","time spent":"925.379969ms","remote":"127.0.0.1:53756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-24T15:02:06.497251Z","caller":"traceutil/trace.go:171","msg":"trace[1632265361] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1648; }","duration":"458.742954ms","start":"2026-03-24T15:02:06.0385Z","end":"2026-03-24T15:02:06.497243Z","steps":["trace[1632265361] 'agreement among raft nodes before linearized reading' (duration: 458.61178ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T15:02:06.497506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.695152854s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-03-24T15:02:06.497511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T15:02:06.038482Z","time spent":"459.012501ms","remote":"127.0.0.1:54020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":549,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-03-24T15:02:06.49755Z","caller":"traceutil/trace.go:171","msg":"trace[591822703] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1648; }","duration":"1.695218816s","start":"2026-03-24T15:02:04.802321Z","end":"2026-03-24T15:02:06.49754Z","steps":["trace[591822703] 'agreement among raft nodes before linearized reading' (duration: 1.695078892s)"],"step_count":1} {"level":"warn","ts":"2026-03-24T15:02:06.497579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T15:02:04.802311Z","time spent":"1.695261706s","remote":"127.0.0.1:54020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-24T15:02:06.497704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.696486296s","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-24T15:02:06.497731Z","caller":"traceutil/trace.go:171","msg":"trace[1362446187] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1648; }","duration":"1.696533577s","start":"2026-03-24T15:02:04.801189Z","end":"2026-03-24T15:02:06.497723Z","steps":["trace[1362446187] 'agreement among raft nodes before linearized reading' (duration: 1.696456725s)"],"step_count":1} {"level":"warn","ts":"2026-03-24T15:02:06.497748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.700857812s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-03-24T15:02:06.497758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T15:02:04.801178Z","time spent":"1.696574279s","remote":"127.0.0.1:54020","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-24T15:02:06.497793Z","caller":"traceutil/trace.go:171","msg":"trace[1668874622] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1648; }","duration":"1.700931794s","start":"2026-03-24T15:02:04.796847Z","end":"2026-03-24T15:02:06.497779Z","steps":["trace[1668874622] 'agreement among raft nodes before linearized reading' (duration: 1.700812221s)"],"step_count":1} {"level":"warn","ts":"2026-03-24T15:02:06.497832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T15:02:04.796832Z","time spent":"1.700989545s","remote":"127.0.0.1:54020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-24T15:02:08.851854Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13905881036702678872,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-24T15:02:09.352575Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13905881036702678872,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-24T15:02:09.853157Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13905881036702678872,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-24T15:02:11.899844Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13905881036702678872,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-24T15:02:11.912726Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.58444102s","expected-duration":"1s"} {"level":"info","ts":"2026-03-24T15:02:11.913063Z","caller":"traceutil/trace.go:171","msg":"trace[2087858014] transaction","detail":"{read_only:false; response_revision:1656; number_of_response:1; }","duration":"3.584922191s","start":"2026-03-24T15:02:08.328125Z","end":"2026-03-24T15:02:11.913047Z","steps":["trace[2087858014] 'process raft request' (duration: 3.584728086s)"],"step_count":1} {"level":"warn","ts":"2026-03-24T15:02:11.913179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T15:02:08.328101Z","time spent":"3.584996763s","remote":"127.0.0.1:54020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-24T15:02:11.916866Z","caller":"traceutil/trace.go:171","msg":"trace[1494516171] linearizableReadLoop","detail":"{readStateIndex:1735; appliedIndex:1734; }","duration":"3.566051875s","start":"2026-03-24T15:02:08.350794Z","end":"2026-03-24T15:02:11.916846Z","steps":["trace[1494516171] 'read index received' (duration: 3.562462479s)","trace[1494516171] 'applied index is now lower than readState.Index' (duration: 3.586516ms)"],"step_count":2} {"level":"warn","ts":"2026-03-24T15:02:11.917232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.566433195s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-03-24T15:02:11.917258Z","caller":"traceutil/trace.go:171","msg":"trace[1069696569] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1657; }","duration":"3.566530257s","start":"2026-03-24T15:02:08.35072Z","end":"2026-03-24T15:02:11.91725Z","steps":["trace[1069696569] 'agreement among raft nodes before linearized reading' (duration: 3.566381474s)"],"step_count":1} {"level":"warn","ts":"2026-03-24T15:02:11.917284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T15:02:08.350691Z","time spent":"3.566585419s","remote":"127.0.0.1:54020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-03-24T15:02:11.917479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.409406143s","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-24T15:02:11.917501Z","caller":"traceutil/trace.go:171","msg":"trace[1020122871] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1657; }","duration":"3.409480766s","start":"2026-03-24T15:02:08.508013Z","end":"2026-03-24T15:02:11.917494Z","steps":["trace[1020122871] 'agreement among raft nodes before linearized reading' (duration: 3.409364033s)"],"step_count":1} {"level":"warn","ts":"2026-03-24T15:02:11.91752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T15:02:08.507981Z","time spent":"3.409535146s","remote":"127.0.0.1:54020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-24T15:02:11.918027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.405268114s","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-24T15:02:11.918049Z","caller":"traceutil/trace.go:171","msg":"trace[1880016110] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1657; }","duration":"3.405326115s","start":"2026-03-24T15:02:08.512716Z","end":"2026-03-24T15:02:11.918042Z","steps":["trace[1880016110] 'agreement among raft nodes before linearized reading' (duration: 3.405272014s)"],"step_count":1} {"level":"warn","ts":"2026-03-24T15:02:11.918066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T15:02:08.512698Z","time spent":"3.405364275s","remote":"127.0.0.1:54020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-24T15:02:11.918177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.405988351s","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:532"} {"level":"info","ts":"2026-03-24T15:02:11.918198Z","caller":"traceutil/trace.go:171","msg":"trace[1515996574] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1657; }","duration":"3.406020682s","start":"2026-03-24T15:02:08.512171Z","end":"2026-03-24T15:02:11.918191Z","steps":["trace[1515996574] 'agreement among raft nodes before linearized reading' (duration: 3.40596173s)"],"step_count":1} {"level":"warn","ts":"2026-03-24T15:02:11.918217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T15:02:08.512165Z","time spent":"3.406047272s","remote":"127.0.0.1:54020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-03-24T15:02:11.918331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.40631722s","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-24T15:02:11.918351Z","caller":"traceutil/trace.go:171","msg":"trace[2061624408] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1657; }","duration":"3.40634991s","start":"2026-03-24T15:02:08.511995Z","end":"2026-03-24T15:02:11.918345Z","steps":["trace[2061624408] 'agreement among raft nodes before linearized reading' (duration: 3.406295109s)"],"step_count":1} {"level":"warn","ts":"2026-03-24T15:02:11.918367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T15:02:08.511988Z","time spent":"3.406375221s","remote":"127.0.0.1:54020","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-24T15:02:11.918473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.406904834s","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-24T15:02:11.918493Z","caller":"traceutil/trace.go:171","msg":"trace[1129515101] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1657; }","duration":"3.406923625s","start":"2026-03-24T15:02:08.511562Z","end":"2026-03-24T15:02:11.918486Z","steps":["trace[1129515101] 'agreement among raft nodes before linearized reading' (duration: 3.406869793s)"],"step_count":1} {"level":"warn","ts":"2026-03-24T15:02:11.918513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T15:02:08.511519Z","time spent":"3.406988316s","remote":"127.0.0.1:54020","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-24T15:02:11.918625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.407438937s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-03-24T15:02:11.918643Z","caller":"traceutil/trace.go:171","msg":"trace[1542545748] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1657; }","duration":"3.407505639s","start":"2026-03-24T15:02:08.511131Z","end":"2026-03-24T15:02:11.918637Z","steps":["trace[1542545748] 'agreement among raft nodes before linearized reading' (duration: 3.407455768s)"],"step_count":1} {"level":"warn","ts":"2026-03-24T15:02:11.918663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T15:02:08.511091Z","time spent":"3.407566931s","remote":"127.0.0.1:54020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}