{"level":"warn","ts":"2026-02-07T04:04:42.609908Z","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-07T04:04:42.610039Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.52: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.52:2380","--initial-cluster=instance=https://162.253.55.52:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.52:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.52: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-07T04:04:42.610251Z","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-07T04:04:42.610267Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.52:2380"]} {"level":"info","ts":"2026-02-07T04:04:42.610287Z","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-07T04:04:42.611199Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.52:2379"]} {"level":"info","ts":"2026-02-07T04:04:42.611741Z","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.52:2380"],"listen-peer-urls":["https://162.253.55.52:2380"],"advertise-client-urls":["https://162.253.55.52:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.52:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.52: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-07T04:04:42.618274Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.103648ms"} {"level":"info","ts":"2026-02-07T04:04:42.625575Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b11e7fc5714be64b","cluster-id":"9349e4f32a52a90a"} {"level":"info","ts":"2026-02-07T04:04:42.625672Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b switched to configuration voters=()"} {"level":"info","ts":"2026-02-07T04:04:42.625695Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became follower at term 0"} {"level":"info","ts":"2026-02-07T04:04:42.625702Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b11e7fc5714be64b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-07T04:04:42.625715Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became follower at term 1"} {"level":"info","ts":"2026-02-07T04:04:42.625754Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b switched to configuration voters=(12762778880000648779)"} {"level":"warn","ts":"2026-02-07T04:04:42.634687Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-07T04:04:42.63968Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-07T04:04:42.653754Z","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-07T04:04:42.659583Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b11e7fc5714be64b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-07T04:04:42.66027Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b11e7fc5714be64b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-07T04:04:42.660722Z","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-07T04:04:42.660757Z","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-07T04:04:42.660866Z","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-07T04:04:42.661462Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b switched to configuration voters=(12762778880000648779)"} {"level":"info","ts":"2026-02-07T04:04:42.661619Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9349e4f32a52a90a","local-member-id":"b11e7fc5714be64b","added-peer-id":"b11e7fc5714be64b","added-peer-peer-urls":["https://162.253.55.52:2380"]} {"level":"info","ts":"2026-02-07T04:04:42.665282Z","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-07T04:04:42.665479Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b11e7fc5714be64b","initial-advertise-peer-urls":["https://162.253.55.52:2380"],"listen-peer-urls":["https://162.253.55.52:2380"],"advertise-client-urls":["https://162.253.55.52:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.52:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-07T04:04:42.665504Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-07T04:04:42.665654Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.52:2380"} {"level":"info","ts":"2026-02-07T04:04:42.665668Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.52:2380"} {"level":"info","ts":"2026-02-07T04:04:43.026403Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b is starting a new election at term 1"} {"level":"info","ts":"2026-02-07T04:04:43.026445Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became pre-candidate at term 1"} {"level":"info","ts":"2026-02-07T04:04:43.026592Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b received MsgPreVoteResp from b11e7fc5714be64b at term 1"} {"level":"info","ts":"2026-02-07T04:04:43.026611Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became candidate at term 2"} {"level":"info","ts":"2026-02-07T04:04:43.031103Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b received MsgVoteResp from b11e7fc5714be64b at term 2"} {"level":"info","ts":"2026-02-07T04:04:43.031122Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became leader at term 2"} {"level":"info","ts":"2026-02-07T04:04:43.031134Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b11e7fc5714be64b elected leader b11e7fc5714be64b at term 2"} {"level":"info","ts":"2026-02-07T04:04:43.032373Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b11e7fc5714be64b","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.52:2379]}","request-path":"/0/members/b11e7fc5714be64b/attributes","cluster-id":"9349e4f32a52a90a","publish-timeout":"7s"} {"level":"info","ts":"2026-02-07T04:04:43.032592Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-07T04:04:43.033059Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-07T04:04:43.033147Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-07T04:04:43.035102Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-07T04:04:43.035159Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-07T04:04:43.036587Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.52:2379"} {"level":"info","ts":"2026-02-07T04:04:43.042813Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9349e4f32a52a90a","local-member-id":"b11e7fc5714be64b","cluster-version":"3.5"} {"level":"info","ts":"2026-02-07T04:04:43.045011Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-07T04:04:43.045148Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-07T04:04:43.046175Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-07T04:04:45.516912Z","caller":"traceutil/trace.go:171","msg":"trace[856955345] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"141.86105ms","start":"2026-02-07T04:04:45.375031Z","end":"2026-02-07T04:04:45.516892Z","steps":["trace[856955345] 'process raft request' (duration: 127.816268ms)","trace[856955345] 'compare' (duration: 13.702722ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:04:45.517587Z","caller":"traceutil/trace.go:171","msg":"trace[290191177] transaction","detail":"{read_only:false; response_revision:21; number_of_response:1; }","duration":"140.402155ms","start":"2026-02-07T04:04:45.377173Z","end":"2026-02-07T04:04:45.517576Z","steps":["trace[290191177] 'process raft request' (duration: 139.494677ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:04:45.518148Z","caller":"traceutil/trace.go:171","msg":"trace[673155724] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"141.142287ms","start":"2026-02-07T04:04:45.376995Z","end":"2026-02-07T04:04:45.518137Z","steps":["trace[673155724] 'process raft request' (duration: 139.635081ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:04:45.518208Z","caller":"traceutil/trace.go:171","msg":"trace[430237323] transaction","detail":"{read_only:false; response_revision:26; number_of_response:1; }","duration":"136.872727ms","start":"2026-02-07T04:04:45.381302Z","end":"2026-02-07T04:04:45.518175Z","steps":["trace[430237323] 'process raft request' (duration: 135.494374ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:04:45.518245Z","caller":"traceutil/trace.go:171","msg":"trace[740833266] transaction","detail":"{read_only:false; response_revision:22; number_of_response:1; }","duration":"141.018323ms","start":"2026-02-07T04:04:45.377218Z","end":"2026-02-07T04:04:45.518236Z","steps":["trace[740833266] 'process raft request' (duration: 139.478046ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:04:45.519979Z","caller":"traceutil/trace.go:171","msg":"trace[2096926512] transaction","detail":"{read_only:false; response_revision:23; number_of_response:1; }","duration":"141.012403ms","start":"2026-02-07T04:04:45.37725Z","end":"2026-02-07T04:04:45.518262Z","steps":["trace[2096926512] 'process raft request' (duration: 139.478196ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:04:45.520187Z","caller":"traceutil/trace.go:171","msg":"trace[121296414] transaction","detail":"{read_only:false; response_revision:24; number_of_response:1; }","duration":"142.729497ms","start":"2026-02-07T04:04:45.377272Z","end":"2026-02-07T04:04:45.520002Z","steps":["trace[121296414] 'process raft request' (duration: 139.480357ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:04:45.51998Z","caller":"traceutil/trace.go:171","msg":"trace[1347467140] transaction","detail":"{read_only:false; response_revision:25; number_of_response:1; }","duration":"142.654684ms","start":"2026-02-07T04:04:45.377315Z","end":"2026-02-07T04:04:45.51997Z","steps":["trace[1347467140] 'process raft request' (duration: 139.457135ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:04:45.520713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.593915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-ltcpd\" ","response":"range_response_count:1 size:861"} {"level":"info","ts":"2026-02-07T04:04:45.520204Z","caller":"traceutil/trace.go:171","msg":"trace[1315327218] linearizableReadLoop","detail":"{readStateIndex:28; appliedIndex:21; }","duration":"142.713546ms","start":"2026-02-07T04:04:45.37748Z","end":"2026-02-07T04:04:45.520193Z","steps":["trace[1315327218] 'read index received' (duration: 125.30464ms)","trace[1315327218] 'applied index is now lower than readState.Index' (duration: 17.407856ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:04:45.521049Z","caller":"traceutil/trace.go:171","msg":"trace[670977579] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-ltcpd; range_end:; response_count:1; response_revision:26; }","duration":"145.667807ms","start":"2026-02-07T04:04:45.375084Z","end":"2026-02-07T04:04:45.520752Z","steps":["trace[670977579] 'agreement among raft nodes before linearized reading' (duration: 145.515742ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:04:45.521352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.234705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3178"} {"level":"info","ts":"2026-02-07T04:04:45.521387Z","caller":"traceutil/trace.go:171","msg":"trace[632858581] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:26; }","duration":"146.342528ms","start":"2026-02-07T04:04:45.375035Z","end":"2026-02-07T04:04:45.521378Z","steps":["trace[632858581] 'agreement among raft nodes before linearized reading' (duration: 146.269116ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:04:45.521536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.920561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:429"} {"level":"info","ts":"2026-02-07T04:04:45.521712Z","caller":"traceutil/trace.go:171","msg":"trace[1527400255] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26; }","duration":"141.091876ms","start":"2026-02-07T04:04:45.380605Z","end":"2026-02-07T04:04:45.521697Z","steps":["trace[1527400255] 'agreement among raft nodes before linearized reading' (duration: 140.89658ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:04:45.522258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.612202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-07T04:04:45.522295Z","caller":"traceutil/trace.go:171","msg":"trace[538773646] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26; }","duration":"141.664814ms","start":"2026-02-07T04:04:45.380621Z","end":"2026-02-07T04:04:45.522286Z","steps":["trace[538773646] 'agreement among raft nodes before linearized reading' (duration: 141.598282ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:04:48.943762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.361446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3704"} {"level":"info","ts":"2026-02-07T04:04:48.943818Z","caller":"traceutil/trace.go:171","msg":"trace[1976782591] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:232; }","duration":"105.444588ms","start":"2026-02-07T04:04:48.838364Z","end":"2026-02-07T04:04:48.943809Z","steps":["trace[1976782591] 'agreement among raft nodes before linearized reading' (duration: 105.368376ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:04:48.943935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.547992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-02-07T04:04:48.94397Z","caller":"traceutil/trace.go:171","msg":"trace[1979210122] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:232; }","duration":"105.596683ms","start":"2026-02-07T04:04:48.838367Z","end":"2026-02-07T04:04:48.943964Z","steps":["trace[1979210122] 'agreement among raft nodes before linearized reading' (duration: 105.549971ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:04:49.143957Z","caller":"traceutil/trace.go:171","msg":"trace[1673099018] linearizableReadLoop","detail":"{readStateIndex:251; appliedIndex:249; }","duration":"109.234445ms","start":"2026-02-07T04:04:49.034708Z","end":"2026-02-07T04:04:49.143942Z","steps":["trace[1673099018] 'read index received' (duration: 19.516461ms)","trace[1673099018] 'applied index is now lower than readState.Index' (duration: 89.717404ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:04:49.144204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.481323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:04:49.144232Z","caller":"traceutil/trace.go:171","msg":"trace[1211274372] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:245; }","duration":"109.537504ms","start":"2026-02-07T04:04:49.034687Z","end":"2026-02-07T04:04:49.144225Z","steps":["trace[1211274372] 'agreement among raft nodes before linearized reading' (duration: 109.318397ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:04:49.144485Z","caller":"traceutil/trace.go:171","msg":"trace[579447000] transaction","detail":"{read_only:false; response_revision:241; number_of_response:1; }","duration":"110.36659ms","start":"2026-02-07T04:04:49.03411Z","end":"2026-02-07T04:04:49.144476Z","steps":["trace[579447000] 'process raft request' (duration: 20.107789ms)","trace[579447000] 'compare' (duration: 89.540809ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:04:49.144714Z","caller":"traceutil/trace.go:171","msg":"trace[1793234201] transaction","detail":"{read_only:false; response_revision:242; number_of_response:1; }","duration":"110.076461ms","start":"2026-02-07T04:04:49.03463Z","end":"2026-02-07T04:04:49.144707Z","steps":["trace[1793234201] 'process raft request' (duration: 109.208234ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:04:49.14497Z","caller":"traceutil/trace.go:171","msg":"trace[55160203] transaction","detail":"{read_only:false; response_revision:243; number_of_response:1; }","duration":"106.819381ms","start":"2026-02-07T04:04:49.038144Z","end":"2026-02-07T04:04:49.144963Z","steps":["trace[55160203] 'process raft request' (duration: 105.715547ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:04:49.14531Z","caller":"traceutil/trace.go:171","msg":"trace[1259827773] transaction","detail":"{read_only:false; response_revision:244; number_of_response:1; }","duration":"106.402147ms","start":"2026-02-07T04:04:49.0389Z","end":"2026-02-07T04:04:49.145302Z","steps":["trace[1259827773] 'process raft request' (duration: 104.981284ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:04:49.145533Z","caller":"traceutil/trace.go:171","msg":"trace[1798899844] transaction","detail":"{read_only:false; response_revision:245; number_of_response:1; }","duration":"106.610444ms","start":"2026-02-07T04:04:49.038916Z","end":"2026-02-07T04:04:49.145526Z","steps":["trace[1798899844] 'process raft request' (duration: 104.997985ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:04:49.387223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.071464ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-07T04:04:49.38732Z","caller":"traceutil/trace.go:171","msg":"trace[1996586741] linearizableReadLoop","detail":"{readStateIndex:261; appliedIndex:260; }","duration":"173.088552ms","start":"2026-02-07T04:04:49.214217Z","end":"2026-02-07T04:04:49.387306Z","steps":["trace[1996586741] 'read index received' (duration: 45.555183ms)","trace[1996586741] 'applied index is now lower than readState.Index' (duration: 127.532199ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:04:49.387461Z","caller":"traceutil/trace.go:171","msg":"trace[193574206] transaction","detail":"{read_only:false; response_revision:252; number_of_response:1; }","duration":"174.64899ms","start":"2026-02-07T04:04:49.212727Z","end":"2026-02-07T04:04:49.387376Z","steps":["trace[193574206] 'process raft request' (duration: 47.099921ms)","trace[193574206] 'compare' (duration: 125.702463ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:04:49.387537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.314909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-07T04:04:49.38759Z","caller":"traceutil/trace.go:171","msg":"trace[413648629] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:252; }","duration":"173.380761ms","start":"2026-02-07T04:04:49.214201Z","end":"2026-02-07T04:04:49.387582Z","steps":["trace[413648629] 'agreement among raft nodes before linearized reading' (duration: 173.186245ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:04:50.21142Z","caller":"traceutil/trace.go:171","msg":"trace[1320107244] linearizableReadLoop","detail":"{readStateIndex:281; appliedIndex:280; }","duration":"198.637369ms","start":"2026-02-07T04:04:50.012766Z","end":"2026-02-07T04:04:50.211404Z","steps":["trace[1320107244] 'read index received' (duration: 188.757895ms)","trace[1320107244] 'applied index is now lower than readState.Index' (duration: 9.878634ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:04:50.211522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.740082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:2898"} {"level":"info","ts":"2026-02-07T04:04:50.211538Z","caller":"traceutil/trace.go:171","msg":"trace[1812700395] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:272; }","duration":"198.788134ms","start":"2026-02-07T04:04:50.012745Z","end":"2026-02-07T04:04:50.211533Z","steps":["trace[1812700395] 'agreement among raft nodes before linearized reading' (duration: 198.717052ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:04:50.211573Z","caller":"traceutil/trace.go:171","msg":"trace[791691750] transaction","detail":"{read_only:false; response_revision:272; number_of_response:1; }","duration":"199.052752ms","start":"2026-02-07T04:04:50.012501Z","end":"2026-02-07T04:04:50.211554Z","steps":["trace[791691750] 'process raft request' (duration: 189.083255ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:04:50.42844Z","caller":"traceutil/trace.go:171","msg":"trace[1238503600] transaction","detail":"{read_only:false; response_revision:274; number_of_response:1; }","duration":"121.114281ms","start":"2026-02-07T04:04:50.307317Z","end":"2026-02-07T04:04:50.428432Z","steps":["trace[1238503600] 'process raft request' (duration: 59.220504ms)","trace[1238503600] 'compare' (duration: 61.577167ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:04:50.428238Z","caller":"traceutil/trace.go:171","msg":"trace[109505868] linearizableReadLoop","detail":"{readStateIndex:283; appliedIndex:282; }","duration":"102.992502ms","start":"2026-02-07T04:04:50.325229Z","end":"2026-02-07T04:04:50.428221Z","steps":["trace[109505868] 'read index received' (duration: 41.255871ms)","trace[109505868] 'applied index is now lower than readState.Index' (duration: 61.736081ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:04:50.428636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.395745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:04:50.428669Z","caller":"traceutil/trace.go:171","msg":"trace[2134403559] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:0; response_revision:274; }","duration":"103.455697ms","start":"2026-02-07T04:04:50.325206Z","end":"2026-02-07T04:04:50.428662Z","steps":["trace[2134403559] 'agreement among raft nodes before linearized reading' (duration: 103.403555ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:04:50.723377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.955886ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T04:04:50.727898Z","caller":"traceutil/trace.go:171","msg":"trace[799614120] linearizableReadLoop","detail":"{readStateIndex:285; appliedIndex:284; }","duration":"271.184484ms","start":"2026-02-07T04:04:50.456683Z","end":"2026-02-07T04:04:50.727868Z","steps":["trace[799614120] 'read index received' (duration: 96.250485ms)","trace[799614120] 'applied index is now lower than readState.Index' (duration: 174.931649ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:04:50.728076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.36811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:04:50.728125Z","caller":"traceutil/trace.go:171","msg":"trace[2058055443] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:0; response_revision:276; }","duration":"271.475613ms","start":"2026-02-07T04:04:50.456643Z","end":"2026-02-07T04:04:50.728119Z","steps":["trace[2058055443] 'agreement among raft nodes before linearized reading' (duration: 271.351679ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:04:50.727941Z","caller":"traceutil/trace.go:171","msg":"trace[996483285] transaction","detail":"{read_only:false; response_revision:276; number_of_response:1; }","duration":"287.514817ms","start":"2026-02-07T04:04:50.44039Z","end":"2026-02-07T04:04:50.727905Z","steps":["trace[996483285] 'process raft request' (duration: 112.585448ms)","trace[996483285] 'compare' (duration: 169.791421ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:04:50.893101Z","caller":"traceutil/trace.go:171","msg":"trace[1036986310] transaction","detail":"{read_only:false; response_revision:278; number_of_response:1; }","duration":"154.572482ms","start":"2026-02-07T04:04:50.738519Z","end":"2026-02-07T04:04:50.893092Z","steps":["trace[1036986310] 'process raft request' (duration: 125.184207ms)","trace[1036986310] 'compare' (duration: 28.90121ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:04:50.892765Z","caller":"traceutil/trace.go:171","msg":"trace[1725070810] linearizableReadLoop","detail":"{readStateIndex:287; appliedIndex:286; }","duration":"129.17337ms","start":"2026-02-07T04:04:50.763573Z","end":"2026-02-07T04:04:50.892747Z","steps":["trace[1725070810] 'read index received' (duration: 100.113604ms)","trace[1725070810] 'applied index is now lower than readState.Index' (duration: 29.059176ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:04:50.893968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.413588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/pv-protection-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:04:50.89399Z","caller":"traceutil/trace.go:171","msg":"trace[2062632311] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/pv-protection-controller; range_end:; response_count:0; response_revision:278; }","duration":"130.49073ms","start":"2026-02-07T04:04:50.763492Z","end":"2026-02-07T04:04:50.893983Z","steps":["trace[2062632311] 'agreement among raft nodes before linearized reading' (duration: 130.447388ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:04:51.224982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.352172ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-07T04:04:51.22506Z","caller":"traceutil/trace.go:171","msg":"trace[1681411182] transaction","detail":"{read_only:false; response_revision:279; number_of_response:1; }","duration":"325.022804ms","start":"2026-02-07T04:04:50.900023Z","end":"2026-02-07T04:04:51.225046Z","steps":["trace[1681411182] 'process raft request' (duration: 211.558508ms)","trace[1681411182] 'compare' (duration: 113.241109ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:04:51.225105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:04:50.900013Z","time spent":"325.070225ms","remote":"127.0.0.1:60578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":199,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-07T04:04:51.229108Z","caller":"traceutil/trace.go:171","msg":"trace[1835663711] transaction","detail":"{read_only:false; response_revision:280; number_of_response:1; }","duration":"321.67897ms","start":"2026-02-07T04:04:50.907416Z","end":"2026-02-07T04:04:51.229095Z","steps":["trace[1835663711] 'process raft request' (duration: 321.401382ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:04:51.229189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:04:50.907407Z","time spent":"321.737182ms","remote":"127.0.0.1:60554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5245,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:04:51.418745Z","caller":"traceutil/trace.go:171","msg":"trace[558932566] transaction","detail":"{read_only:false; response_revision:283; number_of_response:1; }","duration":"139.24893ms","start":"2026-02-07T04:04:51.279473Z","end":"2026-02-07T04:04:51.418722Z","steps":["trace[558932566] 'process raft request' (duration: 65.734105ms)","trace[558932566] 'compare' (duration: 72.188884ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:04:53.84608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.974043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-02-07T04:04:53.846148Z","caller":"traceutil/trace.go:171","msg":"trace[591439387] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:306; }","duration":"240.077756ms","start":"2026-02-07T04:04:53.606054Z","end":"2026-02-07T04:04:53.846131Z","steps":["trace[591439387] 'range keys from in-memory index tree' (duration: 239.90061ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:04:53.846513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.956573ms","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-07T04:04:53.846552Z","caller":"traceutil/trace.go:171","msg":"trace[364771785] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:306; }","duration":"216.032225ms","start":"2026-02-07T04:04:53.63051Z","end":"2026-02-07T04:04:53.846542Z","steps":["trace[364771785] 'range keys from in-memory index tree' (duration: 215.8748ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:04:53.846981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.740953ms","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-07T04:04:53.847059Z","caller":"traceutil/trace.go:171","msg":"trace[577514201] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:306; }","duration":"211.835637ms","start":"2026-02-07T04:04:53.635211Z","end":"2026-02-07T04:04:53.847047Z","steps":["trace[577514201] 'range keys from in-memory index tree' (duration: 211.692632ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:04:53.847459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.554406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-02-07T04:04:53.8475Z","caller":"traceutil/trace.go:171","msg":"trace[1466486126] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:306; }","duration":"184.623138ms","start":"2026-02-07T04:04:53.662866Z","end":"2026-02-07T04:04:53.847489Z","steps":["trace[1466486126] 'range keys from in-memory index tree' (duration: 184.497713ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:04:53.848223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.427816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-02-07T04:04:53.84827Z","caller":"traceutil/trace.go:171","msg":"trace[581460873] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:306; }","duration":"141.497199ms","start":"2026-02-07T04:04:53.706762Z","end":"2026-02-07T04:04:53.848259Z","steps":["trace[581460873] 'range keys from in-memory index tree' (duration: 141.356254ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:04:59.603484Z","caller":"traceutil/trace.go:171","msg":"trace[1966595328] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"263.301291ms","start":"2026-02-07T04:04:59.34015Z","end":"2026-02-07T04:04:59.603451Z","steps":["trace[1966595328] 'process raft request' (duration: 263.103245ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:05:00.663536Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.060277162s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-07T04:05:00.814136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.400914ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T04:05:00.814353Z","caller":"traceutil/trace.go:171","msg":"trace[1094392492] linearizableReadLoop","detail":"{readStateIndex:387; appliedIndex:385; }","duration":"1.257416465s","start":"2026-02-07T04:04:59.556923Z","end":"2026-02-07T04:05:00.814339Z","steps":["trace[1094392492] 'read index received' (duration: 46.40794ms)","trace[1094392492] 'applied index is now lower than readState.Index' (duration: 1.211007175s)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:05:00.814572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.257646842s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-07T04:05:00.81466Z","caller":"traceutil/trace.go:171","msg":"trace[539509736] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:376; }","duration":"1.257779226s","start":"2026-02-07T04:04:59.556871Z","end":"2026-02-07T04:05:00.81465Z","steps":["trace[539509736] 'agreement among raft nodes before linearized reading' (duration: 1.257665822s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:05:00.814725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"913.666006ms","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-07T04:05:00.814755Z","caller":"traceutil/trace.go:171","msg":"trace[1631007467] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:376; }","duration":"913.728288ms","start":"2026-02-07T04:04:59.901019Z","end":"2026-02-07T04:05:00.814748Z","steps":["trace[1631007467] 'agreement among raft nodes before linearized reading' (duration: 913.690657ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:05:00.81478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:04:59.901001Z","time spent":"913.774609ms","remote":"127.0.0.1:60630","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-07T04:05:00.814736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:04:59.556843Z","time spent":"1.257883279s","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-07T04:05:00.814558Z","caller":"traceutil/trace.go:171","msg":"trace[1811168051] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"1.466907649s","start":"2026-02-07T04:04:59.34761Z","end":"2026-02-07T04:05:00.814518Z","steps":["trace[1811168051] 'process raft request' (duration: 1.316062081s)","trace[1811168051] 'compare' (duration: 145.674539ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:05:00.815166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:04:59.347599Z","time spent":"1.467539219s","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T04:05:00.814665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.020217819s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-07T04:05:00.814701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"912.678165ms","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-07T04:05:00.815594Z","caller":"traceutil/trace.go:171","msg":"trace[167428822] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:376; }","duration":"913.599113ms","start":"2026-02-07T04:04:59.901985Z","end":"2026-02-07T04:05:00.815584Z","steps":["trace[167428822] 'agreement among raft nodes before linearized reading' (duration: 912.695356ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:05:00.815674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:04:59.901968Z","time spent":"913.696286ms","remote":"127.0.0.1:60630","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-07T04:05:00.817036Z","caller":"traceutil/trace.go:171","msg":"trace[276410937] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:376; }","duration":"1.021081755s","start":"2026-02-07T04:04:59.794391Z","end":"2026-02-07T04:05:00.815473Z","steps":["trace[276410937] 'agreement among raft nodes before linearized reading' (duration: 1.020234049s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:05:00.817071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:04:59.794371Z","time spent":"1.022691834s","remote":"127.0.0.1:60362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-07T04:05:02.394237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.305886ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-07T04:05:02.394336Z","caller":"traceutil/trace.go:171","msg":"trace[1293304936] linearizableReadLoop","detail":"{readStateIndex:393; appliedIndex:392; }","duration":"179.860461ms","start":"2026-02-07T04:05:02.21446Z","end":"2026-02-07T04:05:02.39432Z","steps":["trace[1293304936] 'read index received' (duration: 22.455302ms)","trace[1293304936] 'applied index is now lower than readState.Index' (duration: 157.403899ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:05:02.394441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.973284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4018"} {"level":"info","ts":"2026-02-07T04:05:02.394475Z","caller":"traceutil/trace.go:171","msg":"trace[1799941588] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:381; }","duration":"180.036076ms","start":"2026-02-07T04:05:02.21443Z","end":"2026-02-07T04:05:02.394467Z","steps":["trace[1799941588] 'agreement among raft nodes before linearized reading' (duration: 179.932273ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:05:14.928669Z","caller":"traceutil/trace.go:171","msg":"trace[782434827] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"112.32792ms","start":"2026-02-07T04:05:14.816318Z","end":"2026-02-07T04:05:14.928646Z","steps":["trace[782434827] 'process raft request' (duration: 84.635147ms)","trace[782434827] 'compare' (duration: 27.385754ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:05:14.931516Z","caller":"traceutil/trace.go:171","msg":"trace[271506147] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"111.745782ms","start":"2026-02-07T04:05:14.819755Z","end":"2026-02-07T04:05:14.931501Z","steps":["trace[271506147] 'process raft request' (duration: 111.35614ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:05:14.932317Z","caller":"traceutil/trace.go:171","msg":"trace[403642306] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"110.665949ms","start":"2026-02-07T04:05:14.821639Z","end":"2026-02-07T04:05:14.932305Z","steps":["trace[403642306] 'process raft request' (duration: 109.556105ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:05:14.932544Z","caller":"traceutil/trace.go:171","msg":"trace[848044787] linearizableReadLoop","detail":"{readStateIndex:481; appliedIndex:478; }","duration":"110.077361ms","start":"2026-02-07T04:05:14.822439Z","end":"2026-02-07T04:05:14.932516Z","steps":["trace[848044787] 'read index received' (duration: 78.442576ms)","trace[848044787] 'applied index is now lower than readState.Index' (duration: 31.631735ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:05:14.932799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.334359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/cilium-operator-7fcd8d6ffd\" ","response":"range_response_count:1 size:4287"} {"level":"info","ts":"2026-02-07T04:05:14.935265Z","caller":"traceutil/trace.go:171","msg":"trace[826614421] range","detail":"{range_begin:/registry/replicasets/kube-system/cilium-operator-7fcd8d6ffd; range_end:; response_count:1; response_revision:467; }","duration":"112.822636ms","start":"2026-02-07T04:05:14.822421Z","end":"2026-02-07T04:05:14.935243Z","steps":["trace[826614421] 'agreement among raft nodes before linearized reading' (duration: 110.193585ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:05:17.903566Z","caller":"traceutil/trace.go:171","msg":"trace[1716644911] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"108.218904ms","start":"2026-02-07T04:05:17.79533Z","end":"2026-02-07T04:05:17.903549Z","steps":["trace[1716644911] 'process raft request' (duration: 108.103761ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:05:19.146901Z","caller":"traceutil/trace.go:171","msg":"trace[1113313279] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"100.482776ms","start":"2026-02-07T04:05:19.046403Z","end":"2026-02-07T04:05:19.146886Z","steps":["trace[1113313279] 'process raft request' (duration: 100.450525ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:05:19.147184Z","caller":"traceutil/trace.go:171","msg":"trace[1760084647] linearizableReadLoop","detail":"{readStateIndex:516; appliedIndex:515; }","duration":"151.121546ms","start":"2026-02-07T04:05:18.996052Z","end":"2026-02-07T04:05:19.147174Z","steps":["trace[1760084647] 'read index received' (duration: 131.13887ms)","trace[1760084647] 'applied index is now lower than readState.Index' (duration: 19.981336ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:05:19.147449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.365103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-07T04:05:19.147518Z","caller":"traceutil/trace.go:171","msg":"trace[1547956547] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:502; }","duration":"151.462286ms","start":"2026-02-07T04:05:18.996027Z","end":"2026-02-07T04:05:19.14749Z","steps":["trace[1547956547] 'agreement among raft nodes before linearized reading' (duration: 151.261729ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:05:19.147737Z","caller":"traceutil/trace.go:171","msg":"trace[1154423005] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"171.628557ms","start":"2026-02-07T04:05:18.976097Z","end":"2026-02-07T04:05:19.147726Z","steps":["trace[1154423005] 'process raft request' (duration: 151.088084ms)","trace[1154423005] 'compare' (duration: 19.551422ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:05:19.61891Z","caller":"traceutil/trace.go:171","msg":"trace[375058554] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"171.7263ms","start":"2026-02-07T04:05:19.447168Z","end":"2026-02-07T04:05:19.618895Z","steps":["trace[375058554] 'process raft request' (duration: 171.599576ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:05:20.303362Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594529008893166923,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-07T04:05:20.710258Z","caller":"traceutil/trace.go:171","msg":"trace[65424560] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"1.085922595s","start":"2026-02-07T04:05:19.624318Z","end":"2026-02-07T04:05:20.710241Z","steps":["trace[65424560] 'process raft request' (duration: 986.928235ms)","trace[65424560] 'compare' (duration: 98.846195ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:05:20.710344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:05:19.624304Z","time spent":"1.085996687s","remote":"127.0.0.1:60400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10353,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:05:20.730258Z","caller":"traceutil/trace.go:171","msg":"trace[888226276] linearizableReadLoop","detail":"{readStateIndex:533; appliedIndex:528; }","duration":"935.791189ms","start":"2026-02-07T04:05:19.794454Z","end":"2026-02-07T04:05:20.730245Z","steps":["trace[888226276] 'read index received' (duration: 816.778583ms)","trace[888226276] 'applied index is now lower than readState.Index' (duration: 119.012136ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:05:20.730274Z","caller":"traceutil/trace.go:171","msg":"trace[1930718529] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"1.083765228s","start":"2026-02-07T04:05:19.646471Z","end":"2026-02-07T04:05:20.730236Z","steps":["trace[1930718529] 'process raft request' (duration: 1.083640194s)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:05:20.730404Z","caller":"traceutil/trace.go:171","msg":"trace[697495626] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"949.942455ms","start":"2026-02-07T04:05:19.780455Z","end":"2026-02-07T04:05:20.730398Z","steps":["trace[697495626] 'process raft request' (duration: 949.716498ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:05:20.730429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:05:19.646453Z","time spent":"1.083911292s","remote":"127.0.0.1:60400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3353,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-07T04:05:20.730489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:05:19.780437Z","time spent":"950.002956ms","remote":"127.0.0.1:60546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4364,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:05:20.730584Z","caller":"traceutil/trace.go:171","msg":"trace[2088431440] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"880.644539ms","start":"2026-02-07T04:05:19.849933Z","end":"2026-02-07T04:05:20.730578Z","steps":["trace[2088431440] 'process raft request' (duration: 880.275198ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:05:20.730605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:05:19.84992Z","time spent":"880.674271ms","remote":"127.0.0.1:60400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-07T04:05:20.730665Z","caller":"traceutil/trace.go:171","msg":"trace[873184753] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"682.700512ms","start":"2026-02-07T04:05:20.04796Z","end":"2026-02-07T04:05:20.730661Z","steps":["trace[873184753] 'process raft request' (duration: 682.265858ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:05:20.730684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:05:20.047941Z","time spent":"682.733732ms","remote":"127.0.0.1:60400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8270,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-07T04:05:20.730749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"936.304704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:05:20.730765Z","caller":"traceutil/trace.go:171","msg":"trace[1981301044] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:518; }","duration":"936.360266ms","start":"2026-02-07T04:05:19.7944Z","end":"2026-02-07T04:05:20.73076Z","steps":["trace[1981301044] 'agreement among raft nodes before linearized reading' (duration: 936.328355ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:05:20.730777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:05:19.794379Z","time spent":"936.395907ms","remote":"127.0.0.1:60362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-07T04:05:20.735687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.596341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:05:20.735719Z","caller":"traceutil/trace.go:171","msg":"trace[1875731584] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:519; }","duration":"290.660973ms","start":"2026-02-07T04:05:20.445049Z","end":"2026-02-07T04:05:20.73571Z","steps":["trace[1875731584] 'agreement among raft nodes before linearized reading' (duration: 290.609532ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:05:20.735812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.257715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-02-07T04:05:20.735858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.156189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:05:20.735878Z","caller":"traceutil/trace.go:171","msg":"trace[211993829] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:519; }","duration":"488.20874ms","start":"2026-02-07T04:05:20.247663Z","end":"2026-02-07T04:05:20.735872Z","steps":["trace[211993829] 'agreement among raft nodes before linearized reading' (duration: 488.174729ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:05:20.735891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:05:20.247622Z","time spent":"488.266102ms","remote":"127.0.0.1:60400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"info","ts":"2026-02-07T04:05:20.735892Z","caller":"traceutil/trace.go:171","msg":"trace[2102365679] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:519; }","duration":"257.345267ms","start":"2026-02-07T04:05:20.47852Z","end":"2026-02-07T04:05:20.735866Z","steps":["trace[2102365679] 'agreement among raft nodes before linearized reading' (duration: 257.137371ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:05:20.736223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"829.528415ms","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-07T04:05:20.736242Z","caller":"traceutil/trace.go:171","msg":"trace[1628842135] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:519; }","duration":"829.580457ms","start":"2026-02-07T04:05:19.906656Z","end":"2026-02-07T04:05:20.736237Z","steps":["trace[1628842135] 'agreement among raft nodes before linearized reading' (duration: 829.484364ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:05:20.736256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:05:19.906637Z","time spent":"829.616448ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-07T04:05:21.338686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.735049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-02-07T04:05:21.338738Z","caller":"traceutil/trace.go:171","msg":"trace[430301251] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:527; }","duration":"292.821021ms","start":"2026-02-07T04:05:21.045907Z","end":"2026-02-07T04:05:21.338728Z","steps":["trace[430301251] 'range keys from in-memory index tree' (duration: 292.613085ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:05:21.338683Z","caller":"traceutil/trace.go:171","msg":"trace[1001666205] linearizableReadLoop","detail":"{readStateIndex:543; appliedIndex:542; }","duration":"150.555898ms","start":"2026-02-07T04:05:21.188103Z","end":"2026-02-07T04:05:21.338659Z","steps":["trace[1001666205] 'read index received' (duration: 51.61746ms)","trace[1001666205] 'applied index is now lower than readState.Index' (duration: 98.937408ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:05:21.338843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.740234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-07T04:05:21.338908Z","caller":"traceutil/trace.go:171","msg":"trace[1929627031] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:528; }","duration":"150.858158ms","start":"2026-02-07T04:05:21.188038Z","end":"2026-02-07T04:05:21.338896Z","steps":["trace[1929627031] 'agreement among raft nodes before linearized reading' (duration: 150.680812ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:05:21.338977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.64482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-07T04:05:21.339019Z","caller":"traceutil/trace.go:171","msg":"trace[1113180418] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:528; }","duration":"148.708322ms","start":"2026-02-07T04:05:21.190281Z","end":"2026-02-07T04:05:21.33899Z","steps":["trace[1113180418] 'agreement among raft nodes before linearized reading' (duration: 148.65127ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:05:21.339231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.525026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-07T04:05:21.339264Z","caller":"traceutil/trace.go:171","msg":"trace[1144832532] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:528; }","duration":"148.585478ms","start":"2026-02-07T04:05:21.190672Z","end":"2026-02-07T04:05:21.339258Z","steps":["trace[1144832532] 'agreement among raft nodes before linearized reading' (duration: 148.516286ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:05:21.33945Z","caller":"traceutil/trace.go:171","msg":"trace[490598157] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"225.775085ms","start":"2026-02-07T04:05:21.113666Z","end":"2026-02-07T04:05:21.339442Z","steps":["trace[490598157] 'process raft request' (duration: 126.054123ms)","trace[490598157] 'compare' (duration: 98.794134ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:05:22.388121Z","caller":"traceutil/trace.go:171","msg":"trace[1680595605] linearizableReadLoop","detail":"{readStateIndex:549; appliedIndex:548; }","duration":"118.15765ms","start":"2026-02-07T04:05:22.269928Z","end":"2026-02-07T04:05:22.388086Z","steps":["trace[1680595605] 'read index received' (duration: 52.789226ms)","trace[1680595605] 'applied index is now lower than readState.Index' (duration: 65.367444ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:05:22.388292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.346585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-02-07T04:05:22.388311Z","caller":"traceutil/trace.go:171","msg":"trace[54258517] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:533; }","duration":"118.403307ms","start":"2026-02-07T04:05:22.269902Z","end":"2026-02-07T04:05:22.388305Z","steps":["trace[54258517] 'agreement among raft nodes before linearized reading' (duration: 118.280654ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:05:22.645475Z","caller":"traceutil/trace.go:171","msg":"trace[1429232860] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"372.438953ms","start":"2026-02-07T04:05:22.273023Z","end":"2026-02-07T04:05:22.645462Z","steps":["trace[1429232860] 'process raft request' (duration: 372.256918ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:05:22.645549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:05:22.273013Z","time spent":"372.501005ms","remote":"127.0.0.1:60400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187455,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-07T04:05:23.007667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.232979ms","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-07T04:05:23.007802Z","caller":"traceutil/trace.go:171","msg":"trace[850105679] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:534; }","duration":"238.383404ms","start":"2026-02-07T04:05:22.769396Z","end":"2026-02-07T04:05:23.007779Z","steps":["trace[850105679] 'range keys from in-memory index tree' (duration: 238.205588ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:05:23.011624Z","caller":"traceutil/trace.go:171","msg":"trace[779778258] linearizableReadLoop","detail":"{readStateIndex:551; appliedIndex:549; }","duration":"565.970565ms","start":"2026-02-07T04:05:22.445623Z","end":"2026-02-07T04:05:23.011594Z","steps":["trace[779778258] 'read index received' (duration: 199.751514ms)","trace[779778258] 'applied index is now lower than readState.Index' (duration: 366.218491ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:05:23.014287Z","caller":"traceutil/trace.go:171","msg":"trace[1985967928] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"690.806401ms","start":"2026-02-07T04:05:22.323472Z","end":"2026-02-07T04:05:23.014279Z","steps":["trace[1985967928] 'process raft request' (duration: 682.0211ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:05:23.014353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:05:22.32344Z","time spent":"690.871803ms","remote":"127.0.0.1:60400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188237,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T04:05:23.01446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.835514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-02-07T04:05:23.01448Z","caller":"traceutil/trace.go:171","msg":"trace[1296216267] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:535; }","duration":"568.877485ms","start":"2026-02-07T04:05:22.445597Z","end":"2026-02-07T04:05:23.014475Z","steps":["trace[1296216267] 'agreement among raft nodes before linearized reading' (duration: 568.808963ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:05:23.014495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:05:22.445584Z","time spent":"568.907476ms","remote":"127.0.0.1:60400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":24268,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"warn","ts":"2026-02-07T04:05:23.014862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.696429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-02-07T04:05:23.014882Z","caller":"traceutil/trace.go:171","msg":"trace[1285337294] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:536; }","duration":"369.739ms","start":"2026-02-07T04:05:22.645138Z","end":"2026-02-07T04:05:23.014877Z","steps":["trace[1285337294] 'agreement among raft nodes before linearized reading' (duration: 369.685919ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:05:23.014896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:05:22.645124Z","time spent":"369.768481ms","remote":"127.0.0.1:60400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":9344,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"info","ts":"2026-02-07T04:05:23.01511Z","caller":"traceutil/trace.go:171","msg":"trace[879360299] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"273.160494ms","start":"2026-02-07T04:05:22.741944Z","end":"2026-02-07T04:05:23.015105Z","steps":["trace[879360299] 'process raft request' (duration: 272.68449ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:05:23.015214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.930575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188252"} {"level":"info","ts":"2026-02-07T04:05:23.015259Z","caller":"traceutil/trace.go:171","msg":"trace[828576817] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:536; }","duration":"170.003927ms","start":"2026-02-07T04:05:22.84525Z","end":"2026-02-07T04:05:23.015254Z","steps":["trace[828576817] 'agreement among raft nodes before linearized reading' (duration: 169.895514ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:05:23.015382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.599307ms","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-07T04:05:23.015395Z","caller":"traceutil/trace.go:171","msg":"trace[698990751] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:536; }","duration":"247.646549ms","start":"2026-02-07T04:05:22.767744Z","end":"2026-02-07T04:05:23.015391Z","steps":["trace[698990751] 'agreement among raft nodes before linearized reading' (duration: 247.607018ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:05:23.01555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.693211ms","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-07T04:05:23.015563Z","caller":"traceutil/trace.go:171","msg":"trace[511353800] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:536; }","duration":"248.726862ms","start":"2026-02-07T04:05:22.766833Z","end":"2026-02-07T04:05:23.015559Z","steps":["trace[511353800] 'agreement among raft nodes before linearized reading' (duration: 248.684551ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:05:24.444411Z","caller":"traceutil/trace.go:171","msg":"trace[2131735061] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"156.882742ms","start":"2026-02-07T04:05:24.287513Z","end":"2026-02-07T04:05:24.444396Z","steps":["trace[2131735061] 'process raft request' (duration: 156.773029ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:05:27.928376Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594529008893167048,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-07T04:05:28.414687Z","caller":"traceutil/trace.go:171","msg":"trace[1226863288] linearizableReadLoop","detail":"{readStateIndex:570; appliedIndex:569; }","duration":"987.174008ms","start":"2026-02-07T04:05:27.427471Z","end":"2026-02-07T04:05:28.414645Z","steps":["trace[1226863288] 'read index received' (duration: 986.843068ms)","trace[1226863288] 'applied index is now lower than readState.Index' (duration: 330.09µs)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:05:28.414817Z","caller":"traceutil/trace.go:171","msg":"trace[698321822] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"988.290472ms","start":"2026-02-07T04:05:27.426517Z","end":"2026-02-07T04:05:28.414807Z","steps":["trace[698321822] 'process raft request' (duration: 987.860039ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:05:28.414949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:05:27.42649Z","time spent":"988.369285ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T04:05:28.414974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"987.413295ms","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-07T04:05:28.415045Z","caller":"traceutil/trace.go:171","msg":"trace[505545905] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:553; }","duration":"987.505368ms","start":"2026-02-07T04:05:27.427525Z","end":"2026-02-07T04:05:28.41503Z","steps":["trace[505545905] 'agreement among raft nodes before linearized reading' (duration: 987.342823ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:05:28.415073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:05:27.427519Z","time spent":"987.548799ms","remote":"127.0.0.1:60630","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-07T04:05:28.415169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"987.697824ms","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-07T04:05:28.415184Z","caller":"traceutil/trace.go:171","msg":"trace[119579211] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:553; }","duration":"987.735275ms","start":"2026-02-07T04:05:27.427444Z","end":"2026-02-07T04:05:28.415179Z","steps":["trace[119579211] 'agreement among raft nodes before linearized reading' (duration: 987.690813ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:05:28.415199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:05:27.42741Z","time spent":"987.786396ms","remote":"127.0.0.1:60630","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-07T04:05:28.811956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.381245ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T04:05:28.812088Z","caller":"traceutil/trace.go:171","msg":"trace[396244640] linearizableReadLoop","detail":"{readStateIndex:572; appliedIndex:570; }","duration":"129.733896ms","start":"2026-02-07T04:05:28.682337Z","end":"2026-02-07T04:05:28.812071Z","steps":["trace[396244640] 'read index received' (duration: 117.238182ms)","trace[396244640] 'applied index is now lower than readState.Index' (duration: 12.494964ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:05:28.812514Z","caller":"traceutil/trace.go:171","msg":"trace[1438339030] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"392.807281ms","start":"2026-02-07T04:05:28.419691Z","end":"2026-02-07T04:05:28.812499Z","steps":["trace[1438339030] 'process raft request' (duration: 64.830237ms)","trace[1438339030] 'compare' (duration: 327.262552ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:05:28.812599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:05:28.419674Z","time spent":"392.885954ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:05:28.812755Z","caller":"traceutil/trace.go:171","msg":"trace[917264792] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"391.946624ms","start":"2026-02-07T04:05:28.420799Z","end":"2026-02-07T04:05:28.812746Z","steps":["trace[917264792] 'process raft request' (duration: 391.225252ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:05:28.812806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:05:28.420786Z","time spent":"391.991696ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T04:05:28.812931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.597033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:05:28.81296Z","caller":"traceutil/trace.go:171","msg":"trace[1609286648] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:555; }","duration":"130.657385ms","start":"2026-02-07T04:05:28.682292Z","end":"2026-02-07T04:05:28.81295Z","steps":["trace[1609286648] 'agreement among raft nodes before linearized reading' (duration: 130.612793ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:05:40.41525Z","caller":"traceutil/trace.go:171","msg":"trace[905109546] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"149.465684ms","start":"2026-02-07T04:05:40.265771Z","end":"2026-02-07T04:05:40.415236Z","steps":["trace[905109546] 'process raft request' (duration: 149.381112ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:05:42.826207Z","caller":"traceutil/trace.go:171","msg":"trace[2001066341] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"210.591599ms","start":"2026-02-07T04:05:42.615601Z","end":"2026-02-07T04:05:42.826193Z","steps":["trace[2001066341] 'process raft request' (duration: 210.508327ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:05:42.897636Z","caller":"traceutil/trace.go:171","msg":"trace[1582143601] linearizableReadLoop","detail":"{readStateIndex:672; appliedIndex:671; }","duration":"103.029337ms","start":"2026-02-07T04:05:42.794589Z","end":"2026-02-07T04:05:42.897619Z","steps":["trace[1582143601] 'read index received' (duration: 32.051668ms)","trace[1582143601] 'applied index is now lower than readState.Index' (duration: 70.977079ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:05:42.897752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.142061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:05:42.897772Z","caller":"traceutil/trace.go:171","msg":"trace[614066750] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:651; }","duration":"103.224003ms","start":"2026-02-07T04:05:42.79454Z","end":"2026-02-07T04:05:42.897764Z","steps":["trace[614066750] 'agreement among raft nodes before linearized reading' (duration: 103.1441ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:05:42.897975Z","caller":"traceutil/trace.go:171","msg":"trace[940825497] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"246.042682ms","start":"2026-02-07T04:05:42.651927Z","end":"2026-02-07T04:05:42.89797Z","steps":["trace[940825497] 'process raft request' (duration: 245.59936ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:05:43.146856Z","caller":"traceutil/trace.go:171","msg":"trace[1170665739] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"145.599528ms","start":"2026-02-07T04:05:43.001226Z","end":"2026-02-07T04:05:43.146825Z","steps":["trace[1170665739] 'process raft request' (duration: 145.421983ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:05:44.61128Z","caller":"traceutil/trace.go:171","msg":"trace[345070168] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"528.089313ms","start":"2026-02-07T04:05:44.083173Z","end":"2026-02-07T04:05:44.611262Z","steps":["trace[345070168] 'process raft request' (duration: 527.98541ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:05:44.611401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:05:44.08316Z","time spent":"528.174955ms","remote":"127.0.0.1:60554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5007,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:05:51.07288Z","caller":"traceutil/trace.go:171","msg":"trace[1123639980] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"436.461492ms","start":"2026-02-07T04:05:50.63629Z","end":"2026-02-07T04:05:51.072751Z","steps":["trace[1123639980] 'process raft request' (duration: 434.59112ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:05:51.072839Z","caller":"traceutil/trace.go:171","msg":"trace[1509678993] linearizableReadLoop","detail":"{readStateIndex:732; appliedIndex:732; }","duration":"277.900876ms","start":"2026-02-07T04:05:50.794917Z","end":"2026-02-07T04:05:51.072818Z","steps":["trace[1509678993] 'read index received' (duration: 277.893126ms)","trace[1509678993] 'applied index is now lower than readState.Index' (duration: 6.42µs)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:05:51.073159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.193904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:05:51.073538Z","caller":"traceutil/trace.go:171","msg":"trace[2097514483] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:710; }","duration":"278.627706ms","start":"2026-02-07T04:05:50.79488Z","end":"2026-02-07T04:05:51.073508Z","steps":["trace[2097514483] 'agreement among raft nodes before linearized reading' (duration: 278.117062ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:05:51.079823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:05:50.636278Z","time spent":"437.037788ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T04:05:51.509513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.211794ms","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-07T04:05:51.5097Z","caller":"traceutil/trace.go:171","msg":"trace[1904634592] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:711; }","duration":"331.43553ms","start":"2026-02-07T04:05:51.17824Z","end":"2026-02-07T04:05:51.509675Z","steps":["trace[1904634592] 'range keys from in-memory index tree' (duration: 330.996298ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:05:51.509892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:05:51.178224Z","time spent":"331.656726ms","remote":"127.0.0.1:60630","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-07T04:05:51.510231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.991841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-07T04:05:51.510286Z","caller":"traceutil/trace.go:171","msg":"trace[767501586] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:711; }","duration":"338.079954ms","start":"2026-02-07T04:05:51.172194Z","end":"2026-02-07T04:05:51.510274Z","steps":["trace[767501586] 'range keys from in-memory index tree' (duration: 337.823577ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:05:51.510312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.01083ms","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-07T04:05:51.510369Z","caller":"traceutil/trace.go:171","msg":"trace[1284658758] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:711; }","duration":"229.133703ms","start":"2026-02-07T04:05:51.281221Z","end":"2026-02-07T04:05:51.510355Z","steps":["trace[1284658758] 'range keys from in-memory index tree' (duration: 228.832845ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:05:51.510313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:05:51.172165Z","time spent":"338.142066ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-07T04:05:51.786599Z","caller":"traceutil/trace.go:171","msg":"trace[1279458591] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"271.975561ms","start":"2026-02-07T04:05:51.514603Z","end":"2026-02-07T04:05:51.786579Z","steps":["trace[1279458591] 'process raft request' (duration: 271.822037ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:05:52.180131Z","caller":"traceutil/trace.go:171","msg":"trace[1337756869] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"571.962549ms","start":"2026-02-07T04:05:51.608149Z","end":"2026-02-07T04:05:52.180112Z","steps":["trace[1337756869] 'process raft request' (duration: 571.930828ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:05:52.180244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:05:51.608133Z","time spent":"572.056392ms","remote":"127.0.0.1:60546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4725,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:05:52.180554Z","caller":"traceutil/trace.go:171","msg":"trace[1432075212] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"663.60075ms","start":"2026-02-07T04:05:51.516945Z","end":"2026-02-07T04:05:52.180545Z","steps":["trace[1432075212] 'process raft request' (duration: 663.015884ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:05:52.180663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:05:51.516923Z","time spent":"663.714003ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:05:52.180769Z","caller":"traceutil/trace.go:171","msg":"trace[1629639159] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"663.660562ms","start":"2026-02-07T04:05:51.517101Z","end":"2026-02-07T04:05:52.180762Z","steps":["trace[1629639159] 'process raft request' (duration: 662.946992ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:05:52.180808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:05:51.517091Z","time spent":"663.693592ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T04:05:52.454413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.858931ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T04:05:52.454489Z","caller":"traceutil/trace.go:171","msg":"trace[1002178320] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"410.843861ms","start":"2026-02-07T04:05:52.043634Z","end":"2026-02-07T04:05:52.454478Z","steps":["trace[1002178320] 'process raft request' (duration: 254.862957ms)","trace[1002178320] 'compare' (duration: 155.797729ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:05:52.454522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:05:52.04362Z","time spent":"410.887862ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T04:05:52.598731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.2517ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-02-07T04:05:52.713061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.244178ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:42"} {"level":"warn","ts":"2026-02-07T04:05:52.713139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:05:52.37318Z","time spent":"339.954897ms","remote":"127.0.0.1:60420","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-07T04:05:53.211218Z","caller":"traceutil/trace.go:171","msg":"trace[1436903793] linearizableReadLoop","detail":"{readStateIndex:742; appliedIndex:741; }","duration":"123.192886ms","start":"2026-02-07T04:05:53.08801Z","end":"2026-02-07T04:05:53.211202Z","steps":["trace[1436903793] 'read index received' (duration: 123.036752ms)","trace[1436903793] 'applied index is now lower than readState.Index' (duration: 155.534µs)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:05:53.211299Z","caller":"traceutil/trace.go:171","msg":"trace[1583777128] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"124.250956ms","start":"2026-02-07T04:05:53.087043Z","end":"2026-02-07T04:05:53.211294Z","steps":["trace[1583777128] 'process raft request' (duration: 124.015879ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:05:53.211569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.688107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:05:53.211613Z","caller":"traceutil/trace.go:171","msg":"trace[1312746758] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:718; }","duration":"120.754908ms","start":"2026-02-07T04:05:53.090848Z","end":"2026-02-07T04:05:53.211602Z","steps":["trace[1312746758] 'agreement among raft nodes before linearized reading' (duration: 120.683636ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:05:53.212531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.508602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-07T04:05:53.212554Z","caller":"traceutil/trace.go:171","msg":"trace[444604434] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:718; }","duration":"124.571844ms","start":"2026-02-07T04:05:53.087975Z","end":"2026-02-07T04:05:53.212547Z","steps":["trace[444604434] 'agreement among raft nodes before linearized reading' (duration: 123.405412ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:06:04.476808Z","caller":"traceutil/trace.go:171","msg":"trace[494761768] linearizableReadLoop","detail":"{readStateIndex:781; appliedIndex:780; }","duration":"207.432801ms","start":"2026-02-07T04:06:04.26936Z","end":"2026-02-07T04:06:04.476793Z","steps":["trace[494761768] 'read index received' (duration: 207.282357ms)","trace[494761768] 'applied index is now lower than readState.Index' (duration: 149.904µs)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:06:04.476951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.587606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-07T04:06:04.476971Z","caller":"traceutil/trace.go:171","msg":"trace[630774664] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:754; }","duration":"207.665418ms","start":"2026-02-07T04:06:04.269301Z","end":"2026-02-07T04:06:04.476966Z","steps":["trace[630774664] 'agreement among raft nodes before linearized reading' (duration: 207.556655ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:06:04.477128Z","caller":"traceutil/trace.go:171","msg":"trace[746716958] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"240.174ms","start":"2026-02-07T04:06:04.236949Z","end":"2026-02-07T04:06:04.477123Z","steps":["trace[746716958] 'process raft request' (duration: 239.743378ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:06:04.627972Z","caller":"traceutil/trace.go:171","msg":"trace[464365385] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"147.978563ms","start":"2026-02-07T04:06:04.479977Z","end":"2026-02-07T04:06:04.627955Z","steps":["trace[464365385] 'process raft request' (duration: 147.778977ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:06:05.440686Z","caller":"traceutil/trace.go:171","msg":"trace[1614923738] linearizableReadLoop","detail":"{readStateIndex:783; appliedIndex:782; }","duration":"185.147164ms","start":"2026-02-07T04:06:05.255518Z","end":"2026-02-07T04:06:05.440665Z","steps":["trace[1614923738] 'read index received' (duration: 184.935588ms)","trace[1614923738] 'applied index is now lower than readState.Index' (duration: 210.635µs)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:06:05.441099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.554805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-07T04:06:05.441148Z","caller":"traceutil/trace.go:171","msg":"trace[1348348737] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:756; }","duration":"185.635348ms","start":"2026-02-07T04:06:05.255499Z","end":"2026-02-07T04:06:05.441134Z","steps":["trace[1348348737] 'agreement among raft nodes before linearized reading' (duration: 185.250957ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:06:05.442185Z","caller":"traceutil/trace.go:171","msg":"trace[1605223362] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"193.062883ms","start":"2026-02-07T04:06:05.249108Z","end":"2026-02-07T04:06:05.442171Z","steps":["trace[1605223362] 'process raft request' (duration: 191.408867ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:06:06.611793Z","caller":"traceutil/trace.go:171","msg":"trace[1625634208] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"128.074112ms","start":"2026-02-07T04:06:06.483694Z","end":"2026-02-07T04:06:06.611768Z","steps":["trace[1625634208] 'process raft request' (duration: 127.922517ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:06:07.280413Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594529008893167484,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-07T04:06:08.73354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"987.833822ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T04:06:08.733752Z","caller":"traceutil/trace.go:171","msg":"trace[605437267] linearizableReadLoop","detail":"{readStateIndex:788; appliedIndex:786; }","duration":"1.953395084s","start":"2026-02-07T04:06:06.780338Z","end":"2026-02-07T04:06:08.733733Z","steps":["trace[605437267] 'read index received' (duration: 965.298096ms)","trace[605437267] 'applied index is now lower than readState.Index' (duration: 988.096078ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:06:08.734005Z","caller":"traceutil/trace.go:171","msg":"trace[1219738264] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"2.097342196s","start":"2026-02-07T04:06:06.636649Z","end":"2026-02-07T04:06:08.733992Z","steps":["trace[1219738264] 'process raft request' (duration: 1.10897592s)","trace[1219738264] 'compare' (duration: 987.151593ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:06:08.734071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:06:06.636633Z","time spent":"2.097402688s","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:06:08.734235Z","caller":"traceutil/trace.go:171","msg":"trace[219415555] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"1.660245396s","start":"2026-02-07T04:06:07.073979Z","end":"2026-02-07T04:06:08.734225Z","steps":["trace[219415555] 'process raft request' (duration: 1.65967448s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:06:08.734288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:06:07.073959Z","time spent":"1.660298347s","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T04:06:08.734486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.954158586s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.52\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-07T04:06:08.734516Z","caller":"traceutil/trace.go:171","msg":"trace[1888570440] range","detail":"{range_begin:/registry/masterleases/162.253.55.52; range_end:; response_count:1; response_revision:761; }","duration":"1.954220308s","start":"2026-02-07T04:06:06.780286Z","end":"2026-02-07T04:06:08.734506Z","steps":["trace[1888570440] 'agreement among raft nodes before linearized reading' (duration: 1.954108845s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:06:08.734574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:06:06.780266Z","time spent":"1.954300929s","remote":"127.0.0.1:60390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/162.253.55.52\" "} {"level":"warn","ts":"2026-02-07T04:06:08.734702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.953142927s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-07T04:06:08.734748Z","caller":"traceutil/trace.go:171","msg":"trace[41050038] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:761; }","duration":"1.95324307s","start":"2026-02-07T04:06:06.781493Z","end":"2026-02-07T04:06:08.734736Z","steps":["trace[41050038] 'agreement among raft nodes before linearized reading' (duration: 1.953153008s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:06:08.734777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:06:06.781466Z","time spent":"1.953304232s","remote":"127.0.0.1:60772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":31,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-02-07T04:06:08.735047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.703571ms","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-07T04:06:08.735067Z","caller":"traceutil/trace.go:171","msg":"trace[280500401] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:761; }","duration":"118.758313ms","start":"2026-02-07T04:06:08.616303Z","end":"2026-02-07T04:06:08.735061Z","steps":["trace[280500401] 'agreement among raft nodes before linearized reading' (duration: 118.669531ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:06:08.735169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"857.986581ms","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-07T04:06:08.735188Z","caller":"traceutil/trace.go:171","msg":"trace[1112535508] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:761; }","duration":"858.038002ms","start":"2026-02-07T04:06:07.877142Z","end":"2026-02-07T04:06:08.735181Z","steps":["trace[1112535508] 'agreement among raft nodes before linearized reading' (duration: 857.992161ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:06:08.735203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:06:07.877123Z","time spent":"858.076474ms","remote":"127.0.0.1:60630","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-07T04:06:08.740489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.275084217s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-07T04:06:08.740574Z","caller":"traceutil/trace.go:171","msg":"trace[23535731] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:761; }","duration":"1.27519284s","start":"2026-02-07T04:06:07.46536Z","end":"2026-02-07T04:06:08.740553Z","steps":["trace[23535731] 'agreement among raft nodes before linearized reading' (duration: 1.269901263s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:06:08.74068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:06:07.46534Z","time spent":"1.275330954s","remote":"127.0.0.1:60630","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-07T04:06:08.743104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.295742049s","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-07T04:06:08.743175Z","caller":"traceutil/trace.go:171","msg":"trace[1629611374] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:761; }","duration":"1.295854973s","start":"2026-02-07T04:06:07.447313Z","end":"2026-02-07T04:06:08.743168Z","steps":["trace[1629611374] 'agreement among raft nodes before linearized reading' (duration: 1.29575358s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:06:08.743208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:06:07.447293Z","time spent":"1.295904464s","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-07T04:06:08.743324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.9489163s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:06:08.743402Z","caller":"traceutil/trace.go:171","msg":"trace[402449044] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:761; }","duration":"1.948997972s","start":"2026-02-07T04:06:06.794391Z","end":"2026-02-07T04:06:08.743389Z","steps":["trace[402449044] 'agreement among raft nodes before linearized reading' (duration: 1.94891856s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:06:08.74344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:06:06.794384Z","time spent":"1.949045264s","remote":"127.0.0.1:60362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-07T04:06:09.004266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.351797ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-02-07T04:06:09.006338Z","caller":"traceutil/trace.go:171","msg":"trace[1045721306] linearizableReadLoop","detail":"{readStateIndex:791; appliedIndex:788; }","duration":"253.494079ms","start":"2026-02-07T04:06:08.752833Z","end":"2026-02-07T04:06:09.006327Z","steps":["trace[1045721306] 'read index received' (duration: 144.123396ms)","trace[1045721306] 'applied index is now lower than readState.Index' (duration: 109.370143ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:06:09.006412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.576201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:06:09.006427Z","caller":"traceutil/trace.go:171","msg":"trace[359449972] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:765; }","duration":"253.608453ms","start":"2026-02-07T04:06:08.752812Z","end":"2026-02-07T04:06:09.00642Z","steps":["trace[359449972] 'agreement among raft nodes before linearized reading' (duration: 253.549541ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:06:09.00653Z","caller":"traceutil/trace.go:171","msg":"trace[2067444138] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"252.187023ms","start":"2026-02-07T04:06:08.754337Z","end":"2026-02-07T04:06:09.006524Z","steps":["trace[2067444138] 'process raft request' (duration: 251.922555ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:06:09.006512Z","caller":"traceutil/trace.go:171","msg":"trace[1933804796] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"254.493777ms","start":"2026-02-07T04:06:08.751997Z","end":"2026-02-07T04:06:09.006491Z","steps":["trace[1933804796] 'process raft request' (duration: 253.363605ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:06:09.006651Z","caller":"traceutil/trace.go:171","msg":"trace[2135464454] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"251.301419ms","start":"2026-02-07T04:06:08.755343Z","end":"2026-02-07T04:06:09.006645Z","steps":["trace[2135464454] 'process raft request' (duration: 250.948149ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:06:09.006703Z","caller":"traceutil/trace.go:171","msg":"trace[1245466014] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"256.923374ms","start":"2026-02-07T04:06:08.749773Z","end":"2026-02-07T04:06:09.006696Z","steps":["trace[1245466014] 'process raft request' (duration: 255.368171ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:06:09.208017Z","caller":"traceutil/trace.go:171","msg":"trace[826358425] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"202.300939ms","start":"2026-02-07T04:06:09.005701Z","end":"2026-02-07T04:06:09.208002Z","steps":["trace[826358425] 'process raft request' (duration: 193.412863ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:06:23.012479Z","caller":"traceutil/trace.go:171","msg":"trace[410472937] transaction","detail":"{read_only:false; response_revision:851; number_of_response:1; }","duration":"118.196577ms","start":"2026-02-07T04:06:22.894264Z","end":"2026-02-07T04:06:23.012461Z","steps":["trace[410472937] 'process raft request' (duration: 118.053403ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:06:23.222327Z","caller":"traceutil/trace.go:171","msg":"trace[1517165460] transaction","detail":"{read_only:false; response_revision:852; number_of_response:1; }","duration":"144.98683ms","start":"2026-02-07T04:06:23.077324Z","end":"2026-02-07T04:06:23.222311Z","steps":["trace[1517165460] 'process raft request' (duration: 144.824706ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:06:23.794141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.808842ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T04:06:23.794242Z","caller":"traceutil/trace.go:171","msg":"trace[1621450330] linearizableReadLoop","detail":"{readStateIndex:885; appliedIndex:883; }","duration":"713.146924ms","start":"2026-02-07T04:06:23.081084Z","end":"2026-02-07T04:06:23.794231Z","steps":["trace[1621450330] 'read index received' (duration: 141.256397ms)","trace[1621450330] 'applied index is now lower than readState.Index' (duration: 571.889717ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:06:23.794386Z","caller":"traceutil/trace.go:171","msg":"trace[725294988] transaction","detail":"{read_only:false; response_revision:853; number_of_response:1; }","duration":"715.202071ms","start":"2026-02-07T04:06:23.079178Z","end":"2026-02-07T04:06:23.79438Z","steps":["trace[725294988] 'process raft request' (duration: 230.104831ms)","trace[725294988] 'compare' (duration: 484.696199ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:06:23.794422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:06:23.079169Z","time spent":"715.237012ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:06:23.794529Z","caller":"traceutil/trace.go:171","msg":"trace[1361079011] transaction","detail":"{read_only:false; response_revision:854; number_of_response:1; }","duration":"714.344527ms","start":"2026-02-07T04:06:23.080179Z","end":"2026-02-07T04:06:23.794523Z","steps":["trace[1361079011] 'process raft request' (duration: 714.020148ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:06:23.794554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:06:23.080148Z","time spent":"714.392669ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T04:06:23.794654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"713.567946ms","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-07T04:06:23.794679Z","caller":"traceutil/trace.go:171","msg":"trace[254960045] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:854; }","duration":"713.604597ms","start":"2026-02-07T04:06:23.081069Z","end":"2026-02-07T04:06:23.794673Z","steps":["trace[254960045] 'agreement among raft nodes before linearized reading' (duration: 713.538615ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:06:23.794692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:06:23.081062Z","time spent":"713.627398ms","remote":"127.0.0.1:60630","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-07T04:06:23.794752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"702.773067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-07T04:06:23.794763Z","caller":"traceutil/trace.go:171","msg":"trace[494769203] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:854; }","duration":"702.795887ms","start":"2026-02-07T04:06:23.091963Z","end":"2026-02-07T04:06:23.794759Z","steps":["trace[494769203] 'agreement among raft nodes before linearized reading' (duration: 702.761446ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:06:23.794774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:06:23.091957Z","time spent":"702.814267ms","remote":"127.0.0.1:60630","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-07T04:06:23.794866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.534949ms","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-07T04:06:23.79488Z","caller":"traceutil/trace.go:171","msg":"trace[772444636] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:854; }","duration":"253.548789ms","start":"2026-02-07T04:06:23.541326Z","end":"2026-02-07T04:06:23.794874Z","steps":["trace[772444636] 'agreement among raft nodes before linearized reading' (duration: 253.525888ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:06:23.962716Z","caller":"traceutil/trace.go:171","msg":"trace[157034285] transaction","detail":"{read_only:false; response_revision:855; number_of_response:1; }","duration":"162.443094ms","start":"2026-02-07T04:06:23.800253Z","end":"2026-02-07T04:06:23.962696Z","steps":["trace[157034285] 'process raft request' (duration: 162.365252ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:06:23.963741Z","caller":"traceutil/trace.go:171","msg":"trace[871207380] transaction","detail":"{read_only:false; response_revision:856; number_of_response:1; }","duration":"161.476557ms","start":"2026-02-07T04:06:23.802257Z","end":"2026-02-07T04:06:23.963734Z","steps":["trace[871207380] 'process raft request' (duration: 161.441886ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:06:28.426457Z","caller":"traceutil/trace.go:171","msg":"trace[2044127967] linearizableReadLoop","detail":"{readStateIndex:900; appliedIndex:899; }","duration":"372.221421ms","start":"2026-02-07T04:06:28.054218Z","end":"2026-02-07T04:06:28.426439Z","steps":["trace[2044127967] 'read index received' (duration: 346.21763ms)","trace[2044127967] 'applied index is now lower than readState.Index' (duration: 26.002871ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:06:28.426573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.338764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-07T04:06:28.426597Z","caller":"traceutil/trace.go:171","msg":"trace[114101949] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:867; }","duration":"372.401906ms","start":"2026-02-07T04:06:28.054188Z","end":"2026-02-07T04:06:28.426589Z","steps":["trace[114101949] 'agreement among raft nodes before linearized reading' (duration: 372.314753ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:06:28.426618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:06:28.054152Z","time spent":"372.460247ms","remote":"127.0.0.1:60848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":3,"response size":31,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"info","ts":"2026-02-07T04:06:28.42681Z","caller":"traceutil/trace.go:171","msg":"trace[1583527267] transaction","detail":"{read_only:false; response_revision:867; number_of_response:1; }","duration":"390.992551ms","start":"2026-02-07T04:06:28.035808Z","end":"2026-02-07T04:06:28.4268Z","steps":["trace[1583527267] 'process raft request' (duration: 364.586799ms)","trace[1583527267] 'compare' (duration: 25.550988ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:06:28.426872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:06:28.035796Z","time spent":"391.038713ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T04:06:29.530102Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594529008893167740,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-07T04:06:29.66413Z","caller":"traceutil/trace.go:171","msg":"trace[1989892237] linearizableReadLoop","detail":"{readStateIndex:901; appliedIndex:900; }","duration":"634.580296ms","start":"2026-02-07T04:06:29.029528Z","end":"2026-02-07T04:06:29.664108Z","steps":["trace[1989892237] 'read index received' (duration: 634.3668ms)","trace[1989892237] 'applied index is now lower than readState.Index' (duration: 212.636µs)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:06:29.664379Z","caller":"traceutil/trace.go:171","msg":"trace[1023692049] transaction","detail":"{read_only:false; response_revision:868; number_of_response:1; }","duration":"682.812103ms","start":"2026-02-07T04:06:28.981556Z","end":"2026-02-07T04:06:29.664368Z","steps":["trace[1023692049] 'process raft request' (duration: 682.395561ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:06:29.664471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:06:28.981529Z","time spent":"682.879725ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T04:06:29.664703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.171942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-07T04:06:29.664735Z","caller":"traceutil/trace.go:171","msg":"trace[611594036] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:868; }","duration":"635.240454ms","start":"2026-02-07T04:06:29.029485Z","end":"2026-02-07T04:06:29.664726Z","steps":["trace[611594036] 'agreement among raft nodes before linearized reading' (duration: 635.135981ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:06:29.664788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:06:29.029465Z","time spent":"635.315986ms","remote":"127.0.0.1:60630","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-07T04:06:29.665045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.977559ms","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-07T04:06:29.665076Z","caller":"traceutil/trace.go:171","msg":"trace[1465429646] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:868; }","duration":"414.03738ms","start":"2026-02-07T04:06:29.251029Z","end":"2026-02-07T04:06:29.665066Z","steps":["trace[1465429646] 'agreement among raft nodes before linearized reading' (duration: 413.957438ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:06:29.665103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:06:29.250977Z","time spent":"414.120062ms","remote":"127.0.0.1:60630","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-07T04:06:29.665313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.62309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:06:29.665351Z","caller":"traceutil/trace.go:171","msg":"trace[497968968] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:868; }","duration":"153.681401ms","start":"2026-02-07T04:06:29.511654Z","end":"2026-02-07T04:06:29.665335Z","steps":["trace[497968968] 'agreement among raft nodes before linearized reading' (duration: 153.6352ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:06:29.99455Z","caller":"traceutil/trace.go:171","msg":"trace[754175421] transaction","detail":"{read_only:false; response_revision:870; number_of_response:1; }","duration":"321.817303ms","start":"2026-02-07T04:06:29.672712Z","end":"2026-02-07T04:06:29.99453Z","steps":["trace[754175421] 'process raft request' (duration: 282.713059ms)","trace[754175421] 'compare' (duration: 38.682422ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:06:29.994684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:06:29.67269Z","time spent":"321.918626ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:06:29.995107Z","caller":"traceutil/trace.go:171","msg":"trace[1844702378] linearizableReadLoop","detail":"{readStateIndex:903; appliedIndex:902; }","duration":"200.299513ms","start":"2026-02-07T04:06:29.793895Z","end":"2026-02-07T04:06:29.994195Z","steps":["trace[1844702378] 'read index received' (duration: 161.466877ms)","trace[1844702378] 'applied index is now lower than readState.Index' (duration: 38.831576ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:06:29.997052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.745901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-07T04:06:29.997092Z","caller":"traceutil/trace.go:171","msg":"trace[827703338] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:870; }","duration":"123.798892ms","start":"2026-02-07T04:06:29.873282Z","end":"2026-02-07T04:06:29.99708Z","steps":["trace[827703338] 'agreement among raft nodes before linearized reading' (duration: 123.672909ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:06:29.997256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.113207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-07T04:06:29.997284Z","caller":"traceutil/trace.go:171","msg":"trace[258860138] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:870; }","duration":"126.143588ms","start":"2026-02-07T04:06:29.871133Z","end":"2026-02-07T04:06:29.997276Z","steps":["trace[258860138] 'agreement among raft nodes before linearized reading' (duration: 126.097157ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:06:29.997946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.347113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:06:29.997985Z","caller":"traceutil/trace.go:171","msg":"trace[963594095] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:870; }","duration":"204.12514ms","start":"2026-02-07T04:06:29.793849Z","end":"2026-02-07T04:06:29.997974Z","steps":["trace[963594095] 'agreement among raft nodes before linearized reading' (duration: 201.359894ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:06:30.24821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.096116ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T04:06:30.248303Z","caller":"traceutil/trace.go:171","msg":"trace[1777393570] transaction","detail":"{read_only:false; response_revision:872; number_of_response:1; }","duration":"167.118744ms","start":"2026-02-07T04:06:30.081166Z","end":"2026-02-07T04:06:30.248285Z","steps":["trace[1777393570] 'process raft request' (duration: 40.853183ms)","trace[1777393570] 'compare' (duration: 126.007674ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:06:30.700728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.888324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-07T04:06:30.700781Z","caller":"traceutil/trace.go:171","msg":"trace[666982806] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:872; }","duration":"269.970965ms","start":"2026-02-07T04:06:30.430797Z","end":"2026-02-07T04:06:30.700768Z","steps":["trace[666982806] 'range keys from in-memory index tree' (duration: 269.74856ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:06:30.871588Z","caller":"traceutil/trace.go:171","msg":"trace[403992851] transaction","detail":"{read_only:false; response_revision:873; number_of_response:1; }","duration":"167.521175ms","start":"2026-02-07T04:06:30.704041Z","end":"2026-02-07T04:06:30.871562Z","steps":["trace[403992851] 'process raft request' (duration: 167.168985ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:06:31.091502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.625153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:06:31.091558Z","caller":"traceutil/trace.go:171","msg":"trace[1482745572] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:873; }","duration":"134.723406ms","start":"2026-02-07T04:06:30.956822Z","end":"2026-02-07T04:06:31.091545Z","steps":["trace[1482745572] 'count revisions from in-memory index tree' (duration: 134.573372ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:06:38.592354Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594529008893167812,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-07T04:06:38.633661Z","caller":"traceutil/trace.go:171","msg":"trace[114953949] linearizableReadLoop","detail":"{readStateIndex:927; appliedIndex:926; }","duration":"542.359048ms","start":"2026-02-07T04:06:38.091288Z","end":"2026-02-07T04:06:38.633647Z","steps":["trace[114953949] 'read index received' (duration: 542.221264ms)","trace[114953949] 'applied index is now lower than readState.Index' (duration: 137.394µs)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:06:38.633804Z","caller":"traceutil/trace.go:171","msg":"trace[758319493] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"560.755399ms","start":"2026-02-07T04:06:38.073043Z","end":"2026-02-07T04:06:38.633798Z","steps":["trace[758319493] 'process raft request' (duration: 560.500642ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:06:38.633885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:06:38.073019Z","time spent":"560.816061ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T04:06:38.633994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.705278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-07T04:06:38.634016Z","caller":"traceutil/trace.go:171","msg":"trace[1002440844] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:892; }","duration":"542.77069ms","start":"2026-02-07T04:06:38.091239Z","end":"2026-02-07T04:06:38.63401Z","steps":["trace[1002440844] 'agreement among raft nodes before linearized reading' (duration: 542.723549ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:06:38.634033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:06:38.091227Z","time spent":"542.80228ms","remote":"127.0.0.1:60696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":14,"response size":31,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-02-07T04:06:38.634152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.064876ms","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-07T04:06:38.634168Z","caller":"traceutil/trace.go:171","msg":"trace[1598653551] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:892; }","duration":"531.097056ms","start":"2026-02-07T04:06:38.103068Z","end":"2026-02-07T04:06:38.634165Z","steps":["trace[1598653551] 'agreement among raft nodes before linearized reading' (duration: 531.034874ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:06:38.63418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:06:38.103059Z","time spent":"531.118296ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-07T04:06:38.634289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.294905ms","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-07T04:06:38.634306Z","caller":"traceutil/trace.go:171","msg":"trace[1458469668] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:892; }","duration":"362.334687ms","start":"2026-02-07T04:06:38.271968Z","end":"2026-02-07T04:06:38.634302Z","steps":["trace[1458469668] 'agreement among raft nodes before linearized reading' (duration: 362.290606ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:06:38.634318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:06:38.271955Z","time spent":"362.360967ms","remote":"127.0.0.1:60630","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-07T04:06:38.634379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.975431ms","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-07T04:06:38.63439Z","caller":"traceutil/trace.go:171","msg":"trace[640566119] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:892; }","duration":"404.013212ms","start":"2026-02-07T04:06:38.230373Z","end":"2026-02-07T04:06:38.634387Z","steps":["trace[640566119] 'agreement among raft nodes before linearized reading' (duration: 403.992412ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:06:38.634402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:06:38.230359Z","time spent":"404.039753ms","remote":"127.0.0.1:60562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":4,"response size":31,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"info","ts":"2026-02-07T04:06:39.918959Z","caller":"traceutil/trace.go:171","msg":"trace[598431383] linearizableReadLoop","detail":"{readStateIndex:931; appliedIndex:930; }","duration":"124.671977ms","start":"2026-02-07T04:06:39.794273Z","end":"2026-02-07T04:06:39.918945Z","steps":["trace[598431383] 'read index received' (duration: 124.547003ms)","trace[598431383] 'applied index is now lower than readState.Index' (duration: 124.444µs)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:06:39.919071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.78455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:06:39.919087Z","caller":"traceutil/trace.go:171","msg":"trace[1473669627] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:896; }","duration":"124.830191ms","start":"2026-02-07T04:06:39.794251Z","end":"2026-02-07T04:06:39.919082Z","steps":["trace[1473669627] 'agreement among raft nodes before linearized reading' (duration: 124.771589ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:06:39.919196Z","caller":"traceutil/trace.go:171","msg":"trace[1834809745] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"132.417722ms","start":"2026-02-07T04:06:39.78677Z","end":"2026-02-07T04:06:39.919188Z","steps":["trace[1834809745] 'process raft request' (duration: 132.087742ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:06:43.071781Z","caller":"traceutil/trace.go:171","msg":"trace[527076201] linearizableReadLoop","detail":"{readStateIndex:939; appliedIndex:938; }","duration":"386.401313ms","start":"2026-02-07T04:06:42.685366Z","end":"2026-02-07T04:06:43.071767Z","steps":["trace[527076201] 'read index received' (duration: 386.256229ms)","trace[527076201] 'applied index is now lower than readState.Index' (duration: 144.394µs)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:06:43.071988Z","caller":"traceutil/trace.go:171","msg":"trace[1439146740] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"425.142237ms","start":"2026-02-07T04:06:42.646838Z","end":"2026-02-07T04:06:43.071981Z","steps":["trace[1439146740] 'process raft request' (duration: 424.825649ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:06:43.072056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:06:42.646828Z","time spent":"425.178509ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T04:06:43.072247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.881947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-07T04:06:43.072268Z","caller":"traceutil/trace.go:171","msg":"trace[1015207029] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:903; }","duration":"386.924228ms","start":"2026-02-07T04:06:42.685337Z","end":"2026-02-07T04:06:43.072262Z","steps":["trace[1015207029] 'agreement among raft nodes before linearized reading' (duration: 386.837406ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:06:43.072285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:06:42.685325Z","time spent":"386.956329ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-07T04:06:43.072394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.292024ms","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-07T04:06:43.072412Z","caller":"traceutil/trace.go:171","msg":"trace[882198677] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:903; }","duration":"380.327155ms","start":"2026-02-07T04:06:42.69208Z","end":"2026-02-07T04:06:43.072407Z","steps":["trace[882198677] 'agreement among raft nodes before linearized reading' (duration: 380.275204ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:06:43.072427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:06:42.692069Z","time spent":"380.355066ms","remote":"127.0.0.1:60630","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-07T04:06:43.072572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.704468ms","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-07T04:06:43.072591Z","caller":"traceutil/trace.go:171","msg":"trace[1391774382] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:903; }","duration":"168.758909ms","start":"2026-02-07T04:06:42.903827Z","end":"2026-02-07T04:06:43.072586Z","steps":["trace[1391774382] 'agreement among raft nodes before linearized reading' (duration: 168.699217ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:06:43.072675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.070479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:06:43.07269Z","caller":"traceutil/trace.go:171","msg":"trace[1755891234] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:903; }","duration":"278.121761ms","start":"2026-02-07T04:06:42.794564Z","end":"2026-02-07T04:06:43.072685Z","steps":["trace[1755891234] 'agreement among raft nodes before linearized reading' (duration: 278.09737ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:06:43.893079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.824102ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T04:06:43.893179Z","caller":"traceutil/trace.go:171","msg":"trace[1343438899] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"812.502552ms","start":"2026-02-07T04:06:43.080659Z","end":"2026-02-07T04:06:43.893161Z","steps":["trace[1343438899] 'process raft request' (duration: 317.510915ms)","trace[1343438899] 'compare' (duration: 494.535894ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:06:43.893243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:06:43.080651Z","time spent":"812.558513ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:06:43.894753Z","caller":"traceutil/trace.go:171","msg":"trace[1010784802] linearizableReadLoop","detail":"{readStateIndex:942; appliedIndex:939; }","duration":"677.867328ms","start":"2026-02-07T04:06:43.216872Z","end":"2026-02-07T04:06:43.89474Z","steps":["trace[1010784802] 'read index received' (duration: 181.36627ms)","trace[1010784802] 'applied index is now lower than readState.Index' (duration: 496.500328ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:06:43.895439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"678.562448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:06:43.895469Z","caller":"traceutil/trace.go:171","msg":"trace[869555790] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:906; }","duration":"678.643541ms","start":"2026-02-07T04:06:43.216819Z","end":"2026-02-07T04:06:43.895462Z","steps":["trace[869555790] 'agreement among raft nodes before linearized reading' (duration: 678.590639ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:06:43.895491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:06:43.21678Z","time spent":"678.705212ms","remote":"127.0.0.1:60668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"info","ts":"2026-02-07T04:06:43.895574Z","caller":"traceutil/trace.go:171","msg":"trace[616145352] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"814.655881ms","start":"2026-02-07T04:06:43.080912Z","end":"2026-02-07T04:06:43.895568Z","steps":["trace[616145352] 'process raft request' (duration: 813.712565ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:06:43.895607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:06:43.080905Z","time spent":"814.681602ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:06:43.895649Z","caller":"traceutil/trace.go:171","msg":"trace[302843475] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"814.697253ms","start":"2026-02-07T04:06:43.080946Z","end":"2026-02-07T04:06:43.895644Z","steps":["trace[302843475] 'process raft request' (duration: 813.747156ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:06:43.895676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:06:43.080942Z","time spent":"814.716793ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T04:06:43.89581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.42631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-07T04:06:43.89583Z","caller":"traceutil/trace.go:171","msg":"trace[1643227991] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:906; }","duration":"472.471512ms","start":"2026-02-07T04:06:43.423352Z","end":"2026-02-07T04:06:43.895824Z","steps":["trace[1643227991] 'agreement among raft nodes before linearized reading' (duration: 472.438381ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:06:43.895849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:06:43.423337Z","time spent":"472.507742ms","remote":"127.0.0.1:41178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":3,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-02-07T04:06:44.7835Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594529008893167851,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-07T04:06:45.070545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.025766327s","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-07T04:06:45.070621Z","caller":"traceutil/trace.go:171","msg":"trace[1979215958] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:906; }","duration":"1.025871241s","start":"2026-02-07T04:06:44.04473Z","end":"2026-02-07T04:06:45.070601Z","steps":["trace[1979215958] 'range keys from in-memory index tree' (duration: 1.025622893s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:06:45.070659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:06:44.044718Z","time spent":"1.025932792s","remote":"127.0.0.1:60630","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-07T04:06:45.071594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.451968ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T04:06:45.071722Z","caller":"traceutil/trace.go:171","msg":"trace[420423375] linearizableReadLoop","detail":"{readStateIndex:943; appliedIndex:942; }","duration":"788.364264ms","start":"2026-02-07T04:06:44.283334Z","end":"2026-02-07T04:06:45.071698Z","steps":["trace[420423375] 'read index received' (duration: 565.68662ms)","trace[420423375] 'applied index is now lower than readState.Index' (duration: 222.675894ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:06:45.071978Z","caller":"traceutil/trace.go:171","msg":"trace[1681340930] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"977.852056ms","start":"2026-02-07T04:06:44.094111Z","end":"2026-02-07T04:06:45.071964Z","steps":["trace[1681340930] 'process raft request' (duration: 754.906495ms)","trace[1681340930] 'compare' (duration: 221.851481ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:06:45.072045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:06:44.094098Z","time spent":"977.912599ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T04:06:45.072364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"788.943661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:06:45.072409Z","caller":"traceutil/trace.go:171","msg":"trace[1106740674] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:907; }","duration":"789.093154ms","start":"2026-02-07T04:06:44.2833Z","end":"2026-02-07T04:06:45.072394Z","steps":["trace[1106740674] 'agreement among raft nodes before linearized reading' (duration: 788.93384ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:06:45.072446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:06:44.283283Z","time spent":"789.153947ms","remote":"127.0.0.1:60586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-02-07T04:06:45.086348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.083555ms","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-07T04:06:45.08646Z","caller":"traceutil/trace.go:171","msg":"trace[1017261479] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:907; }","duration":"246.2531ms","start":"2026-02-07T04:06:44.840189Z","end":"2026-02-07T04:06:45.086442Z","steps":["trace[1017261479] 'agreement among raft nodes before linearized reading' (duration: 246.089485ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:06:45.087868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.204146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:06:45.087893Z","caller":"traceutil/trace.go:171","msg":"trace[263187266] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:907; }","duration":"296.251588ms","start":"2026-02-07T04:06:44.791635Z","end":"2026-02-07T04:06:45.087887Z","steps":["trace[263187266] 'agreement among raft nodes before linearized reading' (duration: 296.209297ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:06:45.692716Z","caller":"traceutil/trace.go:171","msg":"trace[396352754] transaction","detail":"{read_only:false; response_revision:908; number_of_response:1; }","duration":"596.676581ms","start":"2026-02-07T04:06:45.096012Z","end":"2026-02-07T04:06:45.692689Z","steps":["trace[396352754] 'process raft request' (duration: 596.513947ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:06:45.692886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:06:45.096002Z","time spent":"596.788604ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:06:45.792013Z","caller":"traceutil/trace.go:171","msg":"trace[1950310685] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"688.341807ms","start":"2026-02-07T04:06:45.103646Z","end":"2026-02-07T04:06:45.791988Z","steps":["trace[1950310685] 'process raft request' (duration: 687.767361ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:06:45.792166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:06:45.103621Z","time spent":"688.455679ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T04:06:46.05304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.217221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:06:46.053106Z","caller":"traceutil/trace.go:171","msg":"trace[1165368454] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:909; }","duration":"222.344135ms","start":"2026-02-07T04:06:45.830746Z","end":"2026-02-07T04:06:46.05309Z","steps":["trace[1165368454] 'range keys from in-memory index tree' (duration: 222.135939ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:06:46.053413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.583958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-07T04:06:46.053443Z","caller":"traceutil/trace.go:171","msg":"trace[575865885] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:909; }","duration":"156.656951ms","start":"2026-02-07T04:06:45.896777Z","end":"2026-02-07T04:06:46.053434Z","steps":["trace[575865885] 'range keys from in-memory index tree' (duration: 156.411454ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:06:46.053627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.880783ms","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-07T04:06:46.053656Z","caller":"traceutil/trace.go:171","msg":"trace[309026012] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:909; }","duration":"153.918275ms","start":"2026-02-07T04:06:45.899728Z","end":"2026-02-07T04:06:46.053646Z","steps":["trace[309026012] 'range keys from in-memory index tree' (duration: 153.798711ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:06:46.053808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.575475ms","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-07T04:06:46.053832Z","caller":"traceutil/trace.go:171","msg":"trace[496642467] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:909; }","duration":"153.609826ms","start":"2026-02-07T04:06:45.900215Z","end":"2026-02-07T04:06:46.053825Z","steps":["trace[496642467] 'range keys from in-memory index tree' (duration: 153.514523ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:06:48.258323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.432428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-07T04:06:48.258394Z","caller":"traceutil/trace.go:171","msg":"trace[1824686654] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:922; }","duration":"105.543861ms","start":"2026-02-07T04:06:48.15284Z","end":"2026-02-07T04:06:48.258384Z","steps":["trace[1824686654] 'range keys from in-memory index tree' (duration: 105.299204ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:06:48.258602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.780912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-07T04:06:48.258616Z","caller":"traceutil/trace.go:171","msg":"trace[848772576] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:922; }","duration":"458.817822ms","start":"2026-02-07T04:06:47.799794Z","end":"2026-02-07T04:06:48.258612Z","steps":["trace[848772576] 'range keys from in-memory index tree' (duration: 458.653558ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:06:48.25863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:06:47.799538Z","time spent":"459.087649ms","remote":"127.0.0.1:60630","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-07T04:06:48.258703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.681668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:06:48.258715Z","caller":"traceutil/trace.go:171","msg":"trace[330243318] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:922; }","duration":"458.710899ms","start":"2026-02-07T04:06:47.8Z","end":"2026-02-07T04:06:48.258711Z","steps":["trace[330243318] 'range keys from in-memory index tree' (duration: 458.654347ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:06:48.258726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:06:47.799996Z","time spent":"458.72688ms","remote":"127.0.0.1:60362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-07T04:06:48.25893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.720865ms","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-07T04:06:48.258943Z","caller":"traceutil/trace.go:171","msg":"trace[2064485247] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:922; }","duration":"137.772086ms","start":"2026-02-07T04:06:48.121167Z","end":"2026-02-07T04:06:48.258939Z","steps":["trace[2064485247] 'range keys from in-memory index tree' (duration: 137.634092ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:06:48.532416Z","caller":"traceutil/trace.go:171","msg":"trace[117316976] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"269.524516ms","start":"2026-02-07T04:06:48.262876Z","end":"2026-02-07T04:06:48.5324Z","steps":["trace[117316976] 'process raft request' (duration: 269.442154ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:06:48.556404Z","caller":"traceutil/trace.go:171","msg":"trace[1477145800] linearizableReadLoop","detail":"{readStateIndex:962; appliedIndex:960; }","duration":"275.983935ms","start":"2026-02-07T04:06:48.280397Z","end":"2026-02-07T04:06:48.556381Z","steps":["trace[1477145800] 'read index received' (duration: 252.292257ms)","trace[1477145800] 'applied index is now lower than readState.Index' (duration: 23.690588ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:06:48.556623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.200851ms","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-07T04:06:48.556654Z","caller":"traceutil/trace.go:171","msg":"trace[1355833578] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:925; }","duration":"276.264362ms","start":"2026-02-07T04:06:48.28038Z","end":"2026-02-07T04:06:48.556645Z","steps":["trace[1355833578] 'agreement among raft nodes before linearized reading' (duration: 276.103137ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:06:48.556933Z","caller":"traceutil/trace.go:171","msg":"trace[1642612953] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"290.244151ms","start":"2026-02-07T04:06:48.266677Z","end":"2026-02-07T04:06:48.556921Z","steps":["trace[1642612953] 'process raft request' (duration: 289.561152ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:06:48.557047Z","caller":"traceutil/trace.go:171","msg":"trace[880289689] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"290.319633ms","start":"2026-02-07T04:06:48.266717Z","end":"2026-02-07T04:06:48.557037Z","steps":["trace[880289689] 'process raft request' (duration: 289.628744ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:06:49.403807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.47582ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T04:06:49.403943Z","caller":"traceutil/trace.go:171","msg":"trace[193707041] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"842.163499ms","start":"2026-02-07T04:06:48.561764Z","end":"2026-02-07T04:06:49.403927Z","steps":["trace[193707041] 'process raft request' (duration: 283.505884ms)","trace[193707041] 'compare' (duration: 558.059008ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:06:49.404018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:06:48.561748Z","time spent":"842.22257ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:06:49.894951Z","caller":"traceutil/trace.go:171","msg":"trace[1194959512] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"173.18362ms","start":"2026-02-07T04:06:49.721752Z","end":"2026-02-07T04:06:49.894936Z","steps":["trace[1194959512] 'process raft request' (duration: 172.868911ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:06:56.292334Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594529008893167967,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-07T04:06:56.307656Z","caller":"traceutil/trace.go:171","msg":"trace[1957010494] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"881.167512ms","start":"2026-02-07T04:06:55.426466Z","end":"2026-02-07T04:06:56.307633Z","steps":["trace[1957010494] 'process raft request' (duration: 881.021938ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:06:56.307786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:06:55.42645Z","time spent":"881.259424ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T04:06:56.719948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.503767ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T04:06:56.720037Z","caller":"traceutil/trace.go:171","msg":"trace[1677469748] linearizableReadLoop","detail":"{readStateIndex:1002; appliedIndex:1001; }","duration":"928.557128ms","start":"2026-02-07T04:06:55.791465Z","end":"2026-02-07T04:06:56.720022Z","steps":["trace[1677469748] 'read index received' (duration: 516.730461ms)","trace[1677469748] 'applied index is now lower than readState.Index' (duration: 411.825627ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:06:56.720214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"928.727213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-07T04:06:56.720333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"801.697345ms","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-07T04:06:56.720366Z","caller":"traceutil/trace.go:171","msg":"trace[1903601900] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:963; }","duration":"801.764686ms","start":"2026-02-07T04:06:55.918592Z","end":"2026-02-07T04:06:56.720356Z","steps":["trace[1903601900] 'agreement among raft nodes before linearized reading' (duration: 801.662004ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:06:56.720396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:06:55.91858Z","time spent":"801.807278ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-07T04:06:56.720902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.45372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-07T04:06:56.720292Z","caller":"traceutil/trace.go:171","msg":"trace[650140104] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:963; }","duration":"928.847696ms","start":"2026-02-07T04:06:55.791427Z","end":"2026-02-07T04:06:56.720275Z","steps":["trace[650140104] 'agreement among raft nodes before linearized reading' (duration: 928.714392ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:06:56.720933Z","caller":"traceutil/trace.go:171","msg":"trace[1795169018] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:963; }","duration":"140.518312ms","start":"2026-02-07T04:06:56.580406Z","end":"2026-02-07T04:06:56.720924Z","steps":["trace[1795169018] 'agreement among raft nodes before linearized reading' (duration: 140.44798ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:06:56.720967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:06:55.791412Z","time spent":"929.539655ms","remote":"127.0.0.1:60362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-07T04:06:56.721054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.516403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:06:56.721081Z","caller":"traceutil/trace.go:171","msg":"trace[1793185725] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:963; }","duration":"517.571704ms","start":"2026-02-07T04:06:56.203501Z","end":"2026-02-07T04:06:56.721073Z","steps":["trace[1793185725] 'agreement among raft nodes before linearized reading' (duration: 517.528743ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:06:56.721105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:06:56.203481Z","time spent":"517.617275ms","remote":"127.0.0.1:60818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":29,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"info","ts":"2026-02-07T04:06:56.721184Z","caller":"traceutil/trace.go:171","msg":"trace[1241256812] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"1.234948417s","start":"2026-02-07T04:06:55.48622Z","end":"2026-02-07T04:06:56.721168Z","steps":["trace[1241256812] 'process raft request' (duration: 920.156445ms)","trace[1241256812] 'compare' (duration: 313.414434ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:06:56.72127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:06:55.486202Z","time spent":"1.235012019s","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:07:00.582159Z","caller":"traceutil/trace.go:171","msg":"trace[1414209978] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"254.015704ms","start":"2026-02-07T04:07:00.32811Z","end":"2026-02-07T04:07:00.582126Z","steps":["trace[1414209978] 'process raft request' (duration: 253.815949ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:07:01.247741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.414792ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T04:07:01.247801Z","caller":"traceutil/trace.go:171","msg":"trace[1073871484] linearizableReadLoop","detail":"{readStateIndex:1030; appliedIndex:1029; }","duration":"470.494925ms","start":"2026-02-07T04:07:00.777296Z","end":"2026-02-07T04:07:01.247791Z","steps":["trace[1073871484] 'read index received' (duration: 263.774634ms)","trace[1073871484] 'applied index is now lower than readState.Index' (duration: 206.719641ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:07:01.247927Z","caller":"traceutil/trace.go:171","msg":"trace[406688512] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"632.348231ms","start":"2026-02-07T04:07:00.615573Z","end":"2026-02-07T04:07:01.247921Z","steps":["trace[406688512] 'process raft request' (duration: 425.701542ms)","trace[406688512] 'compare' (duration: 206.214587ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:07:01.247957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:07:00.615545Z","time spent":"632.397002ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T04:07:01.248094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.798144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-07T04:07:01.24811Z","caller":"traceutil/trace.go:171","msg":"trace[1541956232] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:990; }","duration":"470.844735ms","start":"2026-02-07T04:07:00.777258Z","end":"2026-02-07T04:07:01.248103Z","steps":["trace[1541956232] 'agreement among raft nodes before linearized reading' (duration: 470.784983ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:07:01.248123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:07:00.777243Z","time spent":"470.876466ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-07T04:07:01.248205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.921716ms","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-07T04:07:01.248218Z","caller":"traceutil/trace.go:171","msg":"trace[311081319] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:990; }","duration":"466.942557ms","start":"2026-02-07T04:07:00.781271Z","end":"2026-02-07T04:07:01.248213Z","steps":["trace[311081319] 'agreement among raft nodes before linearized reading' (duration: 466.905186ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:07:01.248231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:07:00.781266Z","time spent":"466.962067ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-07T04:07:01.248341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.503699ms","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-07T04:07:01.248353Z","caller":"traceutil/trace.go:171","msg":"trace[557878929] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:990; }","duration":"433.523769ms","start":"2026-02-07T04:07:00.814825Z","end":"2026-02-07T04:07:01.248349Z","steps":["trace[557878929] 'agreement among raft nodes before linearized reading' (duration: 433.486828ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:07:01.248364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:07:00.81482Z","time spent":"433.542139ms","remote":"127.0.0.1:60630","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-07T04:07:01.248438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.221956ms","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-07T04:07:01.248451Z","caller":"traceutil/trace.go:171","msg":"trace[1016909430] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:990; }","duration":"435.252908ms","start":"2026-02-07T04:07:00.813194Z","end":"2026-02-07T04:07:01.248447Z","steps":["trace[1016909430] 'agreement among raft nodes before linearized reading' (duration: 435.217577ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:07:01.248462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:07:00.813189Z","time spent":"435.269998ms","remote":"127.0.0.1:60630","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-07T04:07:01.248525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.375167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-07T04:07:01.248537Z","caller":"traceutil/trace.go:171","msg":"trace[1968227819] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:990; }","duration":"441.399037ms","start":"2026-02-07T04:07:00.807134Z","end":"2026-02-07T04:07:01.248533Z","steps":["trace[1968227819] 'agreement among raft nodes before linearized reading' (duration: 441.372996ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:07:01.248549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:07:00.807129Z","time spent":"441.416558ms","remote":"127.0.0.1:60800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":31,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-02-07T04:07:02.500205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:07:02.059785Z","time spent":"440.412511ms","remote":"127.0.0.1:47588","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-07T04:07:02.50122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.048682324s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T04:07:02.502457Z","caller":"traceutil/trace.go:171","msg":"trace[1721900526] linearizableReadLoop","detail":"{readStateIndex:1034; appliedIndex:1030; }","duration":"1.050970287s","start":"2026-02-07T04:07:01.451468Z","end":"2026-02-07T04:07:02.502438Z","steps":["trace[1721900526] 'read index received' (duration: 973.707µs)","trace[1721900526] 'applied index is now lower than readState.Index' (duration: 1.04999571s)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:07:02.502789Z","caller":"traceutil/trace.go:171","msg":"trace[1687090625] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"1.246530989s","start":"2026-02-07T04:07:01.256243Z","end":"2026-02-07T04:07:02.502774Z","steps":["trace[1687090625] 'process raft request' (duration: 1.246122707s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:07:02.503805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.05214818s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:07:02.503878Z","caller":"traceutil/trace.go:171","msg":"trace[274957511] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:994; }","duration":"1.052428958s","start":"2026-02-07T04:07:01.45143Z","end":"2026-02-07T04:07:02.503859Z","steps":["trace[274957511] 'agreement among raft nodes before linearized reading' (duration: 1.05215656s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:07:02.504049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:07:01.451411Z","time spent":"1.052598863s","remote":"127.0.0.1:60598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"info","ts":"2026-02-07T04:07:02.504464Z","caller":"traceutil/trace.go:171","msg":"trace[644922933] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"1.248157784s","start":"2026-02-07T04:07:01.25629Z","end":"2026-02-07T04:07:02.504448Z","steps":["trace[644922933] 'process raft request' (duration: 1.246115828s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:07:02.504593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:07:01.256286Z","time spent":"1.248218966s","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T04:07:02.504455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:07:01.256235Z","time spent":"1.247175076s","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:07:02.502497Z","caller":"traceutil/trace.go:171","msg":"trace[101330495] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"1.248208935s","start":"2026-02-07T04:07:01.254264Z","end":"2026-02-07T04:07:02.502473Z","steps":["trace[101330495] 'process raft request' (duration: 1.247990129s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:07:02.505191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:07:01.254255Z","time spent":"1.250847088s","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:07:02.502595Z","caller":"traceutil/trace.go:171","msg":"trace[1768435343] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"1.248495724s","start":"2026-02-07T04:07:01.254088Z","end":"2026-02-07T04:07:02.502584Z","steps":["trace[1768435343] 'process raft request' (duration: 198.352849ms)","trace[1768435343] 'compare' (duration: 1.047726427s)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:07:02.505607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:07:01.254068Z","time spent":"1.251498476s","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T04:07:02.562135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"770.062377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:07:02.562346Z","caller":"traceutil/trace.go:171","msg":"trace[68795797] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:994; }","duration":"770.312264ms","start":"2026-02-07T04:07:01.792012Z","end":"2026-02-07T04:07:02.562325Z","steps":["trace[68795797] 'agreement among raft nodes before linearized reading' (duration: 770.056097ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:07:02.562903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:07:01.792Z","time spent":"770.89284ms","remote":"127.0.0.1:60362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-07T04:07:02.563321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.349775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:510"} {"level":"info","ts":"2026-02-07T04:07:02.563746Z","caller":"traceutil/trace.go:171","msg":"trace[735539872] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:994; }","duration":"662.747596ms","start":"2026-02-07T04:07:01.900961Z","end":"2026-02-07T04:07:02.563709Z","steps":["trace[735539872] 'agreement among raft nodes before linearized reading' (duration: 661.977195ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:07:02.564071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:07:01.900899Z","time spent":"663.161238ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":534,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-07T04:07:04.742163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.721026ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T04:07:04.742306Z","caller":"traceutil/trace.go:171","msg":"trace[601828198] linearizableReadLoop","detail":"{readStateIndex:1044; appliedIndex:1042; }","duration":"147.323212ms","start":"2026-02-07T04:07:04.594968Z","end":"2026-02-07T04:07:04.742291Z","steps":["trace[601828198] 'read index received' (duration: 101.490569ms)","trace[601828198] 'applied index is now lower than readState.Index' (duration: 45.831783ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:07:04.742486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.506237ms","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-07T04:07:04.742552Z","caller":"traceutil/trace.go:171","msg":"trace[1708499561] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1003; }","duration":"147.574139ms","start":"2026-02-07T04:07:04.594939Z","end":"2026-02-07T04:07:04.742513Z","steps":["trace[1708499561] 'agreement among raft nodes before linearized reading' (duration: 147.402734ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:07:04.742865Z","caller":"traceutil/trace.go:171","msg":"trace[386143041] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"211.581586ms","start":"2026-02-07T04:07:04.531269Z","end":"2026-02-07T04:07:04.742851Z","steps":["trace[386143041] 'process raft request' (duration: 42.097939ms)","trace[386143041] 'compare' (duration: 168.624403ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:07:04.743032Z","caller":"traceutil/trace.go:171","msg":"trace[57621479] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"193.399971ms","start":"2026-02-07T04:07:04.549587Z","end":"2026-02-07T04:07:04.742987Z","steps":["trace[57621479] 'process raft request' (duration: 192.6607ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:07:05.516686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.047738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:07:05.516752Z","caller":"traceutil/trace.go:171","msg":"trace[2001183411] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1005; }","duration":"226.14893ms","start":"2026-02-07T04:07:05.290584Z","end":"2026-02-07T04:07:05.516733Z","steps":["trace[2001183411] 'range keys from in-memory index tree' (duration: 225.998396ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:07:05.81815Z","caller":"traceutil/trace.go:171","msg":"trace[2066218688] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"426.109054ms","start":"2026-02-07T04:07:05.39202Z","end":"2026-02-07T04:07:05.818129Z","steps":["trace[2066218688] 'process raft request' (duration: 425.996361ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:07:05.818392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:07:05.392002Z","time spent":"426.279439ms","remote":"127.0.0.1:60420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":784,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-07T04:07:05.853174Z","caller":"traceutil/trace.go:171","msg":"trace[1595501407] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"330.982571ms","start":"2026-02-07T04:07:05.522174Z","end":"2026-02-07T04:07:05.853156Z","steps":["trace[1595501407] 'process raft request' (duration: 330.874538ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:07:05.855203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:07:05.522155Z","time spent":"332.753881ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":512,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-07T04:07:42.829643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.715632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-07T04:07:42.82969Z","caller":"traceutil/trace.go:171","msg":"trace[405204185] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1172; }","duration":"102.805355ms","start":"2026-02-07T04:07:42.726874Z","end":"2026-02-07T04:07:42.82968Z","steps":["trace[405204185] 'range keys from in-memory index tree' (duration: 102.590229ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:07:43.106497Z","caller":"traceutil/trace.go:171","msg":"trace[597208661] linearizableReadLoop","detail":"{readStateIndex:1224; appliedIndex:1222; }","duration":"144.066501ms","start":"2026-02-07T04:07:42.962416Z","end":"2026-02-07T04:07:43.106483Z","steps":["trace[597208661] 'read index received' (duration: 140.938484ms)","trace[597208661] 'applied index is now lower than readState.Index' (duration: 3.127536ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:07:43.106633Z","caller":"traceutil/trace.go:171","msg":"trace[120442560] transaction","detail":"{read_only:false; response_revision:1174; number_of_response:1; }","duration":"270.996576ms","start":"2026-02-07T04:07:42.835629Z","end":"2026-02-07T04:07:43.106626Z","steps":["trace[120442560] 'process raft request' (duration: 267.719155ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:07:43.106727Z","caller":"traceutil/trace.go:171","msg":"trace[131291894] transaction","detail":"{read_only:false; response_revision:1175; number_of_response:1; }","duration":"270.77555ms","start":"2026-02-07T04:07:42.835947Z","end":"2026-02-07T04:07:43.106722Z","steps":["trace[131291894] 'process raft request' (duration: 270.509233ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:07:43.106848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.419051ms","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-07T04:07:43.106863Z","caller":"traceutil/trace.go:171","msg":"trace[497404896] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1175; }","duration":"144.469002ms","start":"2026-02-07T04:07:42.96239Z","end":"2026-02-07T04:07:43.106859Z","steps":["trace[497404896] 'agreement among raft nodes before linearized reading' (duration: 144.38436ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:07:45.612693Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594529008893168481,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-07T04:07:46.113279Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594529008893168481,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-07T04:07:46.614371Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594529008893168481,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-07T04:07:47.115103Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594529008893168481,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-07T04:07:47.158482Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.24323805s","expected-duration":"1s"} {"level":"info","ts":"2026-02-07T04:07:47.217649Z","caller":"traceutil/trace.go:171","msg":"trace[1764134509] linearizableReadLoop","detail":"{readStateIndex:1230; appliedIndex:1229; }","duration":"2.105558415s","start":"2026-02-07T04:07:45.112074Z","end":"2026-02-07T04:07:47.217633Z","steps":["trace[1764134509] 'read index received' (duration: 2.105428702s)","trace[1764134509] 'applied index is now lower than readState.Index' (duration: 129.163µs)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:07:47.217696Z","caller":"traceutil/trace.go:171","msg":"trace[1990507617] transaction","detail":"{read_only:false; response_revision:1181; number_of_response:1; }","duration":"2.302503995s","start":"2026-02-07T04:07:44.915186Z","end":"2026-02-07T04:07:47.21769Z","steps":["trace[1990507617] 'process raft request' (duration: 2.302355981s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:07:47.21777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:07:44.915175Z","time spent":"2.302535386s","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T04:07:47.217896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.105821943s","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-07T04:07:47.21791Z","caller":"traceutil/trace.go:171","msg":"trace[1687378178] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1181; }","duration":"2.105876495s","start":"2026-02-07T04:07:45.112029Z","end":"2026-02-07T04:07:47.217906Z","steps":["trace[1687378178] 'agreement among raft nodes before linearized reading' (duration: 2.105813063s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:07:47.217924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:07:45.112011Z","time spent":"2.105910525s","remote":"127.0.0.1:60630","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-07T04:07:47.217987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.104657381s","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-07T04:07:47.217998Z","caller":"traceutil/trace.go:171","msg":"trace[317998258] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1181; }","duration":"2.104689981s","start":"2026-02-07T04:07:45.113304Z","end":"2026-02-07T04:07:47.217994Z","steps":["trace[317998258] 'agreement among raft nodes before linearized reading' (duration: 2.10465616s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:07:47.218011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:07:45.113293Z","time spent":"2.104712883s","remote":"127.0.0.1:60630","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-07T04:07:47.218119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.425655554s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:07:47.218144Z","caller":"traceutil/trace.go:171","msg":"trace[543323528] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1181; }","duration":"1.425706354s","start":"2026-02-07T04:07:45.792433Z","end":"2026-02-07T04:07:47.21814Z","steps":["trace[543323528] 'agreement among raft nodes before linearized reading' (duration: 1.425662853s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:07:47.218156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:07:45.792419Z","time spent":"1.425734596s","remote":"127.0.0.1:60362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-07T04:07:47.218187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.032904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-07T04:07:47.218213Z","caller":"traceutil/trace.go:171","msg":"trace[709992429] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1181; }","duration":"340.105245ms","start":"2026-02-07T04:07:46.878097Z","end":"2026-02-07T04:07:47.218202Z","steps":["trace[709992429] 'agreement among raft nodes before linearized reading' (duration: 340.048744ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:07:47.218231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.448955461s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-07T04:07:47.218233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:07:46.878085Z","time spent":"340.143887ms","remote":"127.0.0.1:60702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":68,"response size":31,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"info","ts":"2026-02-07T04:07:47.218246Z","caller":"traceutil/trace.go:171","msg":"trace[523888729] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1181; }","duration":"1.448999061s","start":"2026-02-07T04:07:45.769243Z","end":"2026-02-07T04:07:47.218242Z","steps":["trace[523888729] 'agreement among raft nodes before linearized reading' (duration: 1.44896852s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:07:47.218261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:07:45.769227Z","time spent":"1.449030823s","remote":"127.0.0.1:60804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":31,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-07T04:07:47.218341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.668043255s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-07T04:07:47.218353Z","caller":"traceutil/trace.go:171","msg":"trace[1718879614] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1181; }","duration":"1.668057075s","start":"2026-02-07T04:07:45.550292Z","end":"2026-02-07T04:07:47.218349Z","steps":["trace[1718879614] 'agreement among raft nodes before linearized reading' (duration: 1.668027584s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:07:47.218359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.439247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.52\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-02-07T04:07:47.218367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:07:45.550232Z","time spent":"1.668131328s","remote":"127.0.0.1:60456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":15,"response size":31,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"info","ts":"2026-02-07T04:07:47.218374Z","caller":"traceutil/trace.go:171","msg":"trace[1406110116] range","detail":"{range_begin:/registry/masterleases/162.253.55.52; range_end:; response_count:1; response_revision:1181; }","duration":"433.485339ms","start":"2026-02-07T04:07:46.784883Z","end":"2026-02-07T04:07:47.218368Z","steps":["trace[1406110116] 'agreement among raft nodes before linearized reading' (duration: 433.444748ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:07:47.218387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:07:46.784868Z","time spent":"433.516409ms","remote":"127.0.0.1:60390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/162.253.55.52\" "} {"level":"warn","ts":"2026-02-07T04:07:47.218486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.965885627s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:78282"} {"level":"info","ts":"2026-02-07T04:07:47.218501Z","caller":"traceutil/trace.go:171","msg":"trace[1499855863] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1181; }","duration":"1.965920797s","start":"2026-02-07T04:07:45.252576Z","end":"2026-02-07T04:07:47.218497Z","steps":["trace[1499855863] 'agreement among raft nodes before linearized reading' (duration: 1.965862336s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:07:47.218512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:07:45.252565Z","time spent":"1.965944379s","remote":"127.0.0.1:60404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":78306,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"warn","ts":"2026-02-07T04:07:47.218531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"809.042989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-02-07T04:07:47.218545Z","caller":"traceutil/trace.go:171","msg":"trace[603558556] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1181; }","duration":"809.084289ms","start":"2026-02-07T04:07:46.409456Z","end":"2026-02-07T04:07:47.218541Z","steps":["trace[603558556] 'agreement among raft nodes before linearized reading' (duration: 809.039408ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:07:47.218557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:07:46.409441Z","time spent":"809.112811ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-02-07T04:07:47.218595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.976944064s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-02-07T04:07:47.21861Z","caller":"traceutil/trace.go:171","msg":"trace[1221333731] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1181; }","duration":"1.976980285s","start":"2026-02-07T04:07:45.241625Z","end":"2026-02-07T04:07:47.218605Z","steps":["trace[1221333731] 'agreement among raft nodes before linearized reading' (duration: 1.976955875s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:07:47.218621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:07:45.241613Z","time spent":"1.977005836s","remote":"127.0.0.1:60418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":142,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"warn","ts":"2026-02-07T04:07:47.218626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"865.773414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-07T04:07:47.21864Z","caller":"traceutil/trace.go:171","msg":"trace[2057047585] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1181; }","duration":"865.838246ms","start":"2026-02-07T04:07:46.352798Z","end":"2026-02-07T04:07:47.218636Z","steps":["trace[2057047585] 'agreement among raft nodes before linearized reading' (duration: 865.815796ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:07:47.218652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:07:46.352779Z","time spent":"865.870127ms","remote":"127.0.0.1:60834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":4,"response size":31,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-02-07T04:07:47.218703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.09923667s","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-07T04:07:47.218717Z","caller":"traceutil/trace.go:171","msg":"trace[739192097] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1181; }","duration":"2.099276091s","start":"2026-02-07T04:07:45.119437Z","end":"2026-02-07T04:07:47.218713Z","steps":["trace[739192097] 'agreement among raft nodes before linearized reading' (duration: 2.09923971s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:07:47.218728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:07:45.119421Z","time spent":"2.099304502s","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-07T04:07:47.218792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.102673325s","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-07T04:07:47.218804Z","caller":"traceutil/trace.go:171","msg":"trace[1006249920] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1181; }","duration":"2.102700217s","start":"2026-02-07T04:07:45.116101Z","end":"2026-02-07T04:07:47.218801Z","steps":["trace[1006249920] 'agreement among raft nodes before linearized reading' (duration: 2.102666536s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:07:47.218816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:07:45.116094Z","time spent":"2.102718767s","remote":"127.0.0.1:60630","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-07T04:07:47.218106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.261147305s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:527"} {"level":"info","ts":"2026-02-07T04:07:47.218849Z","caller":"traceutil/trace.go:171","msg":"trace[1838275358] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1181; }","duration":"1.261937406s","start":"2026-02-07T04:07:45.956907Z","end":"2026-02-07T04:07:47.218845Z","steps":["trace[1838275358] 'agreement among raft nodes before linearized reading' (duration: 1.261113723s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:07:47.218861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:07:45.95689Z","time spent":"1.261967017s","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":551,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-07T04:07:47.218486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"725.696724ms","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-07T04:07:47.218959Z","caller":"traceutil/trace.go:171","msg":"trace[1386098719] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1181; }","duration":"726.193078ms","start":"2026-02-07T04:07:46.49276Z","end":"2026-02-07T04:07:47.218953Z","steps":["trace[1386098719] 'agreement among raft nodes before linearized reading' (duration: 725.710895ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:07:47.218974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:07:46.492749Z","time spent":"726.220158ms","remote":"127.0.0.1:60430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"info","ts":"2026-02-07T04:07:47.614956Z","caller":"traceutil/trace.go:171","msg":"trace[1782163190] linearizableReadLoop","detail":"{readStateIndex:1231; appliedIndex:1230; }","duration":"371.607699ms","start":"2026-02-07T04:07:47.243329Z","end":"2026-02-07T04:07:47.614937Z","steps":["trace[1782163190] 'read index received' (duration: 293.266894ms)","trace[1782163190] 'applied index is now lower than readState.Index' (duration: 78.339835ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:07:47.615052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:07:47.22269Z","time spent":"392.359826ms","remote":"127.0.0.1:60390","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-07T04:07:47.615265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.906678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:07:47.615298Z","caller":"traceutil/trace.go:171","msg":"trace[986941433] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1181; }","duration":"371.99051ms","start":"2026-02-07T04:07:47.243299Z","end":"2026-02-07T04:07:47.61529Z","steps":["trace[986941433] 'agreement among raft nodes before linearized reading' (duration: 371.910118ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:07:47.615319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:07:47.243285Z","time spent":"372.028441ms","remote":"127.0.0.1:60368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-07T04:07:47.69018Z","caller":"traceutil/trace.go:171","msg":"trace[528909608] transaction","detail":"{read_only:false; response_revision:1187; number_of_response:1; }","duration":"401.973392ms","start":"2026-02-07T04:07:47.288188Z","end":"2026-02-07T04:07:47.690161Z","steps":["trace[528909608] 'process raft request' (duration: 401.941101ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:07:47.6903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:07:47.288185Z","time spent":"402.056165ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:07:47.690631Z","caller":"traceutil/trace.go:171","msg":"trace[1621250045] transaction","detail":"{read_only:false; response_revision:1182; number_of_response:1; }","duration":"402.775155ms","start":"2026-02-07T04:07:47.287845Z","end":"2026-02-07T04:07:47.69062Z","steps":["trace[1621250045] 'process raft request' (duration: 402.018174ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:07:47.690694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:07:47.287837Z","time spent":"402.826586ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:07:47.690822Z","caller":"traceutil/trace.go:171","msg":"trace[344275099] transaction","detail":"{read_only:false; response_revision:1183; number_of_response:1; }","duration":"402.747363ms","start":"2026-02-07T04:07:47.288066Z","end":"2026-02-07T04:07:47.690813Z","steps":["trace[344275099] 'process raft request' (duration: 401.949891ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:07:47.690889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:07:47.288059Z","time spent":"402.789125ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:07:47.691109Z","caller":"traceutil/trace.go:171","msg":"trace[1620972906] transaction","detail":"{read_only:false; response_revision:1184; number_of_response:1; }","duration":"402.986131ms","start":"2026-02-07T04:07:47.288095Z","end":"2026-02-07T04:07:47.691081Z","steps":["trace[1620972906] 'process raft request' (duration: 401.952842ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:07:47.691203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:07:47.288092Z","time spent":"403.058333ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:07:47.691287Z","caller":"traceutil/trace.go:171","msg":"trace[1831040982] transaction","detail":"{read_only:false; response_revision:1185; number_of_response:1; }","duration":"403.158965ms","start":"2026-02-07T04:07:47.288123Z","end":"2026-02-07T04:07:47.691282Z","steps":["trace[1831040982] 'process raft request' (duration: 401.952512ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:07:47.691309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:07:47.288119Z","time spent":"403.176836ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:07:47.691343Z","caller":"traceutil/trace.go:171","msg":"trace[872218859] transaction","detail":"{read_only:false; response_revision:1186; number_of_response:1; }","duration":"403.178666ms","start":"2026-02-07T04:07:47.288161Z","end":"2026-02-07T04:07:47.691339Z","steps":["trace[872218859] 'process raft request' (duration: 401.942382ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:07:47.691364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:07:47.288156Z","time spent":"403.196086ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":512,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T04:07:47.691556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.602688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" ","response":"range_response_count:4 size:4278"} {"level":"info","ts":"2026-02-07T04:07:47.691573Z","caller":"traceutil/trace.go:171","msg":"trace[933985302] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:4; response_revision:1187; }","duration":"403.626918ms","start":"2026-02-07T04:07:47.287941Z","end":"2026-02-07T04:07:47.691568Z","steps":["trace[933985302] 'agreement among raft nodes before linearized reading' (duration: 403.515575ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:07:47.691586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:07:47.287937Z","time spent":"403.645979ms","remote":"127.0.0.1:60562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":4,"response size":4302,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" "} {"level":"warn","ts":"2026-02-07T04:07:47.691758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.951168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" ","response":"range_response_count:4 size:4278"} {"level":"info","ts":"2026-02-07T04:07:47.691773Z","caller":"traceutil/trace.go:171","msg":"trace[1217069034] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:4; response_revision:1187; }","duration":"403.983358ms","start":"2026-02-07T04:07:47.287784Z","end":"2026-02-07T04:07:47.691767Z","steps":["trace[1217069034] 'agreement among raft nodes before linearized reading' (duration: 403.938727ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:07:47.691785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:07:47.28777Z","time spent":"404.011199ms","remote":"127.0.0.1:60562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":4,"response size":4302,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" "} {"level":"warn","ts":"2026-02-07T04:07:47.996567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.006245ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T04:07:47.996746Z","caller":"traceutil/trace.go:171","msg":"trace[587223974] transaction","detail":"{read_only:false; response_revision:1189; number_of_response:1; }","duration":"127.055798ms","start":"2026-02-07T04:07:47.869674Z","end":"2026-02-07T04:07:47.99673Z","steps":["trace[587223974] 'process raft request' (duration: 127.000407ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:07:47.996953Z","caller":"traceutil/trace.go:171","msg":"trace[532399512] transaction","detail":"{read_only:false; response_revision:1188; number_of_response:1; }","duration":"372.811213ms","start":"2026-02-07T04:07:47.624131Z","end":"2026-02-07T04:07:47.996942Z","steps":["trace[532399512] 'process raft request' (duration: 181.360377ms)","trace[532399512] 'compare' (duration: 190.711416ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:07:47.997013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:07:47.62411Z","time spent":"372.870325ms","remote":"127.0.0.1:60390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:07:47.997155Z","caller":"traceutil/trace.go:171","msg":"trace[2118744037] linearizableReadLoop","detail":"{readStateIndex:1238; appliedIndex:1237; }","duration":"305.69955ms","start":"2026-02-07T04:07:47.691446Z","end":"2026-02-07T04:07:47.997146Z","steps":["trace[2118744037] 'read index received' (duration: 114.027897ms)","trace[2118744037] 'applied index is now lower than readState.Index' (duration: 191.670673ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:07:47.997493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.030564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:510"} {"level":"info","ts":"2026-02-07T04:07:47.997531Z","caller":"traceutil/trace.go:171","msg":"trace[281104749] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1189; }","duration":"317.094326ms","start":"2026-02-07T04:07:47.680427Z","end":"2026-02-07T04:07:47.997521Z","steps":["trace[281104749] 'agreement among raft nodes before linearized reading' (duration: 316.982353ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:07:47.997561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:07:47.680412Z","time spent":"317.142097ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":534,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-07T04:07:47.998174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.298693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:07:47.998213Z","caller":"traceutil/trace.go:171","msg":"trace[34752451] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1189; }","duration":"370.370185ms","start":"2026-02-07T04:07:47.627833Z","end":"2026-02-07T04:07:47.998203Z","steps":["trace[34752451] 'agreement among raft nodes before linearized reading' (duration: 370.278593ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:07:47.998245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:07:47.627795Z","time spent":"370.445097ms","remote":"127.0.0.1:60362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-07T04:07:47.998549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.338885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:78282"} {"level":"info","ts":"2026-02-07T04:07:47.998796Z","caller":"traceutil/trace.go:171","msg":"trace[871637861] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1189; }","duration":"303.585961ms","start":"2026-02-07T04:07:47.695199Z","end":"2026-02-07T04:07:47.998785Z","steps":["trace[871637861] 'agreement among raft nodes before linearized reading' (duration: 303.264002ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:07:47.998833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:07:47.695178Z","time spent":"303.646002ms","remote":"127.0.0.1:60404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":78306,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"warn","ts":"2026-02-07T04:07:48.000172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.902988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-02-07T04:07:48.000222Z","caller":"traceutil/trace.go:171","msg":"trace[1650429585] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1189; }","duration":"304.964239ms","start":"2026-02-07T04:07:47.695247Z","end":"2026-02-07T04:07:48.000212Z","steps":["trace[1650429585] 'agreement among raft nodes before linearized reading' (duration: 303.868019ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:07:48.000902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:07:47.695244Z","time spent":"305.648228ms","remote":"127.0.0.1:60418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":142,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"info","ts":"2026-02-07T04:07:54.691856Z","caller":"traceutil/trace.go:171","msg":"trace[2037982253] linearizableReadLoop","detail":"{readStateIndex:1265; appliedIndex:1264; }","duration":"224.851336ms","start":"2026-02-07T04:07:54.466988Z","end":"2026-02-07T04:07:54.691839Z","steps":["trace[2037982253] 'read index received' (duration: 224.752254ms)","trace[2037982253] 'applied index is now lower than readState.Index' (duration: 98.342µs)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:07:54.692028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.020481ms","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-07T04:07:54.692056Z","caller":"traceutil/trace.go:171","msg":"trace[1387608975] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1214; }","duration":"225.087602ms","start":"2026-02-07T04:07:54.466959Z","end":"2026-02-07T04:07:54.692047Z","steps":["trace[1387608975] 'agreement among raft nodes before linearized reading' (duration: 224.962809ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:07:54.692062Z","caller":"traceutil/trace.go:171","msg":"trace[1092269212] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"229.351569ms","start":"2026-02-07T04:07:54.462676Z","end":"2026-02-07T04:07:54.692027Z","steps":["trace[1092269212] 'process raft request' (duration: 229.04822ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:07:54.917472Z","caller":"traceutil/trace.go:171","msg":"trace[770294875] linearizableReadLoop","detail":"{readStateIndex:1266; appliedIndex:1265; }","duration":"123.242813ms","start":"2026-02-07T04:07:54.794211Z","end":"2026-02-07T04:07:54.917454Z","steps":["trace[770294875] 'read index received' (duration: 34.321396ms)","trace[770294875] 'applied index is now lower than readState.Index' (duration: 88.920747ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:07:54.917552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.326097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:07:54.917577Z","caller":"traceutil/trace.go:171","msg":"trace[2105978264] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1215; }","duration":"123.378337ms","start":"2026-02-07T04:07:54.794186Z","end":"2026-02-07T04:07:54.917564Z","steps":["trace[2105978264] 'agreement among raft nodes before linearized reading' (duration: 123.328046ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:07:54.917697Z","caller":"traceutil/trace.go:171","msg":"trace[347775588] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"220.875609ms","start":"2026-02-07T04:07:54.696815Z","end":"2026-02-07T04:07:54.91769Z","steps":["trace[347775588] 'process raft request' (duration: 131.758067ms)","trace[347775588] 'compare' (duration: 88.658309ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:08:19.874102Z","caller":"traceutil/trace.go:171","msg":"trace[1883487973] transaction","detail":"{read_only:false; response_revision:1315; number_of_response:1; }","duration":"141.719648ms","start":"2026-02-07T04:08:19.731824Z","end":"2026-02-07T04:08:19.873544Z","steps":["trace[1883487973] 'process raft request' (duration: 141.255515ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:08:32.160919Z","caller":"traceutil/trace.go:171","msg":"trace[860356458] transaction","detail":"{read_only:false; response_revision:1365; number_of_response:1; }","duration":"118.432352ms","start":"2026-02-07T04:08:32.04246Z","end":"2026-02-07T04:08:32.160893Z","steps":["trace[860356458] 'process raft request' (duration: 68.080228ms)","trace[860356458] 'compare' (duration: 50.260312ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:08:40.35847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.469744ms","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-07T04:08:40.35853Z","caller":"traceutil/trace.go:171","msg":"trace[1622102291] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1399; }","duration":"118.577827ms","start":"2026-02-07T04:08:40.239939Z","end":"2026-02-07T04:08:40.358517Z","steps":["trace[1622102291] 'range keys from in-memory index tree' (duration: 118.320149ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:40.35871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.833219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-02-07T04:08:40.358729Z","caller":"traceutil/trace.go:171","msg":"trace[1209854424] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1399; }","duration":"105.88071ms","start":"2026-02-07T04:08:40.252843Z","end":"2026-02-07T04:08:40.358724Z","steps":["trace[1209854424] 'range keys from in-memory index tree' (duration: 105.747006ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:46.726478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.177883ms","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-07T04:08:46.726557Z","caller":"traceutil/trace.go:171","msg":"trace[615281495] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1423; }","duration":"145.283125ms","start":"2026-02-07T04:08:46.581255Z","end":"2026-02-07T04:08:46.726538Z","steps":["trace[615281495] 'range keys from in-memory index tree' (duration: 145.018989ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:46.726958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.369384ms","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-07T04:08:46.727009Z","caller":"traceutil/trace.go:171","msg":"trace[1658491216] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1423; }","duration":"150.488307ms","start":"2026-02-07T04:08:46.576495Z","end":"2026-02-07T04:08:46.726983Z","steps":["trace[1658491216] 'range keys from in-memory index tree' (duration: 150.244701ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:46.727377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.251128ms","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-07T04:08:46.727399Z","caller":"traceutil/trace.go:171","msg":"trace[789344538] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1423; }","duration":"144.286368ms","start":"2026-02-07T04:08:46.583105Z","end":"2026-02-07T04:08:46.727391Z","steps":["trace[789344538] 'range keys from in-memory index tree' (duration: 144.193676ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:08:46.834365Z","caller":"traceutil/trace.go:171","msg":"trace[2014243237] transaction","detail":"{read_only:false; response_revision:1424; number_of_response:1; }","duration":"100.04534ms","start":"2026-02-07T04:08:46.734292Z","end":"2026-02-07T04:08:46.834337Z","steps":["trace[2014243237] 'process raft request' (duration: 99.410583ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:08:46.855506Z","caller":"traceutil/trace.go:171","msg":"trace[1169124272] transaction","detail":"{read_only:false; response_revision:1425; number_of_response:1; }","duration":"119.956014ms","start":"2026-02-07T04:08:46.735519Z","end":"2026-02-07T04:08:46.855475Z","steps":["trace[1169124272] 'process raft request' (duration: 119.313356ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:08:46.855646Z","caller":"traceutil/trace.go:171","msg":"trace[158512870] transaction","detail":"{read_only:false; response_revision:1426; number_of_response:1; }","duration":"120.046966ms","start":"2026-02-07T04:08:46.735579Z","end":"2026-02-07T04:08:46.855626Z","steps":["trace[158512870] 'process raft request' (duration: 119.383998ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:55.150325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.228788ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T04:08:55.150446Z","caller":"traceutil/trace.go:171","msg":"trace[1951746728] linearizableReadLoop","detail":"{readStateIndex:1522; appliedIndex:1520; }","duration":"186.028716ms","start":"2026-02-07T04:08:54.964405Z","end":"2026-02-07T04:08:55.150434Z","steps":["trace[1951746728] 'read index received' (duration: 57.636994ms)","trace[1951746728] 'applied index is now lower than readState.Index' (duration: 128.391062ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:08:55.150604Z","caller":"traceutil/trace.go:171","msg":"trace[269456720] transaction","detail":"{read_only:false; response_revision:1458; number_of_response:1; }","duration":"218.735393ms","start":"2026-02-07T04:08:54.93186Z","end":"2026-02-07T04:08:55.150595Z","steps":["trace[269456720] 'process raft request' (duration: 90.172696ms)","trace[269456720] 'compare' (duration: 128.110425ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:08:55.150731Z","caller":"traceutil/trace.go:171","msg":"trace[186084161] transaction","detail":"{read_only:false; response_revision:1459; number_of_response:1; }","duration":"192.783539ms","start":"2026-02-07T04:08:54.95794Z","end":"2026-02-07T04:08:55.150724Z","steps":["trace[186084161] 'process raft request' (duration: 192.45411ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:55.15087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.466878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-02-07T04:08:55.150894Z","caller":"traceutil/trace.go:171","msg":"trace[409875918] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1459; }","duration":"186.512639ms","start":"2026-02-07T04:08:54.964375Z","end":"2026-02-07T04:08:55.150887Z","steps":["trace[409875918] 'agreement among raft nodes before linearized reading' (duration: 186.428717ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:08:58.180571Z","caller":"traceutil/trace.go:171","msg":"trace[165341140] linearizableReadLoop","detail":"{readStateIndex:1533; appliedIndex:1532; }","duration":"200.965082ms","start":"2026-02-07T04:08:57.979583Z","end":"2026-02-07T04:08:58.180548Z","steps":["trace[165341140] 'read index received' (duration: 200.794017ms)","trace[165341140] 'applied index is now lower than readState.Index' (duration: 170.195µs)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:08:58.180773Z","caller":"traceutil/trace.go:171","msg":"trace[590882563] transaction","detail":"{read_only:false; response_revision:1469; number_of_response:1; }","duration":"308.506779ms","start":"2026-02-07T04:08:57.872254Z","end":"2026-02-07T04:08:58.180761Z","steps":["trace[590882563] 'process raft request' (duration: 308.145969ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:58.180858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:08:57.872239Z","time spent":"308.56361ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T04:08:58.18093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.311431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:527"} {"level":"info","ts":"2026-02-07T04:08:58.181013Z","caller":"traceutil/trace.go:171","msg":"trace[1176749435] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1469; }","duration":"201.499866ms","start":"2026-02-07T04:08:57.979498Z","end":"2026-02-07T04:08:58.180998Z","steps":["trace[1176749435] 'agreement among raft nodes before linearized reading' (duration: 201.227209ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:08:58.737483Z","caller":"traceutil/trace.go:171","msg":"trace[650529336] linearizableReadLoop","detail":"{readStateIndex:1534; appliedIndex:1533; }","duration":"446.195153ms","start":"2026-02-07T04:08:58.291262Z","end":"2026-02-07T04:08:58.737457Z","steps":["trace[650529336] 'read index received' (duration: 355.438221ms)","trace[650529336] 'applied index is now lower than readState.Index' (duration: 90.755672ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:08:58.737792Z","caller":"traceutil/trace.go:171","msg":"trace[1337927993] transaction","detail":"{read_only:false; response_revision:1470; number_of_response:1; }","duration":"552.209159ms","start":"2026-02-07T04:08:58.185559Z","end":"2026-02-07T04:08:58.737768Z","steps":["trace[1337927993] 'process raft request' (duration: 461.18925ms)","trace[1337927993] 'compare' (duration: 90.466164ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:08:58.737915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:08:58.185541Z","time spent":"552.293341ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":512,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T04:08:58.738218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.946104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-02-07T04:08:58.738264Z","caller":"traceutil/trace.go:171","msg":"trace[707824998] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1470; }","duration":"447.031796ms","start":"2026-02-07T04:08:58.291218Z","end":"2026-02-07T04:08:58.73825Z","steps":["trace[707824998] 'agreement among raft nodes before linearized reading' (duration: 446.892652ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:58.738308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:08:58.291186Z","time spent":"447.106768ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-07T04:08:58.73919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.608319ms","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-07T04:08:58.739379Z","caller":"traceutil/trace.go:171","msg":"trace[1558450389] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1470; }","duration":"347.835275ms","start":"2026-02-07T04:08:58.391527Z","end":"2026-02-07T04:08:58.739362Z","steps":["trace[1558450389] 'agreement among raft nodes before linearized reading' (duration: 347.567608ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:58.739625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:08:58.391507Z","time spent":"348.033341ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-07T04:08:58.740082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.354969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-02-07T04:08:58.740215Z","caller":"traceutil/trace.go:171","msg":"trace[126754071] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1470; }","duration":"129.521394ms","start":"2026-02-07T04:08:58.610682Z","end":"2026-02-07T04:08:58.740203Z","steps":["trace[126754071] 'agreement among raft nodes before linearized reading' (duration: 129.335789ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:08:59.007365Z","caller":"traceutil/trace.go:171","msg":"trace[511463212] linearizableReadLoop","detail":"{readStateIndex:1537; appliedIndex:1536; }","duration":"209.144593ms","start":"2026-02-07T04:08:58.798204Z","end":"2026-02-07T04:08:59.007348Z","steps":["trace[511463212] 'read index received' (duration: 35.997806ms)","trace[511463212] 'applied index is now lower than readState.Index' (duration: 173.146067ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:08:59.007538Z","caller":"traceutil/trace.go:171","msg":"trace[1074702690] transaction","detail":"{read_only:false; response_revision:1473; number_of_response:1; }","duration":"258.763749ms","start":"2026-02-07T04:08:58.748765Z","end":"2026-02-07T04:08:59.007528Z","steps":["trace[1074702690] 'process raft request' (duration: 207.55917ms)","trace[1074702690] 'compare' (duration: 50.745216ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:08:59.007681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.466111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:08:59.007735Z","caller":"traceutil/trace.go:171","msg":"trace[816167378] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1473; }","duration":"209.556404ms","start":"2026-02-07T04:08:58.798172Z","end":"2026-02-07T04:08:59.007728Z","steps":["trace[816167378] 'agreement among raft nodes before linearized reading' (duration: 209.479702ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:09:45.428044Z","caller":"traceutil/trace.go:171","msg":"trace[1631801370] linearizableReadLoop","detail":"{readStateIndex:1735; appliedIndex:1734; }","duration":"138.877347ms","start":"2026-02-07T04:09:45.289148Z","end":"2026-02-07T04:09:45.428025Z","steps":["trace[1631801370] 'read index received' (duration: 138.698083ms)","trace[1631801370] 'applied index is now lower than readState.Index' (duration: 159.554µs)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:09:45.428151Z","caller":"traceutil/trace.go:171","msg":"trace[415563902] transaction","detail":"{read_only:false; response_revision:1662; number_of_response:1; }","duration":"151.985933ms","start":"2026-02-07T04:09:45.276153Z","end":"2026-02-07T04:09:45.428139Z","steps":["trace[415563902] 'process raft request' (duration: 151.733556ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:09:45.428649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.181137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-02-07T04:09:45.42878Z","caller":"traceutil/trace.go:171","msg":"trace[1271491194] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1662; }","duration":"135.31713ms","start":"2026-02-07T04:09:45.293445Z","end":"2026-02-07T04:09:45.428762Z","steps":["trace[1271491194] 'agreement among raft nodes before linearized reading' (duration: 135.084044ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:09:45.429097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.940516ms","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-07T04:09:45.429184Z","caller":"traceutil/trace.go:171","msg":"trace[433949280] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1662; }","duration":"140.04684ms","start":"2026-02-07T04:09:45.289125Z","end":"2026-02-07T04:09:45.429172Z","steps":["trace[433949280] 'agreement among raft nodes before linearized reading' (duration: 139.903776ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:09:45.668064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.669875ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T04:09:45.668155Z","caller":"traceutil/trace.go:171","msg":"trace[165328402] linearizableReadLoop","detail":"{readStateIndex:1739; appliedIndex:1738; }","duration":"163.839103ms","start":"2026-02-07T04:09:45.5043Z","end":"2026-02-07T04:09:45.668139Z","steps":["trace[165328402] 'read index received' (duration: 27.87µs)","trace[165328402] 'applied index is now lower than readState.Index' (duration: 163.810173ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:09:45.668622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.318126ms","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-07T04:09:45.668666Z","caller":"traceutil/trace.go:171","msg":"trace[1596964893] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1666; }","duration":"164.45838ms","start":"2026-02-07T04:09:45.504197Z","end":"2026-02-07T04:09:45.668655Z","steps":["trace[1596964893] 'agreement among raft nodes before linearized reading' (duration: 163.984847ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:09:45.669057Z","caller":"traceutil/trace.go:171","msg":"trace[1216044750] transaction","detail":"{read_only:false; response_revision:1666; number_of_response:1; }","duration":"168.772107ms","start":"2026-02-07T04:09:45.500239Z","end":"2026-02-07T04:09:45.669012Z","steps":["trace[1216044750] 'compare' (duration: 164.447249ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:10:17.005257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.355838ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-02-07T04:10:17.00694Z","caller":"traceutil/trace.go:171","msg":"trace[1635171191] linearizableReadLoop","detail":"{readStateIndex:1902; appliedIndex:1901; }","duration":"211.842622ms","start":"2026-02-07T04:10:16.795086Z","end":"2026-02-07T04:10:17.006928Z","steps":["trace[1635171191] 'read index received' (duration: 48.743046ms)","trace[1635171191] 'applied index is now lower than readState.Index' (duration: 163.098536ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:10:17.007073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.978106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:10:17.007137Z","caller":"traceutil/trace.go:171","msg":"trace[1584114690] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1821; }","duration":"212.058187ms","start":"2026-02-07T04:10:16.795071Z","end":"2026-02-07T04:10:17.00713Z","steps":["trace[1584114690] 'agreement among raft nodes before linearized reading' (duration: 211.938384ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:10:18.413981Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594529008893170140,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-07T04:10:18.545639Z","caller":"traceutil/trace.go:171","msg":"trace[545185256] linearizableReadLoop","detail":"{readStateIndex:1906; appliedIndex:1905; }","duration":"632.555854ms","start":"2026-02-07T04:10:17.913065Z","end":"2026-02-07T04:10:18.545621Z","steps":["trace[545185256] 'read index received' (duration: 583.573323ms)","trace[545185256] 'applied index is now lower than readState.Index' (duration: 48.981841ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:10:18.547137Z","caller":"traceutil/trace.go:171","msg":"trace[348886359] transaction","detail":"{read_only:false; response_revision:1825; number_of_response:1; }","duration":"829.231582ms","start":"2026-02-07T04:10:17.717876Z","end":"2026-02-07T04:10:18.547108Z","steps":["trace[348886359] 'process raft request' (duration: 778.828302ms)","trace[348886359] 'compare' (duration: 48.707523ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:10:18.547271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:10:17.717855Z","time spent":"829.337734ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T04:10:18.547552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"634.484556ms","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-07T04:10:18.547575Z","caller":"traceutil/trace.go:171","msg":"trace[1056431501] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1825; }","duration":"634.532747ms","start":"2026-02-07T04:10:17.913035Z","end":"2026-02-07T04:10:18.547568Z","steps":["trace[1056431501] 'agreement among raft nodes before linearized reading' (duration: 634.426514ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:10:18.547593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:10:17.913021Z","time spent":"634.568749ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-07T04:10:18.547714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.680294ms","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-07T04:10:18.54773Z","caller":"traceutil/trace.go:171","msg":"trace[502487791] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1825; }","duration":"633.708625ms","start":"2026-02-07T04:10:17.914016Z","end":"2026-02-07T04:10:18.547725Z","steps":["trace[502487791] 'agreement among raft nodes before linearized reading' (duration: 633.657454ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:10:18.547745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:10:17.914009Z","time spent":"633.732446ms","remote":"127.0.0.1:60630","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\" "}