{"level":"warn","ts":"2026-02-18T19:48:39.579945Z","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-18T19:48:39.582694Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.217:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.217:2380","--initial-cluster=instance=https://162.253.55.217:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.217:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.217: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-18T19:48:39.582774Z","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-18T19:48:39.582789Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.217:2380"]} {"level":"info","ts":"2026-02-18T19:48:39.582806Z","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-18T19:48:39.583415Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.217:2379"]} {"level":"info","ts":"2026-02-18T19:48:39.583484Z","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":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.217:2380"],"listen-peer-urls":["https://162.253.55.217:2380"],"advertise-client-urls":["https://162.253.55.217:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.217:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.217: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-18T19:48:39.637372Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"36.843239ms"} {"level":"info","ts":"2026-02-18T19:48:39.711731Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f1e368e6818bd6f7","cluster-id":"289e52865b7eaeb1"} {"level":"info","ts":"2026-02-18T19:48:39.711825Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 switched to configuration voters=()"} {"level":"info","ts":"2026-02-18T19:48:39.711854Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became follower at term 0"} {"level":"info","ts":"2026-02-18T19:48:39.711874Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f1e368e6818bd6f7 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-18T19:48:39.711891Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became follower at term 1"} {"level":"info","ts":"2026-02-18T19:48:39.711946Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 switched to configuration voters=(17429890322079143671)"} {"level":"warn","ts":"2026-02-18T19:48:39.724206Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-18T19:48:39.725242Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-18T19:48:39.726244Z","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-18T19:48:39.730358Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f1e368e6818bd6f7","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-18T19:48:39.733875Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f1e368e6818bd6f7","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-18T19:48:39.73465Z","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-18T19:48:39.734781Z","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-18T19:48:39.734854Z","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-18T19:48:39.735632Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 switched to configuration voters=(17429890322079143671)"} {"level":"info","ts":"2026-02-18T19:48:39.740898Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"289e52865b7eaeb1","local-member-id":"f1e368e6818bd6f7","added-peer-id":"f1e368e6818bd6f7","added-peer-peer-urls":["https://162.253.55.217:2380"]} {"level":"info","ts":"2026-02-18T19:48:39.757376Z","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-18T19:48:39.757552Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f1e368e6818bd6f7","initial-advertise-peer-urls":["https://162.253.55.217:2380"],"listen-peer-urls":["https://162.253.55.217:2380"],"advertise-client-urls":["https://162.253.55.217:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.217:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-18T19:48:39.757571Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-18T19:48:39.757632Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.217:2380"} {"level":"info","ts":"2026-02-18T19:48:39.757639Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.217:2380"} {"level":"info","ts":"2026-02-18T19:48:40.613497Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 is starting a new election at term 1"} {"level":"info","ts":"2026-02-18T19:48:40.613615Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-18T19:48:40.613684Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 received MsgPreVoteResp from f1e368e6818bd6f7 at term 1"} {"level":"info","ts":"2026-02-18T19:48:40.613699Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became candidate at term 2"} {"level":"info","ts":"2026-02-18T19:48:40.613705Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 received MsgVoteResp from f1e368e6818bd6f7 at term 2"} {"level":"info","ts":"2026-02-18T19:48:40.613716Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became leader at term 2"} {"level":"info","ts":"2026-02-18T19:48:40.613722Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f1e368e6818bd6f7 elected leader f1e368e6818bd6f7 at term 2"} {"level":"info","ts":"2026-02-18T19:48:40.615322Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f1e368e6818bd6f7","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.217:2379]}","request-path":"/0/members/f1e368e6818bd6f7/attributes","cluster-id":"289e52865b7eaeb1","publish-timeout":"7s"} {"level":"info","ts":"2026-02-18T19:48:40.615455Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-18T19:48:40.615506Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-18T19:48:40.615527Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-18T19:48:40.620095Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-18T19:48:40.615596Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T19:48:40.622091Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.217:2379"} {"level":"info","ts":"2026-02-18T19:48:40.623085Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"289e52865b7eaeb1","local-member-id":"f1e368e6818bd6f7","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T19:48:40.623197Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T19:48:40.623254Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T19:48:40.626352Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-18T19:48:45.277966Z","caller":"traceutil/trace.go:171","msg":"trace[845293649] transaction","detail":"{read_only:false; response_revision:218; number_of_response:1; }","duration":"126.453904ms","start":"2026-02-18T19:48:45.151479Z","end":"2026-02-18T19:48:45.277932Z","steps":["trace[845293649] 'process raft request' (duration: 37.723128ms)","trace[845293649] 'compare' (duration: 88.42291ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T19:48:46.224955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.031817ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-18T19:48:46.225019Z","caller":"traceutil/trace.go:171","msg":"trace[1065777216] linearizableReadLoop","detail":"{readStateIndex:276; appliedIndex:275; }","duration":"101.815979ms","start":"2026-02-18T19:48:46.123193Z","end":"2026-02-18T19:48:46.225009Z","steps":["trace[1065777216] 'read index received' (duration: 15.58µs)","trace[1065777216] 'applied index is now lower than readState.Index' (duration: 101.799729ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T19:48:46.225098Z","caller":"traceutil/trace.go:171","msg":"trace[933747274] transaction","detail":"{read_only:false; response_revision:267; number_of_response:1; }","duration":"111.164615ms","start":"2026-02-18T19:48:46.113927Z","end":"2026-02-18T19:48:46.225092Z","steps":["trace[933747274] 'compare' (duration: 104.909384ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:48:46.225206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.007223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T19:48:46.225221Z","caller":"traceutil/trace.go:171","msg":"trace[873313959] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:267; }","duration":"102.042013ms","start":"2026-02-18T19:48:46.123173Z","end":"2026-02-18T19:48:46.225215Z","steps":["trace[873313959] 'agreement among raft nodes before linearized reading' (duration: 101.999792ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T19:48:54.514073Z","caller":"traceutil/trace.go:171","msg":"trace[954335153] linearizableReadLoop","detail":"{readStateIndex:386; appliedIndex:385; }","duration":"178.220978ms","start":"2026-02-18T19:48:54.335829Z","end":"2026-02-18T19:48:54.51405Z","steps":["trace[954335153] 'read index received' (duration: 178.010744ms)","trace[954335153] 'applied index is now lower than readState.Index' (duration: 209.404µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T19:48:54.514182Z","caller":"traceutil/trace.go:171","msg":"trace[645518790] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"203.868085ms","start":"2026-02-18T19:48:54.310305Z","end":"2026-02-18T19:48:54.514173Z","steps":["trace[645518790] 'process raft request' (duration: 203.593399ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:48:54.514537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.392959ms","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-18T19:48:54.514799Z","caller":"traceutil/trace.go:171","msg":"trace[1848220479] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:374; }","duration":"175.673015ms","start":"2026-02-18T19:48:54.339066Z","end":"2026-02-18T19:48:54.514738Z","steps":["trace[1848220479] 'agreement among raft nodes before linearized reading' (duration: 175.378999ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:48:54.514582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.737679ms","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-18T19:48:54.51541Z","caller":"traceutil/trace.go:171","msg":"trace[1819983085] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:374; }","duration":"179.606597ms","start":"2026-02-18T19:48:54.335789Z","end":"2026-02-18T19:48:54.515396Z","steps":["trace[1819983085] 'agreement among raft nodes before linearized reading' (duration: 178.741339ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T19:49:08.470859Z","caller":"traceutil/trace.go:171","msg":"trace[1079535728] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"103.79018ms","start":"2026-02-18T19:49:08.367053Z","end":"2026-02-18T19:49:08.470843Z","steps":["trace[1079535728] 'process raft request' (duration: 103.488934ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T19:49:08.4711Z","caller":"traceutil/trace.go:171","msg":"trace[419890671] transaction","detail":"{read_only:false; response_revision:460; number_of_response:1; }","duration":"103.939703ms","start":"2026-02-18T19:49:08.367153Z","end":"2026-02-18T19:49:08.471092Z","steps":["trace[419890671] 'process raft request' (duration: 103.520135ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T19:49:08.471185Z","caller":"traceutil/trace.go:171","msg":"trace[2020032718] transaction","detail":"{read_only:false; response_revision:461; number_of_response:1; }","duration":"103.926973ms","start":"2026-02-18T19:49:08.367252Z","end":"2026-02-18T19:49:08.471179Z","steps":["trace[2020032718] 'process raft request' (duration: 103.464814ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T19:49:08.471351Z","caller":"traceutil/trace.go:171","msg":"trace[1452137082] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"100.011692ms","start":"2026-02-18T19:49:08.371333Z","end":"2026-02-18T19:49:08.471344Z","steps":["trace[1452137082] 'process raft request' (duration: 99.45729ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T19:49:08.661878Z","caller":"traceutil/trace.go:171","msg":"trace[758360889] linearizableReadLoop","detail":"{readStateIndex:482; appliedIndex:481; }","duration":"150.225581ms","start":"2026-02-18T19:49:08.511627Z","end":"2026-02-18T19:49:08.661853Z","steps":["trace[758360889] 'read index received' (duration: 90.909211ms)","trace[758360889] 'applied index is now lower than readState.Index' (duration: 59.31293ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T19:49:08.66202Z","caller":"traceutil/trace.go:171","msg":"trace[620962993] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"154.685764ms","start":"2026-02-18T19:49:08.507316Z","end":"2026-02-18T19:49:08.662002Z","steps":["trace[620962993] 'process raft request' (duration: 95.17873ms)","trace[620962993] 'compare' (duration: 59.234499ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T19:49:08.662255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.639889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/cilium\" ","response":"range_response_count:1 size:14309"} {"level":"info","ts":"2026-02-18T19:49:08.662306Z","caller":"traceutil/trace.go:171","msg":"trace[702587854] range","detail":"{range_begin:/registry/daemonsets/kube-system/cilium; range_end:; response_count:1; response_revision:467; }","duration":"161.712781ms","start":"2026-02-18T19:49:08.500576Z","end":"2026-02-18T19:49:08.662289Z","steps":["trace[702587854] 'agreement among raft nodes before linearized reading' (duration: 161.562278ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T19:49:08.671987Z","caller":"traceutil/trace.go:171","msg":"trace[285061566] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"154.619623ms","start":"2026-02-18T19:49:08.517355Z","end":"2026-02-18T19:49:08.671975Z","steps":["trace[285061566] 'process raft request' (duration: 154.410999ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T19:49:08.672153Z","caller":"traceutil/trace.go:171","msg":"trace[2067409076] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"152.747104ms","start":"2026-02-18T19:49:08.519372Z","end":"2026-02-18T19:49:08.672119Z","steps":["trace[2067409076] 'process raft request' (duration: 152.464948ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:49:08.672371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.815307ms","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-18T19:49:08.672446Z","caller":"traceutil/trace.go:171","msg":"trace[2036733026] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"139.64339ms","start":"2026-02-18T19:49:08.532774Z","end":"2026-02-18T19:49:08.672417Z","steps":["trace[2036733026] 'process raft request' (duration: 139.094569ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T19:49:08.672532Z","caller":"traceutil/trace.go:171","msg":"trace[1105920068] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:470; }","duration":"110.94019ms","start":"2026-02-18T19:49:08.561519Z","end":"2026-02-18T19:49:08.67246Z","steps":["trace[1105920068] 'agreement among raft nodes before linearized reading' (duration: 110.816148ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:49:08.674036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.172524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329940"} {"level":"info","ts":"2026-02-18T19:49:08.681235Z","caller":"traceutil/trace.go:171","msg":"trace[828244419] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:470; }","duration":"140.304254ms","start":"2026-02-18T19:49:08.540829Z","end":"2026-02-18T19:49:08.681133Z","steps":["trace[828244419] 'agreement among raft nodes before linearized reading' (duration: 131.878027ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T19:49:08.823018Z","caller":"traceutil/trace.go:171","msg":"trace[205209351] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"123.149006ms","start":"2026-02-18T19:49:08.699844Z","end":"2026-02-18T19:49:08.822993Z","steps":["trace[205209351] 'process raft request' (duration: 82.393872ms)","trace[205209351] 'compare' (duration: 40.628171ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T19:49:08.82427Z","caller":"traceutil/trace.go:171","msg":"trace[1386631632] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"123.803899ms","start":"2026-02-18T19:49:08.700457Z","end":"2026-02-18T19:49:08.824261Z","steps":["trace[1386631632] 'process raft request' (duration: 123.581394ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T19:49:08.824352Z","caller":"traceutil/trace.go:171","msg":"trace[1537912484] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"123.764448ms","start":"2026-02-18T19:49:08.700582Z","end":"2026-02-18T19:49:08.824347Z","steps":["trace[1537912484] 'process raft request' (duration: 123.516103ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T19:49:08.8245Z","caller":"traceutil/trace.go:171","msg":"trace[1369715900] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"120.02303ms","start":"2026-02-18T19:49:08.704472Z","end":"2026-02-18T19:49:08.824495Z","steps":["trace[1369715900] 'process raft request' (duration: 119.665213ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T19:49:08.824647Z","caller":"traceutil/trace.go:171","msg":"trace[772983009] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"114.636068ms","start":"2026-02-18T19:49:08.709972Z","end":"2026-02-18T19:49:08.824608Z","steps":["trace[772983009] 'process raft request' (duration: 114.200809ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T19:49:08.882841Z","caller":"traceutil/trace.go:171","msg":"trace[918775907] linearizableReadLoop","detail":"{readStateIndex:490; appliedIndex:485; }","duration":"172.756373ms","start":"2026-02-18T19:49:08.710062Z","end":"2026-02-18T19:49:08.882818Z","steps":["trace[918775907] 'read index received' (duration: 72.133939ms)","trace[918775907] 'applied index is now lower than readState.Index' (duration: 100.621274ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T19:49:08.883266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.945385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" ","response":"range_response_count:1 size:538"} {"level":"info","ts":"2026-02-18T19:49:08.88331Z","caller":"traceutil/trace.go:171","msg":"trace[1668898986] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium-operator; range_end:; response_count:1; response_revision:475; }","duration":"170.995416ms","start":"2026-02-18T19:49:08.712302Z","end":"2026-02-18T19:49:08.883297Z","steps":["trace[1668898986] 'agreement among raft nodes before linearized reading' (duration: 170.901184ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:49:08.883266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.186252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/cilium\" ","response":"range_response_count:1 size:14309"} {"level":"info","ts":"2026-02-18T19:49:08.883575Z","caller":"traceutil/trace.go:171","msg":"trace[80660744] range","detail":"{range_begin:/registry/daemonsets/kube-system/cilium; range_end:; response_count:1; response_revision:475; }","duration":"173.500858ms","start":"2026-02-18T19:49:08.710041Z","end":"2026-02-18T19:49:08.883542Z","steps":["trace[80660744] 'agreement among raft nodes before linearized reading' (duration: 173.075859ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T19:49:10.888434Z","caller":"traceutil/trace.go:171","msg":"trace[780727353] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"375.87635ms","start":"2026-02-18T19:49:10.512531Z","end":"2026-02-18T19:49:10.888407Z","steps":["trace[780727353] 'process raft request' (duration: 375.224337ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:49:10.888929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:49:10.512512Z","time spent":"376.026093ms","remote":"127.0.0.1:58472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4254,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T19:49:13.405174Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15490021458082047014,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T19:49:13.906251Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15490021458082047014,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T19:49:14.406925Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15490021458082047014,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T19:49:14.594548Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.700184894s","expected-duration":"1s"} {"level":"info","ts":"2026-02-18T19:49:14.595182Z","caller":"traceutil/trace.go:171","msg":"trace[2034551037] linearizableReadLoop","detail":"{readStateIndex:500; appliedIndex:499; }","duration":"1.690739315s","start":"2026-02-18T19:49:12.904413Z","end":"2026-02-18T19:49:14.595152Z","steps":["trace[2034551037] 'read index received' (duration: 1.690423489s)","trace[2034551037] 'applied index is now lower than readState.Index' (duration: 314.696µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T19:49:14.595581Z","caller":"traceutil/trace.go:171","msg":"trace[59251489] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"1.701284466s","start":"2026-02-18T19:49:12.894279Z","end":"2026-02-18T19:49:14.595564Z","steps":["trace[59251489] 'process raft request' (duration: 1.700625682s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:49:14.595793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:49:12.894262Z","time spent":"1.701359078s","remote":"127.0.0.1:58580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T19:49:14.596048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.691626843s","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-18T19:49:14.596093Z","caller":"traceutil/trace.go:171","msg":"trace[1594157955] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:484; }","duration":"1.691691865s","start":"2026-02-18T19:49:12.904387Z","end":"2026-02-18T19:49:14.596079Z","steps":["trace[1594157955] 'agreement among raft nodes before linearized reading' (duration: 1.691613504s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:49:14.596131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:49:12.904376Z","time spent":"1.691745516s","remote":"127.0.0.1:58580","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-18T19:49:14.596333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.691324108s","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-18T19:49:14.596374Z","caller":"traceutil/trace.go:171","msg":"trace[477978088] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:484; }","duration":"1.691385539s","start":"2026-02-18T19:49:12.904976Z","end":"2026-02-18T19:49:14.596361Z","steps":["trace[477978088] 'agreement among raft nodes before linearized reading' (duration: 1.691310147s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:49:14.596411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:49:12.90497Z","time spent":"1.691431801s","remote":"127.0.0.1:58580","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-18T19:49:14.596723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.061974697s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.217\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-18T19:49:14.596774Z","caller":"traceutil/trace.go:171","msg":"trace[1585262193] range","detail":"{range_begin:/registry/masterleases/162.253.55.217; range_end:; response_count:1; response_revision:484; }","duration":"1.062059669s","start":"2026-02-18T19:49:13.534701Z","end":"2026-02-18T19:49:14.596761Z","steps":["trace[1585262193] 'agreement among raft nodes before linearized reading' (duration: 1.061973477s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:49:14.596812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:49:13.534682Z","time spent":"1.06211983s","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/162.253.55.217\" "} {"level":"warn","ts":"2026-02-18T19:49:14.597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.117960648s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-18T19:49:14.597041Z","caller":"traceutil/trace.go:171","msg":"trace[966800609] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:484; }","duration":"1.1180259s","start":"2026-02-18T19:49:13.479002Z","end":"2026-02-18T19:49:14.597028Z","steps":["trace[966800609] 'agreement among raft nodes before linearized reading' (duration: 1.117957558s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:49:14.59708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:49:13.478985Z","time spent":"1.118083201s","remote":"127.0.0.1:58580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-18T19:49:14.597255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.563413693s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T19:49:14.597294Z","caller":"traceutil/trace.go:171","msg":"trace[1582306726] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:484; }","duration":"1.563471324s","start":"2026-02-18T19:49:13.033809Z","end":"2026-02-18T19:49:14.597281Z","steps":["trace[1582306726] 'agreement among raft nodes before linearized reading' (duration: 1.563412673s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:49:14.597328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:49:13.033797Z","time spent":"1.563522486s","remote":"127.0.0.1:58252","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-18T19:49:14.597577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.692210647s","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-18T19:49:14.597618Z","caller":"traceutil/trace.go:171","msg":"trace[161528683] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:484; }","duration":"1.692266118s","start":"2026-02-18T19:49:12.90534Z","end":"2026-02-18T19:49:14.597606Z","steps":["trace[161528683] 'agreement among raft nodes before linearized reading' (duration: 1.692130885s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:49:14.59766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:49:12.905334Z","time spent":"1.692316718s","remote":"127.0.0.1:58580","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-18T19:49:15.466832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"696.162536ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-02-18T19:49:15.46702Z","caller":"traceutil/trace.go:171","msg":"trace[172618030] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"854.688032ms","start":"2026-02-18T19:49:14.612321Z","end":"2026-02-18T19:49:15.467009Z","steps":["trace[172618030] 'process raft request' (duration: 854.669392ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:49:15.467082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:49:14.612315Z","time spent":"854.726293ms","remote":"127.0.0.1:58580","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-18T19:49:15.467229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:49:14.602427Z","time spent":"864.801423ms","remote":"127.0.0.1:58304","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-18T19:49:15.467312Z","caller":"traceutil/trace.go:171","msg":"trace[1207214262] linearizableReadLoop","detail":"{readStateIndex:501; appliedIndex:500; }","duration":"855.227823ms","start":"2026-02-18T19:49:14.612078Z","end":"2026-02-18T19:49:15.467306Z","steps":["trace[1207214262] 'read index received' (duration: 158.538146ms)","trace[1207214262] 'applied index is now lower than readState.Index' (duration: 696.689047ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T19:49:15.467352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"855.273084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T19:49:15.467365Z","caller":"traceutil/trace.go:171","msg":"trace[757759494] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:488; }","duration":"855.314384ms","start":"2026-02-18T19:49:14.612046Z","end":"2026-02-18T19:49:15.46736Z","steps":["trace[757759494] 'agreement among raft nodes before linearized reading' (duration: 855.277764ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:49:15.467378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:49:14.612029Z","time spent":"855.346447ms","remote":"127.0.0.1:58258","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-18T19:49:15.467487Z","caller":"traceutil/trace.go:171","msg":"trace[1371708099] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"855.338506ms","start":"2026-02-18T19:49:14.612144Z","end":"2026-02-18T19:49:15.467482Z","steps":["trace[1371708099] 'process raft request' (duration: 854.754553ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:49:15.467512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:49:14.612133Z","time spent":"855.364487ms","remote":"127.0.0.1:58580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T19:49:15.467565Z","caller":"traceutil/trace.go:171","msg":"trace[405507200] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"855.342507ms","start":"2026-02-18T19:49:14.612218Z","end":"2026-02-18T19:49:15.467561Z","steps":["trace[405507200] 'process raft request' (duration: 854.742543ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:49:15.467586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:49:14.612212Z","time spent":"855.361787ms","remote":"127.0.0.1:58580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T19:49:15.467634Z","caller":"traceutil/trace.go:171","msg":"trace[1429544281] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"855.372257ms","start":"2026-02-18T19:49:14.612258Z","end":"2026-02-18T19:49:15.467631Z","steps":["trace[1429544281] 'process raft request' (duration: 854.717273ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:49:15.467654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:49:14.612252Z","time spent":"855.391007ms","remote":"127.0.0.1:58580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T19:49:15.823732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.31719ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T19:49:15.823827Z","caller":"traceutil/trace.go:171","msg":"trace[1307923448] linearizableReadLoop","detail":"{readStateIndex:506; appliedIndex:505; }","duration":"346.025196ms","start":"2026-02-18T19:49:15.477782Z","end":"2026-02-18T19:49:15.823807Z","steps":["trace[1307923448] 'read index received' (duration: 101.503412ms)","trace[1307923448] 'applied index is now lower than readState.Index' (duration: 244.520544ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T19:49:15.823913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.126158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T19:49:15.823938Z","caller":"traceutil/trace.go:171","msg":"trace[1399352907] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:489; }","duration":"346.150479ms","start":"2026-02-18T19:49:15.477779Z","end":"2026-02-18T19:49:15.823929Z","steps":["trace[1399352907] 'agreement among raft nodes before linearized reading' (duration: 346.074897ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:49:15.82397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:49:15.47775Z","time spent":"346.21217ms","remote":"127.0.0.1:58252","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-18T19:49:15.824381Z","caller":"traceutil/trace.go:171","msg":"trace[1073802110] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"353.35631ms","start":"2026-02-18T19:49:15.47101Z","end":"2026-02-18T19:49:15.824366Z","steps":["trace[1073802110] 'process raft request' (duration: 108.330675ms)","trace[1073802110] 'compare' (duration: 244.204708ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T19:49:15.82445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:49:15.470999Z","time spent":"353.414861ms","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T19:49:17.150063Z","caller":"traceutil/trace.go:171","msg":"trace[925417582] linearizableReadLoop","detail":"{readStateIndex:507; appliedIndex:506; }","duration":"114.235559ms","start":"2026-02-18T19:49:17.035808Z","end":"2026-02-18T19:49:17.150044Z","steps":["trace[925417582] 'read index received' (duration: 114.053855ms)","trace[925417582] 'applied index is now lower than readState.Index' (duration: 181.084µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T19:49:17.150163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.342751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T19:49:17.150185Z","caller":"traceutil/trace.go:171","msg":"trace[1531678519] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:490; }","duration":"114.412533ms","start":"2026-02-18T19:49:17.035766Z","end":"2026-02-18T19:49:17.150179Z","steps":["trace[1531678519] 'agreement among raft nodes before linearized reading' (duration: 114.343671ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T19:49:17.150289Z","caller":"traceutil/trace.go:171","msg":"trace[1310124819] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"248.391304ms","start":"2026-02-18T19:49:16.901887Z","end":"2026-02-18T19:49:17.150278Z","steps":["trace[1310124819] 'process raft request' (duration: 248.034716ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T19:49:21.924194Z","caller":"traceutil/trace.go:171","msg":"trace[18923938] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"299.989843ms","start":"2026-02-18T19:49:21.624182Z","end":"2026-02-18T19:49:21.924172Z","steps":["trace[18923938] 'process raft request' (duration: 299.950422ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:49:21.924337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:49:21.624176Z","time spent":"300.083555ms","remote":"127.0.0.1:58580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T19:49:21.924664Z","caller":"traceutil/trace.go:171","msg":"trace[468395220] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"301.510705ms","start":"2026-02-18T19:49:21.62314Z","end":"2026-02-18T19:49:21.924651Z","steps":["trace[468395220] 'process raft request' (duration: 249.983947ms)","trace[468395220] 'compare' (duration: 50.687ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T19:49:21.924722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:49:21.623129Z","time spent":"301.562766ms","remote":"127.0.0.1:58580","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-18T19:49:21.924831Z","caller":"traceutil/trace.go:171","msg":"trace[1865876505] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"301.638987ms","start":"2026-02-18T19:49:21.623184Z","end":"2026-02-18T19:49:21.924823Z","steps":["trace[1865876505] 'process raft request' (duration: 300.874001ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:49:21.924879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:49:21.623168Z","time spent":"301.683759ms","remote":"127.0.0.1:58580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T19:49:23.670922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.316189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.217\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-18T19:49:23.67099Z","caller":"traceutil/trace.go:171","msg":"trace[112750358] range","detail":"{range_begin:/registry/masterleases/162.253.55.217; range_end:; response_count:1; response_revision:513; }","duration":"135.431551ms","start":"2026-02-18T19:49:23.535543Z","end":"2026-02-18T19:49:23.670974Z","steps":["trace[112750358] 'range keys from in-memory index tree' (duration: 135.181096ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T19:49:31.845669Z","caller":"traceutil/trace.go:171","msg":"trace[214296434] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"102.098295ms","start":"2026-02-18T19:49:31.743549Z","end":"2026-02-18T19:49:31.845648Z","steps":["trace[214296434] 'process raft request' (duration: 101.966772ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T19:49:35.563641Z","caller":"traceutil/trace.go:171","msg":"trace[1266264145] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"229.240544ms","start":"2026-02-18T19:49:35.334379Z","end":"2026-02-18T19:49:35.56362Z","steps":["trace[1266264145] 'process raft request' (duration: 227.57069ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T19:49:36.430992Z","caller":"traceutil/trace.go:171","msg":"trace[705176228] linearizableReadLoop","detail":"{readStateIndex:702; appliedIndex:701; }","duration":"350.373247ms","start":"2026-02-18T19:49:36.080599Z","end":"2026-02-18T19:49:36.430972Z","steps":["trace[705176228] 'read index received' (duration: 296.907339ms)","trace[705176228] 'applied index is now lower than readState.Index' (duration: 53.465008ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T19:49:36.431208Z","caller":"traceutil/trace.go:171","msg":"trace[1030421441] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"366.32703ms","start":"2026-02-18T19:49:36.064868Z","end":"2026-02-18T19:49:36.431195Z","steps":["trace[1030421441] 'process raft request' (duration: 312.679769ms)","trace[1030421441] 'compare' (duration: 53.161831ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T19:49:36.4313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:49:36.064852Z","time spent":"366.385851ms","remote":"127.0.0.1:58580","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-18T19:49:36.431561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.953189ms","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-18T19:49:36.431597Z","caller":"traceutil/trace.go:171","msg":"trace[1826136195] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:679; }","duration":"351.014979ms","start":"2026-02-18T19:49:36.080571Z","end":"2026-02-18T19:49:36.431586Z","steps":["trace[1826136195] 'agreement among raft nodes before linearized reading' (duration: 350.891067ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:49:36.431625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:49:36.080557Z","time spent":"351.061831ms","remote":"127.0.0.1:58580","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-18T19:49:36.431798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.729826ms","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-18T19:49:36.431824Z","caller":"traceutil/trace.go:171","msg":"trace[1406112411] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:679; }","duration":"242.778537ms","start":"2026-02-18T19:49:36.189038Z","end":"2026-02-18T19:49:36.431816Z","steps":["trace[1406112411] 'agreement among raft nodes before linearized reading' (duration: 242.698765ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:49:37.012423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.982221ms","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-18T19:49:37.012517Z","caller":"traceutil/trace.go:171","msg":"trace[511542573] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:679; }","duration":"199.085454ms","start":"2026-02-18T19:49:36.813418Z","end":"2026-02-18T19:49:37.012504Z","steps":["trace[511542573] 'range keys from in-memory index tree' (duration: 198.971831ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:49:37.012662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.346485ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T19:49:37.012743Z","caller":"traceutil/trace.go:171","msg":"trace[1805293202] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"570.322416ms","start":"2026-02-18T19:49:36.442406Z","end":"2026-02-18T19:49:37.012729Z","steps":["trace[1805293202] 'process raft request' (duration: 439.819267ms)","trace[1805293202] 'compare' (duration: 129.911516ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T19:49:37.012781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:49:36.44239Z","time spent":"570.374018ms","remote":"127.0.0.1:58580","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-18T19:49:37.109176Z","caller":"traceutil/trace.go:171","msg":"trace[1810093108] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"666.64899ms","start":"2026-02-18T19:49:36.442509Z","end":"2026-02-18T19:49:37.109158Z","steps":["trace[1810093108] 'process raft request' (duration: 666.355374ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:49:37.10928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:49:36.442505Z","time spent":"666.713691ms","remote":"127.0.0.1:58580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T19:49:42.475956Z","caller":"traceutil/trace.go:171","msg":"trace[906002472] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"399.620057ms","start":"2026-02-18T19:49:42.075766Z","end":"2026-02-18T19:49:42.475386Z","steps":["trace[906002472] 'process raft request' (duration: 329.828987ms)","trace[906002472] 'compare' (duration: 69.126965ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T19:49:42.478077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:49:42.075754Z","time spent":"400.401423ms","remote":"127.0.0.1:58580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T19:49:46.352734Z","caller":"traceutil/trace.go:171","msg":"trace[1335140122] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"282.977308ms","start":"2026-02-18T19:49:46.069739Z","end":"2026-02-18T19:49:46.352716Z","steps":["trace[1335140122] 'process raft request' (duration: 282.882146ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:49:47.548553Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15490021458082047474,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T19:49:47.63384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.14624314s","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-18T19:49:47.633896Z","caller":"traceutil/trace.go:171","msg":"trace[1470866776] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:712; }","duration":"1.146352161s","start":"2026-02-18T19:49:46.487531Z","end":"2026-02-18T19:49:47.633883Z","steps":["trace[1470866776] 'range keys from in-memory index tree' (duration: 1.146120157s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:49:47.63394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:49:46.487515Z","time spent":"1.146409743s","remote":"127.0.0.1:58580","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-18T19:49:47.6342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.111646957s","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-18T19:49:47.634229Z","caller":"traceutil/trace.go:171","msg":"trace[107309725] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:712; }","duration":"1.111691597s","start":"2026-02-18T19:49:46.52253Z","end":"2026-02-18T19:49:47.634221Z","steps":["trace[107309725] 'range keys from in-memory index tree' (duration: 1.111577355s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:49:47.634248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:49:46.522513Z","time spent":"1.111730688s","remote":"127.0.0.1:58580","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-18T19:49:47.634418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"992.540395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T19:49:47.634456Z","caller":"traceutil/trace.go:171","msg":"trace[615907179] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:712; }","duration":"992.590576ms","start":"2026-02-18T19:49:46.641847Z","end":"2026-02-18T19:49:47.634437Z","steps":["trace[615907179] 'count revisions from in-memory index tree' (duration: 992.501874ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:49:47.634478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:49:46.641831Z","time spent":"992.642167ms","remote":"127.0.0.1:58472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":30,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-02-18T19:49:47.634794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"821.220923ms","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-18T19:49:47.634849Z","caller":"traceutil/trace.go:171","msg":"trace[1027760686] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:712; }","duration":"821.276494ms","start":"2026-02-18T19:49:46.813565Z","end":"2026-02-18T19:49:47.634841Z","steps":["trace[1027760686] 'range keys from in-memory index tree' (duration: 821.214853ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:49:47.997619Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.129913649s","expected-duration":"1s"} {"level":"info","ts":"2026-02-18T19:49:47.997924Z","caller":"traceutil/trace.go:171","msg":"trace[1916735779] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"1.130308367s","start":"2026-02-18T19:49:46.867601Z","end":"2026-02-18T19:49:47.997909Z","steps":["trace[1916735779] 'process raft request' (duration: 1.130221895s)"],"step_count":1} {"level":"info","ts":"2026-02-18T19:49:47.997999Z","caller":"traceutil/trace.go:171","msg":"trace[217510397] linearizableReadLoop","detail":"{readStateIndex:739; appliedIndex:739; }","duration":"950.004076ms","start":"2026-02-18T19:49:47.047915Z","end":"2026-02-18T19:49:47.997919Z","steps":["trace[217510397] 'read index received' (duration: 949.992246ms)","trace[217510397] 'applied index is now lower than readState.Index' (duration: 10.23µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T19:49:47.998018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:49:46.86758Z","time spent":"1.130377999s","remote":"127.0.0.1:58472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4769,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T19:49:47.998188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"950.257661ms","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-18T19:49:47.998212Z","caller":"traceutil/trace.go:171","msg":"trace[362921795] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:713; }","duration":"950.322113ms","start":"2026-02-18T19:49:47.047883Z","end":"2026-02-18T19:49:47.998205Z","steps":["trace[362921795] 'agreement among raft nodes before linearized reading' (duration: 950.18858ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:49:47.998239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:49:47.047869Z","time spent":"950.364173ms","remote":"127.0.0.1:58580","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-18T19:49:48.01539Z","caller":"traceutil/trace.go:171","msg":"trace[1441561959] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"375.265878ms","start":"2026-02-18T19:49:47.64011Z","end":"2026-02-18T19:49:48.015376Z","steps":["trace[1441561959] 'process raft request' (duration: 375.082474ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:49:48.015449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"873.717081ms","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-18T19:49:48.015502Z","caller":"traceutil/trace.go:171","msg":"trace[906261838] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:715; }","duration":"873.803883ms","start":"2026-02-18T19:49:47.141688Z","end":"2026-02-18T19:49:48.015492Z","steps":["trace[906261838] 'agreement among raft nodes before linearized reading' (duration: 873.65833ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:49:48.015531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:49:47.141673Z","time spent":"873.852154ms","remote":"127.0.0.1:58580","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-18T19:49:48.015655Z","caller":"traceutil/trace.go:171","msg":"trace[1497878932] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"373.530631ms","start":"2026-02-18T19:49:47.642118Z","end":"2026-02-18T19:49:48.015648Z","steps":["trace[1497878932] 'process raft request' (duration: 373.198924ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:49:48.015501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:49:47.640094Z","time spent":"375.331659ms","remote":"127.0.0.1:58580","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-18T19:49:48.015709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:49:47.642099Z","time spent":"373.571742ms","remote":"127.0.0.1:58580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T19:49:50.524631Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15490021458082047498,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T19:49:51.025606Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15490021458082047498,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T19:49:51.526467Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15490021458082047498,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T19:49:51.74729Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.321502874s","expected-duration":"1s"} {"level":"info","ts":"2026-02-18T19:49:51.74776Z","caller":"traceutil/trace.go:171","msg":"trace[665348585] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"1.724072055s","start":"2026-02-18T19:49:50.023661Z","end":"2026-02-18T19:49:51.747733Z","steps":["trace[665348585] 'process raft request' (duration: 1.723854071s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:49:51.747905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:49:50.023649Z","time spent":"1.724174497s","remote":"127.0.0.1:58580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T19:49:51.838433Z","caller":"traceutil/trace.go:171","msg":"trace[1325996823] linearizableReadLoop","detail":"{readStateIndex:748; appliedIndex:747; }","duration":"1.813834382s","start":"2026-02-18T19:49:50.024573Z","end":"2026-02-18T19:49:51.838408Z","steps":["trace[1325996823] 'read index received' (duration: 1.72381148s)","trace[1325996823] 'applied index is now lower than readState.Index' (duration: 90.022052ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T19:49:51.838809Z","caller":"traceutil/trace.go:171","msg":"trace[590099698] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"1.812930613s","start":"2026-02-18T19:49:50.025864Z","end":"2026-02-18T19:49:51.838795Z","steps":["trace[590099698] 'process raft request' (duration: 1.764042675s)","trace[590099698] 'compare' (duration: 48.380848ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T19:49:51.838947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:49:50.025853Z","time spent":"1.812989874s","remote":"127.0.0.1:58580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T19:49:51.839139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.814567587s","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-18T19:49:51.839169Z","caller":"traceutil/trace.go:171","msg":"trace[1639266240] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:721; }","duration":"1.814627639s","start":"2026-02-18T19:49:50.024532Z","end":"2026-02-18T19:49:51.839159Z","steps":["trace[1639266240] 'agreement among raft nodes before linearized reading' (duration: 1.814517147s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:49:51.839198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:49:50.024525Z","time spent":"1.81466648s","remote":"127.0.0.1:58580","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-18T19:49:51.839318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.812159337s","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-18T19:49:51.839342Z","caller":"traceutil/trace.go:171","msg":"trace[270814380] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:721; }","duration":"1.812194477s","start":"2026-02-18T19:49:50.02714Z","end":"2026-02-18T19:49:51.839335Z","steps":["trace[270814380] 'agreement among raft nodes before linearized reading' (duration: 1.812121916s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:49:51.839367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:49:50.027134Z","time spent":"1.812228259s","remote":"127.0.0.1:58580","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-18T19:49:51.839592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.476630495s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-18T19:49:51.839625Z","caller":"traceutil/trace.go:171","msg":"trace[637464125] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:721; }","duration":"1.476703116s","start":"2026-02-18T19:49:50.362913Z","end":"2026-02-18T19:49:51.839617Z","steps":["trace[637464125] 'agreement among raft nodes before linearized reading' (duration: 1.476617715s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:49:51.839652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:49:50.362892Z","time spent":"1.476753487s","remote":"127.0.0.1:58580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-18T19:49:51.839789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.683785909s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T19:49:51.839817Z","caller":"traceutil/trace.go:171","msg":"trace[1035369724] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:721; }","duration":"1.68382678s","start":"2026-02-18T19:49:50.155981Z","end":"2026-02-18T19:49:51.839808Z","steps":["trace[1035369724] 'agreement among raft nodes before linearized reading' (duration: 1.683777479s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:49:51.839845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:49:50.155978Z","time spent":"1.683859581s","remote":"127.0.0.1:54654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-02-18T19:49:51.839989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.684002144s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T19:49:51.840018Z","caller":"traceutil/trace.go:171","msg":"trace[1403353345] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:721; }","duration":"1.684043554s","start":"2026-02-18T19:49:50.155966Z","end":"2026-02-18T19:49:51.84001Z","steps":["trace[1403353345] 'agreement among raft nodes before linearized reading' (duration: 1.683997113s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:49:51.840047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:49:50.155963Z","time spent":"1.684076665s","remote":"127.0.0.1:54654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 "} {"level":"warn","ts":"2026-02-18T19:49:51.840156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.684198137s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T19:49:51.840187Z","caller":"traceutil/trace.go:171","msg":"trace[1684729423] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:721; }","duration":"1.684240198s","start":"2026-02-18T19:49:50.155939Z","end":"2026-02-18T19:49:51.840179Z","steps":["trace[1684729423] 'agreement among raft nodes before linearized reading' (duration: 1.684197557s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:49:51.840214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:49:50.155932Z","time spent":"1.684274429s","remote":"127.0.0.1:54654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 "} {"level":"warn","ts":"2026-02-18T19:49:51.840334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.695774551s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T19:49:51.840362Z","caller":"traceutil/trace.go:171","msg":"trace[422770613] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:721; }","duration":"1.695811462s","start":"2026-02-18T19:49:50.144543Z","end":"2026-02-18T19:49:51.840354Z","steps":["trace[422770613] 'agreement among raft nodes before linearized reading' (duration: 1.695765161s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:49:51.840414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:49:50.14454Z","time spent":"1.695866044s","remote":"127.0.0.1:54640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-18T19:49:51.840515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.695975685s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T19:49:51.840582Z","caller":"traceutil/trace.go:171","msg":"trace[790313702] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:721; }","duration":"1.696057447s","start":"2026-02-18T19:49:50.144507Z","end":"2026-02-18T19:49:51.840564Z","steps":["trace[790313702] 'agreement among raft nodes before linearized reading' (duration: 1.695931324s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:49:51.840623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:49:50.144503Z","time spent":"1.696109348s","remote":"127.0.0.1:54640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-02-18T19:49:51.840833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.696417585s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T19:49:51.840862Z","caller":"traceutil/trace.go:171","msg":"trace[156254150] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:721; }","duration":"1.696459496s","start":"2026-02-18T19:49:50.144393Z","end":"2026-02-18T19:49:51.840853Z","steps":["trace[156254150] 'agreement among raft nodes before linearized reading' (duration: 1.696412225s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:49:51.840889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:49:50.144387Z","time spent":"1.696494736s","remote":"127.0.0.1:54640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 "} {"level":"warn","ts":"2026-02-18T19:49:51.841005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.710256306s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T19:49:51.84103Z","caller":"traceutil/trace.go:171","msg":"trace[512847415] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:721; }","duration":"1.710288326s","start":"2026-02-18T19:49:50.130734Z","end":"2026-02-18T19:49:51.841023Z","steps":["trace[512847415] 'agreement among raft nodes before linearized reading' (duration: 1.710250185s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:49:51.841056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:49:50.130732Z","time spent":"1.710317277s","remote":"127.0.0.1:54634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 "} {"level":"warn","ts":"2026-02-18T19:49:51.841168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.710434429s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T19:49:51.841205Z","caller":"traceutil/trace.go:171","msg":"trace[795101728] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:721; }","duration":"1.710483451s","start":"2026-02-18T19:49:50.130712Z","end":"2026-02-18T19:49:51.841195Z","steps":["trace[795101728] 'agreement among raft nodes before linearized reading' (duration: 1.71042502s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:49:51.841237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:49:50.130709Z","time spent":"1.710519522s","remote":"127.0.0.1:54634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-02-18T19:49:51.841376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.710678374s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T19:49:51.841403Z","caller":"traceutil/trace.go:171","msg":"trace[279085839] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:721; }","duration":"1.710725355s","start":"2026-02-18T19:49:50.130669Z","end":"2026-02-18T19:49:51.841395Z","steps":["trace[279085839] 'agreement among raft nodes before linearized reading' (duration: 1.710682834s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:49:51.841431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:49:50.130656Z","time spent":"1.710766977s","remote":"127.0.0.1:54634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 "} {"level":"warn","ts":"2026-02-18T19:49:51.841583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.807664282s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T19:49:51.841616Z","caller":"traceutil/trace.go:171","msg":"trace[276368357] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:721; }","duration":"1.807712413s","start":"2026-02-18T19:49:50.033894Z","end":"2026-02-18T19:49:51.841607Z","steps":["trace[276368357] 'agreement among raft nodes before linearized reading' (duration: 1.807661712s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:49:51.841642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:49:50.033887Z","time spent":"1.807749614s","remote":"127.0.0.1:58252","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-18T19:49:52.018509Z","caller":"traceutil/trace.go:171","msg":"trace[1757397222] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"166.105941ms","start":"2026-02-18T19:49:51.852386Z","end":"2026-02-18T19:49:52.018492Z","steps":["trace[1757397222] 'process raft request' (duration: 97.794246ms)","trace[1757397222] 'compare' (duration: 68.185982ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T19:49:52.018745Z","caller":"traceutil/trace.go:171","msg":"trace[2064671667] linearizableReadLoop","detail":"{readStateIndex:751; appliedIndex:749; }","duration":"161.390932ms","start":"2026-02-18T19:49:51.857328Z","end":"2026-02-18T19:49:52.018719Z","steps":["trace[2064671667] 'read index received' (duration: 40.389209ms)","trace[2064671667] 'applied index is now lower than readState.Index' (duration: 121.000573ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T19:49:52.018862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.516054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T19:49:52.018891Z","caller":"traceutil/trace.go:171","msg":"trace[493621926] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"162.72271ms","start":"2026-02-18T19:49:51.85616Z","end":"2026-02-18T19:49:52.018882Z","steps":["trace[493621926] 'process raft request' (duration: 162.303651ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T19:49:52.018896Z","caller":"traceutil/trace.go:171","msg":"trace[1129581888] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:724; }","duration":"161.578245ms","start":"2026-02-18T19:49:51.857304Z","end":"2026-02-18T19:49:52.018883Z","steps":["trace[1129581888] 'agreement among raft nodes before linearized reading' (duration: 161.500583ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T19:49:58.360226Z","caller":"traceutil/trace.go:171","msg":"trace[1845097291] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"116.012189ms","start":"2026-02-18T19:49:58.244196Z","end":"2026-02-18T19:49:58.360208Z","steps":["trace[1845097291] 'process raft request' (duration: 115.896936ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T19:50:03.808518Z","caller":"traceutil/trace.go:171","msg":"trace[86163002] linearizableReadLoop","detail":"{readStateIndex:782; appliedIndex:781; }","duration":"237.784258ms","start":"2026-02-18T19:50:03.570717Z","end":"2026-02-18T19:50:03.808502Z","steps":["trace[86163002] 'read index received' (duration: 237.678866ms)","trace[86163002] 'applied index is now lower than readState.Index' (duration: 104.592µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T19:50:03.80863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.89517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T19:50:03.808674Z","caller":"traceutil/trace.go:171","msg":"trace[818118081] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:752; }","duration":"237.971381ms","start":"2026-02-18T19:50:03.570696Z","end":"2026-02-18T19:50:03.808668Z","steps":["trace[818118081] 'agreement among raft nodes before linearized reading' (duration: 237.865779ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:50:04.35552Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15490021458082047594,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T19:50:04.538879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.665155ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T19:50:04.538994Z","caller":"traceutil/trace.go:171","msg":"trace[1203073289] linearizableReadLoop","detail":"{readStateIndex:783; appliedIndex:782; }","duration":"684.124349ms","start":"2026-02-18T19:50:03.854853Z","end":"2026-02-18T19:50:04.538977Z","steps":["trace[1203073289] 'read index received' (duration: 562.424981ms)","trace[1203073289] 'applied index is now lower than readState.Index' (duration: 121.696968ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T19:50:04.539055Z","caller":"traceutil/trace.go:171","msg":"trace[721235011] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"729.456892ms","start":"2026-02-18T19:50:03.809574Z","end":"2026-02-18T19:50:04.539031Z","steps":["trace[721235011] 'process raft request' (duration: 607.747264ms)","trace[721235011] 'compare' (duration: 120.440101ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T19:50:04.539167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:50:03.809562Z","time spent":"729.550354ms","remote":"127.0.0.1:58304","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-18T19:50:04.539228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.376084ms","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-18T19:50:04.539258Z","caller":"traceutil/trace.go:171","msg":"trace[1076379467] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:753; }","duration":"684.434166ms","start":"2026-02-18T19:50:03.854814Z","end":"2026-02-18T19:50:04.539248Z","steps":["trace[1076379467] 'agreement among raft nodes before linearized reading' (duration: 684.253592ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:50:04.539294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:50:03.854797Z","time spent":"684.489096ms","remote":"127.0.0.1:58580","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-18T19:50:04.53952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.84267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-18T19:50:04.539556Z","caller":"traceutil/trace.go:171","msg":"trace[2068893067] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:753; }","duration":"543.924872ms","start":"2026-02-18T19:50:03.995621Z","end":"2026-02-18T19:50:04.539545Z","steps":["trace[2068893067] 'agreement among raft nodes before linearized reading' (duration: 543.802899ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:50:04.539586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:50:03.995601Z","time spent":"543.979092ms","remote":"127.0.0.1:58580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-18T19:50:04.5397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.420721ms","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-18T19:50:04.539727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.212447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T19:50:04.539739Z","caller":"traceutil/trace.go:171","msg":"trace[361442872] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:753; }","duration":"463.474861ms","start":"2026-02-18T19:50:04.076249Z","end":"2026-02-18T19:50:04.539724Z","steps":["trace[361442872] 'agreement among raft nodes before linearized reading' (duration: 463.351929ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T19:50:04.539754Z","caller":"traceutil/trace.go:171","msg":"trace[1213549928] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:753; }","duration":"504.264419ms","start":"2026-02-18T19:50:04.035482Z","end":"2026-02-18T19:50:04.539746Z","steps":["trace[1213549928] 'agreement among raft nodes before linearized reading' (duration: 504.220198ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:50:04.539768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:50:04.076234Z","time spent":"463.528042ms","remote":"127.0.0.1:58580","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-18T19:50:04.53978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:50:04.035442Z","time spent":"504.3332ms","remote":"127.0.0.1:58252","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-18T19:50:04.539934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.431018ms","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-18T19:50:04.53996Z","caller":"traceutil/trace.go:171","msg":"trace[329421011] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:753; }","duration":"539.496259ms","start":"2026-02-18T19:50:04.000456Z","end":"2026-02-18T19:50:04.539952Z","steps":["trace[329421011] 'agreement among raft nodes before linearized reading' (duration: 539.422448ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:50:04.539977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.696877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-02-18T19:50:04.539988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:50:04.000435Z","time spent":"539.54541ms","remote":"127.0.0.1:58580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-18T19:50:04.540006Z","caller":"traceutil/trace.go:171","msg":"trace[1821689694] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:753; }","duration":"463.751697ms","start":"2026-02-18T19:50:04.076246Z","end":"2026-02-18T19:50:04.539997Z","steps":["trace[1821689694] 'agreement among raft nodes before linearized reading' (duration: 463.668635ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:50:04.540033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:50:04.076231Z","time spent":"463.796629ms","remote":"127.0.0.1:58580","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-18T19:50:04.947765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.346653ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T19:50:04.947856Z","caller":"traceutil/trace.go:171","msg":"trace[164984329] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"397.020303ms","start":"2026-02-18T19:50:04.550818Z","end":"2026-02-18T19:50:04.947839Z","steps":["trace[164984329] 'process raft request' (duration: 194.517248ms)","trace[164984329] 'compare' (duration: 202.25817ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T19:50:04.947912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:50:04.550806Z","time spent":"397.078285ms","remote":"127.0.0.1:58580","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-18T19:50:05.759075Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.013810388s","expected-duration":"1s"} {"level":"info","ts":"2026-02-18T19:50:05.759393Z","caller":"traceutil/trace.go:171","msg":"trace[1374179935] linearizableReadLoop","detail":"{readStateIndex:787; appliedIndex:783; }","duration":"1.207136551s","start":"2026-02-18T19:50:04.55224Z","end":"2026-02-18T19:50:05.759377Z","steps":["trace[1374179935] 'read index received' (duration: 193.102639ms)","trace[1374179935] 'applied index is now lower than readState.Index' (duration: 1.014033212s)"],"step_count":2} {"level":"warn","ts":"2026-02-18T19:50:05.759498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.207242523s","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-18T19:50:05.759518Z","caller":"traceutil/trace.go:171","msg":"trace[2146222324] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:758; }","duration":"1.207285784s","start":"2026-02-18T19:50:04.552225Z","end":"2026-02-18T19:50:05.759511Z","steps":["trace[2146222324] 'agreement among raft nodes before linearized reading' (duration: 1.207193882s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:50:05.759542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:50:04.552219Z","time spent":"1.207316005s","remote":"127.0.0.1:58582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"info","ts":"2026-02-18T19:50:05.759842Z","caller":"traceutil/trace.go:171","msg":"trace[367895707] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"1.208622322s","start":"2026-02-18T19:50:04.551212Z","end":"2026-02-18T19:50:05.759835Z","steps":["trace[367895707] 'process raft request' (duration: 1.208016599s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:50:05.759899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:50:04.551204Z","time spent":"1.208657592s","remote":"127.0.0.1:58580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T19:50:05.759986Z","caller":"traceutil/trace.go:171","msg":"trace[1021363454] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"1.208696264s","start":"2026-02-18T19:50:04.551284Z","end":"2026-02-18T19:50:05.75998Z","steps":["trace[1021363454] 'process raft request' (duration: 1.20802909s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:50:05.760016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:50:04.551278Z","time spent":"1.208721904s","remote":"127.0.0.1:58580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T19:50:05.760089Z","caller":"traceutil/trace.go:171","msg":"trace[1673649655] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"1.208767175s","start":"2026-02-18T19:50:04.551317Z","end":"2026-02-18T19:50:05.760084Z","steps":["trace[1673649655] 'process raft request' (duration: 1.208019879s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:50:05.760135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:50:04.551313Z","time spent":"1.208791175s","remote":"127.0.0.1:58580","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-18T19:50:05.76021Z","caller":"traceutil/trace.go:171","msg":"trace[2045688081] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"1.205884204s","start":"2026-02-18T19:50:04.554321Z","end":"2026-02-18T19:50:05.760205Z","steps":["trace[2045688081] 'process raft request' (duration: 1.205033797s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:50:05.760244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:50:04.554312Z","time spent":"1.205915045s","remote":"127.0.0.1:58580","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-18T19:50:07.168078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.214225ms","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-18T19:50:07.168154Z","caller":"traceutil/trace.go:171","msg":"trace[2074281742] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:758; }","duration":"354.300477ms","start":"2026-02-18T19:50:06.813843Z","end":"2026-02-18T19:50:07.168144Z","steps":["trace[2074281742] 'range keys from in-memory index tree' (duration: 354.203535ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:50:07.168757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"868.120027ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T19:50:07.168792Z","caller":"traceutil/trace.go:171","msg":"trace[70866430] linearizableReadLoop","detail":"{readStateIndex:789; appliedIndex:788; }","duration":"1.135179109s","start":"2026-02-18T19:50:06.033606Z","end":"2026-02-18T19:50:07.168785Z","steps":["trace[70866430] 'read index received' (duration: 266.93288ms)","trace[70866430] 'applied index is now lower than readState.Index' (duration: 868.245569ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T19:50:07.168843Z","caller":"traceutil/trace.go:171","msg":"trace[1685341639] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"1.642492251s","start":"2026-02-18T19:50:05.526342Z","end":"2026-02-18T19:50:07.168834Z","steps":["trace[1685341639] 'process raft request' (duration: 774.221592ms)","trace[1685341639] 'compare' (duration: 867.86402ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T19:50:07.168872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:50:05.526324Z","time spent":"1.642531432s","remote":"127.0.0.1:58580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T19:50:07.168949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"839.814491ms","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-18T19:50:07.169011Z","caller":"traceutil/trace.go:171","msg":"trace[310764734] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:759; }","duration":"839.915842ms","start":"2026-02-18T19:50:06.329078Z","end":"2026-02-18T19:50:07.168994Z","steps":["trace[310764734] 'agreement among raft nodes before linearized reading' (duration: 839.79777ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:50:07.169053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.135451124s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-18T19:50:07.169051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:50:06.329063Z","time spent":"839.979233ms","remote":"127.0.0.1:58620","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-18T19:50:07.169069Z","caller":"traceutil/trace.go:171","msg":"trace[1329667132] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:759; }","duration":"1.135490676s","start":"2026-02-18T19:50:06.033573Z","end":"2026-02-18T19:50:07.169064Z","steps":["trace[1329667132] 'agreement among raft nodes before linearized reading' (duration: 1.135460295s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:50:07.169084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:50:06.033556Z","time spent":"1.135525406s","remote":"127.0.0.1:58252","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-18T19:50:07.169253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.181088ms","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-18T19:50:07.169267Z","caller":"traceutil/trace.go:171","msg":"trace[899127999] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:759; }","duration":"212.22227ms","start":"2026-02-18T19:50:06.95704Z","end":"2026-02-18T19:50:07.169263Z","steps":["trace[899127999] 'agreement among raft nodes before linearized reading' (duration: 212.155698ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:50:07.946556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.740134ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T19:50:07.94667Z","caller":"traceutil/trace.go:171","msg":"trace[1020871988] linearizableReadLoop","detail":"{readStateIndex:790; appliedIndex:789; }","duration":"767.45438ms","start":"2026-02-18T19:50:07.1792Z","end":"2026-02-18T19:50:07.946655Z","steps":["trace[1020871988] 'read index received' (duration: 217.483451ms)","trace[1020871988] 'applied index is now lower than readState.Index' (duration: 549.969789ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T19:50:07.946843Z","caller":"traceutil/trace.go:171","msg":"trace[351912286] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"772.577298ms","start":"2026-02-18T19:50:07.174254Z","end":"2026-02-18T19:50:07.946831Z","steps":["trace[351912286] 'process raft request' (duration: 222.494737ms)","trace[351912286] 'compare' (duration: 548.792704ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T19:50:07.946895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:50:07.174245Z","time spent":"772.622238ms","remote":"127.0.0.1:58580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T19:50:07.947038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"767.834958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T19:50:07.947062Z","caller":"traceutil/trace.go:171","msg":"trace[185761982] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:760; }","duration":"767.886739ms","start":"2026-02-18T19:50:07.179167Z","end":"2026-02-18T19:50:07.947054Z","steps":["trace[185761982] 'agreement among raft nodes before linearized reading' (duration: 767.847439ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:50:07.947086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:50:07.17915Z","time spent":"767.93093ms","remote":"127.0.0.1:58252","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-18T19:50:07.947254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.825641ms","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-18T19:50:07.947277Z","caller":"traceutil/trace.go:171","msg":"trace[1864728547] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:760; }","duration":"181.874433ms","start":"2026-02-18T19:50:07.765395Z","end":"2026-02-18T19:50:07.94727Z","steps":["trace[1864728547] 'agreement among raft nodes before linearized reading' (duration: 181.786141ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:50:07.947496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.396622ms","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-18T19:50:07.947524Z","caller":"traceutil/trace.go:171","msg":"trace[237796973] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:760; }","duration":"181.433904ms","start":"2026-02-18T19:50:07.766082Z","end":"2026-02-18T19:50:07.947516Z","steps":["trace[237796973] 'agreement among raft nodes before linearized reading' (duration: 181.357932ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:50:07.947657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.119818ms","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-18T19:50:07.947679Z","caller":"traceutil/trace.go:171","msg":"trace[1564697880] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:760; }","duration":"182.150318ms","start":"2026-02-18T19:50:07.765522Z","end":"2026-02-18T19:50:07.947672Z","steps":["trace[1564697880] 'agreement among raft nodes before linearized reading' (duration: 182.086047ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:50:07.947824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.376623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-18T19:50:07.947848Z","caller":"traceutil/trace.go:171","msg":"trace[183422226] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:760; }","duration":"182.405874ms","start":"2026-02-18T19:50:07.765434Z","end":"2026-02-18T19:50:07.94784Z","steps":["trace[183422226] 'agreement among raft nodes before linearized reading' (duration: 182.328472ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:50:08.535666Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15490021458082047613,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T19:50:09.035897Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15490021458082047613,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T19:50:09.35729Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.401656649s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-18T19:50:11.001373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.643599324s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T19:50:11.001445Z","caller":"traceutil/trace.go:171","msg":"trace[1255628191] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"3.045878448s","start":"2026-02-18T19:50:07.955554Z","end":"2026-02-18T19:50:11.001432Z","steps":["trace[1255628191] 'process raft request' (duration: 1.402150321s)","trace[1255628191] 'compare' (duration: 1.643072543s)"],"step_count":2} {"level":"warn","ts":"2026-02-18T19:50:11.00149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:50:07.955531Z","time spent":"3.045934538s","remote":"127.0.0.1:58580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T19:50:11.025285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.001323713s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-18T19:50:11.025418Z","caller":"traceutil/trace.go:171","msg":"trace[970570380] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.001477807s","start":"2026-02-18T19:50:09.023926Z","end":"2026-02-18T19:50:11.025404Z","steps":["trace[970570380] 'agreement among raft nodes before linearized reading' (duration: 2.001325164s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:50:11.025586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:50:09.023915Z","time spent":"2.001658271s","remote":"127.0.0.1:58252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T19:50:11.034306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000493616s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-18T19:50:11.034501Z","caller":"traceutil/trace.go:171","msg":"trace[722334897] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000612929s","start":"2026-02-18T19:50:09.033765Z","end":"2026-02-18T19:50:11.034378Z","steps":["trace[722334897] 'agreement among raft nodes before linearized reading' (duration: 2.000504377s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:50:11.034693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:50:09.033753Z","time spent":"2.000921906s","remote":"127.0.0.1:58258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T19:50:11.091592Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.571239404s","expected-duration":"1s"} {"level":"info","ts":"2026-02-18T19:50:11.092525Z","caller":"traceutil/trace.go:171","msg":"trace[684586667] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"3.136290729s","start":"2026-02-18T19:50:07.956183Z","end":"2026-02-18T19:50:11.092473Z","steps":["trace[684586667] 'process raft request' (duration: 3.135681916s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:50:11.092744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:50:07.956171Z","time spent":"3.136468252s","remote":"127.0.0.1:58580","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-18T19:50:11.093408Z","caller":"traceutil/trace.go:171","msg":"trace[1286114385] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"3.136257917s","start":"2026-02-18T19:50:07.956764Z","end":"2026-02-18T19:50:11.093022Z","steps":["trace[1286114385] 'process raft request' (duration: 3.135137854s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:50:11.09362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:50:07.956746Z","time spent":"3.136726466s","remote":"127.0.0.1:58580","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-18T19:50:11.093815Z","caller":"traceutil/trace.go:171","msg":"trace[579561023] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"2.720591261s","start":"2026-02-18T19:50:08.373211Z","end":"2026-02-18T19:50:11.093802Z","steps":["trace[579561023] 'process raft request' (duration: 2.718723432s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:50:11.093882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:50:08.373194Z","time spent":"2.720654462s","remote":"127.0.0.1:58580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T19:50:11.094647Z","caller":"traceutil/trace.go:171","msg":"trace[751495380] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"3.138870963s","start":"2026-02-18T19:50:07.955713Z","end":"2026-02-18T19:50:11.094584Z","steps":["trace[751495380] 'process raft request' (duration: 3.136044884s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:50:11.100514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:50:07.955704Z","time spent":"3.144709796s","remote":"127.0.0.1:58580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T19:50:11.461746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.758232ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-18T19:50:11.461904Z","caller":"traceutil/trace.go:171","msg":"trace[1071475695] linearizableReadLoop","detail":"{readStateIndex:796; appliedIndex:790; }","duration":"3.426335944s","start":"2026-02-18T19:50:08.035552Z","end":"2026-02-18T19:50:11.461888Z","steps":["trace[1071475695] 'read index received' (duration: 1.322161669s)","trace[1071475695] 'applied index is now lower than readState.Index' (duration: 2.104172705s)"],"step_count":2} {"level":"warn","ts":"2026-02-18T19:50:12.055802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.933033ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-02-18T19:50:12.056253Z","caller":"traceutil/trace.go:171","msg":"trace[1398746630] linearizableReadLoop","detail":"{readStateIndex:797; appliedIndex:796; }","duration":"594.302751ms","start":"2026-02-18T19:50:11.461932Z","end":"2026-02-18T19:50:12.056235Z","steps":["trace[1398746630] 'read index received' (duration: 176.723µs)","trace[1398746630] 'applied index is now lower than readState.Index' (duration: 594.123908ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T19:50:12.056446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.045218515s","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-18T19:50:12.056644Z","caller":"traceutil/trace.go:171","msg":"trace[282749667] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:765; }","duration":"3.045425609s","start":"2026-02-18T19:50:09.011206Z","end":"2026-02-18T19:50:12.056632Z","steps":["trace[282749667] 'agreement among raft nodes before linearized reading' (duration: 3.045127643s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:50:12.056801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:50:09.011152Z","time spent":"3.045631913s","remote":"127.0.0.1:58668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"warn","ts":"2026-02-18T19:50:12.05718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.021616905s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T19:50:12.057306Z","caller":"traceutil/trace.go:171","msg":"trace[209717215] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:765; }","duration":"4.021768958s","start":"2026-02-18T19:50:08.035524Z","end":"2026-02-18T19:50:12.057293Z","steps":["trace[209717215] 'agreement among raft nodes before linearized reading' (duration: 3.426540319s)","trace[209717215] 'count revisions from in-memory index tree' (duration: 595.065136ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T19:50:12.057508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:50:08.03551Z","time spent":"4.021982713s","remote":"127.0.0.1:58686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-02-18T19:50:12.05844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:50:11.028158Z","time spent":"1.030279634s","remote":"127.0.0.1:58368","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-18T19:50:12.058864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.018799814s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T19:50:12.058979Z","caller":"traceutil/trace.go:171","msg":"trace[221007378] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:765; }","duration":"1.018908406s","start":"2026-02-18T19:50:11.040035Z","end":"2026-02-18T19:50:12.058944Z","steps":["trace[221007378] 'agreement among raft nodes before linearized reading' (duration: 1.018798103s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:50:12.059116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:50:11.040024Z","time spent":"1.019081089s","remote":"127.0.0.1:58252","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-18T19:50:12.059466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.102459419s","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-18T19:50:12.059582Z","caller":"traceutil/trace.go:171","msg":"trace[1832267124] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:765; }","duration":"2.102612582s","start":"2026-02-18T19:50:09.956958Z","end":"2026-02-18T19:50:12.059571Z","steps":["trace[1832267124] 'agreement among raft nodes before linearized reading' (duration: 2.102413597s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:50:12.059713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:50:09.956939Z","time spent":"2.102761965s","remote":"127.0.0.1:58580","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-18T19:50:12.167407Z","caller":"traceutil/trace.go:171","msg":"trace[1641199098] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"104.16545ms","start":"2026-02-18T19:50:12.063223Z","end":"2026-02-18T19:50:12.167389Z","steps":["trace[1641199098] 'process raft request' (duration: 103.772491ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T19:50:26.574119Z","caller":"traceutil/trace.go:171","msg":"trace[2084452558] transaction","detail":"{read_only:false; response_revision:858; number_of_response:1; }","duration":"334.433089ms","start":"2026-02-18T19:50:26.239659Z","end":"2026-02-18T19:50:26.574092Z","steps":["trace[2084452558] 'process raft request' (duration: 334.340657ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:50:26.574217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:50:26.239649Z","time spent":"334.51794ms","remote":"127.0.0.1:58580","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-18T19:50:27.434757Z","caller":"traceutil/trace.go:171","msg":"trace[1921338118] linearizableReadLoop","detail":"{readStateIndex:895; appliedIndex:894; }","duration":"198.37544ms","start":"2026-02-18T19:50:27.236364Z","end":"2026-02-18T19:50:27.434739Z","steps":["trace[1921338118] 'read index received' (duration: 198.194786ms)","trace[1921338118] 'applied index is now lower than readState.Index' (duration: 180.004µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T19:50:27.435053Z","caller":"traceutil/trace.go:171","msg":"trace[1176409277] transaction","detail":"{read_only:false; response_revision:859; number_of_response:1; }","duration":"198.791808ms","start":"2026-02-18T19:50:27.236252Z","end":"2026-02-18T19:50:27.435044Z","steps":["trace[1176409277] 'process raft request' (duration: 198.36977ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:50:27.435278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.902311ms","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-18T19:50:27.435311Z","caller":"traceutil/trace.go:171","msg":"trace[1249460264] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:859; }","duration":"198.958402ms","start":"2026-02-18T19:50:27.236345Z","end":"2026-02-18T19:50:27.435303Z","steps":["trace[1249460264] 'agreement among raft nodes before linearized reading' (duration: 198.850549ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:50:27.435469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.649227ms","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-18T19:50:27.435494Z","caller":"traceutil/trace.go:171","msg":"trace[1550096473] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:859; }","duration":"180.698579ms","start":"2026-02-18T19:50:27.25479Z","end":"2026-02-18T19:50:27.435489Z","steps":["trace[1550096473] 'agreement among raft nodes before linearized reading' (duration: 180.615647ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:50:27.435651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.67537ms","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-18T19:50:27.435675Z","caller":"traceutil/trace.go:171","msg":"trace[191328085] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:859; }","duration":"143.72832ms","start":"2026-02-18T19:50:27.291942Z","end":"2026-02-18T19:50:27.43567Z","steps":["trace[191328085] 'agreement among raft nodes before linearized reading' (duration: 143.670969ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T19:50:27.647306Z","caller":"traceutil/trace.go:171","msg":"trace[1750420826] transaction","detail":"{read_only:false; response_revision:862; number_of_response:1; }","duration":"204.718263ms","start":"2026-02-18T19:50:27.442548Z","end":"2026-02-18T19:50:27.647266Z","steps":["trace[1750420826] 'process raft request' (duration: 204.698873ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T19:50:27.64793Z","caller":"traceutil/trace.go:171","msg":"trace[1099407072] transaction","detail":"{read_only:false; response_revision:860; number_of_response:1; }","duration":"207.082972ms","start":"2026-02-18T19:50:27.440839Z","end":"2026-02-18T19:50:27.647922Z","steps":["trace[1099407072] 'process raft request' (duration: 134.840634ms)","trace[1099407072] 'compare' (duration: 71.277138ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T19:50:27.648006Z","caller":"traceutil/trace.go:171","msg":"trace[1167569844] transaction","detail":"{read_only:false; response_revision:861; number_of_response:1; }","duration":"205.498129ms","start":"2026-02-18T19:50:27.442502Z","end":"2026-02-18T19:50:27.648Z","steps":["trace[1167569844] 'process raft request' (duration: 204.712542ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T19:50:29.912421Z","caller":"traceutil/trace.go:171","msg":"trace[1649276039] transaction","detail":"{read_only:false; response_revision:866; number_of_response:1; }","duration":"202.339663ms","start":"2026-02-18T19:50:29.709843Z","end":"2026-02-18T19:50:29.912182Z","steps":["trace[1649276039] 'process raft request' (duration: 202.158139ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T19:50:29.923595Z","caller":"traceutil/trace.go:171","msg":"trace[1776838484] transaction","detail":"{read_only:false; response_revision:868; number_of_response:1; }","duration":"213.62825ms","start":"2026-02-18T19:50:29.709945Z","end":"2026-02-18T19:50:29.923573Z","steps":["trace[1776838484] 'process raft request' (duration: 213.598259ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T19:50:29.923907Z","caller":"traceutil/trace.go:171","msg":"trace[1025143453] transaction","detail":"{read_only:false; response_revision:867; number_of_response:1; }","duration":"214.004978ms","start":"2026-02-18T19:50:29.709896Z","end":"2026-02-18T19:50:29.923901Z","steps":["trace[1025143453] 'process raft request' (duration: 213.530368ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T19:50:37.167327Z","caller":"traceutil/trace.go:171","msg":"trace[300025969] linearizableReadLoop","detail":"{readStateIndex:924; appliedIndex:923; }","duration":"342.515958ms","start":"2026-02-18T19:50:36.824779Z","end":"2026-02-18T19:50:37.167295Z","steps":["trace[300025969] 'read index received' (duration: 342.349785ms)","trace[300025969] 'applied index is now lower than readState.Index' (duration: 163.913µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T19:50:37.167443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.643251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T19:50:37.16748Z","caller":"traceutil/trace.go:171","msg":"trace[559822804] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:886; }","duration":"342.714843ms","start":"2026-02-18T19:50:36.824759Z","end":"2026-02-18T19:50:37.167473Z","steps":["trace[559822804] 'agreement among raft nodes before linearized reading' (duration: 342.614521ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:50:37.167506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:50:36.824747Z","time spent":"342.751284ms","remote":"127.0.0.1:59506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"info","ts":"2026-02-18T19:50:37.167865Z","caller":"traceutil/trace.go:171","msg":"trace[2108910285] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"385.910061ms","start":"2026-02-18T19:50:36.781945Z","end":"2026-02-18T19:50:37.167855Z","steps":["trace[2108910285] 'process raft request' (duration: 385.243307ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:50:37.167941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:50:36.781936Z","time spent":"385.965162ms","remote":"127.0.0.1:58580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T19:50:38.296991Z","caller":"traceutil/trace.go:171","msg":"trace[1991830503] linearizableReadLoop","detail":"{readStateIndex:926; appliedIndex:925; }","duration":"352.743714ms","start":"2026-02-18T19:50:37.944226Z","end":"2026-02-18T19:50:38.29697Z","steps":["trace[1991830503] 'read index received' (duration: 352.584421ms)","trace[1991830503] 'applied index is now lower than readState.Index' (duration: 158.483µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T19:50:38.297299Z","caller":"traceutil/trace.go:171","msg":"trace[1877235979] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"355.825679ms","start":"2026-02-18T19:50:37.941463Z","end":"2026-02-18T19:50:38.297288Z","steps":["trace[1877235979] 'process raft request' (duration: 355.40286ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:50:38.29746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:50:37.941454Z","time spent":"355.936691ms","remote":"127.0.0.1:58580","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-18T19:50:38.297732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.49891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-18T19:50:38.297771Z","caller":"traceutil/trace.go:171","msg":"trace[1592746099] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:888; }","duration":"353.556351ms","start":"2026-02-18T19:50:37.944204Z","end":"2026-02-18T19:50:38.297761Z","steps":["trace[1592746099] 'agreement among raft nodes before linearized reading' (duration: 353.420178ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:50:38.297805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:50:37.9442Z","time spent":"353.598871ms","remote":"127.0.0.1:58580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-18T19:50:38.297984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.893334ms","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-18T19:50:38.298021Z","caller":"traceutil/trace.go:171","msg":"trace[245904602] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:888; }","duration":"330.943076ms","start":"2026-02-18T19:50:37.967069Z","end":"2026-02-18T19:50:38.298013Z","steps":["trace[245904602] 'agreement among raft nodes before linearized reading' (duration: 330.856014ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:50:38.298054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:50:37.967061Z","time spent":"330.984797ms","remote":"127.0.0.1:58580","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-18T19:50:38.29823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.560271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T19:50:38.298264Z","caller":"traceutil/trace.go:171","msg":"trace[342468006] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:888; }","duration":"265.614243ms","start":"2026-02-18T19:50:38.032642Z","end":"2026-02-18T19:50:38.298256Z","steps":["trace[342468006] 'agreement among raft nodes before linearized reading' (duration: 265.567001ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T19:50:40.534102Z","caller":"traceutil/trace.go:171","msg":"trace[955238411] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"128.298957ms","start":"2026-02-18T19:50:40.405783Z","end":"2026-02-18T19:50:40.534082Z","steps":["trace[955238411] 'process raft request' (duration: 128.243085ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T19:50:40.534476Z","caller":"traceutil/trace.go:171","msg":"trace[587476594] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"130.39243ms","start":"2026-02-18T19:50:40.40407Z","end":"2026-02-18T19:50:40.534463Z","steps":["trace[587476594] 'process raft request' (duration: 73.785491ms)","trace[587476594] 'compare' (duration: 55.603898ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T19:50:41.340554Z","caller":"traceutil/trace.go:171","msg":"trace[703022379] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"122.377952ms","start":"2026-02-18T19:50:41.21816Z","end":"2026-02-18T19:50:41.340538Z","steps":["trace[703022379] 'process raft request' (duration: 122.29269ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:50:41.687547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.157595ms","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-18T19:50:41.687593Z","caller":"traceutil/trace.go:171","msg":"trace[1343146422] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:897; }","duration":"208.236897ms","start":"2026-02-18T19:50:41.479344Z","end":"2026-02-18T19:50:41.687581Z","steps":["trace[1343146422] 'count revisions from in-memory index tree' (duration: 208.099893ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:50:41.687754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.737873ms","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-18T19:50:41.687767Z","caller":"traceutil/trace.go:171","msg":"trace[817191608] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:897; }","duration":"205.760945ms","start":"2026-02-18T19:50:41.482002Z","end":"2026-02-18T19:50:41.687763Z","steps":["trace[817191608] 'range keys from in-memory index tree' (duration: 205.676062ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:50:41.687839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.063678ms","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-18T19:50:41.687853Z","caller":"traceutil/trace.go:171","msg":"trace[814076378] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:897; }","duration":"194.088269ms","start":"2026-02-18T19:50:41.49376Z","end":"2026-02-18T19:50:41.687848Z","steps":["trace[814076378] 'count revisions from in-memory index tree' (duration: 194.039028ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:50:42.535174Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15490021458082047957,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T19:50:42.767819Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.076945794s","expected-duration":"1s"} {"level":"info","ts":"2026-02-18T19:50:42.768079Z","caller":"traceutil/trace.go:171","msg":"trace[1620973874] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"1.077248481s","start":"2026-02-18T19:50:41.690814Z","end":"2026-02-18T19:50:42.768063Z","steps":["trace[1620973874] 'process raft request' (duration: 1.077155689s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:50:42.768163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:50:41.690803Z","time spent":"1.077303632s","remote":"127.0.0.1:58580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T19:50:43.123265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.046963ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T19:50:43.123356Z","caller":"traceutil/trace.go:171","msg":"trace[1340348706] linearizableReadLoop","detail":"{readStateIndex:938; appliedIndex:937; }","duration":"1.089210733s","start":"2026-02-18T19:50:42.03413Z","end":"2026-02-18T19:50:43.12334Z","steps":["trace[1340348706] 'read index received' (duration: 734.316774ms)","trace[1340348706] 'applied index is now lower than readState.Index' (duration: 354.892829ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T19:50:43.123583Z","caller":"traceutil/trace.go:171","msg":"trace[1852270351] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"1.41926273s","start":"2026-02-18T19:50:41.704308Z","end":"2026-02-18T19:50:43.12357Z","steps":["trace[1852270351] 'process raft request' (duration: 1.260799299s)","trace[1852270351] 'compare' (duration: 157.753007ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T19:50:43.123649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:50:41.704299Z","time spent":"1.419317951s","remote":"127.0.0.1:58580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T19:50:43.123821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.089687753s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T19:50:43.123848Z","caller":"traceutil/trace.go:171","msg":"trace[2096660060] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:899; }","duration":"1.089737974s","start":"2026-02-18T19:50:42.034101Z","end":"2026-02-18T19:50:43.123839Z","steps":["trace[2096660060] 'agreement among raft nodes before linearized reading' (duration: 1.089695133s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:50:43.123873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:50:42.034085Z","time spent":"1.089782684s","remote":"127.0.0.1:58252","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-18T19:50:43.12407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"779.934022ms","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-18T19:50:43.124103Z","caller":"traceutil/trace.go:171","msg":"trace[927528191] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:899; }","duration":"780.000693ms","start":"2026-02-18T19:50:42.344094Z","end":"2026-02-18T19:50:43.124095Z","steps":["trace[927528191] 'agreement among raft nodes before linearized reading' (duration: 779.902221ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:50:43.124128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:50:42.344078Z","time spent":"780.044115ms","remote":"127.0.0.1:58580","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-18T19:50:43.124472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.647046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T19:50:43.124509Z","caller":"traceutil/trace.go:171","msg":"trace[1521446696] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:899; }","duration":"336.688706ms","start":"2026-02-18T19:50:42.787811Z","end":"2026-02-18T19:50:43.124499Z","steps":["trace[1521446696] 'agreement among raft nodes before linearized reading' (duration: 336.636025ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:50:43.124538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:50:42.787773Z","time spent":"336.757068ms","remote":"127.0.0.1:58794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-18T19:50:43.124708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.133539ms","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-18T19:50:43.124739Z","caller":"traceutil/trace.go:171","msg":"trace[908067540] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:899; }","duration":"586.174709ms","start":"2026-02-18T19:50:42.538556Z","end":"2026-02-18T19:50:43.124731Z","steps":["trace[908067540] 'agreement among raft nodes before linearized reading' (duration: 586.096348ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:50:43.124764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:50:42.538552Z","time spent":"586.20633ms","remote":"127.0.0.1:58580","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-18T19:50:43.124916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.836354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-18T19:50:43.124956Z","caller":"traceutil/trace.go:171","msg":"trace[530864319] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:899; }","duration":"587.897466ms","start":"2026-02-18T19:50:42.53705Z","end":"2026-02-18T19:50:43.124947Z","steps":["trace[530864319] 'agreement among raft nodes before linearized reading' (duration: 587.807844ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:50:43.12499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:50:42.537037Z","time spent":"587.943517ms","remote":"127.0.0.1:58580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-18T19:50:43.509161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.367933ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T19:50:43.509256Z","caller":"traceutil/trace.go:171","msg":"trace[864571173] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"377.351742ms","start":"2026-02-18T19:50:43.131887Z","end":"2026-02-18T19:50:43.509238Z","steps":["trace[864571173] 'process raft request' (duration: 185.776004ms)","trace[864571173] 'compare' (duration: 191.021405ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T19:50:43.509311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:50:43.131872Z","time spent":"377.412522ms","remote":"127.0.0.1:58580","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-18T19:50:43.512833Z","caller":"traceutil/trace.go:171","msg":"trace[625227077] linearizableReadLoop","detail":"{readStateIndex:940; appliedIndex:938; }","duration":"380.215271ms","start":"2026-02-18T19:50:43.132592Z","end":"2026-02-18T19:50:43.512807Z","steps":["trace[625227077] 'read index received' (duration: 185.045269ms)","trace[625227077] 'applied index is now lower than readState.Index' (duration: 195.169442ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T19:50:43.512972Z","caller":"traceutil/trace.go:171","msg":"trace[1813786522] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"380.746872ms","start":"2026-02-18T19:50:43.132217Z","end":"2026-02-18T19:50:43.512964Z","steps":["trace[1813786522] 'process raft request' (duration: 380.523388ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:50:43.513022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:50:43.13221Z","time spent":"380.776823ms","remote":"127.0.0.1:58580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T19:50:43.513103Z","caller":"traceutil/trace.go:171","msg":"trace[428887771] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"380.15887ms","start":"2026-02-18T19:50:43.132938Z","end":"2026-02-18T19:50:43.513097Z","steps":["trace[428887771] 'process raft request' (duration: 379.846754ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:50:43.513135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:50:43.132931Z","time spent":"380.18447ms","remote":"127.0.0.1:58580","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-18T19:50:43.513214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.619599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T19:50:43.513231Z","caller":"traceutil/trace.go:171","msg":"trace[63456500] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:902; }","duration":"380.649571ms","start":"2026-02-18T19:50:43.132575Z","end":"2026-02-18T19:50:43.513225Z","steps":["trace[63456500] 'agreement among raft nodes before linearized reading' (duration: 380.62093ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:50:43.513247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:50:43.132568Z","time spent":"380.675341ms","remote":"127.0.0.1:58258","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-18T19:50:43.513375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.248016ms","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-18T19:50:43.513391Z","caller":"traceutil/trace.go:171","msg":"trace[981286417] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:902; }","duration":"168.287747ms","start":"2026-02-18T19:50:43.345098Z","end":"2026-02-18T19:50:43.513386Z","steps":["trace[981286417] 'agreement among raft nodes before linearized reading' (duration: 168.220006ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:50:43.885007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.248984ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-02-18T19:50:43.885085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:50:43.564001Z","time spent":"321.080979ms","remote":"127.0.0.1:58304","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-18T19:50:44.148284Z","caller":"traceutil/trace.go:171","msg":"trace[1324770234] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"262.000447ms","start":"2026-02-18T19:50:43.886242Z","end":"2026-02-18T19:50:44.148242Z","steps":["trace[1324770234] 'process raft request' (duration: 164.813804ms)","trace[1324770234] 'compare' (duration: 97.083941ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T19:50:44.483057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.093108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T19:50:44.483103Z","caller":"traceutil/trace.go:171","msg":"trace[235329245] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:904; }","duration":"105.170399ms","start":"2026-02-18T19:50:44.377922Z","end":"2026-02-18T19:50:44.483092Z","steps":["trace[235329245] 'count revisions from in-memory index tree' (duration: 105.035397ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T19:50:45.085848Z","caller":"traceutil/trace.go:171","msg":"trace[1426039172] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"308.82927ms","start":"2026-02-18T19:50:44.777Z","end":"2026-02-18T19:50:45.08583Z","steps":["trace[1426039172] 'process raft request' (duration: 308.592645ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:50:45.085969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:50:44.776985Z","time spent":"308.924633ms","remote":"127.0.0.1:58580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T19:50:47.206932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.401155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T19:50:47.207235Z","caller":"traceutil/trace.go:171","msg":"trace[1820023866] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:913; }","duration":"173.713781ms","start":"2026-02-18T19:50:47.033476Z","end":"2026-02-18T19:50:47.20719Z","steps":["trace[1820023866] 'range keys from in-memory index tree' (duration: 173.344454ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:50:47.20767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.206919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T19:50:47.20791Z","caller":"traceutil/trace.go:171","msg":"trace[1855481043] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:913; }","duration":"142.479584ms","start":"2026-02-18T19:50:47.065414Z","end":"2026-02-18T19:50:47.207894Z","steps":["trace[1855481043] 'range keys from in-memory index tree' (duration: 142.150228ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:50:47.2083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.287995ms","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-18T19:50:47.208524Z","caller":"traceutil/trace.go:171","msg":"trace[814755768] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:913; }","duration":"117.5034ms","start":"2026-02-18T19:50:47.09098Z","end":"2026-02-18T19:50:47.208484Z","steps":["trace[814755768] 'range keys from in-memory index tree' (duration: 117.150433ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T19:50:47.453078Z","caller":"traceutil/trace.go:171","msg":"trace[322017114] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"237.495581ms","start":"2026-02-18T19:50:47.215567Z","end":"2026-02-18T19:50:47.453062Z","steps":["trace[322017114] 'process raft request' (duration: 237.154024ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:50:47.631826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.90935ms","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-18T19:50:47.631879Z","caller":"traceutil/trace.go:171","msg":"trace[730687073] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:914; }","duration":"109.005381ms","start":"2026-02-18T19:50:47.52286Z","end":"2026-02-18T19:50:47.631865Z","steps":["trace[730687073] 'range keys from in-memory index tree' (duration: 108.784787ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:50:47.632158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.134939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-18T19:50:47.632139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.658542ms","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-18T19:50:47.632203Z","caller":"traceutil/trace.go:171","msg":"trace[2110575706] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:914; }","duration":"107.756195ms","start":"2026-02-18T19:50:47.524431Z","end":"2026-02-18T19:50:47.632188Z","steps":["trace[2110575706] 'range keys from in-memory index tree' (duration: 107.5715ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T19:50:47.632176Z","caller":"traceutil/trace.go:171","msg":"trace[1341714098] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:914; }","duration":"105.17337ms","start":"2026-02-18T19:50:47.526997Z","end":"2026-02-18T19:50:47.632171Z","steps":["trace[1341714098] 'range keys from in-memory index tree' (duration: 105.001677ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T19:51:01.173575Z","caller":"traceutil/trace.go:171","msg":"trace[1860473217] linearizableReadLoop","detail":"{readStateIndex:1055; appliedIndex:1054; }","duration":"136.811855ms","start":"2026-02-18T19:51:01.036747Z","end":"2026-02-18T19:51:01.173559Z","steps":["trace[1860473217] 'read index received' (duration: 136.683823ms)","trace[1860473217] 'applied index is now lower than readState.Index' (duration: 127.512µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T19:51:01.173843Z","caller":"traceutil/trace.go:171","msg":"trace[1124172014] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"249.809105ms","start":"2026-02-18T19:51:00.924025Z","end":"2026-02-18T19:51:01.173834Z","steps":["trace[1124172014] 'process raft request' (duration: 249.446428ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:51:01.173888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.141763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T19:51:01.174242Z","caller":"traceutil/trace.go:171","msg":"trace[1328802225] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1012; }","duration":"137.562342ms","start":"2026-02-18T19:51:01.036672Z","end":"2026-02-18T19:51:01.174235Z","steps":["trace[1328802225] 'agreement among raft nodes before linearized reading' (duration: 137.188523ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:51:01.354133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.052651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-02-18T19:51:01.354213Z","caller":"traceutil/trace.go:171","msg":"trace[1364876026] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1012; }","duration":"124.165263ms","start":"2026-02-18T19:51:01.230027Z","end":"2026-02-18T19:51:01.354192Z","steps":["trace[1364876026] 'count revisions from in-memory index tree' (duration: 123.999319ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:54:06.90676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.460298ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T19:54:06.906844Z","caller":"traceutil/trace.go:171","msg":"trace[266330712] linearizableReadLoop","detail":"{readStateIndex:1877; appliedIndex:1876; }","duration":"355.707826ms","start":"2026-02-18T19:54:06.551123Z","end":"2026-02-18T19:54:06.906831Z","steps":["trace[266330712] 'read index received' (duration: 32.644382ms)","trace[266330712] 'applied index is now lower than readState.Index' (duration: 323.062694ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T19:54:06.907045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.055571ms","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-18T19:54:06.907093Z","caller":"traceutil/trace.go:171","msg":"trace[1647872810] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1796; }","duration":"321.138133ms","start":"2026-02-18T19:54:06.585945Z","end":"2026-02-18T19:54:06.907083Z","steps":["trace[1647872810] 'agreement among raft nodes before linearized reading' (duration: 321.012201ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:54:06.907114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:54:06.585931Z","time spent":"321.178204ms","remote":"127.0.0.1:58580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-18T19:54:06.907169Z","caller":"traceutil/trace.go:171","msg":"trace[1681205950] transaction","detail":"{read_only:false; response_revision:1796; number_of_response:1; }","duration":"361.611741ms","start":"2026-02-18T19:54:06.545545Z","end":"2026-02-18T19:54:06.907157Z","steps":["trace[1681205950] 'process raft request' (duration: 193.683343ms)","trace[1681205950] 'compare' (duration: 167.381707ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T19:54:06.907211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:54:06.545536Z","time spent":"361.652442ms","remote":"127.0.0.1:58580","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-18T19:54:06.907288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.291577ms","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":"info","ts":"2026-02-18T19:54:06.907305Z","caller":"traceutil/trace.go:171","msg":"trace[2038104352] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1796; }","duration":"321.314537ms","start":"2026-02-18T19:54:06.585985Z","end":"2026-02-18T19:54:06.907299Z","steps":["trace[2038104352] 'agreement among raft nodes before linearized reading' (duration: 321.265906ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:54:06.90732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:54:06.585982Z","time spent":"321.333807ms","remote":"127.0.0.1:58580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-18T19:54:06.907354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.233317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:536"} {"level":"info","ts":"2026-02-18T19:54:06.907376Z","caller":"traceutil/trace.go:171","msg":"trace[1849564441] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1796; }","duration":"356.267587ms","start":"2026-02-18T19:54:06.551101Z","end":"2026-02-18T19:54:06.907369Z","steps":["trace[1849564441] 'agreement among raft nodes before linearized reading' (duration: 356.208346ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T19:54:06.907393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T19:54:06.551093Z","time spent":"356.296579ms","remote":"127.0.0.1:58580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":559,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-02-18T19:54:12.187342Z","caller":"traceutil/trace.go:171","msg":"trace[1343307230] transaction","detail":"{read_only:false; response_revision:1824; number_of_response:1; }","duration":"173.203697ms","start":"2026-02-18T19:54:12.014106Z","end":"2026-02-18T19:54:12.18731Z","steps":["trace[1343307230] 'process raft request' (duration: 138.863271ms)","trace[1343307230] 'compare' (duration: 34.115132ms)"],"step_count":2}