{"level":"warn","ts":"2026-02-24T10:42:05.38121Z","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:42:05.381305Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.116: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.116:2380","--initial-cluster=instance=https://199.204.45.116:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.116:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.116: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:42:05.381382Z","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:42:05.381393Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.116:2380"]} {"level":"info","ts":"2026-02-24T10:42:05.381417Z","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:42:05.382244Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"]} {"level":"info","ts":"2026-02-24T10:42:05.382332Z","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.116:2380"],"listen-peer-urls":["https://199.204.45.116:2380"],"advertise-client-urls":["https://199.204.45.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.116: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:42:05.387842Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.107621ms"} {"level":"info","ts":"2026-02-24T10:42:05.394905Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5caa70ef1dd6dc04","cluster-id":"45afccab143d2c82"} {"level":"info","ts":"2026-02-24T10:42:05.394984Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=()"} {"level":"info","ts":"2026-02-24T10:42:05.395018Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became follower at term 0"} {"level":"info","ts":"2026-02-24T10:42:05.395037Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5caa70ef1dd6dc04 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-24T10:42:05.395059Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became follower at term 1"} {"level":"info","ts":"2026-02-24T10:42:05.39512Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=(6677273569830296580)"} {"level":"warn","ts":"2026-02-24T10:42:05.411372Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-24T10:42:05.415967Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-24T10:42:05.41739Z","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:42:05.420517Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5caa70ef1dd6dc04","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-24T10:42:05.420779Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5caa70ef1dd6dc04","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-24T10:42:05.421087Z","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:42:05.4212Z","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:42:05.421251Z","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:42:05.421695Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=(6677273569830296580)"} {"level":"info","ts":"2026-02-24T10:42:05.421795Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"45afccab143d2c82","local-member-id":"5caa70ef1dd6dc04","added-peer-id":"5caa70ef1dd6dc04","added-peer-peer-urls":["https://199.204.45.116:2380"]} {"level":"info","ts":"2026-02-24T10:42:05.427087Z","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:42:05.427273Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.116:2380"} {"level":"info","ts":"2026-02-24T10:42:05.427301Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.116:2380"} {"level":"info","ts":"2026-02-24T10:42:05.428101Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5caa70ef1dd6dc04","initial-advertise-peer-urls":["https://199.204.45.116:2380"],"listen-peer-urls":["https://199.204.45.116:2380"],"advertise-client-urls":["https://199.204.45.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-24T10:42:05.428145Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-24T10:42:05.696337Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 is starting a new election at term 1"} {"level":"info","ts":"2026-02-24T10:42:05.696401Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-24T10:42:05.696442Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 received MsgPreVoteResp from 5caa70ef1dd6dc04 at term 1"} {"level":"info","ts":"2026-02-24T10:42:05.696462Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became candidate at term 2"} {"level":"info","ts":"2026-02-24T10:42:05.696475Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 received MsgVoteResp from 5caa70ef1dd6dc04 at term 2"} {"level":"info","ts":"2026-02-24T10:42:05.696502Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became leader at term 2"} {"level":"info","ts":"2026-02-24T10:42:05.696516Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5caa70ef1dd6dc04 elected leader 5caa70ef1dd6dc04 at term 2"} {"level":"info","ts":"2026-02-24T10:42:05.701084Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T10:42:05.703078Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-24T10:42:05.703443Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-24T10:42:05.703606Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-24T10:42:05.703699Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-24T10:42:05.705463Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.116:2379"} {"level":"info","ts":"2026-02-24T10:42:05.705596Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5caa70ef1dd6dc04","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.116:2379]}","request-path":"/0/members/5caa70ef1dd6dc04/attributes","cluster-id":"45afccab143d2c82","publish-timeout":"7s"} {"level":"info","ts":"2026-02-24T10:42:05.705689Z","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:42:05.711577Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"45afccab143d2c82","local-member-id":"5caa70ef1dd6dc04","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T10:42:05.711675Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T10:42:05.711705Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-02-24T10:42:10.450288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.026548392s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-24T10:42:10.450611Z","caller":"traceutil/trace.go:171","msg":"trace[2116736334] transaction","detail":"{read_only:false; response_revision:199; number_of_response:1; }","duration":"1.09301063s","start":"2026-02-24T10:42:09.357575Z","end":"2026-02-24T10:42:10.450586Z","steps":["trace[2116736334] 'process raft request' (duration: 65.563188ms)","trace[2116736334] 'compare' (duration: 1.026357872s)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:42:10.450487Z","caller":"traceutil/trace.go:171","msg":"trace[302953979] linearizableReadLoop","detail":"{readStateIndex:204; appliedIndex:203; }","duration":"222.146245ms","start":"2026-02-24T10:42:10.228321Z","end":"2026-02-24T10:42:10.450468Z","steps":["trace[302953979] 'read index received' (duration: 31.742µs)","trace[302953979] 'applied index is now lower than readState.Index' (duration: 222.113073ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:42:10.450852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:42:09.357526Z","time spent":"1.093251441s","remote":"127.0.0.1:44930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-24T10:42:10.450851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.48111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T10:42:10.451006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.635559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:42:10.451083Z","caller":"traceutil/trace.go:171","msg":"trace[1767671395] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:199; }","duration":"222.756372ms","start":"2026-02-24T10:42:10.228287Z","end":"2026-02-24T10:42:10.451044Z","steps":["trace[1767671395] 'agreement among raft nodes before linearized reading' (duration: 222.392755ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:42:10.45112Z","caller":"traceutil/trace.go:171","msg":"trace[675503088] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:199; }","duration":"159.870618ms","start":"2026-02-24T10:42:10.291232Z","end":"2026-02-24T10:42:10.451103Z","steps":["trace[675503088] 'agreement among raft nodes before linearized reading' (duration: 159.68517ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:42:11.997365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"681.532541ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-24T10:42:11.99752Z","caller":"traceutil/trace.go:171","msg":"trace[1848380654] transaction","detail":"{read_only:false; response_revision:200; number_of_response:1; }","duration":"1.541850202s","start":"2026-02-24T10:42:10.455644Z","end":"2026-02-24T10:42:11.997494Z","steps":["trace[1848380654] 'process raft request' (duration: 860.100761ms)","trace[1848380654] 'compare' (duration: 680.935945ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:42:11.997598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:42:10.45562Z","time spent":"1.541949017s","remote":"127.0.0.1:44862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-24T10:42:13.679124Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.363288786s","expected-duration":"1s"} {"level":"info","ts":"2026-02-24T10:42:13.688886Z","caller":"traceutil/trace.go:171","msg":"trace[1833241607] transaction","detail":"{read_only:false; response_revision:203; number_of_response:1; }","duration":"2.032435385s","start":"2026-02-24T10:42:11.656422Z","end":"2026-02-24T10:42:13.688858Z","steps":["trace[1833241607] 'process raft request' (duration: 2.032383963s)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:42:13.689065Z","caller":"traceutil/trace.go:171","msg":"trace[409461507] linearizableReadLoop","detail":"{readStateIndex:207; appliedIndex:204; }","duration":"2.81891699s","start":"2026-02-24T10:42:10.870103Z","end":"2026-02-24T10:42:13.68902Z","steps":["trace[409461507] 'read index received' (duration: 445.786679ms)","trace[409461507] 'applied index is now lower than readState.Index' (duration: 2.373125941s)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:42:13.689042Z","caller":"traceutil/trace.go:171","msg":"trace[1023607219] transaction","detail":"{read_only:false; response_revision:201; number_of_response:1; }","duration":"3.233071425s","start":"2026-02-24T10:42:10.455859Z","end":"2026-02-24T10:42:13.68893Z","steps":["trace[1023607219] 'process raft request' (duration: 3.223511942s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:42:13.689156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:42:11.656396Z","time spent":"2.032642784s","remote":"127.0.0.1:44792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2883,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-24T10:42:13.689143Z","caller":"traceutil/trace.go:171","msg":"trace[529407084] transaction","detail":"{read_only:false; response_revision:202; number_of_response:1; }","duration":"3.232835315s","start":"2026-02-24T10:42:10.45627Z","end":"2026-02-24T10:42:13.689106Z","steps":["trace[529407084] 'process raft request' (duration: 3.232471409s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:42:13.689261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.819154821s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T10:42:13.689252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:42:10.455844Z","time spent":"3.233297746s","remote":"127.0.0.1:44862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-24T10:42:13.689299Z","caller":"traceutil/trace.go:171","msg":"trace[261543070] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:203; }","duration":"2.819244495s","start":"2026-02-24T10:42:10.870043Z","end":"2026-02-24T10:42:13.689287Z","steps":["trace[261543070] 'agreement among raft nodes before linearized reading' (duration: 2.81911838s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:42:13.689331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:42:10.870016Z","time spent":"2.819308008s","remote":"127.0.0.1:44862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-24T10:42:13.68934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:42:10.456252Z","time spent":"3.233000993s","remote":"127.0.0.1:44698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1342,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-24T10:42:13.689358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.041945651s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:42:13.689435Z","caller":"traceutil/trace.go:171","msg":"trace[1624621942] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:203; }","duration":"2.042064387s","start":"2026-02-24T10:42:11.647333Z","end":"2026-02-24T10:42:13.689397Z","steps":["trace[1624621942] 'agreement among raft nodes before linearized reading' (duration: 2.041945222s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:42:13.689471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.586516076s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:5491"} {"level":"warn","ts":"2026-02-24T10:42:13.689477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:42:11.647311Z","time spent":"2.042157671s","remote":"127.0.0.1:44862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-24T10:42:13.689502Z","caller":"traceutil/trace.go:171","msg":"trace[1305834430] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:203; }","duration":"1.586588859s","start":"2026-02-24T10:42:12.102905Z","end":"2026-02-24T10:42:13.689494Z","steps":["trace[1305834430] 'agreement among raft nodes before linearized reading' (duration: 1.586522446s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:42:13.689529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:42:12.102884Z","time spent":"1.586639161s","remote":"127.0.0.1:44792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":5513,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"warn","ts":"2026-02-24T10:42:13.689627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.686313223s","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:42:13.689656Z","caller":"traceutil/trace.go:171","msg":"trace[229572263] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:203; }","duration":"1.686373465s","start":"2026-02-24T10:42:12.003273Z","end":"2026-02-24T10:42:13.689646Z","steps":["trace[229572263] 'agreement among raft nodes before linearized reading' (duration: 1.686313252s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:42:13.689682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:42:12.003253Z","time spent":"1.686422887s","remote":"127.0.0.1:44862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-24T10:42:36.056545Z","caller":"traceutil/trace.go:171","msg":"trace[102300921] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"211.054765ms","start":"2026-02-24T10:42:35.845461Z","end":"2026-02-24T10:42:36.056515Z","steps":["trace[102300921] 'process raft request' (duration: 210.989523ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:42:36.057041Z","caller":"traceutil/trace.go:171","msg":"trace[1325314920] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"211.728091ms","start":"2026-02-24T10:42:35.845299Z","end":"2026-02-24T10:42:36.057027Z","steps":["trace[1325314920] 'process raft request' (duration: 180.839295ms)","trace[1325314920] 'compare' (duration: 30.153318ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:42:36.057162Z","caller":"traceutil/trace.go:171","msg":"trace[980885576] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"211.820965ms","start":"2026-02-24T10:42:35.845333Z","end":"2026-02-24T10:42:36.057154Z","steps":["trace[980885576] 'process raft request' (duration: 211.079626ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:42:37.949729Z","caller":"traceutil/trace.go:171","msg":"trace[989091841] linearizableReadLoop","detail":"{readStateIndex:449; appliedIndex:448; }","duration":"170.920708ms","start":"2026-02-24T10:42:37.778784Z","end":"2026-02-24T10:42:37.949705Z","steps":["trace[989091841] 'read index received' (duration: 170.543844ms)","trace[989091841] 'applied index is now lower than readState.Index' (duration: 376.004µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:42:37.949819Z","caller":"traceutil/trace.go:171","msg":"trace[1475723283] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"204.326528ms","start":"2026-02-24T10:42:37.745474Z","end":"2026-02-24T10:42:37.9498Z","steps":["trace[1475723283] 'process raft request' (duration: 203.918382ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:42:37.949985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.159537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:3 size:12669"} {"level":"info","ts":"2026-02-24T10:42:37.950112Z","caller":"traceutil/trace.go:171","msg":"trace[1237138949] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:3; response_revision:430; }","duration":"171.335074ms","start":"2026-02-24T10:42:37.778744Z","end":"2026-02-24T10:42:37.950079Z","steps":["trace[1237138949] 'agreement among raft nodes before linearized reading' (duration: 171.086505ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:42:38.1755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.170741ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T10:42:38.175615Z","caller":"traceutil/trace.go:171","msg":"trace[1456783596] linearizableReadLoop","detail":"{readStateIndex:450; appliedIndex:449; }","duration":"112.842044ms","start":"2026-02-24T10:42:38.062754Z","end":"2026-02-24T10:42:38.175596Z","steps":["trace[1456783596] 'read index received' (duration: 44.042µs)","trace[1456783596] 'applied index is now lower than readState.Index' (duration: 112.796332ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:42:38.175618Z","caller":"traceutil/trace.go:171","msg":"trace[778795903] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"220.976301ms","start":"2026-02-24T10:42:37.954619Z","end":"2026-02-24T10:42:38.175596Z","steps":["trace[778795903] 'process raft request' (duration: 47.458856ms)","trace[778795903] 'compare' (duration: 173.056516ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:42:38.175723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.928437ms","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:42:38.175821Z","caller":"traceutil/trace.go:171","msg":"trace[2048755706] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:431; }","duration":"113.049192ms","start":"2026-02-24T10:42:38.062751Z","end":"2026-02-24T10:42:38.1758Z","steps":["trace[2048755706] 'agreement among raft nodes before linearized reading' (duration: 112.864305ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:42:38.175941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.789849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:3 size:12669"} {"level":"warn","ts":"2026-02-24T10:42:38.175809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.048291ms","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:42:38.175909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.115744ms","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:42:38.175993Z","caller":"traceutil/trace.go:171","msg":"trace[322385826] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:3; response_revision:431; }","duration":"105.876333ms","start":"2026-02-24T10:42:38.070098Z","end":"2026-02-24T10:42:38.175975Z","steps":["trace[322385826] 'agreement among raft nodes before linearized reading' (duration: 105.758379ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:42:38.176Z","caller":"traceutil/trace.go:171","msg":"trace[1701027805] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:431; }","duration":"113.26568ms","start":"2026-02-24T10:42:38.062721Z","end":"2026-02-24T10:42:38.175986Z","steps":["trace[1701027805] 'agreement among raft nodes before linearized reading' (duration: 112.975179ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:42:38.176098Z","caller":"traceutil/trace.go:171","msg":"trace[899320672] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:431; }","duration":"113.246609ms","start":"2026-02-24T10:42:38.062751Z","end":"2026-02-24T10:42:38.175998Z","steps":["trace[899320672] 'agreement among raft nodes before linearized reading' (duration: 113.060212ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:42:38.69807Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853968727283980023,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T10:42:39.199006Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853968727283980023,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T10:42:39.37779Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.198217848s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-24T10:42:39.544919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.730866ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T10:42:39.545059Z","caller":"traceutil/trace.go:171","msg":"trace[628621855] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"1.365571408s","start":"2026-02-24T10:42:38.179461Z","end":"2026-02-24T10:42:39.545032Z","steps":["trace[628621855] 'process raft request' (duration: 1.198618733s)","trace[628621855] 'compare' (duration: 166.074341ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:42:39.545163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:42:38.179438Z","time spent":"1.365662982s","remote":"127.0.0.1:44862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T10:42:39.757077Z","caller":"traceutil/trace.go:171","msg":"trace[611082574] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"1.576658958s","start":"2026-02-24T10:42:38.180387Z","end":"2026-02-24T10:42:39.757046Z","steps":["trace[611082574] 'process raft request' (duration: 1.541792852s)","trace[611082574] 'compare' (duration: 34.671078ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:42:39.757262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:42:38.180374Z","time spent":"1.576784443s","remote":"127.0.0.1:44862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T10:42:39.757262Z","caller":"traceutil/trace.go:171","msg":"trace[1891391966] linearizableReadLoop","detail":"{readStateIndex:453; appliedIndex:450; }","duration":"1.559791054s","start":"2026-02-24T10:42:38.197425Z","end":"2026-02-24T10:42:39.757216Z","steps":["trace[1891391966] 'read index received' (duration: 1.180668818s)","trace[1891391966] 'applied index is now lower than readState.Index' (duration: 379.120366ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:42:39.757408Z","caller":"traceutil/trace.go:171","msg":"trace[326468521] transaction","detail":"{read_only:false; response_revision:434; number_of_response:1; }","duration":"1.576683829s","start":"2026-02-24T10:42:38.180714Z","end":"2026-02-24T10:42:39.757398Z","steps":["trace[326468521] 'process raft request' (duration: 1.576287124s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:42:39.757458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"847.42849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.116\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-24T10:42:39.757498Z","caller":"traceutil/trace.go:171","msg":"trace[1753176856] range","detail":"{range_begin:/registry/masterleases/199.204.45.116; range_end:; response_count:1; response_revision:434; }","duration":"847.510074ms","start":"2026-02-24T10:42:38.909977Z","end":"2026-02-24T10:42:39.757487Z","steps":["trace[1753176856] 'agreement among raft nodes before linearized reading' (duration: 847.444451ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:42:39.75753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:42:38.909956Z","time spent":"847.567876ms","remote":"127.0.0.1:44628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.116\" "} {"level":"warn","ts":"2026-02-24T10:42:39.757539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:42:38.180706Z","time spent":"1.576737581s","remote":"127.0.0.1:44862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:42:39.757693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.560246722s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:3 size:12669"} {"level":"info","ts":"2026-02-24T10:42:39.757774Z","caller":"traceutil/trace.go:171","msg":"trace[1494665777] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:3; response_revision:434; }","duration":"1.560370717s","start":"2026-02-24T10:42:38.197381Z","end":"2026-02-24T10:42:39.757752Z","steps":["trace[1494665777] 'agreement among raft nodes before linearized reading' (duration: 1.560224701s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:42:39.75782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:42:38.197366Z","time spent":"1.56044518s","remote":"127.0.0.1:44688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":3,"response size":12691,"request content":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" "} {"level":"warn","ts":"2026-02-24T10:42:39.757772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.444982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:42:39.757948Z","caller":"traceutil/trace.go:171","msg":"trace[712511295] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:434; }","duration":"650.6463ms","start":"2026-02-24T10:42:39.107261Z","end":"2026-02-24T10:42:39.757907Z","steps":["trace[712511295] 'agreement among raft nodes before linearized reading' (duration: 650.468513ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:42:39.758053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:42:39.107238Z","time spent":"650.780835ms","remote":"127.0.0.1:44588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-24T10:42:40.592156Z","caller":"traceutil/trace.go:171","msg":"trace[922764358] transaction","detail":"{read_only:false; response_revision:436; number_of_response:1; }","duration":"407.706884ms","start":"2026-02-24T10:42:40.18442Z","end":"2026-02-24T10:42:40.592127Z","steps":["trace[922764358] 'process raft request' (duration: 407.561499ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:42:40.592339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:42:40.184394Z","time spent":"407.85625ms","remote":"127.0.0.1:44862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:42:43.733453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.074119053s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:42:43.733562Z","caller":"traceutil/trace.go:171","msg":"trace[1604530892] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:0; response_revision:436; }","duration":"3.074282779s","start":"2026-02-24T10:42:40.659252Z","end":"2026-02-24T10:42:43.733535Z","steps":["trace[1604530892] 'range keys from in-memory index tree' (duration: 3.0740306s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:42:43.733614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.972355205s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-24T10:42:43.73362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:42:40.659232Z","time spent":"3.074376803s","remote":"127.0.0.1:44802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":27,"request content":"key:\"/registry/serviceaccounts/kube-system/cilium\" "} {"level":"info","ts":"2026-02-24T10:42:43.73366Z","caller":"traceutil/trace.go:171","msg":"trace[2037280467] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:436; }","duration":"1.972417988s","start":"2026-02-24T10:42:41.761229Z","end":"2026-02-24T10:42:43.733647Z","steps":["trace[2037280467] 'range keys from in-memory index tree' (duration: 1.972269643s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:42:43.733703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:42:41.761224Z","time spent":"1.97247018s","remote":"127.0.0.1:44862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-24T10:42:43.733704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.137705252s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-24T10:42:43.733973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.627577807s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T10:42:43.733973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.972811123s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-24T10:42:43.734057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.474341844s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:42:43.734077Z","caller":"traceutil/trace.go:171","msg":"trace[1684176362] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:436; }","duration":"1.97297257s","start":"2026-02-24T10:42:41.761084Z","end":"2026-02-24T10:42:43.734057Z","steps":["trace[1684176362] 'range keys from in-memory index tree' (duration: 1.972688999s)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:42:43.734094Z","caller":"traceutil/trace.go:171","msg":"trace[1521530017] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:436; }","duration":"1.474417329s","start":"2026-02-24T10:42:42.259666Z","end":"2026-02-24T10:42:43.734083Z","steps":["trace[1521530017] 'range keys from in-memory index tree' (duration: 1.474274762s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:42:43.734125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:42:42.259646Z","time spent":"1.474472231s","remote":"127.0.0.1:44588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T10:42:43.734141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.184388945s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-24T10:42:43.734184Z","caller":"traceutil/trace.go:171","msg":"trace[825108115] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:436; }","duration":"2.184469558s","start":"2026-02-24T10:42:41.549702Z","end":"2026-02-24T10:42:43.734171Z","steps":["trace[825108115] 'range keys from in-memory index tree' (duration: 2.18425209s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:42:43.734225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:42:41.549682Z","time spent":"2.184531801s","remote":"127.0.0.1:44862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-24T10:42:43.734137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:42:41.761064Z","time spent":"1.973058222s","remote":"127.0.0.1:44862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-24T10:42:43.734078Z","caller":"traceutil/trace.go:171","msg":"trace[480746792] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:436; }","duration":"2.627710821s","start":"2026-02-24T10:42:41.106339Z","end":"2026-02-24T10:42:43.734049Z","steps":["trace[480746792] 'range keys from in-memory index tree' (duration: 2.627465812s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:42:43.734413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:42:41.106323Z","time spent":"2.628077356s","remote":"127.0.0.1:44588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T10:42:43.734476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"735.724033ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:42:43.734614Z","caller":"traceutil/trace.go:171","msg":"trace[1971016659] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:436; }","duration":"735.867688ms","start":"2026-02-24T10:42:42.998731Z","end":"2026-02-24T10:42:43.734599Z","steps":["trace[1971016659] 'range keys from in-memory index tree' (duration: 735.711272ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:42:43.734633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.060202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:42:43.734682Z","caller":"traceutil/trace.go:171","msg":"trace[102309823] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:436; }","duration":"627.143066ms","start":"2026-02-24T10:42:43.107527Z","end":"2026-02-24T10:42:43.73467Z","steps":["trace[102309823] 'range keys from in-memory index tree' (duration: 626.99711ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:42:43.73387Z","caller":"traceutil/trace.go:171","msg":"trace[214535465] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:436; }","duration":"1.13793466s","start":"2026-02-24T10:42:42.595913Z","end":"2026-02-24T10:42:43.733848Z","steps":["trace[214535465] 'range keys from in-memory index tree' (duration: 1.137619348s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:42:43.734709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:42:43.107506Z","time spent":"627.195657ms","remote":"127.0.0.1:44592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T10:42:43.736992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:42:42.595894Z","time spent":"1.141063279s","remote":"127.0.0.1:44862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-24T10:42:44.104122Z","caller":"traceutil/trace.go:171","msg":"trace[2019537093] linearizableReadLoop","detail":"{readStateIndex:457; appliedIndex:456; }","duration":"363.16755ms","start":"2026-02-24T10:42:43.740925Z","end":"2026-02-24T10:42:44.104092Z","steps":["trace[2019537093] 'read index received' (duration: 363.063026ms)","trace[2019537093] 'applied index is now lower than readState.Index' (duration: 103.194µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:42:44.104162Z","caller":"traceutil/trace.go:171","msg":"trace[982085529] transaction","detail":"{read_only:false; response_revision:437; number_of_response:1; }","duration":"363.284394ms","start":"2026-02-24T10:42:43.740839Z","end":"2026-02-24T10:42:44.104123Z","steps":["trace[982085529] 'process raft request' (duration: 363.125498ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:42:44.104313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.366787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/cilium-config\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:42:44.104407Z","caller":"traceutil/trace.go:171","msg":"trace[1507483875] range","detail":"{range_begin:/registry/configmaps/kube-system/cilium-config; range_end:; response_count:0; response_revision:437; }","duration":"363.504263ms","start":"2026-02-24T10:42:43.740883Z","end":"2026-02-24T10:42:44.104388Z","steps":["trace[1507483875] 'agreement among raft nodes before linearized reading' (duration: 363.315396ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:42:44.10444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:42:43.740866Z","time spent":"363.569075ms","remote":"127.0.0.1:44698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":27,"request content":"key:\"/registry/configmaps/kube-system/cilium-config\" "} {"level":"warn","ts":"2026-02-24T10:42:44.104428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:42:43.740824Z","time spent":"363.492833ms","remote":"127.0.0.1:44672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-24T10:42:44.156172Z","caller":"traceutil/trace.go:171","msg":"trace[1641103645] transaction","detail":"{read_only:false; response_revision:438; number_of_response:1; }","duration":"414.991618ms","start":"2026-02-24T10:42:43.741153Z","end":"2026-02-24T10:42:44.156145Z","steps":["trace[1641103645] 'process raft request' (duration: 409.055312ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:42:44.156336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:42:43.741145Z","time spent":"415.101142ms","remote":"127.0.0.1:44862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T10:42:44.156426Z","caller":"traceutil/trace.go:171","msg":"trace[1760665320] transaction","detail":"{read_only:false; response_revision:439; number_of_response:1; }","duration":"415.213127ms","start":"2026-02-24T10:42:43.741202Z","end":"2026-02-24T10:42:44.156416Z","steps":["trace[1760665320] 'process raft request' (duration: 414.892864ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:42:44.156476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:42:43.741198Z","time spent":"415.252148ms","remote":"127.0.0.1:44862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T10:42:44.156503Z","caller":"traceutil/trace.go:171","msg":"trace[2058730632] transaction","detail":"{read_only:false; response_revision:440; number_of_response:1; }","duration":"415.231908ms","start":"2026-02-24T10:42:43.741233Z","end":"2026-02-24T10:42:44.156465Z","steps":["trace[2058730632] 'process raft request' (duration: 415.077512ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:42:44.156683Z","caller":"traceutil/trace.go:171","msg":"trace[1437512903] transaction","detail":"{read_only:false; response_revision:441; number_of_response:1; }","duration":"414.759988ms","start":"2026-02-24T10:42:43.741871Z","end":"2026-02-24T10:42:44.156631Z","steps":["trace[1437512903] 'process raft request' (duration: 414.54909ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:42:44.156724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:42:43.741857Z","time spent":"414.845542ms","remote":"127.0.0.1:44862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:42:44.156729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:42:43.741229Z","time spent":"415.362343ms","remote":"127.0.0.1:44862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:42:44.168809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.420843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:42:44.168884Z","caller":"traceutil/trace.go:171","msg":"trace[140257026] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:441; }","duration":"425.515767ms","start":"2026-02-24T10:42:43.74335Z","end":"2026-02-24T10:42:44.168866Z","steps":["trace[140257026] 'agreement among raft nodes before linearized reading' (duration: 425.388302ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:42:44.168926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:42:43.743341Z","time spent":"425.573479ms","remote":"127.0.0.1:44588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-24T10:42:44.452183Z","caller":"traceutil/trace.go:171","msg":"trace[1832034727] transaction","detail":"{read_only:false; response_revision:442; number_of_response:1; }","duration":"181.48913ms","start":"2026-02-24T10:42:44.270656Z","end":"2026-02-24T10:42:44.452145Z","steps":["trace[1832034727] 'process raft request' (duration: 181.129637ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:42:44.748631Z","caller":"traceutil/trace.go:171","msg":"trace[576783498] transaction","detail":"{read_only:false; response_revision:443; number_of_response:1; }","duration":"255.736268ms","start":"2026-02-24T10:42:44.492857Z","end":"2026-02-24T10:42:44.748594Z","steps":["trace[576783498] 'process raft request' (duration: 183.599329ms)","trace[576783498] 'compare' (duration: 71.766815ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:42:44.748687Z","caller":"traceutil/trace.go:171","msg":"trace[1043005285] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"255.750559ms","start":"2026-02-24T10:42:44.49291Z","end":"2026-02-24T10:42:44.74866Z","steps":["trace[1043005285] 'process raft request' (duration: 255.700487ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:42:45.340329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.918403ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-24T10:42:45.340439Z","caller":"traceutil/trace.go:171","msg":"trace[1563428510] linearizableReadLoop","detail":"{readStateIndex:466; appliedIndex:465; }","duration":"233.702996ms","start":"2026-02-24T10:42:45.106717Z","end":"2026-02-24T10:42:45.34042Z","steps":["trace[1563428510] 'read index received' (duration: 124.606286ms)","trace[1563428510] 'applied index is now lower than readState.Index' (duration: 109.09559ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:42:45.340488Z","caller":"traceutil/trace.go:171","msg":"trace[1113969732] transaction","detail":"{read_only:false; response_revision:445; number_of_response:1; }","duration":"585.883625ms","start":"2026-02-24T10:42:44.754588Z","end":"2026-02-24T10:42:45.340472Z","steps":["trace[1113969732] 'process raft request' (duration: 476.749064ms)","trace[1113969732] 'compare' (duration: 108.751446ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:42:45.340556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:42:44.754548Z","time spent":"585.982199ms","remote":"127.0.0.1:44698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6519,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-24T10:42:45.340548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.836841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:42:45.34073Z","caller":"traceutil/trace.go:171","msg":"trace[594046964] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:445; }","duration":"234.052269ms","start":"2026-02-24T10:42:45.106666Z","end":"2026-02-24T10:42:45.340718Z","steps":["trace[594046964] 'agreement among raft nodes before linearized reading' (duration: 233.80761ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:42:45.740044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.154871ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-24T10:42:45.740167Z","caller":"traceutil/trace.go:171","msg":"trace[498843949] transaction","detail":"{read_only:false; response_revision:446; number_of_response:1; }","duration":"395.232799ms","start":"2026-02-24T10:42:45.344911Z","end":"2026-02-24T10:42:45.740144Z","steps":["trace[498843949] 'process raft request' (duration: 215.884021ms)","trace[498843949] 'compare' (duration: 178.949903ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:42:45.740224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:42:45.344902Z","time spent":"395.298521ms","remote":"127.0.0.1:44932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2364,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-24T10:42:45.797085Z","caller":"traceutil/trace.go:171","msg":"trace[254240789] transaction","detail":"{read_only:false; response_revision:447; number_of_response:1; }","duration":"451.54521ms","start":"2026-02-24T10:42:45.345514Z","end":"2026-02-24T10:42:45.797059Z","steps":["trace[254240789] 'process raft request' (duration: 451.433816ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:42:45.797206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:42:45.345495Z","time spent":"451.656225ms","remote":"127.0.0.1:44932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1639,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-24T10:42:46.141926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.090201ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-24T10:42:46.142043Z","caller":"traceutil/trace.go:171","msg":"trace[2032312676] transaction","detail":"{read_only:false; response_revision:455; number_of_response:1; }","duration":"158.588717ms","start":"2026-02-24T10:42:45.983436Z","end":"2026-02-24T10:42:46.142025Z","steps":["trace[2032312676] 'process raft request' (duration: 51.320979ms)","trace[2032312676] 'compare' (duration: 106.978337ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:42:46.257891Z","caller":"traceutil/trace.go:171","msg":"trace[1444752948] transaction","detail":"{read_only:false; response_revision:456; number_of_response:1; }","duration":"263.198104ms","start":"2026-02-24T10:42:45.994659Z","end":"2026-02-24T10:42:46.257857Z","steps":["trace[1444752948] 'process raft request' (duration: 247.722809ms)","trace[1444752948] 'compare' (duration: 15.308688ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:42:46.259085Z","caller":"traceutil/trace.go:171","msg":"trace[1928336250] linearizableReadLoop","detail":"{readStateIndex:478; appliedIndex:476; }","duration":"108.691552ms","start":"2026-02-24T10:42:46.150376Z","end":"2026-02-24T10:42:46.259068Z","steps":["trace[1928336250] 'read index received' (duration: 92.096135ms)","trace[1928336250] 'applied index is now lower than readState.Index' (duration: 16.594227ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:42:46.260437Z","caller":"traceutil/trace.go:171","msg":"trace[1493482029] transaction","detail":"{read_only:false; response_revision:457; number_of_response:1; }","duration":"188.270855ms","start":"2026-02-24T10:42:46.072151Z","end":"2026-02-24T10:42:46.260422Z","steps":["trace[1493482029] 'process raft request' (duration: 186.313471ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:42:46.260667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.273612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:42:46.260717Z","caller":"traceutil/trace.go:171","msg":"trace[346998157] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:457; }","duration":"110.376806ms","start":"2026-02-24T10:42:46.150325Z","end":"2026-02-24T10:42:46.260702Z","steps":["trace[346998157] 'agreement among raft nodes before linearized reading' (duration: 110.285782ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:42:46.260898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.390076ms","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:42:46.26098Z","caller":"traceutil/trace.go:171","msg":"trace[1310294632] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:457; }","duration":"101.4861ms","start":"2026-02-24T10:42:46.159478Z","end":"2026-02-24T10:42:46.260964Z","steps":["trace[1310294632] 'agreement among raft nodes before linearized reading' (duration: 101.379956ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:42:46.261723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.263471ms","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:42:46.26177Z","caller":"traceutil/trace.go:171","msg":"trace[2098034708] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:457; }","duration":"101.331184ms","start":"2026-02-24T10:42:46.160428Z","end":"2026-02-24T10:42:46.261759Z","steps":["trace[2098034708] 'agreement among raft nodes before linearized reading' (duration: 101.244941ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:42:46.261867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.148875ms","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:42:46.261974Z","caller":"traceutil/trace.go:171","msg":"trace[189847680] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:457; }","duration":"102.328261ms","start":"2026-02-24T10:42:46.159616Z","end":"2026-02-24T10:42:46.261944Z","steps":["trace[189847680] 'agreement among raft nodes before linearized reading' (duration: 101.912386ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:42:46.48907Z","caller":"traceutil/trace.go:171","msg":"trace[1873740255] linearizableReadLoop","detail":"{readStateIndex:493; appliedIndex:492; }","duration":"104.617037ms","start":"2026-02-24T10:42:46.384423Z","end":"2026-02-24T10:42:46.48904Z","steps":["trace[1873740255] 'read index received' (duration: 99.042047ms)","trace[1873740255] 'applied index is now lower than readState.Index' (duration: 5.57357ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:42:46.489201Z","caller":"traceutil/trace.go:171","msg":"trace[1370122729] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"103.34872ms","start":"2026-02-24T10:42:46.385826Z","end":"2026-02-24T10:42:46.489174Z","steps":["trace[1370122729] 'process raft request' (duration: 97.624234ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:42:46.489423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.975691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-rlxst\" ","response":"range_response_count:1 size:14921"} {"level":"info","ts":"2026-02-24T10:42:46.489479Z","caller":"traceutil/trace.go:171","msg":"trace[1048034390] range","detail":"{range_begin:/registry/pods/kube-system/cilium-rlxst; range_end:; response_count:1; response_revision:472; }","duration":"105.082235ms","start":"2026-02-24T10:42:46.384381Z","end":"2026-02-24T10:42:46.489463Z","steps":["trace[1048034390] 'agreement among raft nodes before linearized reading' (duration: 104.815105ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:42:46.502787Z","caller":"traceutil/trace.go:171","msg":"trace[407281611] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"111.693005ms","start":"2026-02-24T10:42:46.391071Z","end":"2026-02-24T10:42:46.502764Z","steps":["trace[407281611] 'process raft request' (duration: 111.510988ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:42:46.503111Z","caller":"traceutil/trace.go:171","msg":"trace[1663790747] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"104.215392ms","start":"2026-02-24T10:42:46.398879Z","end":"2026-02-24T10:42:46.503094Z","steps":["trace[1663790747] 'process raft request' (duration: 103.830008ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:42:46.503198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.383121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/cilium\" ","response":"range_response_count:1 size:14309"} {"level":"info","ts":"2026-02-24T10:42:46.503237Z","caller":"traceutil/trace.go:171","msg":"trace[1958607775] range","detail":"{range_begin:/registry/daemonsets/kube-system/cilium; range_end:; response_count:1; response_revision:474; }","duration":"103.462584ms","start":"2026-02-24T10:42:46.399763Z","end":"2026-02-24T10:42:46.503225Z","steps":["trace[1958607775] 'agreement among raft nodes before linearized reading' (duration: 103.186434ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:42:46.724314Z","caller":"traceutil/trace.go:171","msg":"trace[121651503] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"214.482963ms","start":"2026-02-24T10:42:46.509818Z","end":"2026-02-24T10:42:46.724301Z","steps":["trace[121651503] 'process raft request' (duration: 202.668218ms)","trace[121651503] 'compare' (duration: 11.68024ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:42:46.72431Z","caller":"traceutil/trace.go:171","msg":"trace[187267142] linearizableReadLoop","detail":"{readStateIndex:498; appliedIndex:497; }","duration":"159.52157ms","start":"2026-02-24T10:42:46.564734Z","end":"2026-02-24T10:42:46.724272Z","steps":["trace[187267142] 'read index received' (duration: 147.736606ms)","trace[187267142] 'applied index is now lower than readState.Index' (duration: 11.783914ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:42:46.724504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.752759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-8qpck\" ","response":"range_response_count:1 size:3615"} {"level":"warn","ts":"2026-02-24T10:42:46.724543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.530214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/cilium\" ","response":"range_response_count:1 size:14309"} {"level":"info","ts":"2026-02-24T10:42:46.724541Z","caller":"traceutil/trace.go:171","msg":"trace[38669431] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-8qpck; range_end:; response_count:1; response_revision:477; }","duration":"159.862673ms","start":"2026-02-24T10:42:46.564669Z","end":"2026-02-24T10:42:46.724531Z","steps":["trace[38669431] 'agreement among raft nodes before linearized reading' (duration: 159.693216ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:42:46.724559Z","caller":"traceutil/trace.go:171","msg":"trace[1449314206] range","detail":"{range_begin:/registry/daemonsets/kube-system/cilium; range_end:; response_count:1; response_revision:477; }","duration":"153.567456ms","start":"2026-02-24T10:42:46.570986Z","end":"2026-02-24T10:42:46.724553Z","steps":["trace[1449314206] 'agreement among raft nodes before linearized reading' (duration: 153.501253ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:42:46.724677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.452475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:1 size:520"} {"level":"info","ts":"2026-02-24T10:42:46.724696Z","caller":"traceutil/trace.go:171","msg":"trace[415124017] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:477; }","duration":"133.511728ms","start":"2026-02-24T10:42:46.59118Z","end":"2026-02-24T10:42:46.724692Z","steps":["trace[415124017] 'agreement among raft nodes before linearized reading' (duration: 133.458896ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:42:46.724881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.642483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" ","response":"range_response_count:1 size:538"} {"level":"info","ts":"2026-02-24T10:42:46.724954Z","caller":"traceutil/trace.go:171","msg":"trace[1661898605] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium-operator; range_end:; response_count:1; response_revision:477; }","duration":"133.754667ms","start":"2026-02-24T10:42:46.591179Z","end":"2026-02-24T10:42:46.724933Z","steps":["trace[1661898605] 'agreement among raft nodes before linearized reading' (duration: 133.630692ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:42:46.902699Z","caller":"traceutil/trace.go:171","msg":"trace[606271614] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"170.706002ms","start":"2026-02-24T10:42:46.731966Z","end":"2026-02-24T10:42:46.902672Z","steps":["trace[606271614] 'process raft request' (duration: 169.663942ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:42:48.436243Z","caller":"traceutil/trace.go:171","msg":"trace[1422695444] linearizableReadLoop","detail":"{readStateIndex:503; appliedIndex:502; }","duration":"112.867285ms","start":"2026-02-24T10:42:48.323359Z","end":"2026-02-24T10:42:48.436226Z","steps":["trace[1422695444] 'read index received' (duration: 112.704669ms)","trace[1422695444] 'applied index is now lower than readState.Index' (duration: 161.906µs)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:42:48.436418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.03106ms","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:42:48.436447Z","caller":"traceutil/trace.go:171","msg":"trace[604878376] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:482; }","duration":"113.117994ms","start":"2026-02-24T10:42:48.323321Z","end":"2026-02-24T10:42:48.436439Z","steps":["trace[604878376] 'agreement among raft nodes before linearized reading' (duration: 113.00479ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:42:48.436497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.985429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-24T10:42:48.4366Z","caller":"traceutil/trace.go:171","msg":"trace[367540996] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:482; }","duration":"113.150565ms","start":"2026-02-24T10:42:48.323426Z","end":"2026-02-24T10:42:48.436577Z","steps":["trace[367540996] 'agreement among raft nodes before linearized reading' (duration: 112.970039ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:42:48.436563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.994699ms","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:42:48.436671Z","caller":"traceutil/trace.go:171","msg":"trace[1443144128] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:482; }","duration":"113.126885ms","start":"2026-02-24T10:42:48.323519Z","end":"2026-02-24T10:42:48.436646Z","steps":["trace[1443144128] 'agreement among raft nodes before linearized reading' (duration: 112.840394ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:42:48.437091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.727978ms","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:42:48.437141Z","caller":"traceutil/trace.go:171","msg":"trace[1407838716] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:482; }","duration":"113.816341ms","start":"2026-02-24T10:42:48.323314Z","end":"2026-02-24T10:42:48.43713Z","steps":["trace[1407838716] 'agreement among raft nodes before linearized reading' (duration: 112.994229ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:42:48.437426Z","caller":"traceutil/trace.go:171","msg":"trace[1886121598] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"218.424375ms","start":"2026-02-24T10:42:48.218988Z","end":"2026-02-24T10:42:48.437412Z","steps":["trace[1886121598] 'process raft request' (duration: 217.134906ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:42:49.647191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.294632ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T10:42:49.647299Z","caller":"traceutil/trace.go:171","msg":"trace[461177675] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"581.133916ms","start":"2026-02-24T10:42:49.066148Z","end":"2026-02-24T10:42:49.647282Z","steps":["trace[461177675] 'process raft request' (duration: 429.661178ms)","trace[461177675] 'compare' (duration: 151.156385ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:42:49.64735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:42:49.066132Z","time spent":"581.192598ms","remote":"127.0.0.1:44628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T10:42:56.445778Z","caller":"traceutil/trace.go:171","msg":"trace[1002068435] linearizableReadLoop","detail":"{readStateIndex:547; appliedIndex:546; }","duration":"109.731496ms","start":"2026-02-24T10:42:56.336021Z","end":"2026-02-24T10:42:56.445752Z","steps":["trace[1002068435] 'read index received' (duration: 109.56024ms)","trace[1002068435] 'applied index is now lower than readState.Index' (duration: 170.206µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:42:56.445902Z","caller":"traceutil/trace.go:171","msg":"trace[1154603137] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"110.802845ms","start":"2026-02-24T10:42:56.335074Z","end":"2026-02-24T10:42:56.445877Z","steps":["trace[1154603137] 'process raft request' (duration: 110.449962ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:42:56.446011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.987276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/cilium-config\" ","response":"range_response_count:1 size:6534"} {"level":"info","ts":"2026-02-24T10:42:56.446071Z","caller":"traceutil/trace.go:171","msg":"trace[363849497] range","detail":"{range_begin:/registry/configmaps/kube-system/cilium-config; range_end:; response_count:1; response_revision:524; }","duration":"110.12649ms","start":"2026-02-24T10:42:56.335929Z","end":"2026-02-24T10:42:56.446055Z","steps":["trace[363849497] 'agreement among raft nodes before linearized reading' (duration: 109.926273ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:42:56.446893Z","caller":"traceutil/trace.go:171","msg":"trace[2059838584] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"109.759297ms","start":"2026-02-24T10:42:56.337116Z","end":"2026-02-24T10:42:56.446875Z","steps":["trace[2059838584] 'process raft request' (duration: 109.662254ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:42:57.138396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.737747ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T10:42:57.138761Z","caller":"traceutil/trace.go:171","msg":"trace[837673021] linearizableReadLoop","detail":"{readStateIndex:549; appliedIndex:548; }","duration":"649.001882ms","start":"2026-02-24T10:42:56.489737Z","end":"2026-02-24T10:42:57.138739Z","steps":["trace[837673021] 'read index received' (duration: 386.757017ms)","trace[837673021] 'applied index is now lower than readState.Index' (duration: 262.243085ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:42:57.13893Z","caller":"traceutil/trace.go:171","msg":"trace[1634781184] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"653.881053ms","start":"2026-02-24T10:42:56.484969Z","end":"2026-02-24T10:42:57.13885Z","steps":["trace[1634781184] 'process raft request' (duration: 391.594675ms)","trace[1634781184] 'compare' (duration: 261.496179ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:42:57.139034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.283403ms","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:42:57.139055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:42:56.484952Z","time spent":"654.057609ms","remote":"127.0.0.1:44862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T10:42:57.139082Z","caller":"traceutil/trace.go:171","msg":"trace[2104316839] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:526; }","duration":"649.360545ms","start":"2026-02-24T10:42:56.489706Z","end":"2026-02-24T10:42:57.139066Z","steps":["trace[2104316839] 'agreement among raft nodes before linearized reading' (duration: 649.160608ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:42:57.139124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:42:56.48969Z","time spent":"649.422448ms","remote":"127.0.0.1:44862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-24T10:42:57.139621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.51198ms","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:42:57.139628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.709387ms","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:42:57.139672Z","caller":"traceutil/trace.go:171","msg":"trace[1138862432] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:526; }","duration":"640.617154ms","start":"2026-02-24T10:42:56.49904Z","end":"2026-02-24T10:42:57.139657Z","steps":["trace[1138862432] 'agreement among raft nodes before linearized reading' (duration: 640.50481ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:42:57.139681Z","caller":"traceutil/trace.go:171","msg":"trace[1387280998] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:526; }","duration":"640.79477ms","start":"2026-02-24T10:42:56.498872Z","end":"2026-02-24T10:42:57.139666Z","steps":["trace[1387280998] 'agreement among raft nodes before linearized reading' (duration: 640.660705ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:42:57.139711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:42:56.499029Z","time spent":"640.671996ms","remote":"127.0.0.1:44862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-24T10:42:57.139723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:42:56.498859Z","time spent":"640.853433ms","remote":"127.0.0.1:44862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-24T10:42:57.512913Z","caller":"traceutil/trace.go:171","msg":"trace[1831074656] linearizableReadLoop","detail":"{readStateIndex:552; appliedIndex:549; }","duration":"171.315198ms","start":"2026-02-24T10:42:57.341581Z","end":"2026-02-24T10:42:57.512896Z","steps":["trace[1831074656] 'read index received' (duration: 132.596517ms)","trace[1831074656] 'applied index is now lower than readState.Index' (duration: 38.717971ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:42:57.513085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.490405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-rlxst\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-24T10:42:57.513111Z","caller":"traceutil/trace.go:171","msg":"trace[62160945] range","detail":"{range_begin:/registry/pods/kube-system/cilium-rlxst; range_end:; response_count:1; response_revision:529; }","duration":"171.554847ms","start":"2026-02-24T10:42:57.34155Z","end":"2026-02-24T10:42:57.513104Z","steps":["trace[62160945] 'agreement among raft nodes before linearized reading' (duration: 171.422982ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:42:57.513149Z","caller":"traceutil/trace.go:171","msg":"trace[1033425641] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"370.703076ms","start":"2026-02-24T10:42:57.142426Z","end":"2026-02-24T10:42:57.513129Z","steps":["trace[1033425641] 'process raft request' (duration: 331.734576ms)","trace[1033425641] 'compare' (duration: 38.380439ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:42:57.513278Z","caller":"traceutil/trace.go:171","msg":"trace[692756102] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"369.410868ms","start":"2026-02-24T10:42:57.143836Z","end":"2026-02-24T10:42:57.513247Z","steps":["trace[692756102] 'process raft request' (duration: 369.039815ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:42:57.513357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:42:57.142404Z","time spent":"370.874132ms","remote":"127.0.0.1:44862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T10:42:57.513389Z","caller":"traceutil/trace.go:171","msg":"trace[1969060548] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"370.122675ms","start":"2026-02-24T10:42:57.143257Z","end":"2026-02-24T10:42:57.51338Z","steps":["trace[1969060548] 'process raft request' (duration: 369.584846ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:42:57.513428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:42:57.143822Z","time spent":"369.521123ms","remote":"127.0.0.1:44862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:42:57.513445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:42:57.14323Z","time spent":"370.183358ms","remote":"127.0.0.1:44862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T10:42:58.308247Z","caller":"traceutil/trace.go:171","msg":"trace[400734995] linearizableReadLoop","detail":"{readStateIndex:553; appliedIndex:552; }","duration":"201.030144ms","start":"2026-02-24T10:42:58.107201Z","end":"2026-02-24T10:42:58.308231Z","steps":["trace[400734995] 'read index received' (duration: 200.875588ms)","trace[400734995] 'applied index is now lower than readState.Index' (duration: 154.036µs)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:42:58.308351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.143217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:42:58.30837Z","caller":"traceutil/trace.go:171","msg":"trace[679272704] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:530; }","duration":"201.20583ms","start":"2026-02-24T10:42:58.107156Z","end":"2026-02-24T10:42:58.308362Z","steps":["trace[679272704] 'agreement among raft nodes before linearized reading' (duration: 201.146248ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:42:58.308346Z","caller":"traceutil/trace.go:171","msg":"trace[848388382] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"780.479234ms","start":"2026-02-24T10:42:57.527836Z","end":"2026-02-24T10:42:58.308315Z","steps":["trace[848388382] 'process raft request' (duration: 780.297127ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:42:58.308506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:42:57.527819Z","time spent":"780.596168ms","remote":"127.0.0.1:44792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:42:59.412512Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853968727283980224,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T10:42:59.913181Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853968727283980224,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T10:43:00.414119Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853968727283980224,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T10:43:00.845541Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.258155114s","expected-duration":"1s"} {"level":"info","ts":"2026-02-24T10:43:00.846164Z","caller":"traceutil/trace.go:171","msg":"trace[1479342058] linearizableReadLoop","detail":"{readStateIndex:554; appliedIndex:553; }","duration":"1.934763648s","start":"2026-02-24T10:42:58.911376Z","end":"2026-02-24T10:43:00.84614Z","steps":["trace[1479342058] 'read index received' (duration: 1.934336272s)","trace[1479342058] 'applied index is now lower than readState.Index' (duration: 426.086µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:43:00.846238Z","caller":"traceutil/trace.go:171","msg":"trace[1951807467] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"2.258970073s","start":"2026-02-24T10:42:58.587241Z","end":"2026-02-24T10:43:00.846211Z","steps":["trace[1951807467] 'process raft request' (duration: 2.258725874s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:43:00.846393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:42:58.587212Z","time spent":"2.259078236s","remote":"127.0.0.1:44862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:43:00.846454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"741.395066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:43:00.846533Z","caller":"traceutil/trace.go:171","msg":"trace[152797400] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:531; }","duration":"741.52334ms","start":"2026-02-24T10:43:00.104964Z","end":"2026-02-24T10:43:00.846487Z","steps":["trace[152797400] 'agreement among raft nodes before linearized reading' (duration: 741.431487ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:43:00.846526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.704535235s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-24T10:43:00.846572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:43:00.10494Z","time spent":"741.623114ms","remote":"127.0.0.1:44588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-24T10:43:00.846589Z","caller":"traceutil/trace.go:171","msg":"trace[1918824679] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:531; }","duration":"1.704666491s","start":"2026-02-24T10:42:59.141905Z","end":"2026-02-24T10:43:00.846571Z","steps":["trace[1918824679] 'agreement among raft nodes before linearized reading' (duration: 1.704419712s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:43:00.846629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:42:59.141885Z","time spent":"1.704735392s","remote":"127.0.0.1:44862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-24T10:43:00.846641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.329173525s","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:43:00.846731Z","caller":"traceutil/trace.go:171","msg":"trace[1517386244] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:531; }","duration":"1.329285398s","start":"2026-02-24T10:42:59.517421Z","end":"2026-02-24T10:43:00.846707Z","steps":["trace[1517386244] 'agreement among raft nodes before linearized reading' (duration: 1.328947656s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:43:00.846725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.329266288s","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:43:00.846767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.329642111s","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:43:00.84678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:42:59.517416Z","time spent":"1.329354301s","remote":"127.0.0.1:44862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-24T10:43:00.846805Z","caller":"traceutil/trace.go:171","msg":"trace[1810788412] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:531; }","duration":"1.329710164s","start":"2026-02-24T10:42:59.517083Z","end":"2026-02-24T10:43:00.846794Z","steps":["trace[1810788412] 'agreement among raft nodes before linearized reading' (duration: 1.32961827s)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:43:00.846816Z","caller":"traceutil/trace.go:171","msg":"trace[1235525758] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:531; }","duration":"1.329398422s","start":"2026-02-24T10:42:59.517393Z","end":"2026-02-24T10:43:00.846791Z","steps":["trace[1235525758] 'agreement among raft nodes before linearized reading' (duration: 1.329090061s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:43:00.846853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:42:59.517066Z","time spent":"1.329781366s","remote":"127.0.0.1:44862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-24T10:43:00.846868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:42:59.517387Z","time spent":"1.329471745s","remote":"127.0.0.1:44862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-24T10:43:00.847145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.935734464s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.116\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-24T10:43:00.84722Z","caller":"traceutil/trace.go:171","msg":"trace[984707539] range","detail":"{range_begin:/registry/masterleases/199.204.45.116; range_end:; response_count:1; response_revision:531; }","duration":"1.935863818s","start":"2026-02-24T10:42:58.911338Z","end":"2026-02-24T10:43:00.847202Z","steps":["trace[984707539] 'agreement among raft nodes before linearized reading' (duration: 1.934935144s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:43:00.847266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:42:58.911319Z","time spent":"1.935939231s","remote":"127.0.0.1:44628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.116\" "} {"level":"info","ts":"2026-02-24T10:43:01.05389Z","caller":"traceutil/trace.go:171","msg":"trace[236990087] linearizableReadLoop","detail":"{readStateIndex:555; appliedIndex:554; }","duration":"205.104929ms","start":"2026-02-24T10:43:00.848746Z","end":"2026-02-24T10:43:01.053851Z","steps":["trace[236990087] 'read index received' (duration: 141.139809ms)","trace[236990087] 'applied index is now lower than readState.Index' (duration: 63.95896ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:43:01.053944Z","caller":"traceutil/trace.go:171","msg":"trace[516972908] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"202.691421ms","start":"2026-02-24T10:43:00.851235Z","end":"2026-02-24T10:43:01.053926Z","steps":["trace[516972908] 'process raft request' (duration: 202.655879ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:43:01.054033Z","caller":"traceutil/trace.go:171","msg":"trace[191116423] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"203.49523ms","start":"2026-02-24T10:43:00.850516Z","end":"2026-02-24T10:43:01.054011Z","steps":["trace[191116423] 'process raft request' (duration: 203.158037ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:43:01.054086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.308407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:43:01.054131Z","caller":"traceutil/trace.go:171","msg":"trace[232238002] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:535; }","duration":"205.468101ms","start":"2026-02-24T10:43:00.848649Z","end":"2026-02-24T10:43:01.054117Z","steps":["trace[232238002] 'agreement among raft nodes before linearized reading' (duration: 205.312746ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:43:01.054205Z","caller":"traceutil/trace.go:171","msg":"trace[1423676167] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"203.013272ms","start":"2026-02-24T10:43:00.851184Z","end":"2026-02-24T10:43:01.054197Z","steps":["trace[1423676167] 'process raft request' (duration: 202.68119ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:43:01.05426Z","caller":"traceutil/trace.go:171","msg":"trace[1679005745] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"203.730588ms","start":"2026-02-24T10:43:00.850522Z","end":"2026-02-24T10:43:01.054253Z","steps":["trace[1679005745] 'process raft request' (duration: 203.287902ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:43:17.531557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.327762ms","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:43:17.531646Z","caller":"traceutil/trace.go:171","msg":"trace[2004329595] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:718; }","duration":"137.440376ms","start":"2026-02-24T10:43:17.394188Z","end":"2026-02-24T10:43:17.531629Z","steps":["trace[2004329595] 'range keys from in-memory index tree' (duration: 137.057112ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:43:27.440311Z","caller":"traceutil/trace.go:171","msg":"trace[1972670536] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"162.053273ms","start":"2026-02-24T10:43:27.278228Z","end":"2026-02-24T10:43:27.440282Z","steps":["trace[1972670536] 'process raft request' (duration: 114.424407ms)","trace[1972670536] 'compare' (duration: 47.529433ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:43:27.445826Z","caller":"traceutil/trace.go:171","msg":"trace[2015395638] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"166.81706ms","start":"2026-02-24T10:43:27.278984Z","end":"2026-02-24T10:43:27.445801Z","steps":["trace[2015395638] 'process raft request' (duration: 166.656735ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:43:27.446114Z","caller":"traceutil/trace.go:171","msg":"trace[1741248593] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"167.065658ms","start":"2026-02-24T10:43:27.279029Z","end":"2026-02-24T10:43:27.446095Z","steps":["trace[1741248593] 'process raft request' (duration: 166.733847ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:43:27.44615Z","caller":"traceutil/trace.go:171","msg":"trace[890901783] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"166.778378ms","start":"2026-02-24T10:43:27.279346Z","end":"2026-02-24T10:43:27.446125Z","steps":["trace[890901783] 'process raft request' (duration: 166.596772ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:43:29.711274Z","caller":"traceutil/trace.go:171","msg":"trace[1823947342] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"180.989949ms","start":"2026-02-24T10:43:29.530265Z","end":"2026-02-24T10:43:29.711255Z","steps":["trace[1823947342] 'process raft request' (duration: 156.173047ms)","trace[1823947342] 'compare' (duration: 24.656426ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:43:29.711759Z","caller":"traceutil/trace.go:171","msg":"trace[1055727786] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"180.895034ms","start":"2026-02-24T10:43:29.530846Z","end":"2026-02-24T10:43:29.711741Z","steps":["trace[1055727786] 'process raft request' (duration: 180.717218ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:43:41.633078Z","caller":"traceutil/trace.go:171","msg":"trace[1342638856] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"140.357302ms","start":"2026-02-24T10:43:41.492659Z","end":"2026-02-24T10:43:41.633016Z","steps":["trace[1342638856] 'process raft request' (duration: 124.764399ms)","trace[1342638856] 'compare' (duration: 15.357874ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:43:44.121816Z","caller":"traceutil/trace.go:171","msg":"trace[1221845718] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"148.58246ms","start":"2026-02-24T10:43:43.973212Z","end":"2026-02-24T10:43:44.121795Z","steps":["trace[1221845718] 'process raft request' (duration: 148.418034ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:43:44.208129Z","caller":"traceutil/trace.go:171","msg":"trace[688709472] linearizableReadLoop","detail":"{readStateIndex:834; appliedIndex:832; }","duration":"101.531343ms","start":"2026-02-24T10:43:44.106559Z","end":"2026-02-24T10:43:44.20809Z","steps":["trace[688709472] 'read index received' (duration: 15.081735ms)","trace[688709472] 'applied index is now lower than readState.Index' (duration: 86.447738ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:43:44.208511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.920996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:43:44.208613Z","caller":"traceutil/trace.go:171","msg":"trace[958451167] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:798; }","duration":"102.068511ms","start":"2026-02-24T10:43:44.106521Z","end":"2026-02-24T10:43:44.20859Z","steps":["trace[958451167] 'agreement among raft nodes before linearized reading' (duration: 101.869844ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:43:47.829543Z","caller":"traceutil/trace.go:171","msg":"trace[18034743] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"118.438215ms","start":"2026-02-24T10:43:47.711083Z","end":"2026-02-24T10:43:47.829522Z","steps":["trace[18034743] 'process raft request' (duration: 118.31442ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:43:48.131809Z","caller":"traceutil/trace.go:171","msg":"trace[651945121] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"123.934954ms","start":"2026-02-24T10:43:48.007831Z","end":"2026-02-24T10:43:48.131766Z","steps":["trace[651945121] 'process raft request' (duration: 123.697225ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:43:51.737735Z","caller":"traceutil/trace.go:171","msg":"trace[600467960] transaction","detail":"{read_only:false; response_revision:816; number_of_response:1; }","duration":"176.092438ms","start":"2026-02-24T10:43:51.561618Z","end":"2026-02-24T10:43:51.73771Z","steps":["trace[600467960] 'process raft request' (duration: 175.944993ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:43:54.358561Z","caller":"traceutil/trace.go:171","msg":"trace[1531564502] transaction","detail":"{read_only:false; response_revision:826; number_of_response:1; }","duration":"126.552703ms","start":"2026-02-24T10:43:54.231974Z","end":"2026-02-24T10:43:54.358527Z","steps":["trace[1531564502] 'process raft request' (duration: 100.234322ms)","trace[1531564502] 'compare' (duration: 26.159026ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:44:02.297237Z","caller":"traceutil/trace.go:171","msg":"trace[298058043] transaction","detail":"{read_only:false; response_revision:847; number_of_response:1; }","duration":"205.896224ms","start":"2026-02-24T10:44:02.091322Z","end":"2026-02-24T10:44:02.297218Z","steps":["trace[298058043] 'process raft request' (duration: 191.504307ms)","trace[298058043] 'compare' (duration: 14.000893ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:44:04.384486Z","caller":"traceutil/trace.go:171","msg":"trace[1738162927] linearizableReadLoop","detail":"{readStateIndex:891; appliedIndex:890; }","duration":"226.250181ms","start":"2026-02-24T10:44:04.158209Z","end":"2026-02-24T10:44:04.384459Z","steps":["trace[1738162927] 'read index received' (duration: 226.008993ms)","trace[1738162927] 'applied index is now lower than readState.Index' (duration: 240.088µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:44:04.384575Z","caller":"traceutil/trace.go:171","msg":"trace[822246039] transaction","detail":"{read_only:false; response_revision:851; number_of_response:1; }","duration":"282.242144ms","start":"2026-02-24T10:44:04.102297Z","end":"2026-02-24T10:44:04.384539Z","steps":["trace[822246039] 'process raft request' (duration: 282.000286ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:44:04.384759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.533741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4914"} {"level":"info","ts":"2026-02-24T10:44:04.384969Z","caller":"traceutil/trace.go:171","msg":"trace[625361793] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:851; }","duration":"226.786399ms","start":"2026-02-24T10:44:04.158155Z","end":"2026-02-24T10:44:04.384941Z","steps":["trace[625361793] 'agreement among raft nodes before linearized reading' (duration: 226.407246ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:44:04.539424Z","caller":"traceutil/trace.go:171","msg":"trace[342022677] linearizableReadLoop","detail":"{readStateIndex:893; appliedIndex:891; }","duration":"144.944194ms","start":"2026-02-24T10:44:04.394458Z","end":"2026-02-24T10:44:04.539402Z","steps":["trace[342022677] 'read index received' (duration: 75.618561ms)","trace[342022677] 'applied index is now lower than readState.Index' (duration: 69.324493ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:44:04.539565Z","caller":"traceutil/trace.go:171","msg":"trace[234924263] transaction","detail":"{read_only:false; response_revision:852; number_of_response:1; }","duration":"149.813023ms","start":"2026-02-24T10:44:04.389724Z","end":"2026-02-24T10:44:04.539537Z","steps":["trace[234924263] 'process raft request' (duration: 80.20576ms)","trace[234924263] 'compare' (duration: 69.127226ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:44:04.539638Z","caller":"traceutil/trace.go:171","msg":"trace[1670787099] transaction","detail":"{read_only:false; response_revision:853; number_of_response:1; }","duration":"149.685349ms","start":"2026-02-24T10:44:04.389944Z","end":"2026-02-24T10:44:04.539629Z","steps":["trace[1670787099] 'process raft request' (duration: 149.369888ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:44:04.540563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.737962ms","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:44:04.540623Z","caller":"traceutil/trace.go:171","msg":"trace[143368171] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:853; }","duration":"138.840935ms","start":"2026-02-24T10:44:04.401765Z","end":"2026-02-24T10:44:04.540606Z","steps":["trace[143368171] 'agreement among raft nodes before linearized reading' (duration: 138.657279ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:44:04.539793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.314486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4914"} {"level":"info","ts":"2026-02-24T10:44:04.54076Z","caller":"traceutil/trace.go:171","msg":"trace[713418589] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:853; }","duration":"146.310762ms","start":"2026-02-24T10:44:04.394428Z","end":"2026-02-24T10:44:04.540739Z","steps":["trace[713418589] 'agreement among raft nodes before linearized reading' (duration: 145.234994ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:44:08.668897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.983654ms","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:44:08.668969Z","caller":"traceutil/trace.go:171","msg":"trace[1794354041] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:862; }","duration":"108.067987ms","start":"2026-02-24T10:44:08.560884Z","end":"2026-02-24T10:44:08.668952Z","steps":["trace[1794354041] 'range keys from in-memory index tree' (duration: 107.735336ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:44:08.668953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.573378ms","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:44:08.669034Z","caller":"traceutil/trace.go:171","msg":"trace[584347174] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:862; }","duration":"111.708332ms","start":"2026-02-24T10:44:08.557307Z","end":"2026-02-24T10:44:08.669016Z","steps":["trace[584347174] 'range keys from in-memory index tree' (duration: 111.258548ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:44:08.668869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.273198ms","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:44:08.669184Z","caller":"traceutil/trace.go:171","msg":"trace[1180932791] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:862; }","duration":"111.631581ms","start":"2026-02-24T10:44:08.557541Z","end":"2026-02-24T10:44:08.669173Z","steps":["trace[1180932791] 'range keys from in-memory index tree' (duration: 111.074851ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:44:08.791936Z","caller":"traceutil/trace.go:171","msg":"trace[1200166815] transaction","detail":"{read_only:false; response_revision:865; number_of_response:1; }","duration":"117.854605ms","start":"2026-02-24T10:44:08.67406Z","end":"2026-02-24T10:44:08.791915Z","steps":["trace[1200166815] 'process raft request' (duration: 117.808434ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:44:08.79201Z","caller":"traceutil/trace.go:171","msg":"trace[786143434] transaction","detail":"{read_only:false; response_revision:864; number_of_response:1; }","duration":"118.271989ms","start":"2026-02-24T10:44:08.673704Z","end":"2026-02-24T10:44:08.791976Z","steps":["trace[786143434] 'process raft request' (duration: 108.841914ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:44:14.773431Z","caller":"traceutil/trace.go:171","msg":"trace[219274174] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"131.344784ms","start":"2026-02-24T10:44:14.642036Z","end":"2026-02-24T10:44:14.77338Z","steps":["trace[219274174] 'process raft request' (duration: 131.042244ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:44:19.955637Z","caller":"traceutil/trace.go:171","msg":"trace[431215892] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"137.046507ms","start":"2026-02-24T10:44:19.818566Z","end":"2026-02-24T10:44:19.955613Z","steps":["trace[431215892] 'process raft request' (duration: 136.918442ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:44:20.226203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.861402ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T10:44:20.22635Z","caller":"traceutil/trace.go:171","msg":"trace[5566050] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"260.800455ms","start":"2026-02-24T10:44:19.965525Z","end":"2026-02-24T10:44:20.226325Z","steps":["trace[5566050] 'process raft request' (duration: 145.693914ms)","trace[5566050] 'compare' (duration: 114.624814ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:44:20.811481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.033521ms","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:44:20.811637Z","caller":"traceutil/trace.go:171","msg":"trace[482953625] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:927; }","duration":"351.242008ms","start":"2026-02-24T10:44:20.460374Z","end":"2026-02-24T10:44:20.811616Z","steps":["trace[482953625] 'range keys from in-memory index tree' (duration: 350.803563ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:44:20.81171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:44:20.460352Z","time spent":"351.344361ms","remote":"127.0.0.1:44862","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:44:21.056745Z","caller":"traceutil/trace.go:171","msg":"trace[1417731933] linearizableReadLoop","detail":"{readStateIndex:973; appliedIndex:972; }","duration":"145.727755ms","start":"2026-02-24T10:44:20.910997Z","end":"2026-02-24T10:44:21.056724Z","steps":["trace[1417731933] 'read index received' (duration: 145.529728ms)","trace[1417731933] 'applied index is now lower than readState.Index' (duration: 197.127µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:44:21.0568Z","caller":"traceutil/trace.go:171","msg":"trace[822059616] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"241.940266ms","start":"2026-02-24T10:44:20.814826Z","end":"2026-02-24T10:44:21.056767Z","steps":["trace[822059616] 'process raft request' (duration: 241.77784ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:44:21.057016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.015705ms","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:44:21.057086Z","caller":"traceutil/trace.go:171","msg":"trace[1176026195] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:928; }","duration":"146.096789ms","start":"2026-02-24T10:44:20.910977Z","end":"2026-02-24T10:44:21.057074Z","steps":["trace[1176026195] 'agreement among raft nodes before linearized reading' (duration: 145.908442ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:44:21.319063Z","caller":"traceutil/trace.go:171","msg":"trace[458321994] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"258.06792ms","start":"2026-02-24T10:44:21.060963Z","end":"2026-02-24T10:44:21.319031Z","steps":["trace[458321994] 'process raft request' (duration: 159.172517ms)","trace[458321994] 'compare' (duration: 98.662305ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:44:21.649422Z","caller":"traceutil/trace.go:171","msg":"trace[624742901] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"588.165789ms","start":"2026-02-24T10:44:21.06123Z","end":"2026-02-24T10:44:21.649395Z","steps":["trace[624742901] 'process raft request' (duration: 587.919ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:44:21.64954Z","caller":"traceutil/trace.go:171","msg":"trace[1550819636] linearizableReadLoop","detail":"{readStateIndex:976; appliedIndex:973; }","duration":"540.874201ms","start":"2026-02-24T10:44:21.108642Z","end":"2026-02-24T10:44:21.649516Z","steps":["trace[1550819636] 'read index received' (duration: 111.561739ms)","trace[1550819636] 'applied index is now lower than readState.Index' (duration: 429.311192ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:44:21.649568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:44:21.06121Z","time spent":"588.287182ms","remote":"127.0.0.1:44862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:44:21.649654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.011746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:44:21.650403Z","caller":"traceutil/trace.go:171","msg":"trace[15061836] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:931; }","duration":"541.785902ms","start":"2026-02-24T10:44:21.108586Z","end":"2026-02-24T10:44:21.650372Z","steps":["trace[15061836] 'agreement among raft nodes before linearized reading' (duration: 541.006975ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:44:21.65048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:44:21.10857Z","time spent":"541.882365ms","remote":"127.0.0.1:44588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-24T10:44:21.649694Z","caller":"traceutil/trace.go:171","msg":"trace[1429491901] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"587.715844ms","start":"2026-02-24T10:44:21.061966Z","end":"2026-02-24T10:44:21.649682Z","steps":["trace[1429491901] 'process raft request' (duration: 587.351801ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:44:21.650866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:44:21.061954Z","time spent":"588.759499ms","remote":"127.0.0.1:44862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T10:44:23.203784Z","caller":"traceutil/trace.go:171","msg":"trace[818476602] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"139.365644ms","start":"2026-02-24T10:44:23.064388Z","end":"2026-02-24T10:44:23.203754Z","steps":["trace[818476602] 'process raft request' (duration: 139.055723ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:44:23.782367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.585815ms","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:44:23.782447Z","caller":"traceutil/trace.go:171","msg":"trace[271015426] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:934; }","duration":"126.732659ms","start":"2026-02-24T10:44:23.655697Z","end":"2026-02-24T10:44:23.78243Z","steps":["trace[271015426] 'range keys from in-memory index tree' (duration: 126.368187ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:44:23.78277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.136943ms","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:44:23.782885Z","caller":"traceutil/trace.go:171","msg":"trace[1849023593] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:934; }","duration":"127.257688ms","start":"2026-02-24T10:44:23.655575Z","end":"2026-02-24T10:44:23.782833Z","steps":["trace[1849023593] 'range keys from in-memory index tree' (duration: 126.969267ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:44:23.783375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.349081ms","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:44:23.783474Z","caller":"traceutil/trace.go:171","msg":"trace[332096176] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:934; }","duration":"459.496246ms","start":"2026-02-24T10:44:23.323955Z","end":"2026-02-24T10:44:23.783451Z","steps":["trace[332096176] 'range keys from in-memory index tree' (duration: 459.060531ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:44:23.783876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:44:23.323927Z","time spent":"459.9286ms","remote":"127.0.0.1:44862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-24T10:44:24.287012Z","caller":"traceutil/trace.go:171","msg":"trace[166865998] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"498.655771ms","start":"2026-02-24T10:44:23.788329Z","end":"2026-02-24T10:44:24.286985Z","steps":["trace[166865998] 'process raft request' (duration: 498.606519ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:44:24.287181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:44:23.788324Z","time spent":"498.756206ms","remote":"127.0.0.1:44862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T10:44:24.287351Z","caller":"traceutil/trace.go:171","msg":"trace[554630705] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"499.077837ms","start":"2026-02-24T10:44:23.788261Z","end":"2026-02-24T10:44:24.287339Z","steps":["trace[554630705] 'process raft request' (duration: 498.510197ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:44:24.287419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:44:23.788244Z","time spent":"499.141868ms","remote":"127.0.0.1:44862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:44:25.045143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.101519ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-24T10:44:25.045373Z","caller":"traceutil/trace.go:171","msg":"trace[86367298] linearizableReadLoop","detail":"{readStateIndex:983; appliedIndex:980; }","duration":"937.780523ms","start":"2026-02-24T10:44:24.107574Z","end":"2026-02-24T10:44:25.045354Z","steps":["trace[86367298] 'read index received' (duration: 179.164011ms)","trace[86367298] 'applied index is now lower than readState.Index' (duration: 758.614792ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:44:25.045494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"938.000761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:44:25.045557Z","caller":"traceutil/trace.go:171","msg":"trace[1084243790] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:937; }","duration":"938.107304ms","start":"2026-02-24T10:44:24.107434Z","end":"2026-02-24T10:44:25.045541Z","steps":["trace[1084243790] 'agreement among raft nodes before linearized reading' (duration: 938.00019ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:44:25.045597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:44:24.107412Z","time spent":"938.175727ms","remote":"127.0.0.1:44588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T10:44:25.045608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"737.519196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-24T10:44:25.045664Z","caller":"traceutil/trace.go:171","msg":"trace[1836657947] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:937; }","duration":"737.612989ms","start":"2026-02-24T10:44:24.308034Z","end":"2026-02-24T10:44:25.045647Z","steps":["trace[1836657947] 'agreement among raft nodes before linearized reading' (duration: 737.444003ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:44:25.045711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:44:24.308015Z","time spent":"737.685141ms","remote":"127.0.0.1:44862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-24T10:44:25.544564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.850439ms","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:44:25.544722Z","caller":"traceutil/trace.go:171","msg":"trace[860579064] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:938; }","duration":"336.028146ms","start":"2026-02-24T10:44:25.208607Z","end":"2026-02-24T10:44:25.544635Z","steps":["trace[860579064] 'range keys from in-memory index tree' (duration: 335.587379ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:44:25.54511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:44:25.208588Z","time spent":"336.500882ms","remote":"127.0.0.1:44862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-24T10:44:26.515173Z","caller":"traceutil/trace.go:171","msg":"trace[1329902598] linearizableReadLoop","detail":"{readStateIndex:986; appliedIndex:985; }","duration":"407.880055ms","start":"2026-02-24T10:44:26.107263Z","end":"2026-02-24T10:44:26.515143Z","steps":["trace[1329902598] 'read index received' (duration: 407.648317ms)","trace[1329902598] 'applied index is now lower than readState.Index' (duration: 230.618µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:44:26.515275Z","caller":"traceutil/trace.go:171","msg":"trace[729687579] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"628.242381ms","start":"2026-02-24T10:44:25.886997Z","end":"2026-02-24T10:44:26.51524Z","steps":["trace[729687579] 'process raft request' (duration: 627.91761ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:44:26.51536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.079911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:44:26.515405Z","caller":"traceutil/trace.go:171","msg":"trace[1329185613] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:940; }","duration":"408.175914ms","start":"2026-02-24T10:44:26.107218Z","end":"2026-02-24T10:44:26.515394Z","steps":["trace[1329185613] 'agreement among raft nodes before linearized reading' (duration: 408.089461ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:44:26.51544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:44:26.107201Z","time spent":"408.231747ms","remote":"127.0.0.1:44588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T10:44:26.515567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.396031ms","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:44:26.515567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.859636ms","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:44:26.515648Z","caller":"traceutil/trace.go:171","msg":"trace[1505681558] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:940; }","duration":"223.516295ms","start":"2026-02-24T10:44:26.292112Z","end":"2026-02-24T10:44:26.515629Z","steps":["trace[1505681558] 'agreement among raft nodes before linearized reading' (duration: 223.297258ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:44:26.515663Z","caller":"traceutil/trace.go:171","msg":"trace[98349066] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:940; }","duration":"223.994702ms","start":"2026-02-24T10:44:26.29165Z","end":"2026-02-24T10:44:26.515644Z","steps":["trace[98349066] 'agreement among raft nodes before linearized reading' (duration: 223.740983ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:44:26.515803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:44:25.886973Z","time spent":"628.382266ms","remote":"127.0.0.1:44862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:44:26.73302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.507089ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T10:44:26.733118Z","caller":"traceutil/trace.go:171","msg":"trace[132081020] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"213.391148ms","start":"2026-02-24T10:44:26.519712Z","end":"2026-02-24T10:44:26.733103Z","steps":["trace[132081020] 'process raft request' (duration: 68.737124ms)","trace[132081020] 'compare' (duration: 144.408976ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:44:27.185736Z","caller":"traceutil/trace.go:171","msg":"trace[870697481] linearizableReadLoop","detail":"{readStateIndex:988; appliedIndex:987; }","duration":"229.498511ms","start":"2026-02-24T10:44:26.956213Z","end":"2026-02-24T10:44:27.185711Z","steps":["trace[870697481] 'read index received' (duration: 229.259662ms)","trace[870697481] 'applied index is now lower than readState.Index' (duration: 237.669µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:44:27.185828Z","caller":"traceutil/trace.go:171","msg":"trace[1631022324] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"665.77283ms","start":"2026-02-24T10:44:26.520017Z","end":"2026-02-24T10:44:27.18579Z","steps":["trace[1631022324] 'process raft request' (duration: 665.523932ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:44:27.185989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:44:26.519999Z","time spent":"665.905655ms","remote":"127.0.0.1:44862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:44:27.186105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.871923ms","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:44:27.186197Z","caller":"traceutil/trace.go:171","msg":"trace[1883366717] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:942; }","duration":"230.020839ms","start":"2026-02-24T10:44:26.956161Z","end":"2026-02-24T10:44:27.186182Z","steps":["trace[1883366717] 'agreement among raft nodes before linearized reading' (duration: 229.801261ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:44:27.810468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.551241ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T10:44:27.810618Z","caller":"traceutil/trace.go:171","msg":"trace[323798491] linearizableReadLoop","detail":"{readStateIndex:989; appliedIndex:988; }","duration":"309.187549ms","start":"2026-02-24T10:44:27.501408Z","end":"2026-02-24T10:44:27.810595Z","steps":["trace[323798491] 'read index received' (duration: 22.40731ms)","trace[323798491] 'applied index is now lower than readState.Index' (duration: 286.778519ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:44:27.810692Z","caller":"traceutil/trace.go:171","msg":"trace[282111088] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"620.4375ms","start":"2026-02-24T10:44:27.190234Z","end":"2026-02-24T10:44:27.810671Z","steps":["trace[282111088] 'process raft request' (duration: 333.586788ms)","trace[282111088] 'compare' (duration: 286.20196ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:44:27.810795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:44:27.190213Z","time spent":"620.527672ms","remote":"127.0.0.1:44862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:44:27.810824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.402407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2476"} {"level":"info","ts":"2026-02-24T10:44:27.810879Z","caller":"traceutil/trace.go:171","msg":"trace[114701578] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:943; }","duration":"309.535581ms","start":"2026-02-24T10:44:27.501326Z","end":"2026-02-24T10:44:27.810862Z","steps":["trace[114701578] 'agreement among raft nodes before linearized reading' (duration: 309.343895ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:44:27.810934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:44:27.501292Z","time spent":"309.629024ms","remote":"127.0.0.1:44792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":2498,"request content":"key:\"/registry/pods/default/test-pvc-pod\" "} {"level":"warn","ts":"2026-02-24T10:44:27.811004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.536857ms","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:44:27.811256Z","caller":"traceutil/trace.go:171","msg":"trace[1046005299] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:943; }","duration":"245.015484ms","start":"2026-02-24T10:44:27.566206Z","end":"2026-02-24T10:44:27.811221Z","steps":["trace[1046005299] 'agreement among raft nodes before linearized reading' (duration: 244.501486ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:44:27.811373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.356294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T10:44:27.811446Z","caller":"traceutil/trace.go:171","msg":"trace[688388139] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:943; }","duration":"199.508749ms","start":"2026-02-24T10:44:27.61192Z","end":"2026-02-24T10:44:27.811429Z","steps":["trace[688388139] 'agreement among raft nodes before linearized reading' (duration: 199.310693ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:44:28.676375Z","caller":"traceutil/trace.go:171","msg":"trace[698381823] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"151.546449ms","start":"2026-02-24T10:44:28.524809Z","end":"2026-02-24T10:44:28.676356Z","steps":["trace[698381823] 'process raft request' (duration: 151.422545ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:44:28.917619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.800089ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T10:44:28.91774Z","caller":"traceutil/trace.go:171","msg":"trace[856199719] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"176.474307ms","start":"2026-02-24T10:44:28.741248Z","end":"2026-02-24T10:44:28.917722Z","steps":["trace[856199719] 'process raft request' (duration: 10.46746ms)","trace[856199719] 'compare' (duration: 165.695025ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:44:29.438232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.994788ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T10:44:29.438358Z","caller":"traceutil/trace.go:171","msg":"trace[1966732802] linearizableReadLoop","detail":"{readStateIndex:995; appliedIndex:994; }","duration":"248.144228ms","start":"2026-02-24T10:44:29.190192Z","end":"2026-02-24T10:44:29.438336Z","steps":["trace[1966732802] 'read index received' (duration: 101.856931ms)","trace[1966732802] 'applied index is now lower than readState.Index' (duration: 146.286047ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:44:29.438523Z","caller":"traceutil/trace.go:171","msg":"trace[1607359544] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"300.310641ms","start":"2026-02-24T10:44:29.138138Z","end":"2026-02-24T10:44:29.438449Z","steps":["trace[1607359544] 'process raft request' (duration: 153.984322ms)","trace[1607359544] 'compare' (duration: 145.879564ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:44:29.438622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.436788ms","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:44:29.43866Z","caller":"traceutil/trace.go:171","msg":"trace[1812323730] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:948; }","duration":"248.512131ms","start":"2026-02-24T10:44:29.190138Z","end":"2026-02-24T10:44:29.43865Z","steps":["trace[1812323730] 'agreement among raft nodes before linearized reading' (duration: 248.306764ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:44:29.438888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:44:29.138117Z","time spent":"300.513218ms","remote":"127.0.0.1:44628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T10:44:29.931874Z","caller":"traceutil/trace.go:171","msg":"trace[1490961468] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"111.505573ms","start":"2026-02-24T10:44:29.820345Z","end":"2026-02-24T10:44:29.931851Z","steps":["trace[1490961468] 'process raft request' (duration: 111.271094ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:44:31.422381Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853968727283981261,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-24T10:44:31.468614Z","caller":"traceutil/trace.go:171","msg":"trace[455338607] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"783.842416ms","start":"2026-02-24T10:44:30.68475Z","end":"2026-02-24T10:44:31.468592Z","steps":["trace[455338607] 'process raft request' (duration: 783.704282ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:44:31.468754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:44:30.684723Z","time spent":"783.95092ms","remote":"127.0.0.1:44862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:44:31.469156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.762832ms","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:44:31.469202Z","caller":"traceutil/trace.go:171","msg":"trace[988791773] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:952; }","duration":"547.839865ms","start":"2026-02-24T10:44:30.921351Z","end":"2026-02-24T10:44:31.469191Z","steps":["trace[988791773] 'agreement among raft nodes before linearized reading' (duration: 547.717481ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:44:31.469171Z","caller":"traceutil/trace.go:171","msg":"trace[688674310] linearizableReadLoop","detail":"{readStateIndex:999; appliedIndex:999; }","duration":"547.598426ms","start":"2026-02-24T10:44:30.921397Z","end":"2026-02-24T10:44:31.468996Z","steps":["trace[688674310] 'read index received' (duration: 547.588876ms)","trace[688674310] 'applied index is now lower than readState.Index' (duration: 7.11µs)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:44:31.46924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:44:30.92133Z","time spent":"547.898757ms","remote":"127.0.0.1:44862","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:44:32.124892Z","caller":"traceutil/trace.go:171","msg":"trace[263261574] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"651.553907ms","start":"2026-02-24T10:44:31.473309Z","end":"2026-02-24T10:44:32.124863Z","steps":["trace[263261574] 'process raft request' (duration: 643.624716ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:44:32.125062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:44:31.473283Z","time spent":"651.688192ms","remote":"127.0.0.1:44862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:44:32.507539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.222466ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T10:44:32.507663Z","caller":"traceutil/trace.go:171","msg":"trace[1718967019] linearizableReadLoop","detail":"{readStateIndex:1001; appliedIndex:999; }","duration":"669.508374ms","start":"2026-02-24T10:44:31.838135Z","end":"2026-02-24T10:44:32.507644Z","steps":["trace[1718967019] 'read index received' (duration: 278.836971ms)","trace[1718967019] 'applied index is now lower than readState.Index' (duration: 390.669723ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:44:32.50779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"669.656209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:44:32.507869Z","caller":"traceutil/trace.go:171","msg":"trace[1238414412] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:954; }","duration":"669.769683ms","start":"2026-02-24T10:44:31.838088Z","end":"2026-02-24T10:44:32.507857Z","steps":["trace[1238414412] 'agreement among raft nodes before linearized reading' (duration: 669.649089ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:44:32.507937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.787706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-24T10:44:32.507915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.427308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T10:44:32.507974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:44:31.838049Z","time spent":"669.905537ms","remote":"127.0.0.1:45024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":27,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"info","ts":"2026-02-24T10:44:32.508025Z","caller":"traceutil/trace.go:171","msg":"trace[1289345474] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:954; }","duration":"400.585774ms","start":"2026-02-24T10:44:32.107421Z","end":"2026-02-24T10:44:32.508007Z","steps":["trace[1289345474] 'agreement among raft nodes before linearized reading' (duration: 400.380167ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:44:32.508057Z","caller":"traceutil/trace.go:171","msg":"trace[1688175229] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:954; }","duration":"571.935661ms","start":"2026-02-24T10:44:31.936085Z","end":"2026-02-24T10:44:32.50802Z","steps":["trace[1688175229] 'agreement among raft nodes before linearized reading' (duration: 571.700483ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:44:32.508106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:44:31.936064Z","time spent":"572.031864ms","remote":"127.0.0.1:44862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-24T10:44:32.508128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:44:32.107402Z","time spent":"400.693617ms","remote":"127.0.0.1:44588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T10:44:32.508187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.883291ms","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:44:32.508278Z","caller":"traceutil/trace.go:171","msg":"trace[363739259] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:954; }","duration":"554.023066ms","start":"2026-02-24T10:44:31.954235Z","end":"2026-02-24T10:44:32.508258Z","steps":["trace[363739259] 'agreement among raft nodes before linearized reading' (duration: 553.764427ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:44:32.508326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:44:31.954213Z","time spent":"554.103889ms","remote":"127.0.0.1:44862","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:44:32.509246Z","caller":"traceutil/trace.go:171","msg":"trace[444210183] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"1.034582649s","start":"2026-02-24T10:44:31.4746Z","end":"2026-02-24T10:44:32.509182Z","steps":["trace[444210183] 'process raft request' (duration: 841.917129ms)","trace[444210183] 'compare' (duration: 189.821852ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:44:32.509412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:44:31.474582Z","time spent":"1.034746885s","remote":"127.0.0.1:44862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:44:32.855491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.097135ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T10:44:32.856064Z","caller":"traceutil/trace.go:171","msg":"trace[15946091] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"341.444631ms","start":"2026-02-24T10:44:32.514588Z","end":"2026-02-24T10:44:32.856032Z","steps":["trace[15946091] 'process raft request' (duration: 126.735185ms)","trace[15946091] 'compare' (duration: 213.988922ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:44:32.856064Z","caller":"traceutil/trace.go:171","msg":"trace[509329630] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"155.030816ms","start":"2026-02-24T10:44:32.700827Z","end":"2026-02-24T10:44:32.855857Z","steps":["trace[509329630] 'process raft request' (duration: 154.872011ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:44:32.856492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:44:32.51458Z","time spent":"341.600536ms","remote":"127.0.0.1:44862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:44:33.100191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.932562ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:44:33.100317Z","caller":"traceutil/trace.go:171","msg":"trace[25178385] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:957; }","duration":"102.071058ms","start":"2026-02-24T10:44:32.998228Z","end":"2026-02-24T10:44:33.100299Z","steps":["trace[25178385] 'range keys from in-memory index tree' (duration: 101.91585ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:44:33.642687Z","caller":"traceutil/trace.go:171","msg":"trace[1566942106] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"165.573158ms","start":"2026-02-24T10:44:33.477088Z","end":"2026-02-24T10:44:33.642661Z","steps":["trace[1566942106] 'process raft request' (duration: 165.402382ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:44:35.265976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.147633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T10:44:35.266027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.882867ms","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:44:35.266095Z","caller":"traceutil/trace.go:171","msg":"trace[1734455218] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:962; }","duration":"158.319448ms","start":"2026-02-24T10:44:35.107749Z","end":"2026-02-24T10:44:35.266069Z","steps":["trace[1734455218] 'range keys from in-memory index tree' (duration: 157.868873ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:44:35.266136Z","caller":"traceutil/trace.go:171","msg":"trace[1800766560] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:962; }","duration":"267.258871ms","start":"2026-02-24T10:44:34.998855Z","end":"2026-02-24T10:44:35.266114Z","steps":["trace[1800766560] 'count revisions from in-memory index tree' (duration: 266.738763ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:44:35.754Z","caller":"traceutil/trace.go:171","msg":"trace[869342299] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"102.080197ms","start":"2026-02-24T10:44:35.651889Z","end":"2026-02-24T10:44:35.753969Z","steps":["trace[869342299] 'process raft request' (duration: 101.919761ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:44:36.693758Z","caller":"traceutil/trace.go:171","msg":"trace[1073338841] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"147.974552ms","start":"2026-02-24T10:44:36.545754Z","end":"2026-02-24T10:44:36.693728Z","steps":["trace[1073338841] 'process raft request' (duration: 147.726994ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:44:39.137627Z","caller":"traceutil/trace.go:171","msg":"trace[96725362] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"186.223244ms","start":"2026-02-24T10:44:38.951358Z","end":"2026-02-24T10:44:39.137582Z","steps":["trace[96725362] 'process raft request' (duration: 88.294552ms)","trace[96725362] 'compare' (duration: 97.732206ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:44:41.228267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.045415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:44:41.228361Z","caller":"traceutil/trace.go:171","msg":"trace[1255270912] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:983; }","duration":"121.204841ms","start":"2026-02-24T10:44:41.107134Z","end":"2026-02-24T10:44:41.228339Z","steps":["trace[1255270912] 'range keys from in-memory index tree' (duration: 120.957192ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:44:44.043899Z","caller":"traceutil/trace.go:171","msg":"trace[1912241073] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"232.622594ms","start":"2026-02-24T10:44:43.81125Z","end":"2026-02-24T10:44:44.043872Z","steps":["trace[1912241073] 'process raft request' (duration: 232.463209ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:44:44.734885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.561775ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-24T10:44:44.734994Z","caller":"traceutil/trace.go:171","msg":"trace[956127316] linearizableReadLoop","detail":"{readStateIndex:1042; appliedIndex:1041; }","duration":"627.173245ms","start":"2026-02-24T10:44:44.107801Z","end":"2026-02-24T10:44:44.734975Z","steps":["trace[956127316] 'read index received' (duration: 74.422714ms)","trace[956127316] 'applied index is now lower than readState.Index' (duration: 552.748991ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:44:44.735151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.702034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:44:44.735247Z","caller":"traceutil/trace.go:171","msg":"trace[1289234254] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:992; }","duration":"500.823609ms","start":"2026-02-24T10:44:44.234403Z","end":"2026-02-24T10:44:44.735227Z","steps":["trace[1289234254] 'agreement among raft nodes before linearized reading' (duration: 500.696094ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:44:44.735148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.640195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T10:44:44.735335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.515148ms","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:44:44.735408Z","caller":"traceutil/trace.go:171","msg":"trace[1753968061] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:992; }","duration":"322.630823ms","start":"2026-02-24T10:44:44.412757Z","end":"2026-02-24T10:44:44.735388Z","steps":["trace[1753968061] 'agreement among raft nodes before linearized reading' (duration: 322.326793ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:44:44.735403Z","caller":"traceutil/trace.go:171","msg":"trace[1065094724] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:992; }","duration":"533.906814ms","start":"2026-02-24T10:44:44.201476Z","end":"2026-02-24T10:44:44.735383Z","steps":["trace[1065094724] 'agreement among raft nodes before linearized reading' (duration: 533.604554ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:44:44.735452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:44:44.412726Z","time spent":"322.716106ms","remote":"127.0.0.1:44862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-24T10:44:44.735463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:44:44.201434Z","time spent":"534.012078ms","remote":"127.0.0.1:44894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-24T10:44:44.735119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.299699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:44:44.735654Z","caller":"traceutil/trace.go:171","msg":"trace[1803994213] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:992; }","duration":"627.781735ms","start":"2026-02-24T10:44:44.107756Z","end":"2026-02-24T10:44:44.735538Z","steps":["trace[1803994213] 'agreement among raft nodes before linearized reading' (duration: 627.297669ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:44:44.735299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:44:44.234392Z","time spent":"500.894811ms","remote":"127.0.0.1:44746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":27,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"warn","ts":"2026-02-24T10:44:44.735882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:44:44.107736Z","time spent":"628.114327ms","remote":"127.0.0.1:44588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-24T10:44:45.219868Z","caller":"traceutil/trace.go:171","msg":"trace[1071825358] linearizableReadLoop","detail":"{readStateIndex:1043; appliedIndex:1042; }","duration":"391.954251ms","start":"2026-02-24T10:44:44.827889Z","end":"2026-02-24T10:44:45.219844Z","steps":["trace[1071825358] 'read index received' (duration: 391.64635ms)","trace[1071825358] 'applied index is now lower than readState.Index' (duration: 306.561µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:44:45.219988Z","caller":"traceutil/trace.go:171","msg":"trace[138841688] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"478.810813ms","start":"2026-02-24T10:44:44.74114Z","end":"2026-02-24T10:44:45.219951Z","steps":["trace[138841688] 'process raft request' (duration: 478.516203ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:44:45.220111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.204ms","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:44:45.220156Z","caller":"traceutil/trace.go:171","msg":"trace[1639344252] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:993; }","duration":"392.299543ms","start":"2026-02-24T10:44:44.827845Z","end":"2026-02-24T10:44:45.220144Z","steps":["trace[1639344252] 'agreement among raft nodes before linearized reading' (duration: 392.135578ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:44:45.220166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:44:44.741122Z","time spent":"478.947907ms","remote":"127.0.0.1:44862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:44:45.220196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:44:44.82783Z","time spent":"392.354034ms","remote":"127.0.0.1:44862","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:44:45.220275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.739492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-24T10:44:45.220321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.114284ms","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:44:45.220371Z","caller":"traceutil/trace.go:171","msg":"trace[1537054512] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:993; }","duration":"365.879326ms","start":"2026-02-24T10:44:44.854469Z","end":"2026-02-24T10:44:45.220349Z","steps":["trace[1537054512] 'agreement among raft nodes before linearized reading' (duration: 365.574656ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:44:45.220387Z","caller":"traceutil/trace.go:171","msg":"trace[1078849457] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:993; }","duration":"123.223419ms","start":"2026-02-24T10:44:45.097145Z","end":"2026-02-24T10:44:45.220368Z","steps":["trace[1078849457] 'agreement among raft nodes before linearized reading' (duration: 123.026552ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:44:45.220438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:44:44.854457Z","time spent":"365.96944ms","remote":"127.0.0.1:44862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-24T10:44:46.549986Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853968727283981379,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T10:44:46.879387Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.65346244s","expected-duration":"1s"} {"level":"info","ts":"2026-02-24T10:44:46.879833Z","caller":"traceutil/trace.go:171","msg":"trace[1284704952] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"1.653986008s","start":"2026-02-24T10:44:45.225823Z","end":"2026-02-24T10:44:46.879809Z","steps":["trace[1284704952] 'process raft request' (duration: 1.653817262s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:44:46.880102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:44:45.225803Z","time spent":"1.654104432s","remote":"127.0.0.1:44862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T10:44:47.008283Z","caller":"traceutil/trace.go:171","msg":"trace[1902562017] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"1.782042103s","start":"2026-02-24T10:44:45.226214Z","end":"2026-02-24T10:44:47.008256Z","steps":["trace[1902562017] 'process raft request' (duration: 1.721390812s)","trace[1902562017] 'compare' (duration: 60.300789ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:44:47.008403Z","caller":"traceutil/trace.go:171","msg":"trace[1515585389] linearizableReadLoop","detail":"{readStateIndex:1046; appliedIndex:1043; }","duration":"959.156023ms","start":"2026-02-24T10:44:46.049227Z","end":"2026-02-24T10:44:47.008383Z","steps":["trace[1515585389] 'read index received' (duration: 830.510499ms)","trace[1515585389] 'applied index is now lower than readState.Index' (duration: 128.644414ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:44:47.008676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"902.286601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:44:47.008729Z","caller":"traceutil/trace.go:171","msg":"trace[1362934452] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:996; }","duration":"902.382085ms","start":"2026-02-24T10:44:46.106335Z","end":"2026-02-24T10:44:47.008717Z","steps":["trace[1362934452] 'agreement among raft nodes before linearized reading' (duration: 902.25756ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:44:47.008764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:44:46.106303Z","time spent":"902.452557ms","remote":"127.0.0.1:44588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T10:44:47.008794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"959.560207ms","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:44:47.008851Z","caller":"traceutil/trace.go:171","msg":"trace[1379195256] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:996; }","duration":"959.65159ms","start":"2026-02-24T10:44:46.049181Z","end":"2026-02-24T10:44:47.008833Z","steps":["trace[1379195256] 'agreement among raft nodes before linearized reading' (duration: 959.458283ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:44:47.008917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:44:46.049163Z","time spent":"959.738913ms","remote":"127.0.0.1:44862","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:44:47.009179Z","caller":"traceutil/trace.go:171","msg":"trace[427560171] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"1.782919893s","start":"2026-02-24T10:44:45.226242Z","end":"2026-02-24T10:44:47.009161Z","steps":["trace[427560171] 'process raft request' (duration: 1.782065754s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:44:47.009286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:44:45.226231Z","time spent":"1.782995656s","remote":"127.0.0.1:44862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:44:47.009782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.010005ms","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:44:47.009942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:44:45.226205Z","time spent":"1.783607207s","remote":"127.0.0.1:44862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T10:44:47.01001Z","caller":"traceutil/trace.go:171","msg":"trace[424210747] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:996; }","duration":"212.187591ms","start":"2026-02-24T10:44:46.7977Z","end":"2026-02-24T10:44:47.009888Z","steps":["trace[424210747] 'agreement among raft nodes before linearized reading' (duration: 211.528989ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:44:47.44818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.512003ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T10:44:47.44829Z","caller":"traceutil/trace.go:171","msg":"trace[1234030344] linearizableReadLoop","detail":"{readStateIndex:1047; appliedIndex:1046; }","duration":"222.930939ms","start":"2026-02-24T10:44:47.225336Z","end":"2026-02-24T10:44:47.448267Z","steps":["trace[1234030344] 'read index received' (duration: 70.293322ms)","trace[1234030344] 'applied index is now lower than readState.Index' (duration: 152.635647ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:44:47.448618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.290381ms","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:44:47.448629Z","caller":"traceutil/trace.go:171","msg":"trace[1948621961] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"432.658105ms","start":"2026-02-24T10:44:47.015917Z","end":"2026-02-24T10:44:47.448575Z","steps":["trace[1948621961] 'process raft request' (duration: 279.674636ms)","trace[1948621961] 'compare' (duration: 152.12727ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:44:47.44867Z","caller":"traceutil/trace.go:171","msg":"trace[473211636] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:997; }","duration":"223.386914ms","start":"2026-02-24T10:44:47.225263Z","end":"2026-02-24T10:44:47.44865Z","steps":["trace[473211636] 'agreement among raft nodes before linearized reading' (duration: 223.070243ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:44:47.448918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:44:47.015902Z","time spent":"432.834491ms","remote":"127.0.0.1:44862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:45:09.126624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.886392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-system/system:controller:cloud-provider\" ","response":"range_response_count:1 size:625"} {"level":"info","ts":"2026-02-24T10:45:09.126712Z","caller":"traceutil/trace.go:171","msg":"trace[442395528] range","detail":"{range_begin:/registry/roles/kube-system/system:controller:cloud-provider; range_end:; response_count:1; response_revision:1060; }","duration":"143.983925ms","start":"2026-02-24T10:45:08.98271Z","end":"2026-02-24T10:45:09.126694Z","steps":["trace[442395528] 'range keys from in-memory index tree' (duration: 143.82954ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:45:13.509999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.205448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:70 size:51621"} {"level":"info","ts":"2026-02-24T10:45:13.510088Z","caller":"traceutil/trace.go:171","msg":"trace[702528636] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:70; response_revision:1074; }","duration":"115.359683ms","start":"2026-02-24T10:45:13.394711Z","end":"2026-02-24T10:45:13.51007Z","steps":["trace[702528636] 'range keys from in-memory index tree' (duration: 114.757572ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:45:13.510226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.729862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:70 size:51621"} {"level":"info","ts":"2026-02-24T10:45:13.510342Z","caller":"traceutil/trace.go:171","msg":"trace[730975933] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:70; response_revision:1074; }","duration":"114.867386ms","start":"2026-02-24T10:45:13.395452Z","end":"2026-02-24T10:45:13.510319Z","steps":["trace[730975933] 'range keys from in-memory index tree' (duration: 114.237865ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:45:19.456789Z","caller":"traceutil/trace.go:171","msg":"trace[788261398] linearizableReadLoop","detail":"{readStateIndex:1147; appliedIndex:1146; }","duration":"113.83461ms","start":"2026-02-24T10:45:19.342929Z","end":"2026-02-24T10:45:19.456764Z","steps":["trace[788261398] 'read index received' (duration: 113.563021ms)","trace[788261398] 'applied index is now lower than readState.Index' (duration: 270.319µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:45:19.456848Z","caller":"traceutil/trace.go:171","msg":"trace[1718867120] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"131.127844ms","start":"2026-02-24T10:45:19.325701Z","end":"2026-02-24T10:45:19.456829Z","steps":["trace[1718867120] 'process raft request' (duration: 130.864695ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:45:19.456987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.033387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5098"} {"level":"info","ts":"2026-02-24T10:45:19.457152Z","caller":"traceutil/trace.go:171","msg":"trace[962172735] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:1089; }","duration":"114.194503ms","start":"2026-02-24T10:45:19.3429Z","end":"2026-02-24T10:45:19.457094Z","steps":["trace[962172735] 'agreement among raft nodes before linearized reading' (duration: 113.994726ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:45:19.653051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.472571ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T10:45:19.653246Z","caller":"traceutil/trace.go:171","msg":"trace[348525668] linearizableReadLoop","detail":"{readStateIndex:1149; appliedIndex:1147; }","duration":"188.703805ms","start":"2026-02-24T10:45:19.464526Z","end":"2026-02-24T10:45:19.65323Z","steps":["trace[348525668] 'read index received' (duration: 38.920673ms)","trace[348525668] 'applied index is now lower than readState.Index' (duration: 149.782182ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:45:19.653368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.83659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5098"} {"level":"info","ts":"2026-02-24T10:45:19.653406Z","caller":"traceutil/trace.go:171","msg":"trace[94731695] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:1091; }","duration":"188.893942ms","start":"2026-02-24T10:45:19.464503Z","end":"2026-02-24T10:45:19.653397Z","steps":["trace[94731695] 'agreement among raft nodes before linearized reading' (duration: 188.780828ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:45:19.653403Z","caller":"traceutil/trace.go:171","msg":"trace[55236370] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"191.685137ms","start":"2026-02-24T10:45:19.461698Z","end":"2026-02-24T10:45:19.653383Z","steps":["trace[55236370] 'process raft request' (duration: 41.783382ms)","trace[55236370] 'compare' (duration: 149.345846ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:45:19.653604Z","caller":"traceutil/trace.go:171","msg":"trace[642077023] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"191.316995ms","start":"2026-02-24T10:45:19.462276Z","end":"2026-02-24T10:45:19.653593Z","steps":["trace[642077023] 'process raft request' (duration: 190.900101ms)"],"step_count":1}