{"level":"warn","ts":"2026-02-24T10:50:17.308888Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-24T10:50:17.309026Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.25: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.204.45.25:2380","--initial-cluster=instance=https://199.204.45.25:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.25:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.25: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-02-24T10:50:17.309142Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-24T10:50:17.30916Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.25:2380"]} {"level":"info","ts":"2026-02-24T10:50:17.309193Z","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-02-24T10:50:17.310411Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.25:2379"]} {"level":"info","ts":"2026-02-24T10:50:17.310552Z","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.204.45.25:2380"],"listen-peer-urls":["https://199.204.45.25:2380"],"advertise-client-urls":["https://199.204.45.25:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.25:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.25: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-02-24T10:50:17.316633Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.741359ms"} {"level":"info","ts":"2026-02-24T10:50:17.324092Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ba52c55cf4193752","cluster-id":"4468ed52294bc271"} {"level":"info","ts":"2026-02-24T10:50:17.324221Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 switched to configuration voters=()"} {"level":"info","ts":"2026-02-24T10:50:17.324242Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became follower at term 0"} {"level":"info","ts":"2026-02-24T10:50:17.324252Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ba52c55cf4193752 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-24T10:50:17.324261Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became follower at term 1"} {"level":"info","ts":"2026-02-24T10:50:17.3243Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 switched to configuration voters=(13426010442167826258)"} {"level":"warn","ts":"2026-02-24T10:50:17.333326Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-24T10:50:17.338065Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-24T10:50:17.33959Z","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-02-24T10:50:17.342025Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ba52c55cf4193752","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-24T10:50:17.342162Z","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-02-24T10:50:17.342237Z","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-02-24T10:50:17.342248Z","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-02-24T10:50:17.34244Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ba52c55cf4193752","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-24T10:50:17.343239Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 switched to configuration voters=(13426010442167826258)"} {"level":"info","ts":"2026-02-24T10:50:17.343654Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4468ed52294bc271","local-member-id":"ba52c55cf4193752","added-peer-id":"ba52c55cf4193752","added-peer-peer-urls":["https://199.204.45.25:2380"]} {"level":"info","ts":"2026-02-24T10:50:17.345961Z","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-02-24T10:50:17.346087Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.25:2380"} {"level":"info","ts":"2026-02-24T10:50:17.346153Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.25:2380"} {"level":"info","ts":"2026-02-24T10:50:17.346659Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ba52c55cf4193752","initial-advertise-peer-urls":["https://199.204.45.25:2380"],"listen-peer-urls":["https://199.204.45.25:2380"],"advertise-client-urls":["https://199.204.45.25:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.25:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-24T10:50:17.346709Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-24T10:50:17.425005Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 is starting a new election at term 1"} {"level":"info","ts":"2026-02-24T10:50:17.425085Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-24T10:50:17.425131Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 received MsgPreVoteResp from ba52c55cf4193752 at term 1"} {"level":"info","ts":"2026-02-24T10:50:17.425153Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became candidate at term 2"} {"level":"info","ts":"2026-02-24T10:50:17.425169Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 received MsgVoteResp from ba52c55cf4193752 at term 2"} {"level":"info","ts":"2026-02-24T10:50:17.425201Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became leader at term 2"} {"level":"info","ts":"2026-02-24T10:50:17.425216Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ba52c55cf4193752 elected leader ba52c55cf4193752 at term 2"} {"level":"info","ts":"2026-02-24T10:50:17.426164Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T10:50:17.427014Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ba52c55cf4193752","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.25:2379]}","request-path":"/0/members/ba52c55cf4193752/attributes","cluster-id":"4468ed52294bc271","publish-timeout":"7s"} {"level":"info","ts":"2026-02-24T10:50:17.427578Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-24T10:50:17.427583Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-24T10:50:17.427889Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4468ed52294bc271","local-member-id":"ba52c55cf4193752","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T10:50:17.427963Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-24T10:50:17.428049Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T10:50:17.42811Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T10:50:17.427303Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-24T10:50:17.435508Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.25:2379"} {"level":"info","ts":"2026-02-24T10:50:17.436106Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-24T10:50:41.42836Z","caller":"traceutil/trace.go:171","msg":"trace[1872576255] linearizableReadLoop","detail":"{readStateIndex:431; appliedIndex:430; }","duration":"434.95244ms","start":"2026-02-24T10:50:40.993385Z","end":"2026-02-24T10:50:41.428337Z","steps":["trace[1872576255] 'read index received' (duration: 434.736575ms)","trace[1872576255] 'applied index is now lower than readState.Index' (duration: 214.905µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:50:41.428863Z","caller":"traceutil/trace.go:171","msg":"trace[182996737] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"583.554717ms","start":"2026-02-24T10:50:40.845266Z","end":"2026-02-24T10:50:41.42882Z","steps":["trace[182996737] 'process raft request' (duration: 582.92017ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:50:41.428835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.277535ms","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-02-24T10:50:41.428932Z","caller":"traceutil/trace.go:171","msg":"trace[1965884799] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:418; }","duration":"340.453089ms","start":"2026-02-24T10:50:41.088462Z","end":"2026-02-24T10:50:41.428915Z","steps":["trace[1965884799] 'agreement among raft nodes before linearized reading' (duration: 340.250624ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:50:41.428948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.543546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.25\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-02-24T10:50:41.42898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:50:41.088435Z","time spent":"340.537671ms","remote":"127.0.0.1:47046","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-02-24T10:50:41.429068Z","caller":"traceutil/trace.go:171","msg":"trace[2005504887] range","detail":"{range_begin:/registry/masterleases/199.204.45.25; range_end:; response_count:1; response_revision:418; }","duration":"435.71783ms","start":"2026-02-24T10:50:40.993332Z","end":"2026-02-24T10:50:41.42905Z","steps":["trace[2005504887] 'agreement among raft nodes before linearized reading' (duration: 435.504025ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:50:41.429114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:50:40.993314Z","time spent":"435.793011ms","remote":"127.0.0.1:46804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.25\" "} {"level":"warn","ts":"2026-02-24T10:50:41.42965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:50:40.845238Z","time spent":"583.67357ms","remote":"127.0.0.1:47046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:50:41.683922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.146252ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-02-24T10:50:41.684091Z","caller":"traceutil/trace.go:171","msg":"trace[1935464790] linearizableReadLoop","detail":"{readStateIndex:433; appliedIndex:431; }","duration":"126.784495ms","start":"2026-02-24T10:50:41.557297Z","end":"2026-02-24T10:50:41.684082Z","steps":["trace[1935464790] 'read index received' (duration: 115.995803ms)","trace[1935464790] 'applied index is now lower than readState.Index' (duration: 10.787772ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:50:41.684182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.881127ms","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-02-24T10:50:41.684201Z","caller":"traceutil/trace.go:171","msg":"trace[539184291] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:419; }","duration":"126.978551ms","start":"2026-02-24T10:50:41.557218Z","end":"2026-02-24T10:50:41.684196Z","steps":["trace[539184291] 'agreement among raft nodes before linearized reading' (duration: 126.909189ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:50:41.68462Z","caller":"traceutil/trace.go:171","msg":"trace[266393761] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"251.974892ms","start":"2026-02-24T10:50:41.43261Z","end":"2026-02-24T10:50:41.684585Z","steps":["trace[266393761] 'process raft request' (duration: 251.384138ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:50:46.826011Z","caller":"traceutil/trace.go:171","msg":"trace[770771847] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"258.989272ms","start":"2026-02-24T10:50:46.566891Z","end":"2026-02-24T10:50:46.82588Z","steps":["trace[770771847] 'process raft request' (duration: 255.16547ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:50:49.926303Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986420759335235608,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T10:50:50.427198Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986420759335235608,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T10:50:50.761622Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.662061979s","expected-duration":"1s"} {"level":"info","ts":"2026-02-24T10:50:50.762133Z","caller":"traceutil/trace.go:171","msg":"trace[2056186864] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"1.662661744s","start":"2026-02-24T10:50:49.099442Z","end":"2026-02-24T10:50:50.762104Z","steps":["trace[2056186864] 'process raft request' (duration: 1.662463719s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:50:50.7623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:50:49.09942Z","time spent":"1.662796706s","remote":"127.0.0.1:46954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4212,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T10:50:50.768475Z","caller":"traceutil/trace.go:171","msg":"trace[193784943] linearizableReadLoop","detail":"{readStateIndex:486; appliedIndex:484; }","duration":"1.34261522s","start":"2026-02-24T10:50:49.425832Z","end":"2026-02-24T10:50:50.768447Z","steps":["trace[193784943] 'read index received' (duration: 1.336104212s)","trace[193784943] 'applied index is now lower than readState.Index' (duration: 6.510078ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:50:50.768597Z","caller":"traceutil/trace.go:171","msg":"trace[2104711843] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"570.628263ms","start":"2026-02-24T10:50:50.197945Z","end":"2026-02-24T10:50:50.768573Z","steps":["trace[2104711843] 'process raft request' (duration: 570.377897ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:50:50.76866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.306782411s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-02-24T10:50:50.768663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.342817716s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:50:50.768708Z","caller":"traceutil/trace.go:171","msg":"trace[1620424044] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:471; }","duration":"1.306870973s","start":"2026-02-24T10:50:49.461823Z","end":"2026-02-24T10:50:50.768695Z","steps":["trace[1620424044] 'agreement among raft nodes before linearized reading' (duration: 1.306777351s)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:50:50.768729Z","caller":"traceutil/trace.go:171","msg":"trace[1735652095] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:471; }","duration":"1.342933529s","start":"2026-02-24T10:50:49.425776Z","end":"2026-02-24T10:50:50.76871Z","steps":["trace[1735652095] 'agreement among raft nodes before linearized reading' (duration: 1.342802406s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:50:50.768748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:50:49.46181Z","time spent":"1.306929314s","remote":"127.0.0.1:47046","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-02-24T10:50:50.768762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:50:49.425755Z","time spent":"1.34300109s","remote":"127.0.0.1:46780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T10:50:50.768751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:50:50.197921Z","time spent":"570.735445ms","remote":"127.0.0.1:47046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:50:50.768758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"674.026341ms","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-02-24T10:50:50.768819Z","caller":"traceutil/trace.go:171","msg":"trace[1291226430] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:471; }","duration":"674.128114ms","start":"2026-02-24T10:50:50.094675Z","end":"2026-02-24T10:50:50.768803Z","steps":["trace[1291226430] 'agreement among raft nodes before linearized reading' (duration: 674.022221ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:50:50.76909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:50:50.094653Z","time spent":"674.424201ms","remote":"127.0.0.1:47046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-24T10:50:50.768902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.043194737s","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-02-24T10:50:50.769219Z","caller":"traceutil/trace.go:171","msg":"trace[1516849104] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:471; }","duration":"1.043527324s","start":"2026-02-24T10:50:49.725679Z","end":"2026-02-24T10:50:50.769207Z","steps":["trace[1516849104] 'agreement among raft nodes before linearized reading' (duration: 1.043189386s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:50:50.769257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:50:49.725667Z","time spent":"1.043579876s","remote":"127.0.0.1:47046","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-02-24T10:50:50.76894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.05076809s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-24T10:50:50.769363Z","caller":"traceutil/trace.go:171","msg":"trace[1717027578] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:471; }","duration":"1.051221601s","start":"2026-02-24T10:50:49.718132Z","end":"2026-02-24T10:50:50.769354Z","steps":["trace[1717027578] 'agreement among raft nodes before linearized reading' (duration: 1.050787501s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:50:50.769399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:50:49.71811Z","time spent":"1.051280332s","remote":"127.0.0.1:47046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-24T10:50:56.936195Z","caller":"traceutil/trace.go:171","msg":"trace[458017408] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"116.70985ms","start":"2026-02-24T10:50:56.819459Z","end":"2026-02-24T10:50:56.936169Z","steps":["trace[458017408] 'process raft request' (duration: 92.545774ms)","trace[458017408] 'compare' (duration: 24.062643ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:50:57.269654Z","caller":"traceutil/trace.go:171","msg":"trace[1283088153] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"449.719669ms","start":"2026-02-24T10:50:56.819911Z","end":"2026-02-24T10:50:57.269631Z","steps":["trace[1283088153] 'process raft request' (duration: 443.862857ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:50:57.269814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:50:56.819891Z","time spent":"449.841442ms","remote":"127.0.0.1:47046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T10:50:57.270068Z","caller":"traceutil/trace.go:171","msg":"trace[660317131] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"449.168115ms","start":"2026-02-24T10:50:56.820887Z","end":"2026-02-24T10:50:57.270056Z","steps":["trace[660317131] 'process raft request' (duration: 448.698914ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:50:57.270317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:50:56.820879Z","time spent":"449.347061ms","remote":"127.0.0.1:47046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T10:50:57.330425Z","caller":"traceutil/trace.go:171","msg":"trace[1234286520] linearizableReadLoop","detail":"{readStateIndex:509; appliedIndex:506; }","duration":"180.068608ms","start":"2026-02-24T10:50:57.150326Z","end":"2026-02-24T10:50:57.330395Z","steps":["trace[1234286520] 'read index received' (duration: 113.456162ms)","trace[1234286520] 'applied index is now lower than readState.Index' (duration: 66.610766ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:50:57.330662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.316544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-zbd6v\" ","response":"range_response_count:1 size:16652"} {"level":"info","ts":"2026-02-24T10:50:57.330699Z","caller":"traceutil/trace.go:171","msg":"trace[986107302] range","detail":"{range_begin:/registry/pods/kube-system/cilium-zbd6v; range_end:; response_count:1; response_revision:492; }","duration":"180.377135ms","start":"2026-02-24T10:50:57.150312Z","end":"2026-02-24T10:50:57.330689Z","steps":["trace[986107302] 'agreement among raft nodes before linearized reading' (duration: 180.192151ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:50:57.926365Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986420759335235664,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-24T10:50:58.015976Z","caller":"traceutil/trace.go:171","msg":"trace[897056788] linearizableReadLoop","detail":"{readStateIndex:510; appliedIndex:509; }","duration":"589.910371ms","start":"2026-02-24T10:50:57.426029Z","end":"2026-02-24T10:50:58.015939Z","steps":["trace[897056788] 'read index received' (duration: 589.640724ms)","trace[897056788] 'applied index is now lower than readState.Index' (duration: 268.627µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:50:58.016014Z","caller":"traceutil/trace.go:171","msg":"trace[1266957051] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"671.613883ms","start":"2026-02-24T10:50:57.344353Z","end":"2026-02-24T10:50:58.015967Z","steps":["trace[1266957051] 'process raft request' (duration: 671.418918ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:50:58.016178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.123426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:50:58.016261Z","caller":"traceutil/trace.go:171","msg":"trace[1649131745] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:493; }","duration":"590.250669ms","start":"2026-02-24T10:50:57.425992Z","end":"2026-02-24T10:50:58.016242Z","steps":["trace[1649131745] 'agreement among raft nodes before linearized reading' (duration: 590.081415ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:50:58.01631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:50:57.425974Z","time spent":"590.328061ms","remote":"127.0.0.1:46780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T10:50:58.016286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:50:57.344326Z","time spent":"671.839238ms","remote":"127.0.0.1:46966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:50:59.440242Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986420759335235669,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T10:50:59.941016Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986420759335235669,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T10:50:59.950368Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.126837404s","expected-duration":"1s"} {"level":"info","ts":"2026-02-24T10:50:59.950611Z","caller":"traceutil/trace.go:171","msg":"trace[952424568] linearizableReadLoop","detail":"{readStateIndex:511; appliedIndex:510; }","duration":"1.010712377s","start":"2026-02-24T10:50:58.939879Z","end":"2026-02-24T10:50:59.950592Z","steps":["trace[952424568] 'read index received' (duration: 1.010560103s)","trace[952424568] 'applied index is now lower than readState.Index' (duration: 151.724µs)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:50:59.950708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"676.322726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-24T10:50:59.950733Z","caller":"traceutil/trace.go:171","msg":"trace[182836637] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:494; }","duration":"676.381057ms","start":"2026-02-24T10:50:59.274344Z","end":"2026-02-24T10:50:59.950725Z","steps":["trace[182836637] 'agreement among raft nodes before linearized reading' (duration: 676.307785ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:50:59.950752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:50:59.274328Z","time spent":"676.419348ms","remote":"127.0.0.1:47046","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-02-24T10:50:59.950845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.980264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:50:59.950865Z","caller":"traceutil/trace.go:171","msg":"trace[124653936] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:494; }","duration":"527.022554ms","start":"2026-02-24T10:50:59.423838Z","end":"2026-02-24T10:50:59.95086Z","steps":["trace[124653936] 'agreement among raft nodes before linearized reading' (duration: 526.989693ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:50:59.95084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.010943303s","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-02-24T10:50:59.950909Z","caller":"traceutil/trace.go:171","msg":"trace[340544312] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"1.127523011s","start":"2026-02-24T10:50:58.82338Z","end":"2026-02-24T10:50:59.950903Z","steps":["trace[340544312] 'process raft request' (duration: 1.127121141s)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:50:59.95093Z","caller":"traceutil/trace.go:171","msg":"trace[1050012564] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:494; }","duration":"1.011077165s","start":"2026-02-24T10:50:58.939828Z","end":"2026-02-24T10:50:59.950905Z","steps":["trace[1050012564] 'agreement among raft nodes before linearized reading' (duration: 1.010834149s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:50:59.950964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:50:58.823355Z","time spent":"1.127570393s","remote":"127.0.0.1:47046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:50:59.950998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:50:58.93981Z","time spent":"1.011174028s","remote":"127.0.0.1:47046","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":"warn","ts":"2026-02-24T10:50:59.950882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:50:59.423822Z","time spent":"527.056385ms","remote":"127.0.0.1:46784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T10:50:59.951038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"676.539811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-24T10:50:59.951114Z","caller":"traceutil/trace.go:171","msg":"trace[2049785244] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:494; }","duration":"676.626673ms","start":"2026-02-24T10:50:59.274465Z","end":"2026-02-24T10:50:59.951092Z","steps":["trace[2049785244] 'agreement among raft nodes before linearized reading' (duration: 676.431248ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:50:59.951184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:50:59.274406Z","time spent":"676.765817ms","remote":"127.0.0.1:47046","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-02-24T10:51:01.150895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.089724ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T10:51:01.151182Z","caller":"traceutil/trace.go:171","msg":"trace[888661503] linearizableReadLoop","detail":"{readStateIndex:514; appliedIndex:511; }","duration":"724.328741ms","start":"2026-02-24T10:51:00.426833Z","end":"2026-02-24T10:51:01.151162Z","steps":["trace[888661503] 'read index received' (duration: 415.896659ms)","trace[888661503] 'applied index is now lower than readState.Index' (duration: 308.431022ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:51:01.151302Z","caller":"traceutil/trace.go:171","msg":"trace[624236033] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"1.195382739s","start":"2026-02-24T10:50:59.955901Z","end":"2026-02-24T10:51:01.151283Z","steps":["trace[624236033] 'process raft request' (duration: 1.195082722s)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:51:01.151375Z","caller":"traceutil/trace.go:171","msg":"trace[527791000] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"1.194786655s","start":"2026-02-24T10:50:59.956563Z","end":"2026-02-24T10:51:01.15135Z","steps":["trace[527791000] 'process raft request' (duration: 1.194535769s)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:51:01.151364Z","caller":"traceutil/trace.go:171","msg":"trace[1382307829] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"1.195728587s","start":"2026-02-24T10:50:59.955568Z","end":"2026-02-24T10:51:01.151297Z","steps":["trace[1382307829] 'process raft request' (duration: 887.180322ms)","trace[1382307829] 'compare' (duration: 307.769427ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:51:01.1514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:50:59.955887Z","time spent":"1.195459591s","remote":"127.0.0.1:47046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:51:01.151436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:50:59.956545Z","time spent":"1.194854636s","remote":"127.0.0.1:47046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:51:01.151428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"724.594228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:51:01.151496Z","caller":"traceutil/trace.go:171","msg":"trace[206790526] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:497; }","duration":"724.69211ms","start":"2026-02-24T10:51:00.426775Z","end":"2026-02-24T10:51:01.151467Z","steps":["trace[206790526] 'agreement among raft nodes before linearized reading' (duration: 724.589518ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:51:01.151522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:51:00.426753Z","time spent":"724.763232ms","remote":"127.0.0.1:46780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T10:51:01.151538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:50:59.955549Z","time spent":"1.195894341s","remote":"127.0.0.1:47046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T10:51:01.162752Z","caller":"traceutil/trace.go:171","msg":"trace[1548297270] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"301.243349ms","start":"2026-02-24T10:51:00.861479Z","end":"2026-02-24T10:51:01.162723Z","steps":["trace[1548297270] 'process raft request' (duration: 301.087875ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:51:01.16278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.823995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.25\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-24T10:51:01.163016Z","caller":"traceutil/trace.go:171","msg":"trace[1877780094] range","detail":"{range_begin:/registry/masterleases/199.204.45.25; range_end:; response_count:1; response_revision:498; }","duration":"169.107222ms","start":"2026-02-24T10:51:00.993896Z","end":"2026-02-24T10:51:01.163003Z","steps":["trace[1877780094] 'agreement among raft nodes before linearized reading' (duration: 168.810125ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:51:01.163077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:51:00.861392Z","time spent":"301.432713ms","remote":"127.0.0.1:47046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T10:51:02.060856Z","caller":"traceutil/trace.go:171","msg":"trace[1370025768] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"102.123267ms","start":"2026-02-24T10:51:01.958707Z","end":"2026-02-24T10:51:02.06083Z","steps":["trace[1370025768] 'process raft request' (duration: 101.936423ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:51:03.551926Z","caller":"traceutil/trace.go:171","msg":"trace[1180987406] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"223.702477ms","start":"2026-02-24T10:51:03.3282Z","end":"2026-02-24T10:51:03.551902Z","steps":["trace[1180987406] 'process raft request' (duration: 223.545463ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:51:13.406957Z","caller":"traceutil/trace.go:171","msg":"trace[253063611] linearizableReadLoop","detail":"{readStateIndex:647; appliedIndex:646; }","duration":"203.565086ms","start":"2026-02-24T10:51:13.203369Z","end":"2026-02-24T10:51:13.406934Z","steps":["trace[253063611] 'read index received' (duration: 203.379461ms)","trace[253063611] 'applied index is now lower than readState.Index' (duration: 184.735µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:51:13.407015Z","caller":"traceutil/trace.go:171","msg":"trace[789313842] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"219.733194ms","start":"2026-02-24T10:51:13.187242Z","end":"2026-02-24T10:51:13.406975Z","steps":["trace[789313842] 'process raft request' (duration: 219.56203ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:51:13.407285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.613414ms","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-02-24T10:51:13.407335Z","caller":"traceutil/trace.go:171","msg":"trace[1131186168] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:626; }","duration":"191.694117ms","start":"2026-02-24T10:51:13.215627Z","end":"2026-02-24T10:51:13.407321Z","steps":["trace[1131186168] 'agreement among raft nodes before linearized reading' (duration: 191.43673ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:51:13.407402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.05284ms","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-02-24T10:51:13.407495Z","caller":"traceutil/trace.go:171","msg":"trace[875106695] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:626; }","duration":"191.167562ms","start":"2026-02-24T10:51:13.216302Z","end":"2026-02-24T10:51:13.40747Z","steps":["trace[875106695] 'agreement among raft nodes before linearized reading' (duration: 190.862295ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:51:13.407499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.11813ms","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-02-24T10:51:13.407588Z","caller":"traceutil/trace.go:171","msg":"trace[473225655] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:626; }","duration":"204.253783ms","start":"2026-02-24T10:51:13.203314Z","end":"2026-02-24T10:51:13.407567Z","steps":["trace[473225655] 'agreement among raft nodes before linearized reading' (duration: 203.75458ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:51:13.514893Z","caller":"traceutil/trace.go:171","msg":"trace[1800968535] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"103.226844ms","start":"2026-02-24T10:51:13.411644Z","end":"2026-02-24T10:51:13.51487Z","steps":["trace[1800968535] 'process raft request' (duration: 46.839612ms)","trace[1800968535] 'compare' (duration: 56.135776ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:51:13.514915Z","caller":"traceutil/trace.go:171","msg":"trace[1042843291] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"102.825953ms","start":"2026-02-24T10:51:13.412069Z","end":"2026-02-24T10:51:13.514895Z","steps":["trace[1042843291] 'process raft request' (duration: 102.776442ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:51:13.514924Z","caller":"traceutil/trace.go:171","msg":"trace[1292973565] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"103.158121ms","start":"2026-02-24T10:51:13.411742Z","end":"2026-02-24T10:51:13.514901Z","steps":["trace[1292973565] 'process raft request' (duration: 103.046348ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:51:19.259251Z","caller":"traceutil/trace.go:171","msg":"trace[527002663] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"245.143804ms","start":"2026-02-24T10:51:19.014069Z","end":"2026-02-24T10:51:19.259213Z","steps":["trace[527002663] 'process raft request' (duration: 244.695713ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:51:20.235001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"695.161501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-24T10:51:20.235085Z","caller":"traceutil/trace.go:171","msg":"trace[1678954847] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:677; }","duration":"695.274424ms","start":"2026-02-24T10:51:19.53979Z","end":"2026-02-24T10:51:20.235064Z","steps":["trace[1678954847] 'range keys from in-memory index tree' (duration: 695.033308ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:51:20.235126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:51:19.539782Z","time spent":"695.334416ms","remote":"127.0.0.1:47046","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-02-24T10:51:20.235461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"808.770186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:51:20.235492Z","caller":"traceutil/trace.go:171","msg":"trace[1302020774] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:677; }","duration":"808.826717ms","start":"2026-02-24T10:51:19.426655Z","end":"2026-02-24T10:51:20.235482Z","steps":["trace[1302020774] 'range keys from in-memory index tree' (duration: 808.709144ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:51:20.235519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:51:19.42664Z","time spent":"808.872639ms","remote":"127.0.0.1:46780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T10:51:20.235661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.794078ms","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-02-24T10:51:20.235715Z","caller":"traceutil/trace.go:171","msg":"trace[1010292238] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:677; }","duration":"697.87744ms","start":"2026-02-24T10:51:19.537824Z","end":"2026-02-24T10:51:20.235701Z","steps":["trace[1010292238] 'range keys from in-memory index tree' (duration: 697.729636ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:51:20.235741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:51:19.537812Z","time spent":"697.920161ms","remote":"127.0.0.1:47046","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":"warn","ts":"2026-02-24T10:51:20.235892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"699.600493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-24T10:51:20.235911Z","caller":"traceutil/trace.go:171","msg":"trace[958122183] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:677; }","duration":"699.653866ms","start":"2026-02-24T10:51:19.536251Z","end":"2026-02-24T10:51:20.235905Z","steps":["trace[958122183] 'range keys from in-memory index tree' (duration: 699.515691ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:51:20.235934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:51:19.536231Z","time spent":"699.697276ms","remote":"127.0.0.1:47046","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-02-24T10:51:20.927462Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986420759335236023,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-24T10:51:21.102608Z","caller":"traceutil/trace.go:171","msg":"trace[1772210481] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"862.319572ms","start":"2026-02-24T10:51:20.240248Z","end":"2026-02-24T10:51:21.102567Z","steps":["trace[1772210481] 'process raft request' (duration: 862.175518ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:51:21.102804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:51:20.240232Z","time spent":"862.453915ms","remote":"127.0.0.1:47046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:51:21.638989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.106947ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T10:51:21.639214Z","caller":"traceutil/trace.go:171","msg":"trace[1197559850] linearizableReadLoop","detail":"{readStateIndex:703; appliedIndex:700; }","duration":"1.212342529s","start":"2026-02-24T10:51:20.426855Z","end":"2026-02-24T10:51:21.639198Z","steps":["trace[1197559850] 'read index received' (duration: 676.10602ms)","trace[1197559850] 'applied index is now lower than readState.Index' (duration: 536.235649ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:51:21.639295Z","caller":"traceutil/trace.go:171","msg":"trace[897912999] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"1.39886057s","start":"2026-02-24T10:51:20.240422Z","end":"2026-02-24T10:51:21.639282Z","steps":["trace[897912999] 'process raft request' (duration: 1.009378393s)","trace[897912999] 'compare' (duration: 388.968524ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:51:21.639326Z","caller":"traceutil/trace.go:171","msg":"trace[1531948301] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"1.398787697s","start":"2026-02-24T10:51:20.240517Z","end":"2026-02-24T10:51:21.639305Z","steps":["trace[1531948301] 'process raft request' (duration: 1.398568962s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:51:21.639362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:51:20.240414Z","time spent":"1.398913011s","remote":"127.0.0.1:47046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:51:21.639452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:51:20.240494Z","time spent":"1.398885811s","remote":"127.0.0.1:47046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T10:51:21.639432Z","caller":"traceutil/trace.go:171","msg":"trace[1553457431] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"1.398681026s","start":"2026-02-24T10:51:20.240725Z","end":"2026-02-24T10:51:21.639406Z","steps":["trace[1553457431] 'process raft request' (duration: 1.398435128s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:51:21.639572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:51:20.240702Z","time spent":"1.398797269s","remote":"127.0.0.1:47046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:51:21.639721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.212853683s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:51:21.63976Z","caller":"traceutil/trace.go:171","msg":"trace[2005739219] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:681; }","duration":"1.212940045s","start":"2026-02-24T10:51:20.426809Z","end":"2026-02-24T10:51:21.639749Z","steps":["trace[2005739219] 'agreement among raft nodes before linearized reading' (duration: 1.212862563s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:51:21.639794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:51:20.426789Z","time spent":"1.212998126s","remote":"127.0.0.1:46780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T10:51:21.722451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.812311ms","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-02-24T10:51:21.722523Z","caller":"traceutil/trace.go:171","msg":"trace[880456577] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:682; }","duration":"458.893183ms","start":"2026-02-24T10:51:21.263613Z","end":"2026-02-24T10:51:21.722506Z","steps":["trace[880456577] 'agreement among raft nodes before linearized reading' (duration: 458.715958ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:51:21.722564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:51:21.263569Z","time spent":"458.985855ms","remote":"127.0.0.1:47046","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-02-24T10:51:21.722607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"728.241139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.25\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-24T10:51:21.722665Z","caller":"traceutil/trace.go:171","msg":"trace[743929760] range","detail":"{range_begin:/registry/masterleases/199.204.45.25; range_end:; response_count:1; response_revision:682; }","duration":"728.340201ms","start":"2026-02-24T10:51:20.99431Z","end":"2026-02-24T10:51:21.72265Z","steps":["trace[743929760] 'agreement among raft nodes before linearized reading' (duration: 728.191088ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:51:21.722702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:51:20.99429Z","time spent":"728.404752ms","remote":"127.0.0.1:46804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.25\" "} {"level":"info","ts":"2026-02-24T10:51:21.722813Z","caller":"traceutil/trace.go:171","msg":"trace[117602259] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"422.954623ms","start":"2026-02-24T10:51:21.299832Z","end":"2026-02-24T10:51:21.722787Z","steps":["trace[117602259] 'process raft request' (duration: 422.368268ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:51:21.722916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:51:21.299813Z","time spent":"423.045996ms","remote":"127.0.0.1:47046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:51:21.723059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.583186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:51:21.723117Z","caller":"traceutil/trace.go:171","msg":"trace[1394622004] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:682; }","duration":"317.643878ms","start":"2026-02-24T10:51:21.40546Z","end":"2026-02-24T10:51:21.723104Z","steps":["trace[1394622004] 'agreement among raft nodes before linearized reading' (duration: 317.513374ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:51:21.723219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:51:21.405401Z","time spent":"317.791862ms","remote":"127.0.0.1:47102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"info","ts":"2026-02-24T10:51:21.976059Z","caller":"traceutil/trace.go:171","msg":"trace[388327509] linearizableReadLoop","detail":"{readStateIndex:706; appliedIndex:704; }","duration":"246.772735ms","start":"2026-02-24T10:51:21.729267Z","end":"2026-02-24T10:51:21.97604Z","steps":["trace[388327509] 'read index received' (duration: 123.540336ms)","trace[388327509] 'applied index is now lower than readState.Index' (duration: 123.231489ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:51:21.9762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.912218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:51:21.976226Z","caller":"traceutil/trace.go:171","msg":"trace[822166827] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:683; }","duration":"246.970319ms","start":"2026-02-24T10:51:21.729249Z","end":"2026-02-24T10:51:21.976219Z","steps":["trace[822166827] 'agreement among raft nodes before linearized reading' (duration: 246.862727ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:51:21.976519Z","caller":"traceutil/trace.go:171","msg":"trace[808889924] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"249.489264ms","start":"2026-02-24T10:51:21.727022Z","end":"2026-02-24T10:51:21.976511Z","steps":["trace[808889924] 'process raft request' (duration: 248.884769ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:51:22.085048Z","caller":"traceutil/trace.go:171","msg":"trace[950416022] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"146.17591ms","start":"2026-02-24T10:51:21.938846Z","end":"2026-02-24T10:51:22.085022Z","steps":["trace[950416022] 'process raft request' (duration: 122.637634ms)","trace[950416022] 'compare' (duration: 23.435853ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:51:23.520431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.215065708s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T10:51:23.520498Z","caller":"traceutil/trace.go:171","msg":"trace[1406729716] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:684; }","duration":"1.215189271s","start":"2026-02-24T10:51:22.305295Z","end":"2026-02-24T10:51:23.520484Z","steps":["trace[1406729716] 'count revisions from in-memory index tree' (duration: 1.214965265s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:51:23.520527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:51:22.305266Z","time spent":"1.215251883s","remote":"127.0.0.1:47132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":66,"response size":30,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-02-24T10:51:23.520584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.632278ms","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-02-24T10:51:23.520644Z","caller":"traceutil/trace.go:171","msg":"trace[748984765] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:684; }","duration":"413.72257ms","start":"2026-02-24T10:51:23.106901Z","end":"2026-02-24T10:51:23.520624Z","steps":["trace[748984765] 'range keys from in-memory index tree' (duration: 413.438663ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:51:23.52063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.183060178s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T10:51:23.520672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:51:23.10688Z","time spent":"413.785731ms","remote":"127.0.0.1:47046","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-02-24T10:51:23.520691Z","caller":"traceutil/trace.go:171","msg":"trace[1788912909] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:684; }","duration":"1.18312229s","start":"2026-02-24T10:51:22.337559Z","end":"2026-02-24T10:51:23.520681Z","steps":["trace[1788912909] 'range keys from in-memory index tree' (duration: 1.183050398s)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:51:23.99124Z","caller":"traceutil/trace.go:171","msg":"trace[1373851858] linearizableReadLoop","detail":"{readStateIndex:708; appliedIndex:707; }","duration":"347.280248ms","start":"2026-02-24T10:51:23.643941Z","end":"2026-02-24T10:51:23.991221Z","steps":["trace[1373851858] 'read index received' (duration: 347.019142ms)","trace[1373851858] 'applied index is now lower than readState.Index' (duration: 259.876µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:51:23.991327Z","caller":"traceutil/trace.go:171","msg":"trace[85075538] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"467.623314ms","start":"2026-02-24T10:51:23.523669Z","end":"2026-02-24T10:51:23.991293Z","steps":["trace[85075538] 'process raft request' (duration: 467.369808ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:51:23.991474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.491783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-24T10:51:23.991473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:51:23.523633Z","time spent":"467.758197ms","remote":"127.0.0.1:47046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T10:51:23.991516Z","caller":"traceutil/trace.go:171","msg":"trace[569208881] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:685; }","duration":"347.609326ms","start":"2026-02-24T10:51:23.643897Z","end":"2026-02-24T10:51:23.991506Z","steps":["trace[569208881] 'agreement among raft nodes before linearized reading' (duration: 347.446292ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:51:23.991551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:51:23.643881Z","time spent":"347.660248ms","remote":"127.0.0.1:47046","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-02-24T10:51:23.991575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.301939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-24T10:51:23.99162Z","caller":"traceutil/trace.go:171","msg":"trace[1235330116] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:685; }","duration":"347.372631ms","start":"2026-02-24T10:51:23.644234Z","end":"2026-02-24T10:51:23.991607Z","steps":["trace[1235330116] 'agreement among raft nodes before linearized reading' (duration: 347.247908ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:51:23.991652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:51:23.644224Z","time spent":"347.421182ms","remote":"127.0.0.1:47046","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-02-24T10:51:23.992171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.793031ms","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-02-24T10:51:23.992244Z","caller":"traceutil/trace.go:171","msg":"trace[857299617] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:685; }","duration":"347.893814ms","start":"2026-02-24T10:51:23.644322Z","end":"2026-02-24T10:51:23.992216Z","steps":["trace[857299617] 'agreement among raft nodes before linearized reading' (duration: 347.279219ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:51:23.992285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:51:23.64429Z","time spent":"347.986446ms","remote":"127.0.0.1:47046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-24T10:51:24.199583Z","caller":"traceutil/trace.go:171","msg":"trace[536674813] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"202.573776ms","start":"2026-02-24T10:51:23.996979Z","end":"2026-02-24T10:51:24.199553Z","steps":["trace[536674813] 'process raft request' (duration: 196.623245ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:51:24.209429Z","caller":"traceutil/trace.go:171","msg":"trace[419907305] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"208.852605ms","start":"2026-02-24T10:51:24.000553Z","end":"2026-02-24T10:51:24.209406Z","steps":["trace[419907305] 'process raft request' (duration: 208.828745ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:51:24.209475Z","caller":"traceutil/trace.go:171","msg":"trace[1900059972] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"209.81663ms","start":"2026-02-24T10:51:23.999646Z","end":"2026-02-24T10:51:24.209463Z","steps":["trace[1900059972] 'process raft request' (duration: 209.603165ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:51:24.20965Z","caller":"traceutil/trace.go:171","msg":"trace[1331282631] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"209.988043ms","start":"2026-02-24T10:51:23.999655Z","end":"2026-02-24T10:51:24.209643Z","steps":["trace[1331282631] 'process raft request' (duration: 209.704816ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:51:45.828694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.537486607s","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-02-24T10:51:45.828781Z","caller":"traceutil/trace.go:171","msg":"trace[1318120208] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:747; }","duration":"1.537656311s","start":"2026-02-24T10:51:44.291104Z","end":"2026-02-24T10:51:45.82876Z","steps":["trace[1318120208] 'range keys from in-memory index tree' (duration: 1.537279521s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:51:45.828821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:51:44.291083Z","time spent":"1.537728903s","remote":"127.0.0.1:47046","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-02-24T10:51:45.829115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.53094654s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-24T10:51:45.829149Z","caller":"traceutil/trace.go:171","msg":"trace[1661855481] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:747; }","duration":"1.531024612s","start":"2026-02-24T10:51:44.298115Z","end":"2026-02-24T10:51:45.82914Z","steps":["trace[1661855481] 'range keys from in-memory index tree' (duration: 1.530779457s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:51:45.829175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:51:44.298094Z","time spent":"1.531074794s","remote":"127.0.0.1:47046","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-02-24T10:51:45.829343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.514149946s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-24T10:51:45.82937Z","caller":"traceutil/trace.go:171","msg":"trace[80823594] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:747; }","duration":"1.514244019s","start":"2026-02-24T10:51:44.315116Z","end":"2026-02-24T10:51:45.829361Z","steps":["trace[80823594] 'range keys from in-memory index tree' (duration: 1.514031723s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:51:45.829394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:51:44.315098Z","time spent":"1.51429019s","remote":"127.0.0.1:47046","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-02-24T10:51:45.82955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.508546245s","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-02-24T10:51:45.82958Z","caller":"traceutil/trace.go:171","msg":"trace[1000821177] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:747; }","duration":"1.508597976s","start":"2026-02-24T10:51:44.320971Z","end":"2026-02-24T10:51:45.829569Z","steps":["trace[1000821177] 'range keys from in-memory index tree' (duration: 1.508446812s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:51:45.829615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:51:44.320958Z","time spent":"1.508647848s","remote":"127.0.0.1:47046","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-02-24T10:51:45.829738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.402704066s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:51:45.829824Z","caller":"traceutil/trace.go:171","msg":"trace[838907859] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:747; }","duration":"1.402806279s","start":"2026-02-24T10:51:44.426971Z","end":"2026-02-24T10:51:45.829778Z","steps":["trace[838907859] 'range keys from in-memory index tree' (duration: 1.402552553s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:51:45.829864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:51:44.426949Z","time spent":"1.402905191s","remote":"127.0.0.1:46780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T10:51:45.830117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.276150124s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T10:51:45.830211Z","caller":"traceutil/trace.go:171","msg":"trace[1339857866] linearizableReadLoop","detail":"{readStateIndex:776; appliedIndex:775; }","duration":"527.674544ms","start":"2026-02-24T10:51:45.302519Z","end":"2026-02-24T10:51:45.830194Z","steps":["trace[1339857866] 'read index received' (duration: 40.021µs)","trace[1339857866] 'applied index is now lower than readState.Index' (duration: 527.633303ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:51:45.830288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.769736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:51:45.83035Z","caller":"traceutil/trace.go:171","msg":"trace[1611543761] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:748; }","duration":"527.862828ms","start":"2026-02-24T10:51:45.302467Z","end":"2026-02-24T10:51:45.83033Z","steps":["trace[1611543761] 'agreement among raft nodes before linearized reading' (duration: 527.780236ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:51:45.830417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:51:45.302447Z","time spent":"527.950591ms","remote":"127.0.0.1:46862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"info","ts":"2026-02-24T10:51:45.830682Z","caller":"traceutil/trace.go:171","msg":"trace[1601616035] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"1.324829156s","start":"2026-02-24T10:51:44.505818Z","end":"2026-02-24T10:51:45.830647Z","steps":["trace[1601616035] 'process raft request' (duration: 48.063626ms)","trace[1601616035] 'compare' (duration: 1.274628006s)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:51:45.830792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:51:44.505797Z","time spent":"1.324947228s","remote":"127.0.0.1:47046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:51:46.091939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.352473ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T10:51:46.092037Z","caller":"traceutil/trace.go:171","msg":"trace[665950345] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"257.254281ms","start":"2026-02-24T10:51:45.834766Z","end":"2026-02-24T10:51:46.09202Z","steps":["trace[665950345] 'process raft request' (duration: 144.511898ms)","trace[665950345] 'compare' (duration: 112.21413ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:51:46.136133Z","caller":"traceutil/trace.go:171","msg":"trace[1350105330] linearizableReadLoop","detail":"{readStateIndex:780; appliedIndex:776; }","duration":"295.819676ms","start":"2026-02-24T10:51:45.840291Z","end":"2026-02-24T10:51:46.13611Z","steps":["trace[1350105330] 'read index received' (duration: 139.000347ms)","trace[1350105330] 'applied index is now lower than readState.Index' (duration: 156.818649ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:51:46.136224Z","caller":"traceutil/trace.go:171","msg":"trace[1353480488] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"299.194361ms","start":"2026-02-24T10:51:45.837023Z","end":"2026-02-24T10:51:46.136217Z","steps":["trace[1353480488] 'process raft request' (duration: 299.065298ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:51:46.13622Z","caller":"traceutil/trace.go:171","msg":"trace[1847266122] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"299.743885ms","start":"2026-02-24T10:51:45.836453Z","end":"2026-02-24T10:51:46.136196Z","steps":["trace[1847266122] 'process raft request' (duration: 299.611282ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:51:46.136174Z","caller":"traceutil/trace.go:171","msg":"trace[1943668163] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"300.792122ms","start":"2026-02-24T10:51:45.835362Z","end":"2026-02-24T10:51:46.136154Z","steps":["trace[1943668163] 'process raft request' (duration: 300.586627ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:51:46.136449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.122343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:51:46.136542Z","caller":"traceutil/trace.go:171","msg":"trace[393704247] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:752; }","duration":"296.239457ms","start":"2026-02-24T10:51:45.84028Z","end":"2026-02-24T10:51:46.13652Z","steps":["trace[393704247] 'agreement among raft nodes before linearized reading' (duration: 296.075133ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:51:46.136721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:51:45.83535Z","time spent":"301.15264ms","remote":"127.0.0.1:47046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T10:51:48.281737Z","caller":"traceutil/trace.go:171","msg":"trace[1301581471] linearizableReadLoop","detail":"{readStateIndex:783; appliedIndex:782; }","duration":"141.86758ms","start":"2026-02-24T10:51:48.139844Z","end":"2026-02-24T10:51:48.281712Z","steps":["trace[1301581471] 'read index received' (duration: 141.47722ms)","trace[1301581471] 'applied index is now lower than readState.Index' (duration: 389.22µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:51:48.281801Z","caller":"traceutil/trace.go:171","msg":"trace[587156870] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"180.541299ms","start":"2026-02-24T10:51:48.101228Z","end":"2026-02-24T10:51:48.281769Z","steps":["trace[587156870] 'process raft request' (duration: 180.19224ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:51:48.281975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.118737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-24T10:51:48.282016Z","caller":"traceutil/trace.go:171","msg":"trace[297623033] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:754; }","duration":"142.227469ms","start":"2026-02-24T10:51:48.139779Z","end":"2026-02-24T10:51:48.282006Z","steps":["trace[297623033] 'agreement among raft nodes before linearized reading' (duration: 142.072316ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:51:48.28216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.867755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-24T10:51:48.282204Z","caller":"traceutil/trace.go:171","msg":"trace[1244656720] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:754; }","duration":"140.962507ms","start":"2026-02-24T10:51:48.141228Z","end":"2026-02-24T10:51:48.28219Z","steps":["trace[1244656720] 'agreement among raft nodes before linearized reading' (duration: 140.747712ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:51:48.282208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.848844ms","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":"warn","ts":"2026-02-24T10:51:48.282438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.186617ms","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-02-24T10:51:48.282516Z","caller":"traceutil/trace.go:171","msg":"trace[92264832] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:754; }","duration":"123.319431ms","start":"2026-02-24T10:51:48.159179Z","end":"2026-02-24T10:51:48.282498Z","steps":["trace[92264832] 'agreement among raft nodes before linearized reading' (duration: 122.967712ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:51:48.282249Z","caller":"traceutil/trace.go:171","msg":"trace[1493115018] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:754; }","duration":"140.904415ms","start":"2026-02-24T10:51:48.141323Z","end":"2026-02-24T10:51:48.282228Z","steps":["trace[1493115018] 'agreement among raft nodes before linearized reading' (duration: 140.814503ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:51:48.559009Z","caller":"traceutil/trace.go:171","msg":"trace[1302147892] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"272.756811ms","start":"2026-02-24T10:51:48.286223Z","end":"2026-02-24T10:51:48.55898Z","steps":["trace[1302147892] 'process raft request' (duration: 246.279921ms)","trace[1302147892] 'compare' (duration: 26.361258ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:51:49.185187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.955834ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T10:51:49.185325Z","caller":"traceutil/trace.go:171","msg":"trace[1159192448] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"898.725202ms","start":"2026-02-24T10:51:48.28659Z","end":"2026-02-24T10:51:49.185315Z","steps":["trace[1159192448] 'process raft request' (duration: 898.66116ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:51:49.185367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:51:48.286565Z","time spent":"898.784294ms","remote":"127.0.0.1:47046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T10:51:49.185376Z","caller":"traceutil/trace.go:171","msg":"trace[883077215] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"897.068892ms","start":"2026-02-24T10:51:48.288284Z","end":"2026-02-24T10:51:49.185353Z","steps":["trace[883077215] 'process raft request' (duration: 896.992169ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:51:49.185488Z","caller":"traceutil/trace.go:171","msg":"trace[435702120] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"898.888387ms","start":"2026-02-24T10:51:48.286594Z","end":"2026-02-24T10:51:49.185483Z","steps":["trace[435702120] 'process raft request' (duration: 569.583093ms)","trace[435702120] 'compare' (duration: 328.874802ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:51:49.185501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:51:48.288272Z","time spent":"897.165994ms","remote":"127.0.0.1:47046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:51:49.185536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:51:48.286573Z","time spent":"898.948178ms","remote":"127.0.0.1:47046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T10:51:51.296323Z","caller":"traceutil/trace.go:171","msg":"trace[1925602861] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"103.617592ms","start":"2026-02-24T10:51:51.192682Z","end":"2026-02-24T10:51:51.2963Z","steps":["trace[1925602861] 'process raft request' (duration: 103.473889ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:51:51.296625Z","caller":"traceutil/trace.go:171","msg":"trace[848348719] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"103.680544ms","start":"2026-02-24T10:51:51.192918Z","end":"2026-02-24T10:51:51.296599Z","steps":["trace[848348719] 'process raft request' (duration: 103.345645ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:51:59.556484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.549054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:51:59.556552Z","caller":"traceutil/trace.go:171","msg":"trace[806860308] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:832; }","duration":"130.667027ms","start":"2026-02-24T10:51:59.425872Z","end":"2026-02-24T10:51:59.556539Z","steps":["trace[806860308] 'range keys from in-memory index tree' (duration: 130.485163ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:51:59.556648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.08935ms","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-02-24T10:51:59.55672Z","caller":"traceutil/trace.go:171","msg":"trace[1008587366] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:832; }","duration":"220.200553ms","start":"2026-02-24T10:51:59.336503Z","end":"2026-02-24T10:51:59.556703Z","steps":["trace[1008587366] 'range keys from in-memory index tree' (duration: 219.929806ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:51:59.556725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.151508ms","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-02-24T10:51:59.556796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.375146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:6 size:360321"} {"level":"info","ts":"2026-02-24T10:51:59.556805Z","caller":"traceutil/trace.go:171","msg":"trace[556424166] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:832; }","duration":"225.270572ms","start":"2026-02-24T10:51:59.331513Z","end":"2026-02-24T10:51:59.556783Z","steps":["trace[556424166] 'range keys from in-memory index tree' (duration: 224.981104ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:51:59.556844Z","caller":"traceutil/trace.go:171","msg":"trace[1860695997] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:6; response_revision:832; }","duration":"201.463449ms","start":"2026-02-24T10:51:59.355366Z","end":"2026-02-24T10:51:59.55683Z","steps":["trace[1860695997] 'range keys from in-memory index tree' (duration: 201.076949ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:52:02.588988Z","caller":"traceutil/trace.go:171","msg":"trace[1702072879] linearizableReadLoop","detail":"{readStateIndex:883; appliedIndex:882; }","duration":"162.945134ms","start":"2026-02-24T10:52:02.426029Z","end":"2026-02-24T10:52:02.588974Z","steps":["trace[1702072879] 'read index received' (duration: 162.821951ms)","trace[1702072879] 'applied index is now lower than readState.Index' (duration: 122.483µs)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:52:02.589135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.102067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:52:02.589163Z","caller":"traceutil/trace.go:171","msg":"trace[125905019] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:849; }","duration":"163.183069ms","start":"2026-02-24T10:52:02.425971Z","end":"2026-02-24T10:52:02.589154Z","steps":["trace[125905019] 'agreement among raft nodes before linearized reading' (duration: 163.068286ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:52:02.589207Z","caller":"traceutil/trace.go:171","msg":"trace[1084051830] transaction","detail":"{read_only:false; response_revision:849; number_of_response:1; }","duration":"228.495262ms","start":"2026-02-24T10:52:02.360692Z","end":"2026-02-24T10:52:02.589188Z","steps":["trace[1084051830] 'process raft request' (duration: 228.139423ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:52:04.79958Z","caller":"traceutil/trace.go:171","msg":"trace[4706809] transaction","detail":"{read_only:false; response_revision:860; number_of_response:1; }","duration":"168.827652ms","start":"2026-02-24T10:52:04.630729Z","end":"2026-02-24T10:52:04.799557Z","steps":["trace[4706809] 'process raft request' (duration: 117.587996ms)","trace[4706809] 'compare' (duration: 51.147164ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:52:05.860651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.311138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-24T10:52:05.860734Z","caller":"traceutil/trace.go:171","msg":"trace[1454922459] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:860; }","duration":"571.491563ms","start":"2026-02-24T10:52:05.289222Z","end":"2026-02-24T10:52:05.860713Z","steps":["trace[1454922459] 'range keys from in-memory index tree' (duration: 571.102563ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:52:05.860774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:52:05.289201Z","time spent":"571.564194ms","remote":"127.0.0.1:47046","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-02-24T10:52:05.861033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.791203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:52:05.861053Z","caller":"traceutil/trace.go:171","msg":"trace[1858601479] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:860; }","duration":"436.839935ms","start":"2026-02-24T10:52:05.424206Z","end":"2026-02-24T10:52:05.861046Z","steps":["trace[1858601479] 'range keys from in-memory index tree' (duration: 436.730572ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:52:05.861071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:52:05.42419Z","time spent":"436.876915ms","remote":"127.0.0.1:46780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T10:52:05.861268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.716332ms","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-02-24T10:52:05.861286Z","caller":"traceutil/trace.go:171","msg":"trace[726287708] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:860; }","duration":"261.764054ms","start":"2026-02-24T10:52:05.599517Z","end":"2026-02-24T10:52:05.861281Z","steps":["trace[726287708] 'range keys from in-memory index tree' (duration: 261.591649ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:52:05.861389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.610179ms","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-02-24T10:52:05.861405Z","caller":"traceutil/trace.go:171","msg":"trace[1990389792] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:860; }","duration":"242.65681ms","start":"2026-02-24T10:52:05.618743Z","end":"2026-02-24T10:52:05.8614Z","steps":["trace[1990389792] 'range keys from in-memory index tree' (duration: 242.503997ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:52:07.12429Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986420759335236493,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T10:52:07.624532Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986420759335236493,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T10:52:08.10399Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.614359422s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-24T10:52:08.90126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"797.097071ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-24T10:52:08.901356Z","caller":"traceutil/trace.go:171","msg":"trace[1022320937] linearizableReadLoop","detail":"{readStateIndex:899; appliedIndex:898; }","duration":"2.278038218s","start":"2026-02-24T10:52:06.6233Z","end":"2026-02-24T10:52:08.901338Z","steps":["trace[1022320937] 'read index received' (duration: 1.480787253s)","trace[1022320937] 'applied index is now lower than readState.Index' (duration: 797.249615ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:52:08.90161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.278273064s","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-02-24T10:52:08.901656Z","caller":"traceutil/trace.go:171","msg":"trace[261800157] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:864; }","duration":"2.278380727s","start":"2026-02-24T10:52:06.623259Z","end":"2026-02-24T10:52:08.901639Z","steps":["trace[261800157] 'agreement among raft nodes before linearized reading' (duration: 2.278189861s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:52:08.901693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:52:06.623238Z","time spent":"2.278445439s","remote":"127.0.0.1:47046","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-02-24T10:52:08.901754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.469532408s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T10:52:08.901751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.42179285s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T10:52:08.901832Z","caller":"traceutil/trace.go:171","msg":"trace[1555915682] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:864; }","duration":"1.469650961s","start":"2026-02-24T10:52:07.43216Z","end":"2026-02-24T10:52:08.90181Z","steps":["trace[1555915682] 'agreement among raft nodes before linearized reading' (duration: 1.469514598s)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:52:08.901841Z","caller":"traceutil/trace.go:171","msg":"trace[595450601] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:864; }","duration":"1.421957254s","start":"2026-02-24T10:52:07.479866Z","end":"2026-02-24T10:52:08.901823Z","steps":["trace[595450601] 'agreement among raft nodes before linearized reading' (duration: 1.42179151s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:52:08.901864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.003112745s","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-02-24T10:52:08.901873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:52:07.432142Z","time spent":"1.469722563s","remote":"127.0.0.1:46780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-24T10:52:08.901895Z","caller":"traceutil/trace.go:171","msg":"trace[750444197] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:864; }","duration":"1.003156846s","start":"2026-02-24T10:52:07.898725Z","end":"2026-02-24T10:52:08.901882Z","steps":["trace[750444197] 'agreement among raft nodes before linearized reading' (duration: 1.003096585s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:52:08.90191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:52:07.479822Z","time spent":"1.422065156s","remote":"127.0.0.1:46806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":11,"response size":30,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-02-24T10:52:08.901932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:52:07.898718Z","time spent":"1.003204787s","remote":"127.0.0.1:47046","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":"warn","ts":"2026-02-24T10:52:08.901789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.02861252s","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":"warn","ts":"2026-02-24T10:52:08.902031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.003399922s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-24T10:52:08.902041Z","caller":"traceutil/trace.go:171","msg":"trace[501600320] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:864; }","duration":"1.028890378s","start":"2026-02-24T10:52:07.873136Z","end":"2026-02-24T10:52:08.902026Z","steps":["trace[501600320] 'agreement among raft nodes before linearized reading' (duration: 1.028519998s)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:52:08.902064Z","caller":"traceutil/trace.go:171","msg":"trace[874142253] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:864; }","duration":"1.003485074s","start":"2026-02-24T10:52:07.898569Z","end":"2026-02-24T10:52:08.902054Z","steps":["trace[874142253] 'agreement among raft nodes before linearized reading' (duration: 1.003363801s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:52:08.902091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:52:07.898522Z","time spent":"1.003563027s","remote":"127.0.0.1:47046","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-02-24T10:52:08.902091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:52:07.873121Z","time spent":"1.028955479s","remote":"127.0.0.1:47046","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-02-24T10:52:08.902291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.098474654s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-24T10:52:08.902324Z","caller":"traceutil/trace.go:171","msg":"trace[366620895] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:864; }","duration":"2.098587297s","start":"2026-02-24T10:52:06.803726Z","end":"2026-02-24T10:52:08.902313Z","steps":["trace[366620895] 'agreement among raft nodes before linearized reading' (duration: 2.097825378s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:52:08.902351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:52:06.803712Z","time spent":"2.098633618s","remote":"127.0.0.1:47046","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-02-24T10:52:09.202603Z","caller":"traceutil/trace.go:171","msg":"trace[204485468] transaction","detail":"{read_only:false; response_revision:866; number_of_response:1; }","duration":"295.762825ms","start":"2026-02-24T10:52:08.906821Z","end":"2026-02-24T10:52:09.202583Z","steps":["trace[204485468] 'process raft request' (duration: 294.590546ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:52:09.202848Z","caller":"traceutil/trace.go:171","msg":"trace[708807908] transaction","detail":"{read_only:false; response_revision:867; number_of_response:1; }","duration":"295.833117ms","start":"2026-02-24T10:52:08.906987Z","end":"2026-02-24T10:52:09.20282Z","steps":["trace[708807908] 'process raft request' (duration: 295.52881ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:52:09.203078Z","caller":"traceutil/trace.go:171","msg":"trace[504490099] transaction","detail":"{read_only:false; response_revision:868; number_of_response:1; }","duration":"294.189674ms","start":"2026-02-24T10:52:08.908838Z","end":"2026-02-24T10:52:09.203028Z","steps":["trace[504490099] 'process raft request' (duration: 293.898487ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:52:09.203164Z","caller":"traceutil/trace.go:171","msg":"trace[1910865753] linearizableReadLoop","detail":"{readStateIndex:902; appliedIndex:899; }","duration":"295.488717ms","start":"2026-02-24T10:52:08.90766Z","end":"2026-02-24T10:52:09.203149Z","steps":["trace[1910865753] 'read index received' (duration: 28.653245ms)","trace[1910865753] 'applied index is now lower than readState.Index' (duration: 266.834562ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:52:09.203264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.58688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:52:09.203306Z","caller":"traceutil/trace.go:171","msg":"trace[427102195] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:869; }","duration":"295.666312ms","start":"2026-02-24T10:52:08.907628Z","end":"2026-02-24T10:52:09.203295Z","steps":["trace[427102195] 'agreement among raft nodes before linearized reading' (duration: 295.57914ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:52:09.203341Z","caller":"traceutil/trace.go:171","msg":"trace[1307481764] transaction","detail":"{read_only:false; response_revision:869; number_of_response:1; }","duration":"292.269156ms","start":"2026-02-24T10:52:08.911056Z","end":"2026-02-24T10:52:09.203325Z","steps":["trace[1307481764] 'process raft request' (duration: 291.866106ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:52:15.068338Z","caller":"traceutil/trace.go:171","msg":"trace[1657486109] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"108.114646ms","start":"2026-02-24T10:52:14.960203Z","end":"2026-02-24T10:52:15.068318Z","steps":["trace[1657486109] 'process raft request' (duration: 107.961212ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:52:15.420395Z","caller":"traceutil/trace.go:171","msg":"trace[46105315] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"115.278318ms","start":"2026-02-24T10:52:15.305096Z","end":"2026-02-24T10:52:15.420374Z","steps":["trace[46105315] 'process raft request' (duration: 115.239978ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:52:15.42073Z","caller":"traceutil/trace.go:171","msg":"trace[1536147369] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"116.610751ms","start":"2026-02-24T10:52:15.304109Z","end":"2026-02-24T10:52:15.420719Z","steps":["trace[1536147369] 'process raft request' (duration: 116.052396ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:52:15.420853Z","caller":"traceutil/trace.go:171","msg":"trace[1469810689] transaction","detail":"{read_only:false; response_revision:885; number_of_response:1; }","duration":"116.699513ms","start":"2026-02-24T10:52:15.304145Z","end":"2026-02-24T10:52:15.420845Z","steps":["trace[1469810689] 'process raft request' (duration: 116.144299ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:52:21.334082Z","caller":"traceutil/trace.go:171","msg":"trace[1480971904] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"127.352953ms","start":"2026-02-24T10:52:21.206709Z","end":"2026-02-24T10:52:21.334062Z","steps":["trace[1480971904] 'process raft request' (duration: 123.836214ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:52:21.673981Z","caller":"traceutil/trace.go:171","msg":"trace[927955623] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"175.135255ms","start":"2026-02-24T10:52:21.498815Z","end":"2026-02-24T10:52:21.67395Z","steps":["trace[927955623] 'process raft request' (duration: 87.284995ms)","trace[927955623] 'compare' (duration: 87.669636ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:52:21.674239Z","caller":"traceutil/trace.go:171","msg":"trace[1221552021] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"167.818531ms","start":"2026-02-24T10:52:21.506394Z","end":"2026-02-24T10:52:21.674212Z","steps":["trace[1221552021] 'process raft request' (duration: 167.499323ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:52:21.934058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.293592ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T10:52:21.934198Z","caller":"traceutil/trace.go:171","msg":"trace[1542792333] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"245.898314ms","start":"2026-02-24T10:52:21.688287Z","end":"2026-02-24T10:52:21.934185Z","steps":["trace[1542792333] 'process raft request' (duration: 245.852133ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:52:21.934213Z","caller":"traceutil/trace.go:171","msg":"trace[659362709] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"251.68025ms","start":"2026-02-24T10:52:21.682509Z","end":"2026-02-24T10:52:21.934189Z","steps":["trace[659362709] 'process raft request' (duration: 120.180752ms)","trace[659362709] 'compare' (duration: 131.176129ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:52:23.332753Z","caller":"traceutil/trace.go:171","msg":"trace[396285819] linearizableReadLoop","detail":"{readStateIndex:954; appliedIndex:953; }","duration":"213.706195ms","start":"2026-02-24T10:52:23.119022Z","end":"2026-02-24T10:52:23.332728Z","steps":["trace[396285819] 'read index received' (duration: 213.542211ms)","trace[396285819] 'applied index is now lower than readState.Index' (duration: 163.054µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:52:23.332794Z","caller":"traceutil/trace.go:171","msg":"trace[824173851] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"273.881908ms","start":"2026-02-24T10:52:23.058884Z","end":"2026-02-24T10:52:23.332766Z","steps":["trace[824173851] 'process raft request' (duration: 273.698784ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:52:23.332939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.90855ms","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-02-24T10:52:23.332971Z","caller":"traceutil/trace.go:171","msg":"trace[1966169178] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:916; }","duration":"213.952981ms","start":"2026-02-24T10:52:23.119008Z","end":"2026-02-24T10:52:23.332961Z","steps":["trace[1966169178] 'agreement among raft nodes before linearized reading' (duration: 213.826058ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:52:25.529596Z","caller":"traceutil/trace.go:171","msg":"trace[152823356] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"118.590162ms","start":"2026-02-24T10:52:25.410962Z","end":"2026-02-24T10:52:25.529552Z","steps":["trace[152823356] 'process raft request' (duration: 118.333036ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:52:26.363639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.891631ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T10:52:26.363734Z","caller":"traceutil/trace.go:171","msg":"trace[323125212] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"829.936444ms","start":"2026-02-24T10:52:25.533779Z","end":"2026-02-24T10:52:26.363716Z","steps":["trace[323125212] 'process raft request' (duration: 407.890429ms)","trace[323125212] 'compare' (duration: 421.404919ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:52:26.363802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:52:25.533759Z","time spent":"830.004015ms","remote":"127.0.0.1:47046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:52:26.955012Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.013243544s","expected-duration":"1s"} {"level":"info","ts":"2026-02-24T10:52:26.984801Z","caller":"traceutil/trace.go:171","msg":"trace[1927786321] linearizableReadLoop","detail":"{readStateIndex:968; appliedIndex:965; }","duration":"1.297087144s","start":"2026-02-24T10:52:25.687689Z","end":"2026-02-24T10:52:26.984776Z","steps":["trace[1927786321] 'read index received' (duration: 254.08334ms)","trace[1927786321] 'applied index is now lower than readState.Index' (duration: 1.043002504s)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:52:26.984881Z","caller":"traceutil/trace.go:171","msg":"trace[177608806] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"1.387835676s","start":"2026-02-24T10:52:25.597035Z","end":"2026-02-24T10:52:26.98487Z","steps":["trace[177608806] 'process raft request' (duration: 1.387689932s)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:52:26.984816Z","caller":"traceutil/trace.go:171","msg":"trace[1154316132] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"1.450894162s","start":"2026-02-24T10:52:25.533893Z","end":"2026-02-24T10:52:26.984787Z","steps":["trace[1154316132] 'process raft request' (duration: 1.421446901s)","trace[1154316132] 'compare' (duration: 29.257156ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:52:26.984986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:52:25.597012Z","time spent":"1.387902148s","remote":"127.0.0.1:46954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5379,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:52:26.985018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:52:25.533867Z","time spent":"1.451097307s","remote":"127.0.0.1:47046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:52:26.985041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.297332289s","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-02-24T10:52:26.985087Z","caller":"traceutil/trace.go:171","msg":"trace[1582666801] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:930; }","duration":"1.297397231s","start":"2026-02-24T10:52:25.687674Z","end":"2026-02-24T10:52:26.985072Z","steps":["trace[1582666801] 'agreement among raft nodes before linearized reading' (duration: 1.297220097s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:52:26.985123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:52:25.687618Z","time spent":"1.297496513s","remote":"127.0.0.1:47046","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-02-24T10:52:28.427388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00032497s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-24T10:52:28.427477Z","caller":"traceutil/trace.go:171","msg":"trace[1654010819] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000452003s","start":"2026-02-24T10:52:26.426996Z","end":"2026-02-24T10:52:28.427448Z","steps":["trace[1654010819] 'agreement among raft nodes before linearized reading' (duration: 2.000350521s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:52:28.427526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:52:26.426981Z","time spent":"2.000534935s","remote":"127.0.0.1:46780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/24 10:52:28 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-24T10:52:28.604121Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.293958304s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-24T10:52:28.605292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.29498382s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T10:52:28.605677Z","caller":"traceutil/trace.go:171","msg":"trace[1449051677] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"2.399670473s","start":"2026-02-24T10:52:26.205974Z","end":"2026-02-24T10:52:28.605644Z","steps":["trace[1449051677] 'process raft request' (duration: 1.104269163s)","trace[1449051677] 'compare' (duration: 1.294878227s)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:52:28.605822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:52:26.205943Z","time spent":"2.399800377s","remote":"127.0.0.1:47046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:52:29.196592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.184949ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-24T10:52:29.196989Z","caller":"traceutil/trace.go:171","msg":"trace[193076803] linearizableReadLoop","detail":"{readStateIndex:970; appliedIndex:968; }","duration":"2.212096106s","start":"2026-02-24T10:52:26.984868Z","end":"2026-02-24T10:52:29.196964Z","steps":["trace[193076803] 'read index received' (duration: 325.391673ms)","trace[193076803] 'applied index is now lower than readState.Index' (duration: 1.886702643s)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:52:29.19717Z","caller":"traceutil/trace.go:171","msg":"trace[363575879] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"2.206892195s","start":"2026-02-24T10:52:26.990244Z","end":"2026-02-24T10:52:29.197136Z","steps":["trace[363575879] 'process raft request' (duration: 2.206489995s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:52:29.197333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:52:26.990223Z","time spent":"2.207019898s","remote":"127.0.0.1:47046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:52:29.370929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"759.303167ms","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-02-24T10:52:29.371004Z","caller":"traceutil/trace.go:171","msg":"trace[1787639077] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:932; }","duration":"759.443441ms","start":"2026-02-24T10:52:28.611541Z","end":"2026-02-24T10:52:29.370984Z","steps":["trace[1787639077] 'agreement among raft nodes before linearized reading' (duration: 585.608688ms)","trace[1787639077] 'count revisions from in-memory index tree' (duration: 173.72058ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:52:29.371042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:52:28.61152Z","time spent":"759.514403ms","remote":"127.0.0.1:46954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":30,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-02-24T10:52:29.371072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.019466931s","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-02-24T10:52:29.371169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.075974133s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:52:29.371183Z","caller":"traceutil/trace.go:171","msg":"trace[858442181] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:932; }","duration":"2.019628676s","start":"2026-02-24T10:52:27.351531Z","end":"2026-02-24T10:52:29.37116Z","steps":["trace[858442181] 'agreement among raft nodes before linearized reading' (duration: 1.845632279s)","trace[858442181] 'range keys from in-memory index tree' (duration: 173.74605ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:52:29.371164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.253798734s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T10:52:29.371207Z","caller":"traceutil/trace.go:171","msg":"trace[1451646646] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:932; }","duration":"1.076084696s","start":"2026-02-24T10:52:28.295107Z","end":"2026-02-24T10:52:29.371192Z","steps":["trace[1451646646] 'agreement among raft nodes before linearized reading' (duration: 902.008276ms)","trace[1451646646] 'count revisions from in-memory index tree' (duration: 174.028408ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:52:29.371232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:52:27.351511Z","time spent":"2.019711888s","remote":"127.0.0.1:47046","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-02-24T10:52:29.371241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:52:28.295072Z","time spent":"1.076161438s","remote":"127.0.0.1:36080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"warn","ts":"2026-02-24T10:52:29.371226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.214919ms","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-02-24T10:52:29.371249Z","caller":"traceutil/trace.go:171","msg":"trace[959698678] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:932; }","duration":"2.25395796s","start":"2026-02-24T10:52:27.117268Z","end":"2026-02-24T10:52:29.371226Z","steps":["trace[959698678] 'agreement among raft nodes before linearized reading' (duration: 2.079845009s)","trace[959698678] 'count revisions from in-memory index tree' (duration: 173.956435ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:52:29.371271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.837740431s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-24T10:52:29.371284Z","caller":"traceutil/trace.go:171","msg":"trace[763772351] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:932; }","duration":"381.302861ms","start":"2026-02-24T10:52:28.989964Z","end":"2026-02-24T10:52:29.371267Z","steps":["trace[763772351] 'agreement among raft nodes before linearized reading' (duration: 207.174ms)","trace[763772351] 'range keys from in-memory index tree' (duration: 173.961186ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:52:29.3713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.722457012s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T10:52:29.371271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.003818458s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-24T10:52:29.371298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:52:27.117249Z","time spent":"2.254037492s","remote":"127.0.0.1:47226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-02-24T10:52:29.371324Z","caller":"traceutil/trace.go:171","msg":"trace[892914080] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:932; }","duration":"1.837837834s","start":"2026-02-24T10:52:27.533472Z","end":"2026-02-24T10:52:29.37131Z","steps":["trace[892914080] 'agreement among raft nodes before linearized reading' (duration: 1.663682382s)","trace[892914080] 'range keys from in-memory index tree' (duration: 174.022507ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:52:29.371323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:52:28.989946Z","time spent":"381.369163ms","remote":"127.0.0.1:47046","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":"warn","ts":"2026-02-24T10:52:29.371357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:52:27.533451Z","time spent":"1.837898856s","remote":"127.0.0.1:47046","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-02-24T10:52:29.371365Z","caller":"traceutil/trace.go:171","msg":"trace[1739300401] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:932; }","duration":"1.003973031s","start":"2026-02-24T10:52:28.367366Z","end":"2026-02-24T10:52:29.371339Z","steps":["trace[1739300401] 'agreement among raft nodes before linearized reading' (duration: 829.80414ms)","trace[1739300401] 'range keys from in-memory index tree' (duration: 173.885753ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:52:29.371334Z","caller":"traceutil/trace.go:171","msg":"trace[443822938] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:932; }","duration":"1.722520393s","start":"2026-02-24T10:52:27.648804Z","end":"2026-02-24T10:52:29.371325Z","steps":["trace[443822938] 'agreement among raft nodes before linearized reading' (duration: 1.548338821s)","trace[443822938] 'count revisions from in-memory index tree' (duration: 174.135151ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:52:29.3711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.644928612s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-24T10:52:29.371415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:52:28.367339Z","time spent":"1.004065254s","remote":"127.0.0.1:47046","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-02-24T10:52:29.371425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:52:27.648788Z","time spent":"1.722628176s","remote":"127.0.0.1:47086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":28,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"info","ts":"2026-02-24T10:52:29.37144Z","caller":"traceutil/trace.go:171","msg":"trace[365817805] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:932; }","duration":"1.645318533s","start":"2026-02-24T10:52:27.726111Z","end":"2026-02-24T10:52:29.371429Z","steps":["trace[365817805] 'agreement among raft nodes before linearized reading' (duration: 1.471021718s)","trace[365817805] 'count revisions from in-memory index tree' (duration: 173.923154ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:52:29.371464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:52:27.72609Z","time spent":"1.645366433s","remote":"127.0.0.1:47264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":3,"response size":30,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-02-24T10:52:29.371472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"943.702735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:52:29.371495Z","caller":"traceutil/trace.go:171","msg":"trace[497317848] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:932; }","duration":"943.772487ms","start":"2026-02-24T10:52:28.427716Z","end":"2026-02-24T10:52:29.371489Z","steps":["trace[497317848] 'agreement among raft nodes before linearized reading' (duration: 769.443012ms)","trace[497317848] 'range keys from in-memory index tree' (duration: 174.297404ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:52:29.371513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:52:28.427697Z","time spent":"943.812099ms","remote":"127.0.0.1:46784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T10:52:30.715593Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.306420938s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-24T10:52:31.225036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.216207ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-24T10:52:31.225274Z","caller":"traceutil/trace.go:171","msg":"trace[1104509891] linearizableReadLoop","detail":"{readStateIndex:976; appliedIndex:971; }","duration":"1.846625335s","start":"2026-02-24T10:52:29.378632Z","end":"2026-02-24T10:52:31.225257Z","steps":["trace[1104509891] 'read index received' (duration: 30.59115ms)","trace[1104509891] 'applied index is now lower than readState.Index' (duration: 1.816031345s)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:52:31.225353Z","caller":"traceutil/trace.go:171","msg":"trace[1959664877] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"1.849006045s","start":"2026-02-24T10:52:29.376336Z","end":"2026-02-24T10:52:31.225342Z","steps":["trace[1959664877] 'process raft request' (duration: 1.339416718s)","trace[1959664877] 'compare' (duration: 509.108255ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:52:31.225404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:52:29.376318Z","time spent":"1.849059766s","remote":"127.0.0.1:46850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-24T10:52:31.225722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.847058276s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T10:52:31.225754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.492305594s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T10:52:31.225808Z","caller":"traceutil/trace.go:171","msg":"trace[793909498] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:937; }","duration":"1.492417836s","start":"2026-02-24T10:52:29.733377Z","end":"2026-02-24T10:52:31.225794Z","steps":["trace[793909498] 'agreement among raft nodes before linearized reading' (duration: 1.492311693s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:52:31.225858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:52:29.733356Z","time spent":"1.492494938s","remote":"127.0.0.1:47190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":30,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"info","ts":"2026-02-24T10:52:31.225887Z","caller":"traceutil/trace.go:171","msg":"trace[731743039] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"1.848805199s","start":"2026-02-24T10:52:29.377074Z","end":"2026-02-24T10:52:31.225879Z","steps":["trace[731743039] 'process raft request' (duration: 1.848148522s)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:52:31.225975Z","caller":"traceutil/trace.go:171","msg":"trace[290856519] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"1.849203761s","start":"2026-02-24T10:52:29.376746Z","end":"2026-02-24T10:52:31.22595Z","steps":["trace[290856519] 'process raft request' (duration: 1.848445131s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:52:31.226003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:52:29.37705Z","time spent":"1.848889902s","remote":"127.0.0.1:47046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T10:52:31.225858Z","caller":"traceutil/trace.go:171","msg":"trace[2106907628] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"1.849383535s","start":"2026-02-24T10:52:29.376453Z","end":"2026-02-24T10:52:31.225836Z","steps":["trace[2106907628] 'process raft request' (duration: 1.848680307s)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:52:31.225796Z","caller":"traceutil/trace.go:171","msg":"trace[559753632] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:937; }","duration":"1.847171729s","start":"2026-02-24T10:52:29.378606Z","end":"2026-02-24T10:52:31.225778Z","steps":["trace[559753632] 'agreement among raft nodes before linearized reading' (duration: 1.847029844s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:52:31.226091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"698.032168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T10:52:31.226125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.663387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.25\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-24T10:52:31.226177Z","caller":"traceutil/trace.go:171","msg":"trace[1443400633] range","detail":"{range_begin:/registry/masterleases/199.204.45.25; range_end:; response_count:1; response_revision:937; }","duration":"227.753419ms","start":"2026-02-24T10:52:30.998411Z","end":"2026-02-24T10:52:31.226164Z","steps":["trace[1443400633] 'agreement among raft nodes before linearized reading' (duration: 227.619546ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:52:31.226161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:52:29.378598Z","time spent":"1.847544529s","remote":"127.0.0.1:46780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-24T10:52:31.226202Z","caller":"traceutil/trace.go:171","msg":"trace[529679564] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:937; }","duration":"698.147251ms","start":"2026-02-24T10:52:30.527992Z","end":"2026-02-24T10:52:31.226139Z","steps":["trace[529679564] 'agreement among raft nodes before linearized reading' (duration: 698.024487ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:52:31.226432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:52:30.527971Z","time spent":"698.446367ms","remote":"127.0.0.1:45192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-02-24T10:52:31.226204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:52:29.376428Z","time spent":"1.849690203s","remote":"127.0.0.1:47046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:52:31.226235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:52:29.376735Z","time spent":"1.849335194s","remote":"127.0.0.1:47046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:52:31.728007Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986420759335236662,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T10:52:32.187113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.696768ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-02-24T10:52:32.187427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:52:31.226921Z","time spent":"960.497557ms","remote":"127.0.0.1:46804","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-24T10:52:32.18802Z","caller":"traceutil/trace.go:171","msg":"trace[1721836844] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"957.897092ms","start":"2026-02-24T10:52:31.2301Z","end":"2026-02-24T10:52:32.187997Z","steps":["trace[1721836844] 'process raft request' (duration: 957.225235ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:52:32.188211Z","caller":"traceutil/trace.go:171","msg":"trace[1262684952] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"958.041186ms","start":"2026-02-24T10:52:31.230147Z","end":"2026-02-24T10:52:32.188188Z","steps":["trace[1262684952] 'process raft request' (duration: 957.774189ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:52:32.188247Z","caller":"traceutil/trace.go:171","msg":"trace[1076371317] linearizableReadLoop","detail":"{readStateIndex:979; appliedIndex:976; }","duration":"960.919628ms","start":"2026-02-24T10:52:31.227231Z","end":"2026-02-24T10:52:32.188151Z","steps":["trace[1076371317] 'read index received' (duration: 600.273417ms)","trace[1076371317] 'applied index is now lower than readState.Index' (duration: 360.644331ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:52:32.188342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:52:31.230129Z","time spent":"958.143149ms","remote":"127.0.0.1:46850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":871,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-24T10:52:32.188391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"961.099373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:52:32.188497Z","caller":"traceutil/trace.go:171","msg":"trace[1525458445] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:939; }","duration":"961.281987ms","start":"2026-02-24T10:52:31.227195Z","end":"2026-02-24T10:52:32.188477Z","steps":["trace[1525458445] 'agreement among raft nodes before linearized reading' (duration: 961.047661ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:52:32.188537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"775.071704ms","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-02-24T10:52:32.188551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.007331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T10:52:32.188577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:52:31.22718Z","time spent":"961.38526ms","remote":"127.0.0.1:46784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-24T10:52:32.188611Z","caller":"traceutil/trace.go:171","msg":"trace[484399782] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:939; }","duration":"775.191877ms","start":"2026-02-24T10:52:31.41341Z","end":"2026-02-24T10:52:32.188602Z","steps":["trace[484399782] 'agreement among raft nodes before linearized reading' (duration: 774.990892ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:52:32.188645Z","caller":"traceutil/trace.go:171","msg":"trace[207162756] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:939; }","duration":"336.108033ms","start":"2026-02-24T10:52:31.852511Z","end":"2026-02-24T10:52:32.188619Z","steps":["trace[207162756] 'agreement among raft nodes before linearized reading' (duration: 335.96465ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:52:32.188675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:52:31.413391Z","time spent":"775.276968ms","remote":"127.0.0.1:47046","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-02-24T10:52:32.188711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:52:31.852442Z","time spent":"336.247737ms","remote":"127.0.0.1:45178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-02-24T10:52:32.188438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:52:31.230083Z","time spent":"957.997535ms","remote":"127.0.0.1:47046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T10:52:32.388257Z","caller":"traceutil/trace.go:171","msg":"trace[229476496] linearizableReadLoop","detail":"{readStateIndex:981; appliedIndex:979; }","duration":"191.018136ms","start":"2026-02-24T10:52:32.197216Z","end":"2026-02-24T10:52:32.388234Z","steps":["trace[229476496] 'read index received' (duration: 93.345419ms)","trace[229476496] 'applied index is now lower than readState.Index' (duration: 97.671667ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:52:32.388404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.168629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:52:32.388447Z","caller":"traceutil/trace.go:171","msg":"trace[1648600921] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:941; }","duration":"191.270212ms","start":"2026-02-24T10:52:32.197168Z","end":"2026-02-24T10:52:32.388438Z","steps":["trace[1648600921] 'agreement among raft nodes before linearized reading' (duration: 191.156509ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:52:32.388563Z","caller":"traceutil/trace.go:171","msg":"trace[728847514] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"200.125984ms","start":"2026-02-24T10:52:32.188419Z","end":"2026-02-24T10:52:32.388545Z","steps":["trace[728847514] 'process raft request' (duration: 102.091979ms)","trace[728847514] 'compare' (duration: 97.546662ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:52:32.388671Z","caller":"traceutil/trace.go:171","msg":"trace[80861136] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"194.968434ms","start":"2026-02-24T10:52:32.193676Z","end":"2026-02-24T10:52:32.388645Z","steps":["trace[80861136] 'process raft request' (duration: 194.512841ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:52:33.731257Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986420759335236675,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T10:52:34.2319Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986420759335236675,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T10:52:34.308599Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.176401118s","expected-duration":"1s"} {"level":"info","ts":"2026-02-24T10:52:34.309083Z","caller":"traceutil/trace.go:171","msg":"trace[967099207] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"1.176989633s","start":"2026-02-24T10:52:33.132067Z","end":"2026-02-24T10:52:34.309057Z","steps":["trace[967099207] 'process raft request' (duration: 1.176829729s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:52:34.309219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:52:33.132041Z","time spent":"1.177097276s","remote":"127.0.0.1:46850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":790,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-24T10:52:35.427094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000421623s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-24T10:52:35.427211Z","caller":"traceutil/trace.go:171","msg":"trace[659597805] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000612358s","start":"2026-02-24T10:52:33.426581Z","end":"2026-02-24T10:52:35.427194Z","steps":["trace[659597805] 'agreement among raft nodes before linearized reading' (duration: 2.000458204s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:52:35.427258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:52:33.426552Z","time spent":"2.000695539s","remote":"127.0.0.1:46780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/24 10:52:35 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-24T10:52:35.518779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.059426875s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T10:52:35.518927Z","caller":"traceutil/trace.go:171","msg":"trace[1432693361] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"1.204604637s","start":"2026-02-24T10:52:34.314306Z","end":"2026-02-24T10:52:35.518911Z","steps":["trace[1432693361] 'process raft request' (duration: 1.204554115s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:52:35.518978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:52:34.314272Z","time spent":"1.204682089s","remote":"127.0.0.1:46850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":817,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-24T10:52:35.519112Z","caller":"traceutil/trace.go:171","msg":"trace[1504351051] linearizableReadLoop","detail":"{readStateIndex:984; appliedIndex:982; }","duration":"2.288985691s","start":"2026-02-24T10:52:33.230119Z","end":"2026-02-24T10:52:35.519105Z","steps":["trace[1504351051] 'read index received' (duration: 1.078820204s)","trace[1504351051] 'applied index is now lower than readState.Index' (duration: 1.210164636s)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:52:35.519444Z","caller":"traceutil/trace.go:171","msg":"trace[1271822747] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"2.173791743s","start":"2026-02-24T10:52:33.345149Z","end":"2026-02-24T10:52:35.518941Z","steps":["trace[1271822747] 'process raft request' (duration: 1.114124822s)","trace[1271822747] 'compare' (duration: 1.059046946s)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:52:35.51967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.289542814s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-24T10:52:35.519715Z","caller":"traceutil/trace.go:171","msg":"trace[505514305] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:945; }","duration":"2.289632246s","start":"2026-02-24T10:52:33.230072Z","end":"2026-02-24T10:52:35.519704Z","steps":["trace[505514305] 'agreement among raft nodes before linearized reading' (duration: 2.289455272s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:52:35.519745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:52:33.230049Z","time spent":"2.289688708s","remote":"127.0.0.1:47046","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-02-24T10:52:35.519741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:52:33.345121Z","time spent":"2.174448269s","remote":"127.0.0.1:47046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:52:35.520957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.290757945s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-24T10:52:35.520997Z","caller":"traceutil/trace.go:171","msg":"trace[1305073434] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:945; }","duration":"2.290835737s","start":"2026-02-24T10:52:33.230152Z","end":"2026-02-24T10:52:35.520988Z","steps":["trace[1305073434] 'agreement among raft nodes before linearized reading' (duration: 2.290739275s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:52:35.521025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:52:33.230125Z","time spent":"2.290892639s","remote":"127.0.0.1:47046","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-02-24T10:52:35.521107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.358177899s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-24T10:52:35.521158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.290681923s","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-02-24T10:52:35.521156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.128421111s","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-02-24T10:52:35.521195Z","caller":"traceutil/trace.go:171","msg":"trace[1270774985] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:945; }","duration":"2.290745005s","start":"2026-02-24T10:52:33.230439Z","end":"2026-02-24T10:52:35.521184Z","steps":["trace[1270774985] 'agreement among raft nodes before linearized reading' (duration: 2.290637992s)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:52:35.521205Z","caller":"traceutil/trace.go:171","msg":"trace[1798806227] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:945; }","duration":"1.358326473s","start":"2026-02-24T10:52:34.162854Z","end":"2026-02-24T10:52:35.52118Z","steps":["trace[1798806227] 'agreement among raft nodes before linearized reading' (duration: 1.358153409s)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:52:35.521239Z","caller":"traceutil/trace.go:171","msg":"trace[1671261324] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:945; }","duration":"1.128552745s","start":"2026-02-24T10:52:34.392653Z","end":"2026-02-24T10:52:35.521206Z","steps":["trace[1671261324] 'agreement among raft nodes before linearized reading' (duration: 1.128334989s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:52:35.521274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:52:34.162834Z","time spent":"1.358426916s","remote":"127.0.0.1:46976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":42,"response size":30,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-02-24T10:52:35.521281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:52:34.39262Z","time spent":"1.128652387s","remote":"127.0.0.1:47046","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-02-24T10:52:35.521222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:52:33.230423Z","time spent":"2.290791406s","remote":"127.0.0.1:47046","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":"warn","ts":"2026-02-24T10:52:35.52137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.327888137s","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-02-24T10:52:35.52149Z","caller":"traceutil/trace.go:171","msg":"trace[1906987618] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:945; }","duration":"1.328034341s","start":"2026-02-24T10:52:34.193441Z","end":"2026-02-24T10:52:35.521476Z","steps":["trace[1906987618] 'agreement among raft nodes before linearized reading' (duration: 1.327664102s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:52:35.521568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:52:34.193429Z","time spent":"1.328126663s","remote":"127.0.0.1:47046","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-02-24T10:52:35.795545Z","caller":"traceutil/trace.go:171","msg":"trace[1444183084] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"267.95209ms","start":"2026-02-24T10:52:35.527566Z","end":"2026-02-24T10:52:35.795518Z","steps":["trace[1444183084] 'process raft request' (duration: 245.358362ms)","trace[1444183084] 'compare' (duration: 22.398283ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:52:35.795785Z","caller":"traceutil/trace.go:171","msg":"trace[120401917] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"268.049712ms","start":"2026-02-24T10:52:35.527721Z","end":"2026-02-24T10:52:35.79577Z","steps":["trace[120401917] 'process raft request' (duration: 267.925989ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:52:35.7958Z","caller":"traceutil/trace.go:171","msg":"trace[408312318] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"268.095813ms","start":"2026-02-24T10:52:35.527676Z","end":"2026-02-24T10:52:35.795772Z","steps":["trace[408312318] 'process raft request' (duration: 267.798086ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:52:35.795824Z","caller":"traceutil/trace.go:171","msg":"trace[402934802] linearizableReadLoop","detail":"{readStateIndex:989; appliedIndex:985; }","duration":"267.905458ms","start":"2026-02-24T10:52:35.527906Z","end":"2026-02-24T10:52:35.795812Z","steps":["trace[402934802] 'read index received' (duration: 21.179923ms)","trace[402934802] 'applied index is now lower than readState.Index' (duration: 246.724855ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:52:35.795954Z","caller":"traceutil/trace.go:171","msg":"trace[153677115] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"267.028376ms","start":"2026-02-24T10:52:35.528917Z","end":"2026-02-24T10:52:35.795945Z","steps":["trace[153677115] 'process raft request' (duration: 266.81248ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:52:35.795975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.048222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18972943c12b0ca4\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-02-24T10:52:35.796011Z","caller":"traceutil/trace.go:171","msg":"trace[868379661] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18972943c12b0ca4; range_end:; response_count:1; response_revision:950; }","duration":"268.119024ms","start":"2026-02-24T10:52:35.52788Z","end":"2026-02-24T10:52:35.795999Z","steps":["trace[868379661] 'agreement among raft nodes before linearized reading' (duration: 267.96633ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:52:35.796008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.519369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:52:35.79605Z","caller":"traceutil/trace.go:171","msg":"trace[1301073537] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:950; }","duration":"265.59695ms","start":"2026-02-24T10:52:35.530443Z","end":"2026-02-24T10:52:35.79604Z","steps":["trace[1301073537] 'agreement among raft nodes before linearized reading' (duration: 265.523708ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:52:37.773033Z","caller":"traceutil/trace.go:171","msg":"trace[1021801582] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"202.974006ms","start":"2026-02-24T10:52:37.570041Z","end":"2026-02-24T10:52:37.773015Z","steps":["trace[1021801582] 'process raft request' (duration: 202.847762ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:52:38.101985Z","caller":"traceutil/trace.go:171","msg":"trace[35299479] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"297.313517ms","start":"2026-02-24T10:52:37.804639Z","end":"2026-02-24T10:52:38.101953Z","steps":["trace[35299479] 'process raft request' (duration: 297.109912ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:52:38.525805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.435638ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T10:52:38.526111Z","caller":"traceutil/trace.go:171","msg":"trace[1180555762] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"719.769063ms","start":"2026-02-24T10:52:37.806322Z","end":"2026-02-24T10:52:38.526091Z","steps":["trace[1180555762] 'process raft request' (duration: 719.714611ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:52:38.526133Z","caller":"traceutil/trace.go:171","msg":"trace[1189012436] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"720.632115ms","start":"2026-02-24T10:52:37.80548Z","end":"2026-02-24T10:52:38.526112Z","steps":["trace[1189012436] 'process raft request' (duration: 335.757124ms)","trace[1189012436] 'compare' (duration: 384.272735ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:52:38.526221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:52:37.80628Z","time spent":"719.890136ms","remote":"127.0.0.1:47046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T10:52:38.526233Z","caller":"traceutil/trace.go:171","msg":"trace[2031019387] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"720.174754ms","start":"2026-02-24T10:52:37.806Z","end":"2026-02-24T10:52:38.526175Z","steps":["trace[2031019387] 'process raft request' (duration: 719.914257ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:52:38.526317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:52:37.805471Z","time spent":"720.775099ms","remote":"127.0.0.1:47046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:52:38.526509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:52:37.805977Z","time spent":"720.394499ms","remote":"127.0.0.1:47046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:52:39.927002Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986420759335236699,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T10:52:40.368608Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.453472547s","expected-duration":"1s"} {"level":"info","ts":"2026-02-24T10:52:40.389606Z","caller":"traceutil/trace.go:171","msg":"trace[1580336747] linearizableReadLoop","detail":"{readStateIndex:998; appliedIndex:997; }","duration":"963.499663ms","start":"2026-02-24T10:52:39.426085Z","end":"2026-02-24T10:52:40.389585Z","steps":["trace[1580336747] 'read index received' (duration: 942.647648ms)","trace[1580336747] 'applied index is now lower than readState.Index' (duration: 20.849865ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:52:40.390218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"964.134479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:52:40.390224Z","caller":"traceutil/trace.go:171","msg":"trace[577631211] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"1.475168902s","start":"2026-02-24T10:52:38.915027Z","end":"2026-02-24T10:52:40.390196Z","steps":["trace[577631211] 'process raft request' (duration: 1.453773724s)","trace[577631211] 'compare' (duration: 20.029933ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:52:40.390251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.123661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-02-24T10:52:40.390428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.766142ms","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-02-24T10:52:40.390504Z","caller":"traceutil/trace.go:171","msg":"trace[52561950] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:957; }","duration":"284.890385ms","start":"2026-02-24T10:52:40.105596Z","end":"2026-02-24T10:52:40.390486Z","steps":["trace[52561950] 'agreement among raft nodes before linearized reading' (duration: 284.598158ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:52:40.390477Z","caller":"traceutil/trace.go:171","msg":"trace[1868673825] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:957; }","duration":"613.384238ms","start":"2026-02-24T10:52:39.777075Z","end":"2026-02-24T10:52:40.390459Z","steps":["trace[1868673825] 'agreement among raft nodes before linearized reading' (duration: 613.05925ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:52:40.390273Z","caller":"traceutil/trace.go:171","msg":"trace[1211654932] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:957; }","duration":"964.266412ms","start":"2026-02-24T10:52:39.425994Z","end":"2026-02-24T10:52:40.39026Z","steps":["trace[1211654932] 'agreement among raft nodes before linearized reading' (duration: 964.174759ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:52:40.390577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:52:39.425974Z","time spent":"964.58911ms","remote":"127.0.0.1:46780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T10:52:40.390342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:52:38.915004Z","time spent":"1.475276625s","remote":"127.0.0.1:47046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:52:40.390582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:52:39.777054Z","time spent":"613.50545ms","remote":"127.0.0.1:47046","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-02-24T10:55:52.235426Z","caller":"traceutil/trace.go:171","msg":"trace[2113691087] transaction","detail":"{read_only:false; response_revision:1895; number_of_response:1; }","duration":"133.638549ms","start":"2026-02-24T10:55:52.101762Z","end":"2026-02-24T10:55:52.235401Z","steps":["trace[2113691087] 'process raft request' (duration: 133.506586ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:56:00.961322Z","caller":"traceutil/trace.go:171","msg":"trace[1555004069] transaction","detail":"{read_only:false; response_revision:1940; number_of_response:1; }","duration":"105.841189ms","start":"2026-02-24T10:56:00.855459Z","end":"2026-02-24T10:56:00.9613Z","steps":["trace[1555004069] 'process raft request' (duration: 70.888698ms)","trace[1555004069] 'compare' (duration: 34.760446ms)"],"step_count":2}