{"level":"warn","ts":"2026-02-26T06:17:08.168776Z","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-26T06:17:08.1691Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.233: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.233:2380","--initial-cluster=instance=https://199.204.45.233:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.233:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.233: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-26T06:17:08.169319Z","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-26T06:17:08.17077Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.233:2380"]} {"level":"info","ts":"2026-02-26T06:17:08.170835Z","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-26T06:17:08.171693Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.233:2379"]} {"level":"info","ts":"2026-02-26T06:17:08.172013Z","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.233:2380"],"listen-peer-urls":["https://199.204.45.233:2380"],"advertise-client-urls":["https://199.204.45.233:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.233:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.233: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-26T06:17:08.178347Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.87812ms"} {"level":"info","ts":"2026-02-26T06:17:08.184836Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"90a0cb2044183f95","cluster-id":"782fef34d5ea94f9"} {"level":"info","ts":"2026-02-26T06:17:08.184921Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 switched to configuration voters=()"} {"level":"info","ts":"2026-02-26T06:17:08.18495Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became follower at term 0"} {"level":"info","ts":"2026-02-26T06:17:08.18497Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 90a0cb2044183f95 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-26T06:17:08.184987Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became follower at term 1"} {"level":"info","ts":"2026-02-26T06:17:08.185031Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 switched to configuration voters=(10421552877177159573)"} {"level":"warn","ts":"2026-02-26T06:17:08.1912Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-26T06:17:08.192682Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-26T06:17:08.193905Z","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-26T06:17:08.195911Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"90a0cb2044183f95","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-26T06:17:08.196108Z","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-26T06:17:08.19621Z","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-26T06:17:08.19622Z","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-26T06:17:08.196533Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"90a0cb2044183f95","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-26T06:17:08.196933Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 switched to configuration voters=(10421552877177159573)"} {"level":"info","ts":"2026-02-26T06:17:08.197072Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"782fef34d5ea94f9","local-member-id":"90a0cb2044183f95","added-peer-id":"90a0cb2044183f95","added-peer-peer-urls":["https://199.204.45.233:2380"]} {"level":"info","ts":"2026-02-26T06:17:08.201988Z","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-26T06:17:08.20228Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"90a0cb2044183f95","initial-advertise-peer-urls":["https://199.204.45.233:2380"],"listen-peer-urls":["https://199.204.45.233:2380"],"advertise-client-urls":["https://199.204.45.233:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.233:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-26T06:17:08.20232Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-26T06:17:08.202511Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.233:2380"} {"level":"info","ts":"2026-02-26T06:17:08.20254Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.233:2380"} {"level":"info","ts":"2026-02-26T06:17:08.585627Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 is starting a new election at term 1"} {"level":"info","ts":"2026-02-26T06:17:08.585703Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-26T06:17:08.585749Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 received MsgPreVoteResp from 90a0cb2044183f95 at term 1"} {"level":"info","ts":"2026-02-26T06:17:08.585771Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became candidate at term 2"} {"level":"info","ts":"2026-02-26T06:17:08.58579Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 received MsgVoteResp from 90a0cb2044183f95 at term 2"} {"level":"info","ts":"2026-02-26T06:17:08.585806Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became leader at term 2"} {"level":"info","ts":"2026-02-26T06:17:08.585827Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 90a0cb2044183f95 elected leader 90a0cb2044183f95 at term 2"} {"level":"info","ts":"2026-02-26T06:17:08.663107Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T06:17:08.665752Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"90a0cb2044183f95","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.233:2379]}","request-path":"/0/members/90a0cb2044183f95/attributes","cluster-id":"782fef34d5ea94f9","publish-timeout":"7s"} {"level":"info","ts":"2026-02-26T06:17:08.665964Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-26T06:17:08.666018Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-26T06:17:08.666327Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-26T06:17:08.666369Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-26T06:17:08.668161Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-26T06:17:08.668388Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"782fef34d5ea94f9","local-member-id":"90a0cb2044183f95","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T06:17:08.668534Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T06:17:08.668588Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T06:17:08.670872Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.233:2379"} {"level":"info","ts":"2026-02-26T06:17:38.483795Z","caller":"traceutil/trace.go:171","msg":"trace[486315436] linearizableReadLoop","detail":"{readStateIndex:523; appliedIndex:522; }","duration":"187.51036ms","start":"2026-02-26T06:17:38.296256Z","end":"2026-02-26T06:17:38.483766Z","steps":["trace[486315436] 'read index received' (duration: 187.231344ms)","trace[486315436] 'applied index is now lower than readState.Index' (duration: 277.206µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:17:38.483901Z","caller":"traceutil/trace.go:171","msg":"trace[1991040296] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"243.559669ms","start":"2026-02-26T06:17:38.2403Z","end":"2026-02-26T06:17:38.48386Z","steps":["trace[1991040296] 'process raft request' (duration: 243.260572ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:17:38.484063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.236104ms","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-26T06:17:38.48403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.744206ms","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-26T06:17:38.484144Z","caller":"traceutil/trace.go:171","msg":"trace[1976643168] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:506; }","duration":"187.385108ms","start":"2026-02-26T06:17:38.29674Z","end":"2026-02-26T06:17:38.484125Z","steps":["trace[1976643168] 'agreement among raft nodes before linearized reading' (duration: 187.213924ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:17:38.484167Z","caller":"traceutil/trace.go:171","msg":"trace[1179123593] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:506; }","duration":"187.91162ms","start":"2026-02-26T06:17:38.296229Z","end":"2026-02-26T06:17:38.48414Z","steps":["trace[1179123593] 'agreement among raft nodes before linearized reading' (duration: 187.672694ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:17:39.424872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.252841ms","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-26T06:17:39.424926Z","caller":"traceutil/trace.go:171","msg":"trace[686095926] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"936.887104ms","start":"2026-02-26T06:17:38.488008Z","end":"2026-02-26T06:17:39.424896Z","steps":["trace[686095926] 'process raft request' (duration: 919.883178ms)","trace[686095926] 'compare' (duration: 16.894433ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:17:39.425009Z","caller":"traceutil/trace.go:171","msg":"trace[988308081] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:506; }","duration":"145.444075ms","start":"2026-02-26T06:17:39.279545Z","end":"2026-02-26T06:17:39.424989Z","steps":["trace[988308081] 'range keys from in-memory index tree' (duration: 145.21959ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:17:39.425283Z","caller":"traceutil/trace.go:171","msg":"trace[1620259739] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"935.280137ms","start":"2026-02-26T06:17:38.48999Z","end":"2026-02-26T06:17:39.42527Z","steps":["trace[1620259739] 'process raft request' (duration: 935.093462ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:17:39.425559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:17:38.487991Z","time spent":"937.015948ms","remote":"127.0.0.1:49536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T06:17:39.425641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:17:38.489971Z","time spent":"935.338448ms","remote":"127.0.0.1:49536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T06:17:40.84572Z","caller":"traceutil/trace.go:171","msg":"trace[1921587897] linearizableReadLoop","detail":"{readStateIndex:527; appliedIndex:526; }","duration":"437.862172ms","start":"2026-02-26T06:17:40.407836Z","end":"2026-02-26T06:17:40.845698Z","steps":["trace[1921587897] 'read index received' (duration: 437.665197ms)","trace[1921587897] 'applied index is now lower than readState.Index' (duration: 196.044µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:17:40.845841Z","caller":"traceutil/trace.go:171","msg":"trace[1872445156] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"711.319825ms","start":"2026-02-26T06:17:40.134501Z","end":"2026-02-26T06:17:40.845821Z","steps":["trace[1872445156] 'process raft request' (duration: 711.057089ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:17:40.845907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.139516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-02-26T06:17:40.84595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:17:40.134467Z","time spent":"711.403028ms","remote":"127.0.0.1:49448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4567,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T06:17:40.845966Z","caller":"traceutil/trace.go:171","msg":"trace[783776243] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:510; }","duration":"358.245878ms","start":"2026-02-26T06:17:40.487705Z","end":"2026-02-26T06:17:40.845951Z","steps":["trace[783776243] 'agreement among raft nodes before linearized reading' (duration: 358.137686ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:17:40.846036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:17:40.48768Z","time spent":"358.347951ms","remote":"127.0.0.1:49536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-26T06:17:40.846072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.214911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:17:40.846162Z","caller":"traceutil/trace.go:171","msg":"trace[1225157728] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:510; }","duration":"438.358305ms","start":"2026-02-26T06:17:40.407782Z","end":"2026-02-26T06:17:40.84614Z","steps":["trace[1225157728] 'agreement among raft nodes before linearized reading' (duration: 438.19419ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:17:40.846205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:17:40.407756Z","time spent":"438.440756ms","remote":"127.0.0.1:49278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-26T06:17:47.129072Z","caller":"traceutil/trace.go:171","msg":"trace[1594683549] linearizableReadLoop","detail":"{readStateIndex:547; appliedIndex:546; }","duration":"250.415393ms","start":"2026-02-26T06:17:46.878631Z","end":"2026-02-26T06:17:47.129047Z","steps":["trace[1594683549] 'read index received' (duration: 250.205658ms)","trace[1594683549] 'applied index is now lower than readState.Index' (duration: 206.524µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:17:47.129122Z","caller":"traceutil/trace.go:171","msg":"trace[428824130] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"463.039304ms","start":"2026-02-26T06:17:46.66605Z","end":"2026-02-26T06:17:47.129089Z","steps":["trace[428824130] 'process raft request' (duration: 462.829699ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:17:47.129236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.594147ms","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-26T06:17:47.129281Z","caller":"traceutil/trace.go:171","msg":"trace[1602796822] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:529; }","duration":"250.70477ms","start":"2026-02-26T06:17:46.878565Z","end":"2026-02-26T06:17:47.12927Z","steps":["trace[1602796822] 'agreement among raft nodes before linearized reading' (duration: 250.596027ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:17:47.129359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:17:46.666027Z","time spent":"463.167316ms","remote":"127.0.0.1:49452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T06:17:47.999907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.411408ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-26T06:17:48.000009Z","caller":"traceutil/trace.go:171","msg":"trace[23028699] linearizableReadLoop","detail":"{readStateIndex:549; appliedIndex:548; }","duration":"543.333151ms","start":"2026-02-26T06:17:47.456656Z","end":"2026-02-26T06:17:47.999989Z","steps":["trace[23028699] 'read index received' (duration: 383.707597ms)","trace[23028699] 'applied index is now lower than readState.Index' (duration: 159.623994ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:17:48.000196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.531917ms","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-26T06:17:48.000256Z","caller":"traceutil/trace.go:171","msg":"trace[1245518638] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:530; }","duration":"543.649039ms","start":"2026-02-26T06:17:47.456594Z","end":"2026-02-26T06:17:48.000243Z","steps":["trace[1245518638] 'agreement among raft nodes before linearized reading' (duration: 543.527566ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:17:48.000304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:17:47.45657Z","time spent":"543.72492ms","remote":"127.0.0.1:49536","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-26T06:17:48.000525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.579768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-26T06:17:48.000543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.792023ms","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-26T06:17:48.000569Z","caller":"traceutil/trace.go:171","msg":"trace[1568716804] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:530; }","duration":"533.64425ms","start":"2026-02-26T06:17:47.466914Z","end":"2026-02-26T06:17:48.000558Z","steps":["trace[1568716804] 'agreement among raft nodes before linearized reading' (duration: 533.567258ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:17:48.000618Z","caller":"traceutil/trace.go:171","msg":"trace[1679527638] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:530; }","duration":"538.900555ms","start":"2026-02-26T06:17:47.4617Z","end":"2026-02-26T06:17:48.0006Z","steps":["trace[1679527638] 'agreement among raft nodes before linearized reading' (duration: 538.751601ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:17:48.000615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:17:47.466863Z","time spent":"533.744772ms","remote":"127.0.0.1:49292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T06:17:48.000669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:17:47.461685Z","time spent":"538.974037ms","remote":"127.0.0.1:49536","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-26T06:17:48.001234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.333059ms","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-26T06:17:48.001281Z","caller":"traceutil/trace.go:171","msg":"trace[566388868] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:530; }","duration":"460.40175ms","start":"2026-02-26T06:17:47.540864Z","end":"2026-02-26T06:17:48.001266Z","steps":["trace[566388868] 'agreement among raft nodes before linearized reading' (duration: 460.217766ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:17:48.00141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:17:47.54085Z","time spent":"460.547624ms","remote":"127.0.0.1:49536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-26T06:17:49.426705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.276007217s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"warn","ts":"2026-02-26T06:17:49.426636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.123425ms","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-26T06:17:49.426864Z","caller":"traceutil/trace.go:171","msg":"trace[1955509922] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:531; }","duration":"147.389322ms","start":"2026-02-26T06:17:49.279448Z","end":"2026-02-26T06:17:49.426837Z","steps":["trace[1955509922] 'range keys from in-memory index tree' (duration: 147.090964ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:17:49.426884Z","caller":"traceutil/trace.go:171","msg":"trace[193383470] linearizableReadLoop","detail":"{readStateIndex:552; appliedIndex:550; }","duration":"958.456381ms","start":"2026-02-26T06:17:48.468406Z","end":"2026-02-26T06:17:49.426863Z","steps":["trace[193383470] 'read index received' (duration: 41.83µs)","trace[193383470] 'applied index is now lower than readState.Index' (duration: 958.413631ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:17:49.427138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"958.731488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:17:49.427181Z","caller":"traceutil/trace.go:171","msg":"trace[284003301] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:533; }","duration":"958.83516ms","start":"2026-02-26T06:17:48.468335Z","end":"2026-02-26T06:17:49.42717Z","steps":["trace[284003301] 'agreement among raft nodes before linearized reading' (duration: 958.753628ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:17:49.42723Z","caller":"traceutil/trace.go:171","msg":"trace[113571457] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"1.421206077s","start":"2026-02-26T06:17:48.006011Z","end":"2026-02-26T06:17:49.427217Z","steps":["trace[113571457] 'process raft request' (duration: 1.420795047s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:17:49.427227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:17:48.468311Z","time spent":"958.906742ms","remote":"127.0.0.1:49278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-26T06:17:49.427253Z","caller":"traceutil/trace.go:171","msg":"trace[608230868] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"1.421565725s","start":"2026-02-26T06:17:48.005657Z","end":"2026-02-26T06:17:49.427223Z","steps":["trace[608230868] 'process raft request' (duration: 144.919512ms)","trace[608230868] 'compare' (duration: 1.275828333s)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:17:49.427309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:17:48.005982Z","time spent":"1.421291999s","remote":"127.0.0.1:49536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T06:17:49.42738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.329094ms","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-26T06:17:49.427478Z","caller":"traceutil/trace.go:171","msg":"trace[256632609] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:533; }","duration":"248.481318ms","start":"2026-02-26T06:17:49.178975Z","end":"2026-02-26T06:17:49.427456Z","steps":["trace[256632609] 'agreement among raft nodes before linearized reading' (duration: 248.201181ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:17:49.427411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:17:48.005647Z","time spent":"1.421678028s","remote":"127.0.0.1:49536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T06:17:50.42674Z","caller":"traceutil/trace.go:171","msg":"trace[1990226644] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"995.031954ms","start":"2026-02-26T06:17:49.431678Z","end":"2026-02-26T06:17:50.42671Z","steps":["trace[1990226644] 'process raft request' (duration: 941.393292ms)","trace[1990226644] 'compare' (duration: 53.389986ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:17:50.426792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.669623ms","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-26T06:17:50.426872Z","caller":"traceutil/trace.go:171","msg":"trace[143623024] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:534; }","duration":"346.820477ms","start":"2026-02-26T06:17:50.08003Z","end":"2026-02-26T06:17:50.42685Z","steps":["trace[143623024] 'agreement among raft nodes before linearized reading' (duration: 346.685473ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:17:50.426884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:17:49.431659Z","time spent":"995.151178ms","remote":"127.0.0.1:49536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T06:17:50.426924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:17:50.079996Z","time spent":"346.907738ms","remote":"127.0.0.1:49536","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-26T06:17:50.427073Z","caller":"traceutil/trace.go:171","msg":"trace[832940262] linearizableReadLoop","detail":"{readStateIndex:553; appliedIndex:552; }","duration":"346.598381ms","start":"2026-02-26T06:17:50.080111Z","end":"2026-02-26T06:17:50.42671Z","steps":["trace[832940262] 'read index received' (duration: 292.906668ms)","trace[832940262] 'applied index is now lower than readState.Index' (duration: 53.690223ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:17:52.284666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.883366ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T06:17:52.284829Z","caller":"traceutil/trace.go:171","msg":"trace[1832997647] linearizableReadLoop","detail":"{readStateIndex:574; appliedIndex:573; }","duration":"479.367693ms","start":"2026-02-26T06:17:51.805439Z","end":"2026-02-26T06:17:52.284807Z","steps":["trace[1832997647] 'read index received' (duration: 176.235721ms)","trace[1832997647] 'applied index is now lower than readState.Index' (duration: 303.130052ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:17:52.284923Z","caller":"traceutil/trace.go:171","msg":"trace[1136915724] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"480.119122ms","start":"2026-02-26T06:17:51.804787Z","end":"2026-02-26T06:17:52.284906Z","steps":["trace[1136915724] 'process raft request' (duration: 176.892617ms)","trace[1136915724] 'compare' (duration: 302.757784ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:17:52.28504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:17:51.804772Z","time spent":"480.195643ms","remote":"127.0.0.1:49536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T06:17:52.285083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.597919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:17:52.285208Z","caller":"traceutil/trace.go:171","msg":"trace[2029606746] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:0; response_revision:554; }","duration":"479.742712ms","start":"2026-02-26T06:17:51.805434Z","end":"2026-02-26T06:17:52.285176Z","steps":["trace[2029606746] 'agreement among raft nodes before linearized reading' (duration: 479.522137ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:17:52.285265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:17:51.80539Z","time spent":"479.864325ms","remote":"127.0.0.1:49326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"info","ts":"2026-02-26T06:17:52.287922Z","caller":"traceutil/trace.go:171","msg":"trace[108583599] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"481.374771ms","start":"2026-02-26T06:17:51.806126Z","end":"2026-02-26T06:17:52.2875Z","steps":["trace[108583599] 'process raft request' (duration: 481.219917ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:17:52.28813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:17:51.806108Z","time spent":"481.928734ms","remote":"127.0.0.1:49326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4580,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-26T06:17:52.28829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.247322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:17:52.288578Z","caller":"traceutil/trace.go:171","msg":"trace[724999185] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:0; response_revision:555; }","duration":"482.458216ms","start":"2026-02-26T06:17:51.806009Z","end":"2026-02-26T06:17:52.288467Z","steps":["trace[724999185] 'agreement among raft nodes before linearized reading' (duration: 482.138839ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:17:52.288624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.320259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:17:52.288707Z","caller":"traceutil/trace.go:171","msg":"trace[244331252] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:555; }","duration":"473.417901ms","start":"2026-02-26T06:17:51.815268Z","end":"2026-02-26T06:17:52.288686Z","steps":["trace[244331252] 'agreement among raft nodes before linearized reading' (duration: 473.304008ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:17:52.288721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:17:51.806004Z","time spent":"482.698342ms","remote":"127.0.0.1:49326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" "} {"level":"warn","ts":"2026-02-26T06:17:52.288761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:17:51.815257Z","time spent":"473.493352ms","remote":"127.0.0.1:49326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"warn","ts":"2026-02-26T06:17:52.289035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.145733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:17:52.289087Z","caller":"traceutil/trace.go:171","msg":"trace[272177673] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:555; }","duration":"285.261916ms","start":"2026-02-26T06:17:52.003812Z","end":"2026-02-26T06:17:52.289074Z","steps":["trace[272177673] 'agreement among raft nodes before linearized reading' (duration: 285.189144ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:17:52.289213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.905012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:17:52.289281Z","caller":"traceutil/trace.go:171","msg":"trace[1217529814] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:0; response_revision:555; }","duration":"474.003274ms","start":"2026-02-26T06:17:51.815259Z","end":"2026-02-26T06:17:52.289262Z","steps":["trace[1217529814] 'agreement among raft nodes before linearized reading' (duration: 473.872871ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:17:52.28933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.833101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-ww7xr\" ","response":"range_response_count:1 size:4888"} {"level":"warn","ts":"2026-02-26T06:17:52.28941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.134928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:17:52.289442Z","caller":"traceutil/trace.go:171","msg":"trace[1340027099] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:555; }","duration":"474.195419ms","start":"2026-02-26T06:17:51.81524Z","end":"2026-02-26T06:17:52.289435Z","steps":["trace[1340027099] 'agreement among raft nodes before linearized reading' (duration: 474.115127ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:17:52.289464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.105367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:17:52.28951Z","caller":"traceutil/trace.go:171","msg":"trace[946612638] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:0; response_revision:555; }","duration":"474.181309ms","start":"2026-02-26T06:17:51.815317Z","end":"2026-02-26T06:17:52.289499Z","steps":["trace[946612638] 'agreement among raft nodes before linearized reading' (duration: 474.114297ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:17:52.289538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.314391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:17:52.28956Z","caller":"traceutil/trace.go:171","msg":"trace[1167476436] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:555; }","duration":"474.372513ms","start":"2026-02-26T06:17:51.815181Z","end":"2026-02-26T06:17:52.289554Z","steps":["trace[1167476436] 'agreement among raft nodes before linearized reading' (duration: 474.317272ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:17:52.289557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:17:51.815303Z","time spent":"474.24463ms","remote":"127.0.0.1:49326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" "} {"level":"warn","ts":"2026-02-26T06:17:52.289586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:17:51.815161Z","time spent":"474.420315ms","remote":"127.0.0.1:49326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"info","ts":"2026-02-26T06:17:52.289407Z","caller":"traceutil/trace.go:171","msg":"trace[1726732549] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-ww7xr; range_end:; response_count:1; response_revision:555; }","duration":"437.923942ms","start":"2026-02-26T06:17:51.85147Z","end":"2026-02-26T06:17:52.289394Z","steps":["trace[1726732549] 'agreement among raft nodes before linearized reading' (duration: 437.704897ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:17:52.289686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:17:51.851458Z","time spent":"438.21809ms","remote":"127.0.0.1:49452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":4911,"request content":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-ww7xr\" "} {"level":"warn","ts":"2026-02-26T06:17:52.289758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.557558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:17:52.289803Z","caller":"traceutil/trace.go:171","msg":"trace[22118795] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:0; response_revision:555; }","duration":"479.6177ms","start":"2026-02-26T06:17:51.810178Z","end":"2026-02-26T06:17:52.289796Z","steps":["trace[22118795] 'agreement among raft nodes before linearized reading' (duration: 479.528798ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:17:52.289846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:17:51.81017Z","time spent":"479.669671ms","remote":"127.0.0.1:49326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" "} {"level":"warn","ts":"2026-02-26T06:17:52.289462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:17:51.815236Z","time spent":"474.22112ms","remote":"127.0.0.1:49326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"warn","ts":"2026-02-26T06:17:52.28934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:17:51.815255Z","time spent":"474.075886ms","remote":"127.0.0.1:49326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"info","ts":"2026-02-26T06:18:08.555676Z","caller":"traceutil/trace.go:171","msg":"trace[1977654631] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"188.613244ms","start":"2026-02-26T06:18:08.367035Z","end":"2026-02-26T06:18:08.555648Z","steps":["trace[1977654631] 'process raft request' (duration: 188.415549ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:10.911599Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581740375119453964,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T06:18:11.217895Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.635452409s","expected-duration":"1s"} {"level":"info","ts":"2026-02-26T06:18:11.218343Z","caller":"traceutil/trace.go:171","msg":"trace[2089858793] linearizableReadLoop","detail":"{readStateIndex:753; appliedIndex:752; }","duration":"807.161255ms","start":"2026-02-26T06:18:10.411152Z","end":"2026-02-26T06:18:11.218313Z","steps":["trace[2089858793] 'read index received' (duration: 806.895229ms)","trace[2089858793] 'applied index is now lower than readState.Index' (duration: 264.746µs)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:18:11.218534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"750.467758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:18:11.218497Z","caller":"traceutil/trace.go:171","msg":"trace[746319284] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"1.694556593s","start":"2026-02-26T06:18:09.523879Z","end":"2026-02-26T06:18:11.218435Z","steps":["trace[746319284] 'process raft request' (duration: 1.694261566s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:11.218561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"807.387011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:18:11.218574Z","caller":"traceutil/trace.go:171","msg":"trace[1350474515] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:727; }","duration":"750.593261ms","start":"2026-02-26T06:18:10.467968Z","end":"2026-02-26T06:18:11.218561Z","steps":["trace[1350474515] 'agreement among raft nodes before linearized reading' (duration: 750.500069ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:11.218666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.778757ms","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-26T06:18:11.218725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"657.540996ms","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-26T06:18:11.218746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:10.467936Z","time spent":"750.789076ms","remote":"127.0.0.1:49292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-26T06:18:11.21876Z","caller":"traceutil/trace.go:171","msg":"trace[1898337124] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:727; }","duration":"657.610868ms","start":"2026-02-26T06:18:10.561139Z","end":"2026-02-26T06:18:11.21875Z","steps":["trace[1898337124] 'agreement among raft nodes before linearized reading' (duration: 657.456464ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:18:11.218768Z","caller":"traceutil/trace.go:171","msg":"trace[1620740855] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:727; }","duration":"591.915031ms","start":"2026-02-26T06:18:10.626831Z","end":"2026-02-26T06:18:11.218746Z","steps":["trace[1620740855] 'agreement among raft nodes before linearized reading' (duration: 591.684204ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:11.218768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:09.523858Z","time spent":"1.69475535s","remote":"127.0.0.1:49536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T06:18:11.218779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"637.015734ms","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-26T06:18:11.218808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:10.561119Z","time spent":"657.681349ms","remote":"127.0.0.1:49536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-26T06:18:11.218816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:10.626819Z","time spent":"591.987462ms","remote":"127.0.0.1:49536","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-26T06:18:11.218866Z","caller":"traceutil/trace.go:171","msg":"trace[1162463003] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:727; }","duration":"637.148357ms","start":"2026-02-26T06:18:10.581695Z","end":"2026-02-26T06:18:11.218843Z","steps":["trace[1162463003] 'agreement among raft nodes before linearized reading' (duration: 636.855899ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:11.218928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:10.581678Z","time spent":"637.24206ms","remote":"127.0.0.1:49536","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-26T06:18:11.219251Z","caller":"traceutil/trace.go:171","msg":"trace[1682141858] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:727; }","duration":"807.537636ms","start":"2026-02-26T06:18:10.411114Z","end":"2026-02-26T06:18:11.218652Z","steps":["trace[1682141858] 'agreement among raft nodes before linearized reading' (duration: 807.34833ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:11.219372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:10.411099Z","time spent":"808.239262ms","remote":"127.0.0.1:49278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-26T06:18:11.734911Z","caller":"traceutil/trace.go:171","msg":"trace[987563878] linearizableReadLoop","detail":"{readStateIndex:756; appliedIndex:753; }","duration":"266.743485ms","start":"2026-02-26T06:18:11.468137Z","end":"2026-02-26T06:18:11.734881Z","steps":["trace[987563878] 'read index received' (duration: 234.505566ms)","trace[987563878] 'applied index is now lower than readState.Index' (duration: 32.236599ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:18:11.735035Z","caller":"traceutil/trace.go:171","msg":"trace[1227702376] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"511.35467ms","start":"2026-02-26T06:18:11.223652Z","end":"2026-02-26T06:18:11.735007Z","steps":["trace[1227702376] 'process raft request' (duration: 511.145875ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:18:11.735091Z","caller":"traceutil/trace.go:171","msg":"trace[1899227071] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"511.592445ms","start":"2026-02-26T06:18:11.223471Z","end":"2026-02-26T06:18:11.735064Z","steps":["trace[1899227071] 'process raft request' (duration: 511.178535ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:11.735199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:11.223615Z","time spent":"511.497674ms","remote":"127.0.0.1:49536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T06:18:11.735241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:11.223457Z","time spent":"511.700899ms","remote":"127.0.0.1:49536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T06:18:11.735384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.453566ms","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-26T06:18:11.735408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.281488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:18:11.735439Z","caller":"traceutil/trace.go:171","msg":"trace[982712964] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:731; }","duration":"149.566078ms","start":"2026-02-26T06:18:11.585862Z","end":"2026-02-26T06:18:11.735428Z","steps":["trace[982712964] 'agreement among raft nodes before linearized reading' (duration: 149.395324ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:18:11.735444Z","caller":"traceutil/trace.go:171","msg":"trace[927519224] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:731; }","duration":"267.3657ms","start":"2026-02-26T06:18:11.468067Z","end":"2026-02-26T06:18:11.735433Z","steps":["trace[927519224] 'agreement among raft nodes before linearized reading' (duration: 267.302699ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:18:11.734923Z","caller":"traceutil/trace.go:171","msg":"trace[573584506] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"511.835661ms","start":"2026-02-26T06:18:11.22305Z","end":"2026-02-26T06:18:11.734886Z","steps":["trace[573584506] 'process raft request' (duration: 479.451108ms)","trace[573584506] 'compare' (duration: 31.731987ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:18:11.735763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:11.223024Z","time spent":"512.631881ms","remote":"127.0.0.1:49536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T06:18:12.014002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.043572ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-02-26T06:18:14.115871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.99484343s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T06:18:14.115933Z","caller":"traceutil/trace.go:171","msg":"trace[1373264023] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:733; }","duration":"1.994944742s","start":"2026-02-26T06:18:12.120974Z","end":"2026-02-26T06:18:14.115919Z","steps":["trace[1373264023] 'count revisions from in-memory index tree' (duration: 1.994773918s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:14.115925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"647.080719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-26T06:18:14.115967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:12.120958Z","time spent":"1.995002014s","remote":"127.0.0.1:49344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":99,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-02-26T06:18:14.116004Z","caller":"traceutil/trace.go:171","msg":"trace[1758155544] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:733; }","duration":"647.209573ms","start":"2026-02-26T06:18:13.468774Z","end":"2026-02-26T06:18:14.115984Z","steps":["trace[1758155544] 'range keys from in-memory index tree' (duration: 646.980987ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:14.116047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:13.468757Z","time spent":"647.280214ms","remote":"127.0.0.1:49278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T06:18:14.116055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.238365ms","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-26T06:18:14.116107Z","caller":"traceutil/trace.go:171","msg":"trace[1232595181] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:733; }","duration":"376.314556ms","start":"2026-02-26T06:18:13.739779Z","end":"2026-02-26T06:18:14.116094Z","steps":["trace[1232595181] 'range keys from in-memory index tree' (duration: 376.116361ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:14.116134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:13.739774Z","time spent":"376.354097ms","remote":"127.0.0.1:49536","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-26T06:18:14.116157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"892.631347ms","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-26T06:18:14.116194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.705137894s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T06:18:14.116203Z","caller":"traceutil/trace.go:171","msg":"trace[1743541305] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:733; }","duration":"892.707409ms","start":"2026-02-26T06:18:13.223482Z","end":"2026-02-26T06:18:14.116189Z","steps":["trace[1743541305] 'range keys from in-memory index tree' (duration: 892.458803ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:18:14.116222Z","caller":"traceutil/trace.go:171","msg":"trace[1316494598] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:733; }","duration":"1.705192155s","start":"2026-02-26T06:18:12.411019Z","end":"2026-02-26T06:18:14.116211Z","steps":["trace[1316494598] 'count revisions from in-memory index tree' (duration: 1.705088443s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:14.11624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:12.411003Z","time spent":"1.705232526s","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":51,"response size":30,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-02-26T06:18:14.116239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:13.22346Z","time spent":"892.7675ms","remote":"127.0.0.1:49536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-26T06:18:14.116355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.625673ms","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-26T06:18:14.116388Z","caller":"traceutil/trace.go:171","msg":"trace[593235254] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:733; }","duration":"376.694425ms","start":"2026-02-26T06:18:13.739684Z","end":"2026-02-26T06:18:14.116378Z","steps":["trace[593235254] 'range keys from in-memory index tree' (duration: 376.414608ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:14.116414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:13.73966Z","time spent":"376.746327ms","remote":"127.0.0.1:49536","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-26T06:18:14.116599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.183832ms","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-26T06:18:14.116625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.892715ms","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-26T06:18:14.116658Z","caller":"traceutil/trace.go:171","msg":"trace[2103970272] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:733; }","duration":"376.305655ms","start":"2026-02-26T06:18:13.740343Z","end":"2026-02-26T06:18:14.116648Z","steps":["trace[2103970272] 'range keys from in-memory index tree' (duration: 375.936186ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:18:14.116678Z","caller":"traceutil/trace.go:171","msg":"trace[1793242632] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:733; }","duration":"348.968407ms","start":"2026-02-26T06:18:13.767699Z","end":"2026-02-26T06:18:14.116667Z","steps":["trace[1793242632] 'range keys from in-memory index tree' (duration: 348.744452ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:14.116687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:13.740307Z","time spent":"376.369936ms","remote":"127.0.0.1:49536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-26T06:18:14.116708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:13.767686Z","time spent":"349.015048ms","remote":"127.0.0.1:49536","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-26T06:18:34.749179Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581740375119454177,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-26T06:18:34.947238Z","caller":"traceutil/trace.go:171","msg":"trace[1443361194] linearizableReadLoop","detail":"{readStateIndex:828; appliedIndex:827; }","duration":"698.623572ms","start":"2026-02-26T06:18:34.24858Z","end":"2026-02-26T06:18:34.947204Z","steps":["trace[1443361194] 'read index received' (duration: 698.405227ms)","trace[1443361194] 'applied index is now lower than readState.Index' (duration: 216.825µs)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:18:34.947649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"699.039442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-02-26T06:18:34.947715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"698.260732ms","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-26T06:18:34.947839Z","caller":"traceutil/trace.go:171","msg":"trace[103773174] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"704.479934ms","start":"2026-02-26T06:18:34.243345Z","end":"2026-02-26T06:18:34.947825Z","steps":["trace[103773174] 'process raft request' (duration: 703.678975ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:18:34.947853Z","caller":"traceutil/trace.go:171","msg":"trace[1005237053] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:796; }","duration":"698.448216ms","start":"2026-02-26T06:18:34.249388Z","end":"2026-02-26T06:18:34.947837Z","steps":["trace[1005237053] 'agreement among raft nodes before linearized reading' (duration: 698.089278ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:34.947972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:34.243303Z","time spent":"704.580786ms","remote":"127.0.0.1:49536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T06:18:34.948104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:34.249363Z","time spent":"698.724733ms","remote":"127.0.0.1:49536","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-26T06:18:34.948419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"689.938278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-26T06:18:34.948463Z","caller":"traceutil/trace.go:171","msg":"trace[1624964899] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:796; }","duration":"690.00416ms","start":"2026-02-26T06:18:34.258448Z","end":"2026-02-26T06:18:34.948452Z","steps":["trace[1624964899] 'agreement among raft nodes before linearized reading' (duration: 689.893207ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:34.948497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:34.258436Z","time spent":"690.053641ms","remote":"127.0.0.1:49536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-26T06:18:34.948615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"695.084395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-26T06:18:34.948795Z","caller":"traceutil/trace.go:171","msg":"trace[96510399] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:796; }","duration":"695.400552ms","start":"2026-02-26T06:18:34.25338Z","end":"2026-02-26T06:18:34.948781Z","steps":["trace[96510399] 'agreement among raft nodes before linearized reading' (duration: 695.003432ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:34.948966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:34.253354Z","time spent":"695.587077ms","remote":"127.0.0.1:49536","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-26T06:18:34.947776Z","caller":"traceutil/trace.go:171","msg":"trace[444362302] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:796; }","duration":"699.210626ms","start":"2026-02-26T06:18:34.248534Z","end":"2026-02-26T06:18:34.947745Z","steps":["trace[444362302] 'agreement among raft nodes before linearized reading' (duration: 698.927359ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:34.949305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:34.24852Z","time spent":"700.764664ms","remote":"127.0.0.1:49536","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":"info","ts":"2026-02-26T06:18:35.223113Z","caller":"traceutil/trace.go:171","msg":"trace[1850745509] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"269.415381ms","start":"2026-02-26T06:18:34.953664Z","end":"2026-02-26T06:18:35.22308Z","steps":["trace[1850745509] 'process raft request' (duration: 188.697107ms)","trace[1850745509] 'compare' (duration: 79.780621ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:18:35.223796Z","caller":"traceutil/trace.go:171","msg":"trace[1325656799] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"268.688583ms","start":"2026-02-26T06:18:34.955086Z","end":"2026-02-26T06:18:35.223774Z","steps":["trace[1325656799] 'process raft request' (duration: 268.643292ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:18:35.223872Z","caller":"traceutil/trace.go:171","msg":"trace[1341236094] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"269.087563ms","start":"2026-02-26T06:18:34.954747Z","end":"2026-02-26T06:18:35.223835Z","steps":["trace[1341236094] 'process raft request' (duration: 267.909323ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:18:35.224236Z","caller":"traceutil/trace.go:171","msg":"trace[699516127] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"269.253697ms","start":"2026-02-26T06:18:34.954928Z","end":"2026-02-26T06:18:35.224182Z","steps":["trace[699516127] 'process raft request' (duration: 268.757565ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:37.727944Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581740375119454191,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-26T06:18:37.956106Z","caller":"traceutil/trace.go:171","msg":"trace[1913090051] linearizableReadLoop","detail":"{readStateIndex:835; appliedIndex:834; }","duration":"728.619504ms","start":"2026-02-26T06:18:37.227462Z","end":"2026-02-26T06:18:37.956082Z","steps":["trace[1913090051] 'read index received' (duration: 728.45844ms)","trace[1913090051] 'applied index is now lower than readState.Index' (duration: 159.734µs)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:18:37.959261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"731.769141ms","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-26T06:18:37.959374Z","caller":"traceutil/trace.go:171","msg":"trace[764896966] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:802; }","duration":"731.925305ms","start":"2026-02-26T06:18:37.227425Z","end":"2026-02-26T06:18:37.95935Z","steps":["trace[764896966] 'agreement among raft nodes before linearized reading' (duration: 731.570947ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:37.959425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:37.227407Z","time spent":"732.004347ms","remote":"127.0.0.1:49536","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":"info","ts":"2026-02-26T06:18:37.959473Z","caller":"traceutil/trace.go:171","msg":"trace[2125701044] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"999.218945ms","start":"2026-02-26T06:18:36.956938Z","end":"2026-02-26T06:18:37.956157Z","steps":["trace[2125701044] 'process raft request' (duration: 998.99265ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:37.959726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:36.956911Z","time spent":"1.00270185s","remote":"127.0.0.1:49536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T06:18:37.95998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.036675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:18:37.960062Z","caller":"traceutil/trace.go:171","msg":"trace[766074417] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:802; }","duration":"160.144528ms","start":"2026-02-26T06:18:37.799887Z","end":"2026-02-26T06:18:37.960032Z","steps":["trace[766074417] 'agreement among raft nodes before linearized reading' (duration: 160.014485ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:37.960134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"731.30034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-26T06:18:37.960259Z","caller":"traceutil/trace.go:171","msg":"trace[1569815564] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:802; }","duration":"731.381432ms","start":"2026-02-26T06:18:37.228789Z","end":"2026-02-26T06:18:37.96017Z","steps":["trace[1569815564] 'agreement among raft nodes before linearized reading' (duration: 731.241899ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:37.960297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:37.228772Z","time spent":"731.514875ms","remote":"127.0.0.1:49536","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-26T06:18:37.960471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"731.483044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-26T06:18:37.96051Z","caller":"traceutil/trace.go:171","msg":"trace[1302263007] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:802; }","duration":"731.538136ms","start":"2026-02-26T06:18:37.228952Z","end":"2026-02-26T06:18:37.96049Z","steps":["trace[1302263007] 'agreement among raft nodes before linearized reading' (duration: 731.434903ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:37.96054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:37.228928Z","time spent":"731.604078ms","remote":"127.0.0.1:49536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-26T06:18:37.961108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.291795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-26T06:18:37.961183Z","caller":"traceutil/trace.go:171","msg":"trace[2067594100] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:802; }","duration":"732.385837ms","start":"2026-02-26T06:18:37.228782Z","end":"2026-02-26T06:18:37.961168Z","steps":["trace[2067594100] 'agreement among raft nodes before linearized reading' (duration: 730.297956ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:37.96126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:37.228777Z","time spent":"732.460249ms","remote":"127.0.0.1:49536","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-26T06:18:38.27888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.234024ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T06:18:38.278995Z","caller":"traceutil/trace.go:171","msg":"trace[797673481] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"311.91787ms","start":"2026-02-26T06:18:37.967057Z","end":"2026-02-26T06:18:38.278975Z","steps":["trace[797673481] 'process raft request' (duration: 74.085922ms)","trace[797673481] 'compare' (duration: 237.05636ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:18:38.279117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:37.967036Z","time spent":"312.048194ms","remote":"127.0.0.1:49536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T06:18:38.290823Z","caller":"traceutil/trace.go:171","msg":"trace[1030666355] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"323.441422ms","start":"2026-02-26T06:18:37.967371Z","end":"2026-02-26T06:18:38.290812Z","steps":["trace[1030666355] 'process raft request' (duration: 323.380061ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:18:38.29082Z","caller":"traceutil/trace.go:171","msg":"trace[810913532] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"323.576345ms","start":"2026-02-26T06:18:37.967223Z","end":"2026-02-26T06:18:38.290799Z","steps":["trace[810913532] 'process raft request' (duration: 323.424951ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:38.290897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:37.967338Z","time spent":"323.532934ms","remote":"127.0.0.1:49536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T06:18:38.29097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:37.967213Z","time spent":"323.661607ms","remote":"127.0.0.1:49536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T06:18:38.29114Z","caller":"traceutil/trace.go:171","msg":"trace[1673729017] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"323.33373ms","start":"2026-02-26T06:18:37.967745Z","end":"2026-02-26T06:18:38.291078Z","steps":["trace[1673729017] 'process raft request' (duration: 323.029112ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:38.292205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:37.967727Z","time spent":"324.377066ms","remote":"127.0.0.1:49536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T06:18:48.83119Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581740375119454368,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-26T06:18:48.93299Z","caller":"traceutil/trace.go:171","msg":"trace[1932963114] linearizableReadLoop","detail":"{readStateIndex:920; appliedIndex:919; }","duration":"602.695344ms","start":"2026-02-26T06:18:48.330217Z","end":"2026-02-26T06:18:48.932912Z","steps":["trace[1932963114] 'read index received' (duration: 602.426807ms)","trace[1932963114] 'applied index is now lower than readState.Index' (duration: 267.787µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:18:48.933341Z","caller":"traceutil/trace.go:171","msg":"trace[274029974] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"603.34618ms","start":"2026-02-26T06:18:48.329986Z","end":"2026-02-26T06:18:48.933332Z","steps":["trace[274029974] 'process raft request' (duration: 602.753075ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:48.933458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:48.32997Z","time spent":"603.399171ms","remote":"127.0.0.1:49536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T06:18:48.933694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.472903ms","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-26T06:18:48.933722Z","caller":"traceutil/trace.go:171","msg":"trace[1284858995] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:882; }","duration":"603.518355ms","start":"2026-02-26T06:18:48.330195Z","end":"2026-02-26T06:18:48.933713Z","steps":["trace[1284858995] 'agreement among raft nodes before linearized reading' (duration: 603.409462ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:48.933744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:48.330186Z","time spent":"603.554576ms","remote":"127.0.0.1:49536","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-26T06:18:48.933877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.908431ms","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-26T06:18:48.933897Z","caller":"traceutil/trace.go:171","msg":"trace[1147026756] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:882; }","duration":"602.949751ms","start":"2026-02-26T06:18:48.330943Z","end":"2026-02-26T06:18:48.933892Z","steps":["trace[1147026756] 'agreement among raft nodes before linearized reading' (duration: 602.89393ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:48.933916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:48.330923Z","time spent":"602.989092ms","remote":"127.0.0.1:49536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-26T06:18:48.934031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.018361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:18:48.934054Z","caller":"traceutil/trace.go:171","msg":"trace[1279569524] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:882; }","duration":"466.076533ms","start":"2026-02-26T06:18:48.46797Z","end":"2026-02-26T06:18:48.934046Z","steps":["trace[1279569524] 'agreement among raft nodes before linearized reading' (duration: 466.039892ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:48.934074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:48.467947Z","time spent":"466.123554ms","remote":"127.0.0.1:49278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T06:18:48.934201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.871559ms","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-26T06:18:48.934222Z","caller":"traceutil/trace.go:171","msg":"trace[1441651450] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:882; }","duration":"602.920969ms","start":"2026-02-26T06:18:48.331295Z","end":"2026-02-26T06:18:48.934216Z","steps":["trace[1441651450] 'agreement among raft nodes before linearized reading' (duration: 602.868178ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:48.93424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:48.331279Z","time spent":"602.958011ms","remote":"127.0.0.1:49536","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-26T06:18:51.448093Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581740375119454382,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-26T06:18:51.752505Z","caller":"traceutil/trace.go:171","msg":"trace[1783666226] linearizableReadLoop","detail":"{readStateIndex:925; appliedIndex:924; }","duration":"805.006953ms","start":"2026-02-26T06:18:50.947478Z","end":"2026-02-26T06:18:51.752485Z","steps":["trace[1783666226] 'read index received' (duration: 804.827789ms)","trace[1783666226] 'applied index is now lower than readState.Index' (duration: 178.044µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:18:51.75261Z","caller":"traceutil/trace.go:171","msg":"trace[380494642] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"810.046286ms","start":"2026-02-26T06:18:50.942531Z","end":"2026-02-26T06:18:51.752578Z","steps":["trace[380494642] 'process raft request' (duration: 809.82132ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:51.752699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"805.201548ms","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-26T06:18:51.752738Z","caller":"traceutil/trace.go:171","msg":"trace[427631507] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:887; }","duration":"805.26171ms","start":"2026-02-26T06:18:50.947463Z","end":"2026-02-26T06:18:51.752725Z","steps":["trace[427631507] 'agreement among raft nodes before linearized reading' (duration: 805.134997ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:51.752775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:50.947457Z","time spent":"805.309861ms","remote":"127.0.0.1:49536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-26T06:18:51.752792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:50.942499Z","time spent":"810.195699ms","remote":"127.0.0.1:49536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T06:18:51.75305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.348547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:18:51.753089Z","caller":"traceutil/trace.go:171","msg":"trace[672490945] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:887; }","duration":"284.416899ms","start":"2026-02-26T06:18:51.468657Z","end":"2026-02-26T06:18:51.753074Z","steps":["trace[672490945] 'agreement among raft nodes before linearized reading' (duration: 284.349477ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:51.752973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.468575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T06:18:51.753211Z","caller":"traceutil/trace.go:171","msg":"trace[288064984] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:887; }","duration":"263.805243ms","start":"2026-02-26T06:18:51.489382Z","end":"2026-02-26T06:18:51.753187Z","steps":["trace[288064984] 'agreement among raft nodes before linearized reading' (duration: 263.468545ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:51.753484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"805.410002ms","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-26T06:18:51.753593Z","caller":"traceutil/trace.go:171","msg":"trace[1343753855] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:887; }","duration":"805.539195ms","start":"2026-02-26T06:18:50.948022Z","end":"2026-02-26T06:18:51.753561Z","steps":["trace[1343753855] 'agreement among raft nodes before linearized reading' (duration: 805.233858ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:51.753646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:50.948016Z","time spent":"805.620057ms","remote":"127.0.0.1:49536","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-26T06:18:51.753717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"805.948776ms","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-26T06:18:51.753769Z","caller":"traceutil/trace.go:171","msg":"trace[398084546] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:887; }","duration":"806.008357ms","start":"2026-02-26T06:18:50.947747Z","end":"2026-02-26T06:18:51.753755Z","steps":["trace[398084546] 'agreement among raft nodes before linearized reading' (duration: 805.015913ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:51.753799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:50.947743Z","time spent":"806.047838ms","remote":"127.0.0.1:49536","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-26T06:18:52.140013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.696432ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-02-26T06:18:52.140126Z","caller":"traceutil/trace.go:171","msg":"trace[238361781] linearizableReadLoop","detail":"{readStateIndex:926; appliedIndex:925; }","duration":"385.383738ms","start":"2026-02-26T06:18:51.754724Z","end":"2026-02-26T06:18:52.140108Z","steps":["trace[238361781] 'read index received' (duration: 179.539942ms)","trace[238361781] 'applied index is now lower than readState.Index' (duration: 205.842216ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:18:52.140668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:51.754402Z","time spent":"386.254629ms","remote":"127.0.0.1:49308","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-26T06:18:52.140819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.082214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:18:52.140947Z","caller":"traceutil/trace.go:171","msg":"trace[1962442358] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:887; }","duration":"386.272319ms","start":"2026-02-26T06:18:51.754659Z","end":"2026-02-26T06:18:52.140932Z","steps":["trace[1962442358] 'agreement among raft nodes before linearized reading' (duration: 386.107165ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:52.140983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:51.754612Z","time spent":"386.359511ms","remote":"127.0.0.1:49292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-26T06:18:52.589887Z","caller":"traceutil/trace.go:171","msg":"trace[19406016] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"831.531162ms","start":"2026-02-26T06:18:51.758331Z","end":"2026-02-26T06:18:52.589862Z","steps":["trace[19406016] 'process raft request' (duration: 831.160883ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:52.590027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:51.758307Z","time spent":"831.648765ms","remote":"127.0.0.1:49536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T06:18:52.590245Z","caller":"traceutil/trace.go:171","msg":"trace[1227901060] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"831.973723ms","start":"2026-02-26T06:18:51.758264Z","end":"2026-02-26T06:18:52.590238Z","steps":["trace[1227901060] 'process raft request' (duration: 827.7772ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:52.590292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:51.758244Z","time spent":"832.025133ms","remote":"127.0.0.1:49536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T06:18:52.590277Z","caller":"traceutil/trace.go:171","msg":"trace[1096043863] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"831.120372ms","start":"2026-02-26T06:18:51.759115Z","end":"2026-02-26T06:18:52.590236Z","steps":["trace[1096043863] 'process raft request' (duration: 830.538708ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:52.590559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:51.759103Z","time spent":"831.333027ms","remote":"127.0.0.1:49536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T06:18:52.590595Z","caller":"traceutil/trace.go:171","msg":"trace[947335873] transaction","detail":"{read_only:false; response_revision:891; number_of_response:1; }","duration":"448.527342ms","start":"2026-02-26T06:18:52.14206Z","end":"2026-02-26T06:18:52.590587Z","steps":["trace[947335873] 'process raft request' (duration: 447.657631ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:52.590642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:52.142047Z","time spent":"448.569844ms","remote":"127.0.0.1:49308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T06:18:52.591236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.913645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-26T06:18:52.59127Z","caller":"traceutil/trace.go:171","msg":"trace[1510265353] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:892; }","duration":"554.988416ms","start":"2026-02-26T06:18:52.036273Z","end":"2026-02-26T06:18:52.591262Z","steps":["trace[1510265353] 'agreement among raft nodes before linearized reading' (duration: 554.858763ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:52.591313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:52.036252Z","time spent":"555.054398ms","remote":"127.0.0.1:49536","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-26T06:18:52.589908Z","caller":"traceutil/trace.go:171","msg":"trace[988211660] linearizableReadLoop","detail":"{readStateIndex:929; appliedIndex:926; }","duration":"449.144598ms","start":"2026-02-26T06:18:52.140735Z","end":"2026-02-26T06:18:52.589879Z","steps":["trace[988211660] 'read index received' (duration: 445.326654ms)","trace[988211660] 'applied index is now lower than readState.Index' (duration: 3.815494ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:18:52.591874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.113712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:18:52.592152Z","caller":"traceutil/trace.go:171","msg":"trace[518284053] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:892; }","duration":"123.45097ms","start":"2026-02-26T06:18:52.468683Z","end":"2026-02-26T06:18:52.592134Z","steps":["trace[518284053] 'agreement among raft nodes before linearized reading' (duration: 123.139052ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:19:07.527052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.471644ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-26T06:19:07.965922Z","caller":"traceutil/trace.go:171","msg":"trace[588481537] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"143.364048ms","start":"2026-02-26T06:19:07.822529Z","end":"2026-02-26T06:19:07.965893Z","steps":["trace[588481537] 'process raft request' (duration: 143.189934ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:19:08.800233Z","caller":"traceutil/trace.go:171","msg":"trace[1846471345] linearizableReadLoop","detail":"{readStateIndex:996; appliedIndex:995; }","duration":"117.290714ms","start":"2026-02-26T06:19:08.682925Z","end":"2026-02-26T06:19:08.800216Z","steps":["trace[1846471345] 'read index received' (duration: 117.12666ms)","trace[1846471345] 'applied index is now lower than readState.Index' (duration: 163.524µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:19:08.800481Z","caller":"traceutil/trace.go:171","msg":"trace[1549739081] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"124.137842ms","start":"2026-02-26T06:19:08.676337Z","end":"2026-02-26T06:19:08.800475Z","steps":["trace[1549739081] 'process raft request' (duration: 123.777423ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:19:08.800662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.719754ms","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-26T06:19:08.800681Z","caller":"traceutil/trace.go:171","msg":"trace[935614309] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:954; }","duration":"117.774635ms","start":"2026-02-26T06:19:08.682899Z","end":"2026-02-26T06:19:08.800674Z","steps":["trace[935614309] 'agreement among raft nodes before linearized reading' (duration: 117.686913ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:19:08.800753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.314273ms","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-26T06:19:08.800763Z","caller":"traceutil/trace.go:171","msg":"trace[1894016333] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:954; }","duration":"117.342994ms","start":"2026-02-26T06:19:08.683417Z","end":"2026-02-26T06:19:08.80076Z","steps":["trace[1894016333] 'agreement among raft nodes before linearized reading' (duration: 117.307583ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:19:08.800825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.235181ms","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-26T06:19:08.800835Z","caller":"traceutil/trace.go:171","msg":"trace[942692684] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:954; }","duration":"117.246022ms","start":"2026-02-26T06:19:08.683586Z","end":"2026-02-26T06:19:08.800832Z","steps":["trace[942692684] 'agreement among raft nodes before linearized reading' (duration: 117.214571ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:19:10.223464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"943.874451ms","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-26T06:19:10.223604Z","caller":"traceutil/trace.go:171","msg":"trace[1490535501] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:954; }","duration":"944.027925ms","start":"2026-02-26T06:19:09.279557Z","end":"2026-02-26T06:19:10.223585Z","steps":["trace[1490535501] 'range keys from in-memory index tree' (duration: 943.858611ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:19:10.224518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"812.896641ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T06:19:10.224587Z","caller":"traceutil/trace.go:171","msg":"trace[1986487919] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"1.419211037s","start":"2026-02-26T06:19:08.805362Z","end":"2026-02-26T06:19:10.224573Z","steps":["trace[1986487919] 'process raft request' (duration: 606.192184ms)","trace[1986487919] 'compare' (duration: 812.290674ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:19:10.224639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:19:08.805341Z","time spent":"1.419270978s","remote":"127.0.0.1:49536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T06:19:10.262096Z","caller":"traceutil/trace.go:171","msg":"trace[1934546664] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"1.456271354s","start":"2026-02-26T06:19:08.805794Z","end":"2026-02-26T06:19:10.262066Z","steps":["trace[1934546664] 'process raft request' (duration: 1.456058458s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:19:10.26228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:19:08.805763Z","time spent":"1.456429838s","remote":"127.0.0.1:49536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T06:19:10.26226Z","caller":"traceutil/trace.go:171","msg":"trace[1252128415] linearizableReadLoop","detail":"{readStateIndex:999; appliedIndex:996; }","duration":"1.270051145s","start":"2026-02-26T06:19:08.992179Z","end":"2026-02-26T06:19:10.26223Z","steps":["trace[1252128415] 'read index received' (duration: 419.431652ms)","trace[1252128415] 'applied index is now lower than readState.Index' (duration: 850.618393ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:19:10.262515Z","caller":"traceutil/trace.go:171","msg":"trace[635268087] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"1.455901544s","start":"2026-02-26T06:19:08.806603Z","end":"2026-02-26T06:19:10.262505Z","steps":["trace[635268087] 'process raft request' (duration: 1.455416993s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:19:10.26265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:19:08.806596Z","time spent":"1.455945915s","remote":"127.0.0.1:49536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T06:19:10.262838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.541578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:19:10.26288Z","caller":"traceutil/trace.go:171","msg":"trace[1405362898] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:957; }","duration":"502.62704ms","start":"2026-02-26T06:19:09.76024Z","end":"2026-02-26T06:19:10.262867Z","steps":["trace[1405362898] 'agreement among raft nodes before linearized reading' (duration: 502.561909ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:19:10.262913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:19:09.760218Z","time spent":"502.686361ms","remote":"127.0.0.1:49356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-02-26T06:19:10.263092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.270925125s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T06:19:10.263119Z","caller":"traceutil/trace.go:171","msg":"trace[1252976108] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:957; }","duration":"1.271007098s","start":"2026-02-26T06:19:08.992104Z","end":"2026-02-26T06:19:10.263111Z","steps":["trace[1252976108] 'agreement among raft nodes before linearized reading' (duration: 1.270951227s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:19:10.263149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:19:08.99207Z","time spent":"1.271072329s","remote":"127.0.0.1:49602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":14,"response size":30,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-02-26T06:19:10.263181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.731425ms","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-26T06:19:10.26322Z","caller":"traceutil/trace.go:171","msg":"trace[562801485] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:957; }","duration":"292.809746ms","start":"2026-02-26T06:19:09.970397Z","end":"2026-02-26T06:19:10.263207Z","steps":["trace[562801485] 'agreement among raft nodes before linearized reading' (duration: 292.660693ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:19:10.263296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.754358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T06:19:10.263323Z","caller":"traceutil/trace.go:171","msg":"trace[216294321] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:957; }","duration":"484.80251ms","start":"2026-02-26T06:19:09.778512Z","end":"2026-02-26T06:19:10.263315Z","steps":["trace[216294321] 'agreement among raft nodes before linearized reading' (duration: 484.754589ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:19:10.263348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:19:09.778501Z","time spent":"484.840401ms","remote":"127.0.0.1:49610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":14,"response size":30,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-02-26T06:19:10.263372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.214831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:19:10.2634Z","caller":"traceutil/trace.go:171","msg":"trace[1377874280] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:957; }","duration":"199.282423ms","start":"2026-02-26T06:19:10.064109Z","end":"2026-02-26T06:19:10.263392Z","steps":["trace[1377874280] 'agreement among raft nodes before linearized reading' (duration: 199.233122ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:19:10.513145Z","caller":"traceutil/trace.go:171","msg":"trace[1072153435] linearizableReadLoop","detail":"{readStateIndex:1000; appliedIndex:999; }","duration":"101.481143ms","start":"2026-02-26T06:19:10.411638Z","end":"2026-02-26T06:19:10.513119Z","steps":["trace[1072153435] 'read index received' (duration: 2.238426ms)","trace[1072153435] 'applied index is now lower than readState.Index' (duration: 99.241777ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:19:10.513413Z","caller":"traceutil/trace.go:171","msg":"trace[426721203] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"245.785894ms","start":"2026-02-26T06:19:10.267612Z","end":"2026-02-26T06:19:10.513398Z","steps":["trace[426721203] 'process raft request' (duration: 146.330792ms)","trace[426721203] 'compare' (duration: 99.039592ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:19:10.513525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.860372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:19:10.513621Z","caller":"traceutil/trace.go:171","msg":"trace[227619757] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:958; }","duration":"102.017715ms","start":"2026-02-26T06:19:10.41158Z","end":"2026-02-26T06:19:10.513598Z","steps":["trace[227619757] 'agreement among raft nodes before linearized reading' (duration: 101.814201ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:19:11.91086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.124958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-26T06:19:11.910883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.463823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.233\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-26T06:19:11.910942Z","caller":"traceutil/trace.go:171","msg":"trace[986370978] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:960; }","duration":"441.325523ms","start":"2026-02-26T06:19:11.469597Z","end":"2026-02-26T06:19:11.910923Z","steps":["trace[986370978] 'range keys from in-memory index tree' (duration: 441.064107ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:19:11.910959Z","caller":"traceutil/trace.go:171","msg":"trace[2130000064] range","detail":"{range_begin:/registry/masterleases/199.204.45.233; range_end:; response_count:1; response_revision:960; }","duration":"158.610146ms","start":"2026-02-26T06:19:11.752329Z","end":"2026-02-26T06:19:11.910939Z","steps":["trace[2130000064] 'range keys from in-memory index tree' (duration: 158.275638ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:19:11.910988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:19:11.469572Z","time spent":"441.407186ms","remote":"127.0.0.1:49278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T06:19:13.093556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"774.644283ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T06:19:13.093663Z","caller":"traceutil/trace.go:171","msg":"trace[1772423146] linearizableReadLoop","detail":"{readStateIndex:1004; appliedIndex:1003; }","duration":"864.042816ms","start":"2026-02-26T06:19:12.229602Z","end":"2026-02-26T06:19:13.093645Z","steps":["trace[1772423146] 'read index received' (duration: 89.153967ms)","trace[1772423146] 'applied index is now lower than readState.Index' (duration: 774.886849ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:19:13.093732Z","caller":"traceutil/trace.go:171","msg":"trace[901348862] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"1.079215241s","start":"2026-02-26T06:19:12.014506Z","end":"2026-02-26T06:19:13.093721Z","steps":["trace[901348862] 'process raft request' (duration: 304.294341ms)","trace[901348862] 'compare' (duration: 774.426198ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:19:13.093794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:19:12.014486Z","time spent":"1.079276122s","remote":"127.0.0.1:49308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T06:19:13.094313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"864.703852ms","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-26T06:19:13.094357Z","caller":"traceutil/trace.go:171","msg":"trace[1630538381] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:961; }","duration":"864.778235ms","start":"2026-02-26T06:19:12.229569Z","end":"2026-02-26T06:19:13.094347Z","steps":["trace[1630538381] 'agreement among raft nodes before linearized reading' (duration: 864.57259ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:19:13.09439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:19:12.229553Z","time spent":"864.827235ms","remote":"127.0.0.1:49536","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-26T06:19:13.594294Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581740375119454561,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-26T06:19:13.626574Z","caller":"traceutil/trace.go:171","msg":"trace[249911472] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"718.654246ms","start":"2026-02-26T06:19:12.907895Z","end":"2026-02-26T06:19:13.62655Z","steps":["trace[249911472] 'process raft request' (duration: 718.489052ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:19:13.626811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:19:12.90787Z","time spent":"718.850881ms","remote":"127.0.0.1:49536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T06:19:13.655743Z","caller":"traceutil/trace.go:171","msg":"trace[102508563] linearizableReadLoop","detail":"{readStateIndex:1006; appliedIndex:1004; }","duration":"562.03782ms","start":"2026-02-26T06:19:13.093689Z","end":"2026-02-26T06:19:13.655727Z","steps":["trace[102508563] 'read index received' (duration: 532.705894ms)","trace[102508563] 'applied index is now lower than readState.Index' (duration: 29.331206ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:19:13.655951Z","caller":"traceutil/trace.go:171","msg":"trace[1819871616] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"556.710729ms","start":"2026-02-26T06:19:13.099226Z","end":"2026-02-26T06:19:13.655937Z","steps":["trace[1819871616] 'process raft request' (duration: 556.40834ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:19:13.655996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.575985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"warn","ts":"2026-02-26T06:19:13.656057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:19:13.099204Z","time spent":"556.787921ms","remote":"127.0.0.1:49536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T06:19:13.656069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.39024208s","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-26T06:19:13.656057Z","caller":"traceutil/trace.go:171","msg":"trace[33315928] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:963; }","duration":"558.687418ms","start":"2026-02-26T06:19:13.097355Z","end":"2026-02-26T06:19:13.656042Z","steps":["trace[33315928] 'agreement among raft nodes before linearized reading' (duration: 558.571615ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:19:13.656092Z","caller":"traceutil/trace.go:171","msg":"trace[1810227937] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:963; }","duration":"1.390307711s","start":"2026-02-26T06:19:12.265779Z","end":"2026-02-26T06:19:13.656086Z","steps":["trace[1810227937] 'agreement among raft nodes before linearized reading' (duration: 1.39026099s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:19:13.656108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:19:12.265757Z","time spent":"1.390348173s","remote":"127.0.0.1:49536","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-26T06:19:13.656106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:19:13.097334Z","time spent":"558.7636ms","remote":"127.0.0.1:49442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-02-26T06:19:13.656201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.138091109s","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-26T06:19:13.656227Z","caller":"traceutil/trace.go:171","msg":"trace[2072607121] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:963; }","duration":"1.13815535s","start":"2026-02-26T06:19:12.518065Z","end":"2026-02-26T06:19:13.65622Z","steps":["trace[2072607121] 'agreement among raft nodes before linearized reading' (duration: 1.138086729s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:19:13.656246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:19:12.518042Z","time spent":"1.138199501s","remote":"127.0.0.1:49536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-26T06:19:13.65602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"803.379624ms","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-26T06:19:13.656315Z","caller":"traceutil/trace.go:171","msg":"trace[1130092609] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:963; }","duration":"803.724493ms","start":"2026-02-26T06:19:12.852584Z","end":"2026-02-26T06:19:13.656309Z","steps":["trace[1130092609] 'agreement among raft nodes before linearized reading' (duration: 803.334573ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:19:13.656344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:19:12.852565Z","time spent":"803.771513ms","remote":"127.0.0.1:49536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-26T06:19:13.656353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.29643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:19:13.656386Z","caller":"traceutil/trace.go:171","msg":"trace[1834572717] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:963; }","duration":"188.380852ms","start":"2026-02-26T06:19:13.467997Z","end":"2026-02-26T06:19:13.656378Z","steps":["trace[1834572717] 'agreement among raft nodes before linearized reading' (duration: 188.309611ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:19:13.656547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.390244599s","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-26T06:19:13.656597Z","caller":"traceutil/trace.go:171","msg":"trace[1343242454] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:963; }","duration":"1.390340061s","start":"2026-02-26T06:19:12.266244Z","end":"2026-02-26T06:19:13.656584Z","steps":["trace[1343242454] 'agreement among raft nodes before linearized reading' (duration: 1.389617214s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:19:13.65663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:19:12.266225Z","time spent":"1.390396652s","remote":"127.0.0.1:49536","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-26T06:19:13.851254Z","caller":"traceutil/trace.go:171","msg":"trace[150798746] linearizableReadLoop","detail":"{readStateIndex:1007; appliedIndex:1006; }","duration":"191.154761ms","start":"2026-02-26T06:19:13.660072Z","end":"2026-02-26T06:19:13.851227Z","steps":["trace[150798746] 'read index received' (duration: 181.882181ms)","trace[150798746] 'applied index is now lower than readState.Index' (duration: 9.27146ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:19:13.851365Z","caller":"traceutil/trace.go:171","msg":"trace[308663958] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"191.604952ms","start":"2026-02-26T06:19:13.659725Z","end":"2026-02-26T06:19:13.85133Z","steps":["trace[308663958] 'process raft request' (duration: 182.136368ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:19:13.851395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.289634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-02-26T06:19:13.851439Z","caller":"traceutil/trace.go:171","msg":"trace[526946004] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:964; }","duration":"191.391217ms","start":"2026-02-26T06:19:13.660038Z","end":"2026-02-26T06:19:13.851429Z","steps":["trace[526946004] 'agreement among raft nodes before linearized reading' (duration: 191.285624ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:19:14.067346Z","caller":"traceutil/trace.go:171","msg":"trace[798704810] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"405.137987ms","start":"2026-02-26T06:19:13.662188Z","end":"2026-02-26T06:19:14.067326Z","steps":["trace[798704810] 'process raft request' (duration: 405.097136ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:19:14.067349Z","caller":"traceutil/trace.go:171","msg":"trace[1264113504] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"406.599314ms","start":"2026-02-26T06:19:13.660715Z","end":"2026-02-26T06:19:14.067314Z","steps":["trace[1264113504] 'process raft request' (duration: 378.16549ms)","trace[1264113504] 'compare' (duration: 28.208278ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:19:14.067485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:19:13.662159Z","time spent":"405.252219ms","remote":"127.0.0.1:49536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T06:19:14.067514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:19:13.660706Z","time spent":"406.717306ms","remote":"127.0.0.1:49536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T06:19:14.067568Z","caller":"traceutil/trace.go:171","msg":"trace[1413015226] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"406.031819ms","start":"2026-02-26T06:19:13.661501Z","end":"2026-02-26T06:19:14.067532Z","steps":["trace[1413015226] 'process raft request' (duration: 405.751481ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:19:14.067741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:19:13.661494Z","time spent":"406.141093ms","remote":"127.0.0.1:49536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T06:19:14.301263Z","caller":"traceutil/trace.go:171","msg":"trace[1283340388] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"116.935925ms","start":"2026-02-26T06:19:14.184294Z","end":"2026-02-26T06:19:14.30123Z","steps":["trace[1283340388] 'process raft request' (duration: 98.679983ms)","trace[1283340388] 'compare' (duration: 18.095378ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:19:14.595798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.163921ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-26T06:19:14.596229Z","caller":"traceutil/trace.go:171","msg":"trace[385607454] linearizableReadLoop","detail":"{readStateIndex:1014; appliedIndex:1012; }","duration":"127.966056ms","start":"2026-02-26T06:19:14.468238Z","end":"2026-02-26T06:19:14.596204Z","steps":["trace[385607454] 'read index received' (duration: 103.318596ms)","trace[385607454] 'applied index is now lower than readState.Index' (duration: 24.64567ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:19:14.596422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.174382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:19:14.596504Z","caller":"traceutil/trace.go:171","msg":"trace[1155105907] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:971; }","duration":"128.322105ms","start":"2026-02-26T06:19:14.468163Z","end":"2026-02-26T06:19:14.596485Z","steps":["trace[1155105907] 'agreement among raft nodes before linearized reading' (duration: 128.163561ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:19:14.596537Z","caller":"traceutil/trace.go:171","msg":"trace[1180939603] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"205.068296ms","start":"2026-02-26T06:19:14.391384Z","end":"2026-02-26T06:19:14.596453Z","steps":["trace[1180939603] 'process raft request' (duration: 204.686726ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:19:14.596257Z","caller":"traceutil/trace.go:171","msg":"trace[339268101] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"207.113776ms","start":"2026-02-26T06:19:14.38909Z","end":"2026-02-26T06:19:14.596204Z","steps":["trace[339268101] 'process raft request' (duration: 53.421302ms)","trace[339268101] 'compare' (duration: 152.779012ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:19:15.453316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.320565ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-26T06:19:15.453458Z","caller":"traceutil/trace.go:171","msg":"trace[1280922856] linearizableReadLoop","detail":"{readStateIndex:1015; appliedIndex:1014; }","duration":"556.261658ms","start":"2026-02-26T06:19:14.897179Z","end":"2026-02-26T06:19:15.453441Z","steps":["trace[1280922856] 'read index received' (duration: 202.761828ms)","trace[1280922856] 'applied index is now lower than readState.Index' (duration: 353.49847ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:19:15.453563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.37126ms","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":"info","ts":"2026-02-26T06:19:15.453599Z","caller":"traceutil/trace.go:171","msg":"trace[498542053] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:972; }","duration":"556.476253ms","start":"2026-02-26T06:19:14.897114Z","end":"2026-02-26T06:19:15.453591Z","steps":["trace[498542053] 'agreement among raft nodes before linearized reading' (duration: 556.3719ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:19:15.453641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:19:14.89709Z","time spent":"556.542475ms","remote":"127.0.0.1:49582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"info","ts":"2026-02-26T06:19:15.453768Z","caller":"traceutil/trace.go:171","msg":"trace[1349740865] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"965.21306ms","start":"2026-02-26T06:19:14.488509Z","end":"2026-02-26T06:19:15.453722Z","steps":["trace[1349740865] 'process raft request' (duration: 611.408983ms)","trace[1349740865] 'compare' (duration: 353.193772ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:19:15.453861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:19:14.488485Z","time spent":"965.335802ms","remote":"127.0.0.1:49344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":787,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-26T06:19:15.880706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.328224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-26T06:19:15.88076Z","caller":"traceutil/trace.go:171","msg":"trace[569897125] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:973; }","duration":"218.439626ms","start":"2026-02-26T06:19:15.66231Z","end":"2026-02-26T06:19:15.880749Z","steps":["trace[569897125] 'range keys from in-memory index tree' (duration: 218.203411ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:19:16.339569Z","caller":"traceutil/trace.go:171","msg":"trace[1073489648] linearizableReadLoop","detail":"{readStateIndex:1019; appliedIndex:1018; }","duration":"267.486202ms","start":"2026-02-26T06:19:16.072061Z","end":"2026-02-26T06:19:16.339547Z","steps":["trace[1073489648] 'read index received' (duration: 267.40531ms)","trace[1073489648] 'applied index is now lower than readState.Index' (duration: 79.662µs)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:19:16.339815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.739588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-26T06:19:16.339884Z","caller":"traceutil/trace.go:171","msg":"trace[120503042] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:976; }","duration":"267.868501ms","start":"2026-02-26T06:19:16.072005Z","end":"2026-02-26T06:19:16.339873Z","steps":["trace[120503042] 'agreement among raft nodes before linearized reading' (duration: 267.678486ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:19:16.339621Z","caller":"traceutil/trace.go:171","msg":"trace[310562234] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"300.783286ms","start":"2026-02-26T06:19:16.038812Z","end":"2026-02-26T06:19:16.339595Z","steps":["trace[310562234] 'process raft request' (duration: 300.607222ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:19:16.34011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:19:16.038784Z","time spent":"301.234846ms","remote":"127.0.0.1:49448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5958,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T06:19:16.373425Z","caller":"traceutil/trace.go:171","msg":"trace[1905198694] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"158.73816ms","start":"2026-02-26T06:19:16.214667Z","end":"2026-02-26T06:19:16.373406Z","steps":["trace[1905198694] 'process raft request' (duration: 158.607367ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:19:16.373779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.455262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-26T06:19:16.373978Z","caller":"traceutil/trace.go:171","msg":"trace[1591265521] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:977; }","duration":"301.655767ms","start":"2026-02-26T06:19:16.072306Z","end":"2026-02-26T06:19:16.373962Z","steps":["trace[1591265521] 'agreement among raft nodes before linearized reading' (duration: 301.298989ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:19:16.374101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:19:16.07228Z","time spent":"301.80653ms","remote":"127.0.0.1:49536","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-26T06:19:16.374098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.628373ms","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-26T06:19:16.374282Z","caller":"traceutil/trace.go:171","msg":"trace[585399384] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:977; }","duration":"296.898829ms","start":"2026-02-26T06:19:16.077364Z","end":"2026-02-26T06:19:16.374263Z","steps":["trace[585399384] 'agreement among raft nodes before linearized reading' (duration: 296.408257ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:19:16.643424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.64441ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T06:19:16.647884Z","caller":"traceutil/trace.go:171","msg":"trace[596202257] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"268.949336ms","start":"2026-02-26T06:19:16.378903Z","end":"2026-02-26T06:19:16.647852Z","steps":["trace[596202257] 'process raft request' (duration: 77.363014ms)","trace[596202257] 'compare' (duration: 186.473046ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:19:16.647969Z","caller":"traceutil/trace.go:171","msg":"trace[1826761869] linearizableReadLoop","detail":"{readStateIndex:1023; appliedIndex:1021; }","duration":"179.541424ms","start":"2026-02-26T06:19:16.468372Z","end":"2026-02-26T06:19:16.647913Z","steps":["trace[1826761869] 'read index received' (duration: 34.421µs)","trace[1826761869] 'applied index is now lower than readState.Index' (duration: 179.505773ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:19:16.648119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.721808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:19:16.648318Z","caller":"traceutil/trace.go:171","msg":"trace[1850751800] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:980; }","duration":"179.928353ms","start":"2026-02-26T06:19:16.468366Z","end":"2026-02-26T06:19:16.648294Z","steps":["trace[1850751800] 'agreement among raft nodes before linearized reading' (duration: 179.694737ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:19:16.648177Z","caller":"traceutil/trace.go:171","msg":"trace[377833615] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"269.174092ms","start":"2026-02-26T06:19:16.37898Z","end":"2026-02-26T06:19:16.648154Z","steps":["trace[377833615] 'process raft request' (duration: 268.790632ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:19:18.786235Z","caller":"traceutil/trace.go:171","msg":"trace[1069254916] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"130.92266ms","start":"2026-02-26T06:19:18.655288Z","end":"2026-02-26T06:19:18.78621Z","steps":["trace[1069254916] 'process raft request' (duration: 130.787247ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:19:18.78708Z","caller":"traceutil/trace.go:171","msg":"trace[1610651727] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"129.017053ms","start":"2026-02-26T06:19:18.658043Z","end":"2026-02-26T06:19:18.78706Z","steps":["trace[1610651727] 'process raft request' (duration: 128.919551ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:19:19.715649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.766297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-02-26T06:19:19.715733Z","caller":"traceutil/trace.go:171","msg":"trace[1621894022] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:992; }","duration":"113.91699ms","start":"2026-02-26T06:19:19.6018Z","end":"2026-02-26T06:19:19.715717Z","steps":["trace[1621894022] 'range keys from in-memory index tree' (duration: 113.484909ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:19:19.715674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.963942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:19:19.715816Z","caller":"traceutil/trace.go:171","msg":"trace[1045020456] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:992; }","duration":"247.172437ms","start":"2026-02-26T06:19:19.46862Z","end":"2026-02-26T06:19:19.715793Z","steps":["trace[1045020456] 'range keys from in-memory index tree' (duration: 246.810649ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:19:19.715879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.072373ms","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-26T06:19:19.716023Z","caller":"traceutil/trace.go:171","msg":"trace[1584033666] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:992; }","duration":"436.234037ms","start":"2026-02-26T06:19:19.279766Z","end":"2026-02-26T06:19:19.716Z","steps":["trace[1584033666] 'range keys from in-memory index tree' (duration: 436.050813ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:19:20.22914Z","caller":"traceutil/trace.go:171","msg":"trace[610642942] linearizableReadLoop","detail":"{readStateIndex:1037; appliedIndex:1036; }","duration":"311.225233ms","start":"2026-02-26T06:19:19.917892Z","end":"2026-02-26T06:19:20.229117Z","steps":["trace[610642942] 'read index received' (duration: 311.004697ms)","trace[610642942] 'applied index is now lower than readState.Index' (duration: 219.566µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:19:20.229217Z","caller":"traceutil/trace.go:171","msg":"trace[1699926188] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"508.546117ms","start":"2026-02-26T06:19:19.720629Z","end":"2026-02-26T06:19:20.229175Z","steps":["trace[1699926188] 'process raft request' (duration: 508.323082ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:19:20.229488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.574801ms","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-26T06:19:20.22957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:19:19.720607Z","time spent":"508.762053ms","remote":"127.0.0.1:49536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T06:19:20.229634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.297468ms","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-26T06:19:20.229577Z","caller":"traceutil/trace.go:171","msg":"trace[2119225284] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:993; }","duration":"311.723595ms","start":"2026-02-26T06:19:19.917832Z","end":"2026-02-26T06:19:20.229556Z","steps":["trace[2119225284] 'agreement among raft nodes before linearized reading' (duration: 311.420058ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:19:20.22976Z","caller":"traceutil/trace.go:171","msg":"trace[1915317441] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:993; }","duration":"240.472771ms","start":"2026-02-26T06:19:19.989256Z","end":"2026-02-26T06:19:20.229729Z","steps":["trace[1915317441] 'agreement among raft nodes before linearized reading' (duration: 240.105563ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:19:20.229923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:19:19.917808Z","time spent":"312.092684ms","remote":"127.0.0.1:49536","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-26T06:19:20.603734Z","caller":"traceutil/trace.go:171","msg":"trace[1622394216] linearizableReadLoop","detail":"{readStateIndex:1039; appliedIndex:1038; }","duration":"176.542219ms","start":"2026-02-26T06:19:20.427159Z","end":"2026-02-26T06:19:20.603701Z","steps":["trace[1622394216] 'read index received' (duration: 175.883302ms)","trace[1622394216] 'applied index is now lower than readState.Index' (duration: 657.707µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:19:20.604038Z","caller":"traceutil/trace.go:171","msg":"trace[1709185869] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"368.966192ms","start":"2026-02-26T06:19:20.234991Z","end":"2026-02-26T06:19:20.603957Z","steps":["trace[1709185869] 'process raft request' (duration: 368.046329ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:19:20.604347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:19:20.234969Z","time spent":"369.202799ms","remote":"127.0.0.1:49536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T06:19:20.604383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.188826ms","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-26T06:19:20.604754Z","caller":"traceutil/trace.go:171","msg":"trace[238661147] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:995; }","duration":"177.319969ms","start":"2026-02-26T06:19:20.427106Z","end":"2026-02-26T06:19:20.604426Z","steps":["trace[238661147] 'agreement among raft nodes before linearized reading' (duration: 176.706244ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:19:22.810322Z","caller":"traceutil/trace.go:171","msg":"trace[1077579354] linearizableReadLoop","detail":"{readStateIndex:1067; appliedIndex:1066; }","duration":"195.077138ms","start":"2026-02-26T06:19:22.615225Z","end":"2026-02-26T06:19:22.810302Z","steps":["trace[1077579354] 'read index received' (duration: 195.009186ms)","trace[1077579354] 'applied index is now lower than readState.Index' (duration: 65.292µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:19:22.810431Z","caller":"traceutil/trace.go:171","msg":"trace[191017668] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"196.874892ms","start":"2026-02-26T06:19:22.613542Z","end":"2026-02-26T06:19:22.810417Z","steps":["trace[191017668] 'process raft request' (duration: 196.627886ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:19:22.810633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.392086ms","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-26T06:19:22.81067Z","caller":"traceutil/trace.go:171","msg":"trace[23506116] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1022; }","duration":"195.459078ms","start":"2026-02-26T06:19:22.615202Z","end":"2026-02-26T06:19:22.810661Z","steps":["trace[23506116] 'agreement among raft nodes before linearized reading' (duration: 195.332084ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:22:30.006643Z","caller":"traceutil/trace.go:171","msg":"trace[1981804758] linearizableReadLoop","detail":"{readStateIndex:1905; appliedIndex:1904; }","duration":"243.968976ms","start":"2026-02-26T06:22:29.762649Z","end":"2026-02-26T06:22:30.006618Z","steps":["trace[1981804758] 'read index received' (duration: 243.797822ms)","trace[1981804758] 'applied index is now lower than readState.Index' (duration: 158.593µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:22:30.006653Z","caller":"traceutil/trace.go:171","msg":"trace[1052581862] transaction","detail":"{read_only:false; response_revision:1822; number_of_response:1; }","duration":"336.749839ms","start":"2026-02-26T06:22:29.669873Z","end":"2026-02-26T06:22:30.006622Z","steps":["trace[1052581862] 'process raft request' (duration: 336.569674ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:22:30.006873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:22:29.669854Z","time spent":"336.934804ms","remote":"127.0.0.1:49536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T06:22:30.007232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.564181ms","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-26T06:22:30.00729Z","caller":"traceutil/trace.go:171","msg":"trace[1239927573] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1822; }","duration":"244.641553ms","start":"2026-02-26T06:22:29.762636Z","end":"2026-02-26T06:22:30.007278Z","steps":["trace[1239927573] 'agreement among raft nodes before linearized reading' (duration: 244.13739ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:22:30.007814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.648633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-02-26T06:22:30.007794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.13279ms","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-26T06:22:30.007937Z","caller":"traceutil/trace.go:171","msg":"trace[1176905070] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1823; }","duration":"240.308105ms","start":"2026-02-26T06:22:29.767609Z","end":"2026-02-26T06:22:30.007917Z","steps":["trace[1176905070] 'agreement among raft nodes before linearized reading' (duration: 239.969226ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:22:30.007952Z","caller":"traceutil/trace.go:171","msg":"trace[1603976040] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1823; }","duration":"240.791946ms","start":"2026-02-26T06:22:29.767107Z","end":"2026-02-26T06:22:30.007899Z","steps":["trace[1603976040] 'agreement among raft nodes before linearized reading' (duration: 240.416247ms)"],"step_count":1}