{"level":"warn","ts":"2026-01-29T20:23:25.204648Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-29T20:23:25.20482Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.218: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.218:2380","--initial-cluster=instance=https://162.253.55.218:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.218:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.218: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-01-29T20:23:25.20493Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-29T20:23:25.204958Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.218:2380"]} {"level":"info","ts":"2026-01-29T20:23:25.204998Z","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-01-29T20:23:25.205623Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.218:2379"]} {"level":"info","ts":"2026-01-29T20:23:25.205788Z","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.218:2380"],"listen-peer-urls":["https://162.253.55.218:2380"],"advertise-client-urls":["https://162.253.55.218:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.218:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.218: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-01-29T20:23:25.28241Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"75.03383ms"} {"level":"info","ts":"2026-01-29T20:23:25.502312Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"bb7d59f4041c8abe","cluster-id":"5330ca29224af3c4"} {"level":"info","ts":"2026-01-29T20:23:25.502998Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe switched to configuration voters=()"} {"level":"info","ts":"2026-01-29T20:23:25.50311Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe became follower at term 0"} {"level":"info","ts":"2026-01-29T20:23:25.50316Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft bb7d59f4041c8abe [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-01-29T20:23:25.503231Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe became follower at term 1"} {"level":"info","ts":"2026-01-29T20:23:25.503305Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe switched to configuration voters=(13510053361757227710)"} {"level":"warn","ts":"2026-01-29T20:23:25.566874Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-01-29T20:23:25.636958Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-01-29T20:23:25.896504Z","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-01-29T20:23:26.067335Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"bb7d59f4041c8abe","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-01-29T20:23:26.069095Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"bb7d59f4041c8abe","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"warn","ts":"2026-01-29T20:23:26.069269Z","logger":"raft","caller":"etcdserver/zap_raft.go:85","msg":"bb7d59f4041c8abe cannot campaign at term 1 since there are still 1 pending configuration changes to apply"} {"level":"info","ts":"2026-01-29T20:23:26.069468Z","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-01-29T20:23:26.069535Z","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-01-29T20:23:26.069558Z","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-01-29T20:23:26.071364Z","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-01-29T20:23:26.072077Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.218:2380"} {"level":"info","ts":"2026-01-29T20:23:26.072125Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.218:2380"} {"level":"info","ts":"2026-01-29T20:23:26.072152Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"bb7d59f4041c8abe","initial-advertise-peer-urls":["https://162.253.55.218:2380"],"listen-peer-urls":["https://162.253.55.218:2380"],"advertise-client-urls":["https://162.253.55.218:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.218:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-01-29T20:23:26.072474Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-01-29T20:23:26.130412Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe switched to configuration voters=(13510053361757227710)"} {"level":"info","ts":"2026-01-29T20:23:26.130629Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"5330ca29224af3c4","local-member-id":"bb7d59f4041c8abe","added-peer-id":"bb7d59f4041c8abe","added-peer-peer-urls":["https://162.253.55.218:2380"]} {"level":"info","ts":"2026-01-29T20:23:27.004547Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe is starting a new election at term 1"} {"level":"info","ts":"2026-01-29T20:23:27.004648Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe became pre-candidate at term 1"} {"level":"info","ts":"2026-01-29T20:23:27.004697Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe received MsgPreVoteResp from bb7d59f4041c8abe at term 1"} {"level":"info","ts":"2026-01-29T20:23:27.004722Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe became candidate at term 2"} {"level":"info","ts":"2026-01-29T20:23:27.004734Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe received MsgVoteResp from bb7d59f4041c8abe at term 2"} {"level":"info","ts":"2026-01-29T20:23:27.004753Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe became leader at term 2"} {"level":"info","ts":"2026-01-29T20:23:27.004774Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: bb7d59f4041c8abe elected leader bb7d59f4041c8abe at term 2"} {"level":"info","ts":"2026-01-29T20:23:27.009982Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"bb7d59f4041c8abe","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.218:2379]}","request-path":"/0/members/bb7d59f4041c8abe/attributes","cluster-id":"5330ca29224af3c4","publish-timeout":"7s"} {"level":"info","ts":"2026-01-29T20:23:27.010035Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-29T20:23:27.010641Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-01-29T20:23:27.010775Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-29T20:23:27.013892Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.218:2379"} {"level":"info","ts":"2026-01-29T20:23:27.015961Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-01-29T20:23:27.016041Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-01-29T20:23:27.018768Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-01-29T20:23:27.023617Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"5330ca29224af3c4","local-member-id":"bb7d59f4041c8abe","cluster-version":"3.5"} {"level":"info","ts":"2026-01-29T20:23:27.023689Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-01-29T20:23:27.023708Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-01-29T20:23:28.775049Z","caller":"traceutil/trace.go:171","msg":"trace[992375636] linearizableReadLoop","detail":"{readStateIndex:38; appliedIndex:29; }","duration":"103.385047ms","start":"2026-01-29T20:23:28.671632Z","end":"2026-01-29T20:23:28.775017Z","steps":["trace[992375636] 'read index received' (duration: 11.362377ms)","trace[992375636] 'applied index is now lower than readState.Index' (duration: 92.02166ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T20:23:28.775412Z","caller":"traceutil/trace.go:171","msg":"trace[686263519] transaction","detail":"{read_only:false; response_revision:27; number_of_response:1; }","duration":"108.623981ms","start":"2026-01-29T20:23:28.666775Z","end":"2026-01-29T20:23:28.775399Z","steps":["trace[686263519] 'process raft request' (duration: 107.755711ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:23:28.777193Z","caller":"traceutil/trace.go:171","msg":"trace[1781714228] transaction","detail":"{read_only:false; response_revision:28; number_of_response:1; }","duration":"107.197458ms","start":"2026-01-29T20:23:28.669976Z","end":"2026-01-29T20:23:28.777173Z","steps":["trace[1781714228] 'process raft request' (duration: 104.75041ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:23:28.777642Z","caller":"traceutil/trace.go:171","msg":"trace[981429638] transaction","detail":"{read_only:false; response_revision:29; number_of_response:1; }","duration":"107.384972ms","start":"2026-01-29T20:23:28.670244Z","end":"2026-01-29T20:23:28.777629Z","steps":["trace[981429638] 'process raft request' (duration: 104.534535ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:23:28.778253Z","caller":"traceutil/trace.go:171","msg":"trace[677558329] transaction","detail":"{read_only:false; response_revision:30; number_of_response:1; }","duration":"107.769812ms","start":"2026-01-29T20:23:28.67044Z","end":"2026-01-29T20:23:28.77821Z","steps":["trace[677558329] 'process raft request' (duration: 104.370621ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:23:28.778358Z","caller":"traceutil/trace.go:171","msg":"trace[214352072] transaction","detail":"{read_only:false; response_revision:31; number_of_response:1; }","duration":"107.845293ms","start":"2026-01-29T20:23:28.670499Z","end":"2026-01-29T20:23:28.778345Z","steps":["trace[214352072] 'process raft request' (duration: 104.34315ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:23:28.778388Z","caller":"traceutil/trace.go:171","msg":"trace[308483] transaction","detail":"{read_only:false; response_revision:32; number_of_response:1; }","duration":"107.889264ms","start":"2026-01-29T20:23:28.670491Z","end":"2026-01-29T20:23:28.77838Z","steps":["trace[308483] 'process raft request' (duration: 104.377331ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:23:28.778417Z","caller":"traceutil/trace.go:171","msg":"trace[608175361] transaction","detail":"{read_only:false; response_revision:33; number_of_response:1; }","duration":"107.856093ms","start":"2026-01-29T20:23:28.670552Z","end":"2026-01-29T20:23:28.778408Z","steps":["trace[608175361] 'process raft request' (duration: 104.34362ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:23:28.778444Z","caller":"traceutil/trace.go:171","msg":"trace[180644085] transaction","detail":"{read_only:false; response_revision:34; number_of_response:1; }","duration":"107.852793ms","start":"2026-01-29T20:23:28.670584Z","end":"2026-01-29T20:23:28.778437Z","steps":["trace[180644085] 'process raft request' (duration: 104.33798ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:23:28.778659Z","caller":"traceutil/trace.go:171","msg":"trace[254582701] transaction","detail":"{read_only:false; response_revision:35; number_of_response:1; }","duration":"105.17412ms","start":"2026-01-29T20:23:28.673474Z","end":"2026-01-29T20:23:28.778648Z","steps":["trace[254582701] 'process raft request' (duration: 101.474533ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:23:28.778764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.096716ms","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-01-29T20:23:28.785524Z","caller":"traceutil/trace.go:171","msg":"trace[804332223] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:35; }","duration":"113.916696ms","start":"2026-01-29T20:23:28.671591Z","end":"2026-01-29T20:23:28.785508Z","steps":["trace[804332223] 'agreement among raft nodes before linearized reading' (duration: 107.093515ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:23:29.31676Z","caller":"traceutil/trace.go:171","msg":"trace[2024216215] transaction","detail":"{read_only:false; response_revision:74; number_of_response:1; }","duration":"111.893079ms","start":"2026-01-29T20:23:29.204832Z","end":"2026-01-29T20:23:29.316725Z","steps":["trace[2024216215] 'process raft request' (duration: 50.178363ms)","trace[2024216215] 'compare' (duration: 61.290806ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T20:23:29.822996Z","caller":"traceutil/trace.go:171","msg":"trace[352233685] transaction","detail":"{read_only:false; response_revision:104; number_of_response:1; }","duration":"103.713006ms","start":"2026-01-29T20:23:29.719255Z","end":"2026-01-29T20:23:29.822968Z","steps":["trace[352233685] 'process raft request' (duration: 103.644894ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:23:29.823452Z","caller":"traceutil/trace.go:171","msg":"trace[979642417] transaction","detail":"{read_only:false; response_revision:103; number_of_response:1; }","duration":"145.566244ms","start":"2026-01-29T20:23:29.677872Z","end":"2026-01-29T20:23:29.823439Z","steps":["trace[979642417] 'process raft request' (duration: 65.663789ms)","trace[979642417] 'compare' (duration: 79.195138ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T20:23:29.82358Z","caller":"traceutil/trace.go:171","msg":"trace[964141660] linearizableReadLoop","detail":"{readStateIndex:108; appliedIndex:107; }","duration":"137.011172ms","start":"2026-01-29T20:23:29.686558Z","end":"2026-01-29T20:23:29.823569Z","steps":["trace[964141660] 'read index received' (duration: 57.016505ms)","trace[964141660] 'applied index is now lower than readState.Index' (duration: 79.993697ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T20:23:29.823728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.145235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.188f4d544043a0aa\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-01-29T20:23:29.823771Z","caller":"traceutil/trace.go:171","msg":"trace[1211028779] range","detail":"{range_begin:/registry/events/default/instance.188f4d544043a0aa; range_end:; response_count:1; response_revision:104; }","duration":"137.220456ms","start":"2026-01-29T20:23:29.686539Z","end":"2026-01-29T20:23:29.823759Z","steps":["trace[1211028779] 'agreement among raft nodes before linearized reading' (duration: 137.071953ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:23:32.013001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.35272ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-01-29T20:23:32.013259Z","caller":"traceutil/trace.go:171","msg":"trace[965953730] transaction","detail":"{read_only:false; response_revision:220; number_of_response:1; }","duration":"130.13923ms","start":"2026-01-29T20:23:31.883101Z","end":"2026-01-29T20:23:32.013241Z","steps":["trace[965953730] 'process raft request' (duration: 130.053998ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:23:32.013231Z","caller":"traceutil/trace.go:171","msg":"trace[1762320228] transaction","detail":"{read_only:false; response_revision:219; number_of_response:1; }","duration":"130.129429ms","start":"2026-01-29T20:23:31.883062Z","end":"2026-01-29T20:23:32.013191Z","steps":["trace[1762320228] 'process raft request' (duration: 13.526029ms)","trace[1762320228] 'compare' (duration: 115.159325ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T20:23:33.270621Z","caller":"traceutil/trace.go:171","msg":"trace[234266609] transaction","detail":"{read_only:false; response_revision:262; number_of_response:1; }","duration":"189.746005ms","start":"2026-01-29T20:23:33.080847Z","end":"2026-01-29T20:23:33.270593Z","steps":["trace[234266609] 'process raft request' (duration: 148.712977ms)","trace[234266609] 'compare' (duration: 40.556166ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T20:23:33.9623Z","caller":"traceutil/trace.go:171","msg":"trace[1722284672] linearizableReadLoop","detail":"{readStateIndex:281; appliedIndex:280; }","duration":"210.633307ms","start":"2026-01-29T20:23:33.751638Z","end":"2026-01-29T20:23:33.962272Z","steps":["trace[1722284672] 'read index received' (duration: 165.034842ms)","trace[1722284672] 'applied index is now lower than readState.Index' (duration: 45.595575ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T20:23:33.962463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.802411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replication-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T20:23:33.962498Z","caller":"traceutil/trace.go:171","msg":"trace[1158342135] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replication-controller; range_end:; response_count:0; response_revision:275; }","duration":"210.908743ms","start":"2026-01-29T20:23:33.751578Z","end":"2026-01-29T20:23:33.962487Z","steps":["trace[1158342135] 'agreement among raft nodes before linearized reading' (duration: 210.799661ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:23:33.962871Z","caller":"traceutil/trace.go:171","msg":"trace[552825980] transaction","detail":"{read_only:false; response_revision:275; number_of_response:1; }","duration":"261.368164ms","start":"2026-01-29T20:23:33.701489Z","end":"2026-01-29T20:23:33.962857Z","steps":["trace[552825980] 'process raft request' (duration: 215.093433ms)","trace[552825980] 'compare' (duration: 45.563214ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T20:23:34.275152Z","caller":"traceutil/trace.go:171","msg":"trace[17530725] transaction","detail":"{read_only:false; response_revision:282; number_of_response:1; }","duration":"106.966213ms","start":"2026-01-29T20:23:34.16816Z","end":"2026-01-29T20:23:34.275126Z","steps":["trace[17530725] 'process raft request' (duration: 78.83398ms)","trace[17530725] 'compare' (duration: 27.826546ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T20:23:35.014072Z","caller":"traceutil/trace.go:171","msg":"trace[15551249] transaction","detail":"{read_only:false; response_revision:301; number_of_response:1; }","duration":"116.263421ms","start":"2026-01-29T20:23:34.897794Z","end":"2026-01-29T20:23:35.014058Z","steps":["trace[15551249] 'process raft request' (duration: 49.245911ms)","trace[15551249] 'compare' (duration: 66.678942ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T20:23:35.158914Z","caller":"traceutil/trace.go:171","msg":"trace[11847955] transaction","detail":"{read_only:false; response_revision:303; number_of_response:1; }","duration":"111.062739ms","start":"2026-01-29T20:23:35.047821Z","end":"2026-01-29T20:23:35.158884Z","steps":["trace[11847955] 'process raft request' (duration: 110.384413ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:23:35.563678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.238635ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-01-29T20:23:35.563911Z","caller":"traceutil/trace.go:171","msg":"trace[309341848] transaction","detail":"{read_only:false; response_revision:316; number_of_response:1; }","duration":"135.084455ms","start":"2026-01-29T20:23:35.428808Z","end":"2026-01-29T20:23:35.563892Z","steps":["trace[309341848] 'process raft request' (duration: 135.035543ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:23:35.564122Z","caller":"traceutil/trace.go:171","msg":"trace[360000089] transaction","detail":"{read_only:false; response_revision:314; number_of_response:1; }","duration":"183.477866ms","start":"2026-01-29T20:23:35.380633Z","end":"2026-01-29T20:23:35.564111Z","steps":["trace[360000089] 'process raft request' (duration: 48.564925ms)","trace[360000089] 'compare' (duration: 134.082071ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T20:23:35.564463Z","caller":"traceutil/trace.go:171","msg":"trace[1701827540] transaction","detail":"{read_only:false; response_revision:315; number_of_response:1; }","duration":"166.583797ms","start":"2026-01-29T20:23:35.397839Z","end":"2026-01-29T20:23:35.564423Z","steps":["trace[1701827540] 'process raft request' (duration: 165.940342ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:23:35.566531Z","caller":"traceutil/trace.go:171","msg":"trace[2095378702] transaction","detail":"{read_only:false; number_of_response:0; response_revision:316; }","duration":"137.287277ms","start":"2026-01-29T20:23:35.429153Z","end":"2026-01-29T20:23:35.56644Z","steps":["trace[2095378702] 'process raft request' (duration: 136.930768ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:23:35.873231Z","caller":"traceutil/trace.go:171","msg":"trace[1884574591] transaction","detail":"{read_only:false; response_revision:327; number_of_response:1; }","duration":"104.196766ms","start":"2026-01-29T20:23:35.769008Z","end":"2026-01-29T20:23:35.873205Z","steps":["trace[1884574591] 'process raft request' (duration: 104.149925ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:23:35.873245Z","caller":"traceutil/trace.go:171","msg":"trace[814639840] transaction","detail":"{read_only:false; response_revision:326; number_of_response:1; }","duration":"106.409958ms","start":"2026-01-29T20:23:35.76681Z","end":"2026-01-29T20:23:35.87322Z","steps":["trace[814639840] 'process raft request' (duration: 36.937651ms)","trace[814639840] 'compare' (duration: 69.273072ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T20:23:36.697905Z","caller":"traceutil/trace.go:171","msg":"trace[1732425795] transaction","detail":"{read_only:false; response_revision:334; number_of_response:1; }","duration":"180.869176ms","start":"2026-01-29T20:23:36.517007Z","end":"2026-01-29T20:23:36.697876Z","steps":["trace[1732425795] 'process raft request' (duration: 100.348057ms)","trace[1732425795] 'compare' (duration: 80.118579ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T20:23:36.698232Z","caller":"traceutil/trace.go:171","msg":"trace[1047974834] linearizableReadLoop","detail":"{readStateIndex:347; appliedIndex:346; }","duration":"150.11764ms","start":"2026-01-29T20:23:36.548102Z","end":"2026-01-29T20:23:36.69822Z","steps":["trace[1047974834] 'read index received' (duration: 69.291924ms)","trace[1047974834] 'applied index is now lower than readState.Index' (duration: 80.824526ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T20:23:36.698333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.211622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-01-29T20:23:36.698376Z","caller":"traceutil/trace.go:171","msg":"trace[1964575555] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:334; }","duration":"150.302704ms","start":"2026-01-29T20:23:36.548064Z","end":"2026-01-29T20:23:36.698367Z","steps":["trace[1964575555] 'agreement among raft nodes before linearized reading' (duration: 150.196052ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:23:36.954495Z","caller":"traceutil/trace.go:171","msg":"trace[495416395] transaction","detail":"{read_only:false; response_revision:337; number_of_response:1; }","duration":"191.379513ms","start":"2026-01-29T20:23:36.763086Z","end":"2026-01-29T20:23:36.954466Z","steps":["trace[495416395] 'process raft request' (duration: 101.264238ms)","trace[495416395] 'compare' (duration: 89.926041ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T20:23:36.954772Z","caller":"traceutil/trace.go:171","msg":"trace[791571738] transaction","detail":"{read_only:false; response_revision:338; number_of_response:1; }","duration":"154.930134ms","start":"2026-01-29T20:23:36.799836Z","end":"2026-01-29T20:23:36.954767Z","steps":["trace[791571738] 'process raft request' (duration: 154.578206ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:23:37.072053Z","caller":"traceutil/trace.go:171","msg":"trace[1723542667] transaction","detail":"{read_only:false; response_revision:340; number_of_response:1; }","duration":"104.663179ms","start":"2026-01-29T20:23:36.967373Z","end":"2026-01-29T20:23:37.072036Z","steps":["trace[1723542667] 'process raft request' (duration: 99.704102ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:23:37.072159Z","caller":"traceutil/trace.go:171","msg":"trace[2022875071] transaction","detail":"{read_only:false; response_revision:341; number_of_response:1; }","duration":"104.71963ms","start":"2026-01-29T20:23:36.967418Z","end":"2026-01-29T20:23:37.072138Z","steps":["trace[2022875071] 'process raft request' (duration: 104.408673ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:23:37.273486Z","caller":"traceutil/trace.go:171","msg":"trace[182195986] linearizableReadLoop","detail":"{readStateIndex:357; appliedIndex:355; }","duration":"145.782158ms","start":"2026-01-29T20:23:37.127684Z","end":"2026-01-29T20:23:37.273466Z","steps":["trace[182195986] 'read index received' (duration: 40.328611ms)","trace[182195986] 'applied index is now lower than readState.Index' (duration: 105.452897ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T20:23:37.273626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.916501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4067"} {"level":"info","ts":"2026-01-29T20:23:37.273651Z","caller":"traceutil/trace.go:171","msg":"trace[404240516] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:344; }","duration":"145.984002ms","start":"2026-01-29T20:23:37.127661Z","end":"2026-01-29T20:23:37.273645Z","steps":["trace[404240516] 'agreement among raft nodes before linearized reading' (duration: 145.8806ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:23:37.273877Z","caller":"traceutil/trace.go:171","msg":"trace[792700937] transaction","detail":"{read_only:false; response_revision:344; number_of_response:1; }","duration":"148.441321ms","start":"2026-01-29T20:23:37.125429Z","end":"2026-01-29T20:23:37.27387Z","steps":["trace[792700937] 'process raft request' (duration: 69.194272ms)","trace[792700937] 'compare' (duration: 78.750937ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T20:23:37.460757Z","caller":"traceutil/trace.go:171","msg":"trace[1516256393] linearizableReadLoop","detail":"{readStateIndex:360; appliedIndex:359; }","duration":"112.136665ms","start":"2026-01-29T20:23:37.34857Z","end":"2026-01-29T20:23:37.460707Z","steps":["trace[1516256393] 'read index received' (duration: 32.452665ms)","trace[1516256393] 'applied index is now lower than readState.Index' (duration: 79.68272ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T20:23:37.461163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.573835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/token-cleaner\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T20:23:37.461581Z","caller":"traceutil/trace.go:171","msg":"trace[447581265] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/token-cleaner; range_end:; response_count:0; response_revision:347; }","duration":"112.998135ms","start":"2026-01-29T20:23:37.348566Z","end":"2026-01-29T20:23:37.461564Z","steps":["trace[447581265] 'agreement among raft nodes before linearized reading' (duration: 112.3831ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:23:37.462716Z","caller":"traceutil/trace.go:171","msg":"trace[663422411] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"123.032781ms","start":"2026-01-29T20:23:37.339663Z","end":"2026-01-29T20:23:37.462696Z","steps":["trace[663422411] 'process raft request' (duration: 41.384756ms)","trace[663422411] 'compare' (duration: 79.063995ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T20:23:37.858257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.844518ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-29T20:23:37.858345Z","caller":"traceutil/trace.go:171","msg":"trace[401893771] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"142.996733ms","start":"2026-01-29T20:23:37.715325Z","end":"2026-01-29T20:23:37.858322Z","steps":["trace[401893771] 'process raft request' (duration: 41.938049ms)","trace[401893771] 'compare' (duration: 100.251775ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T20:23:37.861657Z","caller":"traceutil/trace.go:171","msg":"trace[2138368720] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"143.853832ms","start":"2026-01-29T20:23:37.717795Z","end":"2026-01-29T20:23:37.861649Z","steps":["trace[2138368720] 'process raft request' (duration: 143.580406ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:23:37.861614Z","caller":"traceutil/trace.go:171","msg":"trace[52935051] linearizableReadLoop","detail":"{readStateIndex:367; appliedIndex:365; }","duration":"113.431815ms","start":"2026-01-29T20:23:37.748163Z","end":"2026-01-29T20:23:37.861595Z","steps":["trace[52935051] 'read index received' (duration: 9.120675ms)","trace[52935051] 'applied index is now lower than readState.Index' (duration: 104.31007ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T20:23:37.864073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.902503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-01-29T20:23:37.867059Z","caller":"traceutil/trace.go:171","msg":"trace[805584914] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:354; }","duration":"118.894034ms","start":"2026-01-29T20:23:37.748152Z","end":"2026-01-29T20:23:37.867046Z","steps":["trace[805584914] 'agreement among raft nodes before linearized reading' (duration: 115.877422ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:23:38.120407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.635078ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-29T20:23:38.120551Z","caller":"traceutil/trace.go:171","msg":"trace[760739864] linearizableReadLoop","detail":"{readStateIndex:368; appliedIndex:367; }","duration":"222.349592ms","start":"2026-01-29T20:23:37.898187Z","end":"2026-01-29T20:23:38.120537Z","steps":["trace[760739864] 'read index received' (duration: 107.943166ms)","trace[760739864] 'applied index is now lower than readState.Index' (duration: 114.405286ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T20:23:38.120748Z","caller":"traceutil/trace.go:171","msg":"trace[1488938407] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"242.191951ms","start":"2026-01-29T20:23:37.878544Z","end":"2026-01-29T20:23:38.120736Z","steps":["trace[1488938407] 'process raft request' (duration: 127.662642ms)","trace[1488938407] 'compare' (duration: 113.420702ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T20:23:38.121072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.880175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-01-29T20:23:38.121126Z","caller":"traceutil/trace.go:171","msg":"trace[1793013174] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:355; }","duration":"222.978668ms","start":"2026-01-29T20:23:37.898139Z","end":"2026-01-29T20:23:38.121118Z","steps":["trace[1793013174] 'agreement among raft nodes before linearized reading' (duration: 222.831114ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:23:38.121277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.816954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-01-29T20:23:38.121339Z","caller":"traceutil/trace.go:171","msg":"trace[560257054] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:355; }","duration":"172.885306ms","start":"2026-01-29T20:23:37.948447Z","end":"2026-01-29T20:23:38.121332Z","steps":["trace[560257054] 'agreement among raft nodes before linearized reading' (duration: 172.800934ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:23:38.121622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.076162ms","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-01-29T20:23:38.121794Z","caller":"traceutil/trace.go:171","msg":"trace[1354520473] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:355; }","duration":"115.294477ms","start":"2026-01-29T20:23:38.006491Z","end":"2026-01-29T20:23:38.121786Z","steps":["trace[1354520473] 'agreement among raft nodes before linearized reading' (duration: 115.113193ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:23:38.276529Z","caller":"traceutil/trace.go:171","msg":"trace[1089492721] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"112.426131ms","start":"2026-01-29T20:23:38.164083Z","end":"2026-01-29T20:23:38.276509Z","steps":["trace[1089492721] 'process raft request' (duration: 112.39632ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:23:38.276796Z","caller":"traceutil/trace.go:171","msg":"trace[1329089229] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"143.058654ms","start":"2026-01-29T20:23:38.133731Z","end":"2026-01-29T20:23:38.276789Z","steps":["trace[1329089229] 'process raft request' (duration: 94.439697ms)","trace[1329089229] 'compare' (duration: 48.165586ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T20:23:38.276845Z","caller":"traceutil/trace.go:171","msg":"trace[914043067] linearizableReadLoop","detail":"{readStateIndex:370; appliedIndex:368; }","duration":"128.952341ms","start":"2026-01-29T20:23:38.147887Z","end":"2026-01-29T20:23:38.27684Z","steps":["trace[914043067] 'read index received' (duration: 12.067404ms)","trace[914043067] 'applied index is now lower than readState.Index' (duration: 116.884427ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T20:23:38.276988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.084494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-01-29T20:23:38.277012Z","caller":"traceutil/trace.go:171","msg":"trace[991991531] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:358; }","duration":"129.123365ms","start":"2026-01-29T20:23:38.147884Z","end":"2026-01-29T20:23:38.277007Z","steps":["trace[991991531] 'agreement among raft nodes before linearized reading' (duration: 129.049513ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:23:38.277082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.069552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:1 size:536"} {"level":"info","ts":"2026-01-29T20:23:38.277099Z","caller":"traceutil/trace.go:171","msg":"trace[1761584657] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:1; response_revision:358; }","duration":"109.110073ms","start":"2026-01-29T20:23:38.167986Z","end":"2026-01-29T20:23:38.277096Z","steps":["trace[1761584657] 'agreement among raft nodes before linearized reading' (duration: 109.081142ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:23:38.436043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.421369ms","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-01-29T20:23:38.436097Z","caller":"traceutil/trace.go:171","msg":"trace[642530891] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:363; }","duration":"117.518922ms","start":"2026-01-29T20:23:38.318567Z","end":"2026-01-29T20:23:38.436086Z","steps":["trace[642530891] 'agreement among raft nodes before linearized reading' (duration: 117.43947ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:23:38.87614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.569724ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-29T20:23:38.876461Z","caller":"traceutil/trace.go:171","msg":"trace[1832358507] linearizableReadLoop","detail":"{readStateIndex:392; appliedIndex:388; }","duration":"260.295348ms","start":"2026-01-29T20:23:38.616131Z","end":"2026-01-29T20:23:38.876426Z","steps":["trace[1832358507] 'read index received' (duration: 108.369016ms)","trace[1832358507] 'applied index is now lower than readState.Index' (duration: 151.925532ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T20:23:38.876536Z","caller":"traceutil/trace.go:171","msg":"trace[510676590] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"271.879111ms","start":"2026-01-29T20:23:38.604647Z","end":"2026-01-29T20:23:38.876526Z","steps":["trace[510676590] 'process raft request' (duration: 271.617585ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:23:38.876638Z","caller":"traceutil/trace.go:171","msg":"trace[1985014730] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"276.353486ms","start":"2026-01-29T20:23:38.600244Z","end":"2026-01-29T20:23:38.876598Z","steps":["trace[1985014730] 'process raft request' (duration: 124.24311ms)","trace[1985014730] 'compare' (duration: 151.440371ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T20:23:38.876822Z","caller":"traceutil/trace.go:171","msg":"trace[183211769] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"269.33297ms","start":"2026-01-29T20:23:38.607476Z","end":"2026-01-29T20:23:38.876809Z","steps":["trace[183211769] 'process raft request' (duration: 268.842709ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:23:38.876934Z","caller":"traceutil/trace.go:171","msg":"trace[1786175178] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"264.107228ms","start":"2026-01-29T20:23:38.612817Z","end":"2026-01-29T20:23:38.876924Z","steps":["trace[1786175178] 'process raft request' (duration: 263.566845ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:23:38.877059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.07178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-hs6kh\" ","response":"range_response_count:1 size:3416"} {"level":"info","ts":"2026-01-29T20:23:38.877099Z","caller":"traceutil/trace.go:171","msg":"trace[1320275415] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-hs6kh; range_end:; response_count:1; response_revision:379; }","duration":"276.113911ms","start":"2026-01-29T20:23:38.600974Z","end":"2026-01-29T20:23:38.877088Z","steps":["trace[1320275415] 'agreement among raft nodes before linearized reading' (duration: 276.040019ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:23:38.906259Z","caller":"traceutil/trace.go:171","msg":"trace[1503988635] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"281.491108ms","start":"2026-01-29T20:23:38.624748Z","end":"2026-01-29T20:23:38.906239Z","steps":["trace[1503988635] 'process raft request' (duration: 281.449247ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:23:38.90657Z","caller":"traceutil/trace.go:171","msg":"trace[1574969488] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"289.684051ms","start":"2026-01-29T20:23:38.616872Z","end":"2026-01-29T20:23:38.906557Z","steps":["trace[1574969488] 'process raft request' (duration: 289.291172ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:23:38.906342Z","caller":"traceutil/trace.go:171","msg":"trace[1982399443] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"289.504297ms","start":"2026-01-29T20:23:38.616821Z","end":"2026-01-29T20:23:38.906325Z","steps":["trace[1982399443] 'process raft request' (duration: 289.23669ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:23:39.003658Z","caller":"traceutil/trace.go:171","msg":"trace[170801937] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"116.413605ms","start":"2026-01-29T20:23:38.887219Z","end":"2026-01-29T20:23:39.003632Z","steps":["trace[170801937] 'process raft request' (duration: 116.335473ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:23:39.004247Z","caller":"traceutil/trace.go:171","msg":"trace[1551984756] linearizableReadLoop","detail":"{readStateIndex:396; appliedIndex:395; }","duration":"127.730122ms","start":"2026-01-29T20:23:38.876494Z","end":"2026-01-29T20:23:39.004224Z","steps":["trace[1551984756] 'read index received' (duration: 29.816413ms)","trace[1551984756] 'applied index is now lower than readState.Index' (duration: 97.912859ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T20:23:39.00452Z","caller":"traceutil/trace.go:171","msg":"trace[1959915531] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"113.464676ms","start":"2026-01-29T20:23:38.891042Z","end":"2026-01-29T20:23:39.004507Z","steps":["trace[1959915531] 'process raft request' (duration: 112.558714ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:23:39.004996Z","caller":"traceutil/trace.go:171","msg":"trace[700216218] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"113.905626ms","start":"2026-01-29T20:23:38.891077Z","end":"2026-01-29T20:23:39.004983Z","steps":["trace[700216218] 'process raft request' (duration: 112.951063ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:23:39.005529Z","caller":"traceutil/trace.go:171","msg":"trace[421495297] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"114.415287ms","start":"2026-01-29T20:23:38.891102Z","end":"2026-01-29T20:23:39.005517Z","steps":["trace[421495297] 'process raft request' (duration: 113.007424ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:23:39.005836Z","caller":"traceutil/trace.go:171","msg":"trace[2073577066] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"114.712365ms","start":"2026-01-29T20:23:38.891106Z","end":"2026-01-29T20:23:39.005819Z","steps":["trace[2073577066] 'process raft request' (duration: 113.032215ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:23:39.006174Z","caller":"traceutil/trace.go:171","msg":"trace[1108348831] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"114.384277ms","start":"2026-01-29T20:23:38.891775Z","end":"2026-01-29T20:23:39.006159Z","steps":["trace[1108348831] 'process raft request' (duration: 112.39599ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:23:39.006287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.433485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-01-29T20:23:39.009197Z","caller":"traceutil/trace.go:171","msg":"trace[888124411] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:389; }","duration":"359.392085ms","start":"2026-01-29T20:23:38.649773Z","end":"2026-01-29T20:23:39.009165Z","steps":["trace[888124411] 'agreement among raft nodes before linearized reading' (duration: 356.438325ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:23:39.009277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:23:38.649754Z","time spent":"359.503208ms","remote":"127.0.0.1:50410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":232,"request content":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" "} {"level":"warn","ts":"2026-01-29T20:23:39.006751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.089208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-01-29T20:23:39.009553Z","caller":"traceutil/trace.go:171","msg":"trace[1153424162] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:389; }","duration":"392.895115ms","start":"2026-01-29T20:23:38.61665Z","end":"2026-01-29T20:23:39.009545Z","steps":["trace[1153424162] 'agreement among raft nodes before linearized reading' (duration: 389.974696ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:23:39.009624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:23:38.61662Z","time spent":"392.993467ms","remote":"127.0.0.1:50410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":220,"request content":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" "} {"level":"info","ts":"2026-01-29T20:23:39.008541Z","caller":"traceutil/trace.go:171","msg":"trace[2096429625] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"195.257414ms","start":"2026-01-29T20:23:38.813264Z","end":"2026-01-29T20:23:39.008521Z","steps":["trace[2096429625] 'process raft request' (duration: 187.326847ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:23:39.267325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.137994ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-01-29T20:23:39.267705Z","caller":"traceutil/trace.go:171","msg":"trace[99616886] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"240.139143ms","start":"2026-01-29T20:23:39.027491Z","end":"2026-01-29T20:23:39.26763Z","steps":["trace[99616886] 'process raft request' (duration: 127.62861ms)","trace[99616886] 'compare' (duration: 111.990651ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T20:23:39.26825Z","caller":"traceutil/trace.go:171","msg":"trace[898843914] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"240.680496ms","start":"2026-01-29T20:23:39.027547Z","end":"2026-01-29T20:23:39.268228Z","steps":["trace[898843914] 'process raft request' (duration: 239.963439ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:23:39.268951Z","caller":"traceutil/trace.go:171","msg":"trace[407734717] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"241.325891ms","start":"2026-01-29T20:23:39.027614Z","end":"2026-01-29T20:23:39.26894Z","steps":["trace[407734717] 'process raft request' (duration: 239.967239ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:23:39.269624Z","caller":"traceutil/trace.go:171","msg":"trace[21942265] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"240.105593ms","start":"2026-01-29T20:23:39.02951Z","end":"2026-01-29T20:23:39.269615Z","steps":["trace[21942265] 'process raft request' (duration: 238.105736ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:23:39.269855Z","caller":"traceutil/trace.go:171","msg":"trace[2024815115] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"240.203295ms","start":"2026-01-29T20:23:39.029637Z","end":"2026-01-29T20:23:39.26984Z","steps":["trace[2024815115] 'process raft request' (duration: 238.039674ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:23:39.270095Z","caller":"traceutil/trace.go:171","msg":"trace[928125177] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"240.155334ms","start":"2026-01-29T20:23:39.029927Z","end":"2026-01-29T20:23:39.270083Z","steps":["trace[928125177] 'process raft request' (duration: 237.784148ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:23:39.270533Z","caller":"traceutil/trace.go:171","msg":"trace[1684469069] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"240.937923ms","start":"2026-01-29T20:23:39.029586Z","end":"2026-01-29T20:23:39.270524Z","steps":["trace[1684469069] 'process raft request' (duration: 238.059895ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:23:40.709946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.299395ms","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-01-29T20:23:40.710133Z","caller":"traceutil/trace.go:171","msg":"trace[349715386] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:405; }","duration":"401.51465ms","start":"2026-01-29T20:23:40.308597Z","end":"2026-01-29T20:23:40.710112Z","steps":["trace[349715386] 'range keys from in-memory index tree' (duration: 401.276424ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:23:40.710552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"775.926378ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-29T20:23:40.710609Z","caller":"traceutil/trace.go:171","msg":"trace[860407441] linearizableReadLoop","detail":"{readStateIndex:419; appliedIndex:418; }","duration":"699.476516ms","start":"2026-01-29T20:23:40.011122Z","end":"2026-01-29T20:23:40.710598Z","steps":["trace[860407441] 'read index received' (duration: 35.351µs)","trace[860407441] 'applied index is now lower than readState.Index' (duration: 699.440155ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T20:23:40.711013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"699.883416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T20:23:40.711051Z","caller":"traceutil/trace.go:171","msg":"trace[680710135] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:406; }","duration":"699.969797ms","start":"2026-01-29T20:23:40.01107Z","end":"2026-01-29T20:23:40.71104Z","steps":["trace[680710135] 'agreement among raft nodes before linearized reading' (duration: 699.895576ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:23:40.711083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:23:40.01105Z","time spent":"700.026238ms","remote":"127.0.0.1:50166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-01-29T20:23:40.711388Z","caller":"traceutil/trace.go:171","msg":"trace[242905492] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"1.012284622s","start":"2026-01-29T20:23:39.699087Z","end":"2026-01-29T20:23:40.711371Z","steps":["trace[242905492] 'process raft request' (duration: 235.469403ms)","trace[242905492] 'compare' (duration: 775.823286ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T20:23:40.711355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.409424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-01-29T20:23:40.711459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:23:39.699072Z","time spent":"1.012349594s","remote":"127.0.0.1:50384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7222,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T20:23:40.711462Z","caller":"traceutil/trace.go:171","msg":"trace[748631704] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:406; }","duration":"194.599159ms","start":"2026-01-29T20:23:40.516837Z","end":"2026-01-29T20:23:40.711436Z","steps":["trace[748631704] 'agreement among raft nodes before linearized reading' (duration: 194.321232ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:23:41.781729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"894.099744ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-29T20:23:41.781874Z","caller":"traceutil/trace.go:171","msg":"trace[1074587912] linearizableReadLoop","detail":"{readStateIndex:420; appliedIndex:419; }","duration":"1.042465223s","start":"2026-01-29T20:23:40.73939Z","end":"2026-01-29T20:23:41.781855Z","steps":["trace[1074587912] 'read index received' (duration: 148.050701ms)","trace[1074587912] 'applied index is now lower than readState.Index' (duration: 894.413102ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T20:23:41.781965Z","caller":"traceutil/trace.go:171","msg":"trace[2145249079] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"1.064219697s","start":"2026-01-29T20:23:40.717733Z","end":"2026-01-29T20:23:41.781952Z","steps":["trace[2145249079] 'process raft request' (duration: 169.789484ms)","trace[2145249079] 'compare' (duration: 893.9139ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T20:23:41.782029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:23:40.717707Z","time spent":"1.064284717s","remote":"127.0.0.1:50484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T20:23:41.782079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.041859379s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T20:23:41.782163Z","caller":"traceutil/trace.go:171","msg":"trace[1350219145] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:407; }","duration":"1.041991202s","start":"2026-01-29T20:23:40.740149Z","end":"2026-01-29T20:23:41.78214Z","steps":["trace[1350219145] 'agreement among raft nodes before linearized reading' (duration: 1.041858679s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:23:41.782208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:23:40.740132Z","time spent":"1.042068144s","remote":"127.0.0.1:50156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-29T20:23:41.782443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.043055757s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7236"} {"level":"info","ts":"2026-01-29T20:23:41.782475Z","caller":"traceutil/trace.go:171","msg":"trace[541910184] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:407; }","duration":"1.043113419s","start":"2026-01-29T20:23:40.739353Z","end":"2026-01-29T20:23:41.782466Z","steps":["trace[541910184] 'agreement among raft nodes before linearized reading' (duration: 1.043047067s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:23:41.782504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:23:40.739344Z","time spent":"1.043154219s","remote":"127.0.0.1:50384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7259,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"warn","ts":"2026-01-29T20:23:41.785041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.096834ms","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-01-29T20:23:41.785088Z","caller":"traceutil/trace.go:171","msg":"trace[170811459] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:407; }","duration":"506.176586ms","start":"2026-01-29T20:23:41.278899Z","end":"2026-01-29T20:23:41.785075Z","steps":["trace[170811459] 'agreement among raft nodes before linearized reading' (duration: 506.079164ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:23:41.785118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:23:41.278879Z","time spent":"506.231808ms","remote":"127.0.0.1:50484","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-01-29T20:23:41.78522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.13169ms","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-01-29T20:23:41.785244Z","caller":"traceutil/trace.go:171","msg":"trace[1823282339] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:407; }","duration":"435.198452ms","start":"2026-01-29T20:23:41.350038Z","end":"2026-01-29T20:23:41.785237Z","steps":["trace[1823282339] 'agreement among raft nodes before linearized reading' (duration: 435.150161ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:23:41.78527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:23:41.350015Z","time spent":"435.249374ms","remote":"127.0.0.1:50484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-01-29T20:23:42.891874Z","caller":"traceutil/trace.go:171","msg":"trace[1202286281] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"272.792552ms","start":"2026-01-29T20:23:42.61904Z","end":"2026-01-29T20:23:42.891832Z","steps":["trace[1202286281] 'process raft request' (duration: 272.588678ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:23:43.017705Z","caller":"traceutil/trace.go:171","msg":"trace[1465676121] linearizableReadLoop","detail":"{readStateIndex:428; appliedIndex:427; }","duration":"267.023807ms","start":"2026-01-29T20:23:42.75066Z","end":"2026-01-29T20:23:43.017684Z","steps":["trace[1465676121] 'read index received' (duration: 141.66666ms)","trace[1465676121] 'applied index is now lower than readState.Index' (duration: 125.355737ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T20:23:43.017812Z","caller":"traceutil/trace.go:171","msg":"trace[1864054490] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"388.579654ms","start":"2026-01-29T20:23:42.629219Z","end":"2026-01-29T20:23:43.017798Z","steps":["trace[1864054490] 'process raft request' (duration: 349.451371ms)","trace[1864054490] 'compare' (duration: 38.812465ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T20:23:43.017909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:23:42.629209Z","time spent":"388.623264ms","remote":"127.0.0.1:50262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-01-29T20:23:43.018136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.464557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4013"} {"level":"info","ts":"2026-01-29T20:23:43.018161Z","caller":"traceutil/trace.go:171","msg":"trace[60992795] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:414; }","duration":"267.544759ms","start":"2026-01-29T20:23:42.750609Z","end":"2026-01-29T20:23:43.018153Z","steps":["trace[60992795] 'agreement among raft nodes before linearized reading' (duration: 267.438186ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:23:43.024785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.542319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-01-29T20:23:43.025701Z","caller":"traceutil/trace.go:171","msg":"trace[674721928] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:414; }","duration":"131.492602ms","start":"2026-01-29T20:23:42.894201Z","end":"2026-01-29T20:23:43.025694Z","steps":["trace[674721928] 'agreement among raft nodes before linearized reading' (duration: 124.061807ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:23:43.192717Z","caller":"traceutil/trace.go:171","msg":"trace[1551235727] linearizableReadLoop","detail":"{readStateIndex:429; appliedIndex:428; }","duration":"163.212329ms","start":"2026-01-29T20:23:43.029476Z","end":"2026-01-29T20:23:43.192688Z","steps":["trace[1551235727] 'read index received' (duration: 159.525982ms)","trace[1551235727] 'applied index is now lower than readState.Index' (duration: 3.684867ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T20:23:43.192944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.436284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-01-29T20:23:43.193004Z","caller":"traceutil/trace.go:171","msg":"trace[1862579914] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:415; }","duration":"163.520777ms","start":"2026-01-29T20:23:43.029465Z","end":"2026-01-29T20:23:43.192985Z","steps":["trace[1862579914] 'agreement among raft nodes before linearized reading' (duration: 163.320952ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:23:43.193241Z","caller":"traceutil/trace.go:171","msg":"trace[1543532605] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"165.505904ms","start":"2026-01-29T20:23:43.027724Z","end":"2026-01-29T20:23:43.19323Z","steps":["trace[1543532605] 'process raft request' (duration: 161.319305ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:23:56.034253Z","caller":"traceutil/trace.go:171","msg":"trace[1214283570] linearizableReadLoop","detail":"{readStateIndex:498; appliedIndex:497; }","duration":"168.150395ms","start":"2026-01-29T20:23:55.866074Z","end":"2026-01-29T20:23:56.034224Z","steps":["trace[1214283570] 'read index received' (duration: 167.819887ms)","trace[1214283570] 'applied index is now lower than readState.Index' (duration: 328.878µs)"],"step_count":2} {"level":"info","ts":"2026-01-29T20:23:56.034396Z","caller":"traceutil/trace.go:171","msg":"trace[1367787498] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"258.500476ms","start":"2026-01-29T20:23:55.775876Z","end":"2026-01-29T20:23:56.034377Z","steps":["trace[1367787498] 'process raft request' (duration: 258.131537ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:23:56.034515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.426971ms","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-01-29T20:23:56.034556Z","caller":"traceutil/trace.go:171","msg":"trace[838086741] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:481; }","duration":"168.528903ms","start":"2026-01-29T20:23:55.866014Z","end":"2026-01-29T20:23:56.034543Z","steps":["trace[838086741] 'agreement among raft nodes before linearized reading' (duration: 168.3703ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:23:56.034638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.689819ms","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-01-29T20:23:56.034663Z","caller":"traceutil/trace.go:171","msg":"trace[717198095] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:481; }","duration":"149.758861ms","start":"2026-01-29T20:23:55.884896Z","end":"2026-01-29T20:23:56.034655Z","steps":["trace[717198095] 'agreement among raft nodes before linearized reading' (duration: 149.70352ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:23:56.034802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.634141ms","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-01-29T20:23:56.034815Z","caller":"traceutil/trace.go:171","msg":"trace[554948241] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:481; }","duration":"105.666232ms","start":"2026-01-29T20:23:55.929145Z","end":"2026-01-29T20:23:56.034811Z","steps":["trace[554948241] 'agreement among raft nodes before linearized reading' (duration: 105.637311ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:23:56.034863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.894241ms","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-01-29T20:23:56.034873Z","caller":"traceutil/trace.go:171","msg":"trace[955832543] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:481; }","duration":"106.948702ms","start":"2026-01-29T20:23:55.927921Z","end":"2026-01-29T20:23:56.03487Z","steps":["trace[955832543] 'agreement among raft nodes before linearized reading' (duration: 106.926992ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:24:03.54955Z","caller":"traceutil/trace.go:171","msg":"trace[280333646] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"116.784964ms","start":"2026-01-29T20:24:03.43274Z","end":"2026-01-29T20:24:03.549525Z","steps":["trace[280333646] 'process raft request' (duration: 115.849402ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:24:03.550962Z","caller":"traceutil/trace.go:171","msg":"trace[982714054] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"105.546949ms","start":"2026-01-29T20:24:03.445402Z","end":"2026-01-29T20:24:03.550949Z","steps":["trace[982714054] 'process raft request' (duration: 103.4699ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:24:03.551918Z","caller":"traceutil/trace.go:171","msg":"trace[24795924] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"108.702774ms","start":"2026-01-29T20:24:03.443204Z","end":"2026-01-29T20:24:03.551906Z","steps":["trace[24795924] 'process raft request' (duration: 105.550429ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:24:03.551962Z","caller":"traceutil/trace.go:171","msg":"trace[1039256435] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"106.83544ms","start":"2026-01-29T20:24:03.445114Z","end":"2026-01-29T20:24:03.551949Z","steps":["trace[1039256435] 'process raft request' (duration: 103.703906ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:24:03.552115Z","caller":"traceutil/trace.go:171","msg":"trace[1915380220] linearizableReadLoop","detail":"{readStateIndex:543; appliedIndex:536; }","duration":"102.024886ms","start":"2026-01-29T20:24:03.450081Z","end":"2026-01-29T20:24:03.552105Z","steps":["trace[1915380220] 'read index received' (duration: 15.815173ms)","trace[1915380220] 'applied index is now lower than readState.Index' (duration: 86.208863ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T20:24:03.552189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.088638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/cilium-operator\" ","response":"range_response_count:1 size:4313"} {"level":"info","ts":"2026-01-29T20:24:03.558018Z","caller":"traceutil/trace.go:171","msg":"trace[312057098] range","detail":"{range_begin:/registry/deployments/kube-system/cilium-operator; range_end:; response_count:1; response_revision:525; }","duration":"107.941885ms","start":"2026-01-29T20:24:03.450027Z","end":"2026-01-29T20:24:03.557969Z","steps":["trace[312057098] 'agreement among raft nodes before linearized reading' (duration: 102.104768ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:24:03.552221Z","caller":"traceutil/trace.go:171","msg":"trace[1014160801] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"102.440046ms","start":"2026-01-29T20:24:03.449774Z","end":"2026-01-29T20:24:03.552214Z","steps":["trace[1014160801] 'process raft request' (duration: 99.452235ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:24:03.738969Z","caller":"traceutil/trace.go:171","msg":"trace[1284626182] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"132.942085ms","start":"2026-01-29T20:24:03.605996Z","end":"2026-01-29T20:24:03.738938Z","steps":["trace[1284626182] 'process raft request' (duration: 52.984779ms)","trace[1284626182] 'compare' (duration: 79.802293ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T20:24:03.753967Z","caller":"traceutil/trace.go:171","msg":"trace[599378116] linearizableReadLoop","detail":"{readStateIndex:549; appliedIndex:544; }","duration":"116.074287ms","start":"2026-01-29T20:24:03.637869Z","end":"2026-01-29T20:24:03.753943Z","steps":["trace[599378116] 'read index received' (duration: 21.562298ms)","trace[599378116] 'applied index is now lower than readState.Index' (duration: 94.510879ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T20:24:03.754558Z","caller":"traceutil/trace.go:171","msg":"trace[833466135] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"148.169584ms","start":"2026-01-29T20:24:03.606372Z","end":"2026-01-29T20:24:03.754541Z","steps":["trace[833466135] 'process raft request' (duration: 147.296313ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:24:03.755067Z","caller":"traceutil/trace.go:171","msg":"trace[965308501] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"148.560454ms","start":"2026-01-29T20:24:03.60647Z","end":"2026-01-29T20:24:03.75503Z","steps":["trace[965308501] 'process raft request' (duration: 147.317344ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:24:03.756275Z","caller":"traceutil/trace.go:171","msg":"trace[554643630] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"149.618879ms","start":"2026-01-29T20:24:03.606601Z","end":"2026-01-29T20:24:03.75622Z","steps":["trace[554643630] 'process raft request' (duration: 147.252363ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:24:03.756819Z","caller":"traceutil/trace.go:171","msg":"trace[719474138] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"143.960725ms","start":"2026-01-29T20:24:03.612849Z","end":"2026-01-29T20:24:03.75681Z","steps":["trace[719474138] 'process raft request' (duration: 141.044976ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:03.756991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.096108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:1 size:520"} {"level":"info","ts":"2026-01-29T20:24:03.757019Z","caller":"traceutil/trace.go:171","msg":"trace[2028669609] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:530; }","duration":"119.211811ms","start":"2026-01-29T20:24:03.637799Z","end":"2026-01-29T20:24:03.757011Z","steps":["trace[2028669609] 'agreement among raft nodes before linearized reading' (duration: 119.124009ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:24:03.92829Z","caller":"traceutil/trace.go:171","msg":"trace[406668386] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"112.517853ms","start":"2026-01-29T20:24:03.815699Z","end":"2026-01-29T20:24:03.928217Z","steps":["trace[406668386] 'process raft request' (duration: 60.139668ms)","trace[406668386] 'compare' (duration: 45.295598ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T20:24:05.860733Z","caller":"traceutil/trace.go:171","msg":"trace[966075137] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"140.616386ms","start":"2026-01-29T20:24:05.720067Z","end":"2026-01-29T20:24:05.860684Z","steps":["trace[966075137] 'process raft request' (duration: 140.35311ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:06.4342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.605034ms","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-01-29T20:24:06.434282Z","caller":"traceutil/trace.go:171","msg":"trace[1278135583] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:541; }","duration":"120.742208ms","start":"2026-01-29T20:24:06.313523Z","end":"2026-01-29T20:24:06.434265Z","steps":["trace[1278135583] 'range keys from in-memory index tree' (duration: 120.510712ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:24:06.564011Z","caller":"traceutil/trace.go:171","msg":"trace[1438242448] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"106.581954ms","start":"2026-01-29T20:24:06.457401Z","end":"2026-01-29T20:24:06.563983Z","steps":["trace[1438242448] 'process raft request' (duration: 106.392229ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:24:06.60702Z","caller":"traceutil/trace.go:171","msg":"trace[70152837] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"146.488675ms","start":"2026-01-29T20:24:06.460505Z","end":"2026-01-29T20:24:06.606994Z","steps":["trace[70152837] 'process raft request' (duration: 146.447124ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:24:06.60748Z","caller":"traceutil/trace.go:171","msg":"trace[449591397] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"149.323101ms","start":"2026-01-29T20:24:06.458143Z","end":"2026-01-29T20:24:06.607467Z","steps":["trace[449591397] 'process raft request' (duration: 148.696736ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:09.458913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.048137358s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-29T20:24:09.459019Z","caller":"traceutil/trace.go:171","msg":"trace[1038457846] linearizableReadLoop","detail":"{readStateIndex:566; appliedIndex:565; }","duration":"890.968481ms","start":"2026-01-29T20:24:08.568035Z","end":"2026-01-29T20:24:09.459004Z","steps":["trace[1038457846] 'read index received' (duration: 23.35µs)","trace[1038457846] 'applied index is now lower than readState.Index' (duration: 890.944001ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T20:24:09.459234Z","caller":"traceutil/trace.go:171","msg":"trace[153069730] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"1.212525564s","start":"2026-01-29T20:24:08.246697Z","end":"2026-01-29T20:24:09.459222Z","steps":["trace[153069730] 'process raft request' (duration: 164.010427ms)","trace[153069730] 'compare' (duration: 1.047583955s)"],"step_count":2} {"level":"warn","ts":"2026-01-29T20:24:09.459293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:24:08.246664Z","time spent":"1.212596956s","remote":"127.0.0.1:50484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T20:24:09.459503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"891.463293ms","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-01-29T20:24:09.459532Z","caller":"traceutil/trace.go:171","msg":"trace[1032118373] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:546; }","duration":"891.526444ms","start":"2026-01-29T20:24:08.567995Z","end":"2026-01-29T20:24:09.459522Z","steps":["trace[1032118373] 'agreement among raft nodes before linearized reading' (duration: 891.429472ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:09.459559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:24:08.567984Z","time spent":"891.568735ms","remote":"127.0.0.1:50484","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-01-29T20:24:09.459716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"847.410004ms","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-01-29T20:24:09.459741Z","caller":"traceutil/trace.go:171","msg":"trace[555919723] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:546; }","duration":"847.467925ms","start":"2026-01-29T20:24:08.612265Z","end":"2026-01-29T20:24:09.459733Z","steps":["trace[555919723] 'agreement among raft nodes before linearized reading' (duration: 847.393983ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:09.459765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:24:08.612254Z","time spent":"847.506146ms","remote":"127.0.0.1:50484","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-01-29T20:24:09.459947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.394438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T20:24:09.459976Z","caller":"traceutil/trace.go:171","msg":"trace[924755646] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:546; }","duration":"449.445699ms","start":"2026-01-29T20:24:09.010521Z","end":"2026-01-29T20:24:09.459967Z","steps":["trace[924755646] 'agreement among raft nodes before linearized reading' (duration: 449.405528ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:09.460009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:24:09.010494Z","time spent":"449.509951ms","remote":"127.0.0.1:50156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-29T20:24:10.369484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"669.052088ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-29T20:24:10.369599Z","caller":"traceutil/trace.go:171","msg":"trace[977114586] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"903.604829ms","start":"2026-01-29T20:24:09.465959Z","end":"2026-01-29T20:24:10.369564Z","steps":["trace[977114586] 'process raft request' (duration: 234.347996ms)","trace[977114586] 'compare' (duration: 668.841163ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T20:24:10.369659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:24:09.465948Z","time spent":"903.682991ms","remote":"127.0.0.1:50484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T20:24:10.384985Z","caller":"traceutil/trace.go:171","msg":"trace[161548048] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"918.870049ms","start":"2026-01-29T20:24:09.46609Z","end":"2026-01-29T20:24:10.38496Z","steps":["trace[161548048] 'process raft request' (duration: 918.782907ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:10.385125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:24:09.466084Z","time spent":"918.968982ms","remote":"127.0.0.1:50484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T20:24:10.386156Z","caller":"traceutil/trace.go:171","msg":"trace[255960360] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"525.272857ms","start":"2026-01-29T20:24:09.860869Z","end":"2026-01-29T20:24:10.386142Z","steps":["trace[255960360] 'process raft request' (duration: 525.082603ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:10.386238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:24:09.860843Z","time spent":"525.352309ms","remote":"127.0.0.1:50484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T20:24:10.386319Z","caller":"traceutil/trace.go:171","msg":"trace[1789147221] linearizableReadLoop","detail":"{readStateIndex:569; appliedIndex:568; }","duration":"375.318711ms","start":"2026-01-29T20:24:10.010992Z","end":"2026-01-29T20:24:10.38631Z","steps":["trace[1789147221] 'read index received' (duration: 374.918712ms)","trace[1789147221] 'applied index is now lower than readState.Index' (duration: 399.089µs)"],"step_count":2} {"level":"warn","ts":"2026-01-29T20:24:10.386391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.401943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T20:24:10.386429Z","caller":"traceutil/trace.go:171","msg":"trace[855148953] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:549; }","duration":"375.461104ms","start":"2026-01-29T20:24:10.010946Z","end":"2026-01-29T20:24:10.386407Z","steps":["trace[855148953] 'agreement among raft nodes before linearized reading' (duration: 375.421494ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:10.386455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:24:10.01093Z","time spent":"375.519035ms","remote":"127.0.0.1:50156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-29T20:24:10.386648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.256759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-01-29T20:24:10.38668Z","caller":"traceutil/trace.go:171","msg":"trace[1340591676] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:549; }","duration":"199.29043ms","start":"2026-01-29T20:24:10.187381Z","end":"2026-01-29T20:24:10.386671Z","steps":["trace[1340591676] 'agreement among raft nodes before linearized reading' (duration: 199.191007ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:24:12.292311Z","caller":"traceutil/trace.go:171","msg":"trace[1165535448] linearizableReadLoop","detail":"{readStateIndex:571; appliedIndex:570; }","duration":"335.573564ms","start":"2026-01-29T20:24:11.956723Z","end":"2026-01-29T20:24:12.292297Z","steps":["trace[1165535448] 'read index received' (duration: 335.40954ms)","trace[1165535448] 'applied index is now lower than readState.Index' (duration: 163.484µs)"],"step_count":2} {"level":"warn","ts":"2026-01-29T20:24:12.292412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.673276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T20:24:12.292428Z","caller":"traceutil/trace.go:171","msg":"trace[61217386] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:551; }","duration":"335.733218ms","start":"2026-01-29T20:24:11.95669Z","end":"2026-01-29T20:24:12.292423Z","steps":["trace[61217386] 'agreement among raft nodes before linearized reading' (duration: 335.664366ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:12.292445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:24:11.956675Z","time spent":"335.766328ms","remote":"127.0.0.1:50156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-01-29T20:24:12.292652Z","caller":"traceutil/trace.go:171","msg":"trace[2014230337] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"827.400353ms","start":"2026-01-29T20:24:11.465246Z","end":"2026-01-29T20:24:12.292647Z","steps":["trace[2014230337] 'process raft request' (duration: 826.957913ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:12.292694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:24:11.465237Z","time spent":"827.427154ms","remote":"127.0.0.1:50484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T20:24:12.894204Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9997599795692979359,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-29T20:24:13.500335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.674558ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-29T20:24:13.500457Z","caller":"traceutil/trace.go:171","msg":"trace[499171928] linearizableReadLoop","detail":"{readStateIndex:572; appliedIndex:571; }","duration":"1.107201882s","start":"2026-01-29T20:24:12.393246Z","end":"2026-01-29T20:24:13.500448Z","steps":["trace[499171928] 'read index received' (duration: 899.294829ms)","trace[499171928] 'applied index is now lower than readState.Index' (duration: 207.906473ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T20:24:13.500563Z","caller":"traceutil/trace.go:171","msg":"trace[1838146193] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"1.122757988s","start":"2026-01-29T20:24:12.377798Z","end":"2026-01-29T20:24:13.500556Z","steps":["trace[1838146193] 'process raft request' (duration: 914.811075ms)","trace[1838146193] 'compare' (duration: 206.380846ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T20:24:13.500594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:24:12.377782Z","time spent":"1.122795289s","remote":"127.0.0.1:50484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T20:24:13.500687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.107441407s","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-01-29T20:24:13.500704Z","caller":"traceutil/trace.go:171","msg":"trace[561177084] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:552; }","duration":"1.107483578s","start":"2026-01-29T20:24:12.393216Z","end":"2026-01-29T20:24:13.500699Z","steps":["trace[561177084] 'agreement among raft nodes before linearized reading' (duration: 1.107454177s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:13.500718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:24:12.3932Z","time spent":"1.107515049s","remote":"127.0.0.1:50484","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-01-29T20:24:13.500778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.081057985s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-01-29T20:24:13.500794Z","caller":"traceutil/trace.go:171","msg":"trace[1848679659] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:552; }","duration":"1.081087076s","start":"2026-01-29T20:24:12.419703Z","end":"2026-01-29T20:24:13.50079Z","steps":["trace[1848679659] 'agreement among raft nodes before linearized reading' (duration: 1.081060615s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:13.500806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:24:12.419697Z","time spent":"1.081105956s","remote":"127.0.0.1:50484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-01-29T20:24:13.500876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.191267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T20:24:13.500891Z","caller":"traceutil/trace.go:171","msg":"trace[2046650216] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:552; }","duration":"489.233627ms","start":"2026-01-29T20:24:13.011654Z","end":"2026-01-29T20:24:13.500888Z","steps":["trace[2046650216] 'agreement among raft nodes before linearized reading' (duration: 489.211017ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:13.500902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:24:13.011633Z","time spent":"489.267029ms","remote":"127.0.0.1:50156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-29T20:24:13.500987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"901.949601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.218\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-01-29T20:24:13.501005Z","caller":"traceutil/trace.go:171","msg":"trace[645072723] range","detail":"{range_begin:/registry/masterleases/162.253.55.218; range_end:; response_count:1; response_revision:552; }","duration":"901.988272ms","start":"2026-01-29T20:24:12.599013Z","end":"2026-01-29T20:24:13.501002Z","steps":["trace[645072723] 'agreement among raft nodes before linearized reading' (duration: 901.935221ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:13.501016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:24:12.599003Z","time spent":"902.010442ms","remote":"127.0.0.1:50216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/162.253.55.218\" "} {"level":"warn","ts":"2026-01-29T20:24:14.831012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.221346882s","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-01-29T20:24:14.8311Z","caller":"traceutil/trace.go:171","msg":"trace[1513337860] linearizableReadLoop","detail":"{readStateIndex:573; appliedIndex:572; }","duration":"1.327448284s","start":"2026-01-29T20:24:13.503638Z","end":"2026-01-29T20:24:14.831086Z","steps":["trace[1513337860] 'read index received' (duration: 106.104062ms)","trace[1513337860] 'applied index is now lower than readState.Index' (duration: 1.221337262s)"],"step_count":2} {"level":"warn","ts":"2026-01-29T20:24:14.831147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:24:13.503462Z","time spent":"1.32768212s","remote":"127.0.0.1:50216","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-01-29T20:24:14.831204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.327557617s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T20:24:14.831304Z","caller":"traceutil/trace.go:171","msg":"trace[1910224344] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:552; }","duration":"1.327664449s","start":"2026-01-29T20:24:13.503625Z","end":"2026-01-29T20:24:14.83129Z","steps":["trace[1910224344] 'agreement among raft nodes before linearized reading' (duration: 1.327544486s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:14.831324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:24:13.50362Z","time spent":"1.32769939s","remote":"127.0.0.1:50166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-29T20:24:14.913942Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.054057329s","expected-duration":"1s"} {"level":"info","ts":"2026-01-29T20:24:14.914345Z","caller":"traceutil/trace.go:171","msg":"trace[852712011] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"1.404766488s","start":"2026-01-29T20:24:13.509557Z","end":"2026-01-29T20:24:14.914323Z","steps":["trace[852712011] 'process raft request' (duration: 1.404720277s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:14.914501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:24:13.509551Z","time spent":"1.40487436s","remote":"127.0.0.1:50484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T20:24:14.914878Z","caller":"traceutil/trace.go:171","msg":"trace[1364482184] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"1.409789096s","start":"2026-01-29T20:24:13.505076Z","end":"2026-01-29T20:24:14.914865Z","steps":["trace[1364482184] 'process raft request' (duration: 1.409034279s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:14.914949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:24:13.505068Z","time spent":"1.409845068s","remote":"127.0.0.1:50484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T20:24:14.915409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.935377ms","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-01-29T20:24:14.915444Z","caller":"traceutil/trace.go:171","msg":"trace[80321395] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:554; }","duration":"605.005109ms","start":"2026-01-29T20:24:14.310429Z","end":"2026-01-29T20:24:14.915434Z","steps":["trace[80321395] 'agreement among raft nodes before linearized reading' (duration: 604.890486ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:14.915473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:24:14.310412Z","time spent":"605.05529ms","remote":"127.0.0.1:50484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-01-29T20:24:15.218622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.332511ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-29T20:24:15.218786Z","caller":"traceutil/trace.go:171","msg":"trace[1126908066] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"386.049364ms","start":"2026-01-29T20:24:14.832685Z","end":"2026-01-29T20:24:15.218734Z","steps":["trace[1126908066] 'process raft request' (duration: 186.524129ms)","trace[1126908066] 'compare' (duration: 199.223788ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T20:24:15.218845Z","caller":"traceutil/trace.go:171","msg":"trace[1750080307] linearizableReadLoop","detail":"{readStateIndex:576; appliedIndex:575; }","duration":"303.542637ms","start":"2026-01-29T20:24:14.915294Z","end":"2026-01-29T20:24:15.218837Z","steps":["trace[1750080307] 'read index received' (duration: 103.991041ms)","trace[1750080307] 'applied index is now lower than readState.Index' (duration: 199.550616ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T20:24:15.218925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.100866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-01-29T20:24:15.218958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:24:14.832661Z","time spent":"386.199977ms","remote":"127.0.0.1:50216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T20:24:15.218949Z","caller":"traceutil/trace.go:171","msg":"trace[1918796808] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:556; }","duration":"378.141987ms","start":"2026-01-29T20:24:14.840799Z","end":"2026-01-29T20:24:15.218941Z","steps":["trace[1918796808] 'agreement among raft nodes before linearized reading' (duration: 378.070215ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:15.219094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:24:14.840791Z","time spent":"378.291891ms","remote":"127.0.0.1:50156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-01-29T20:24:15.21881Z","caller":"traceutil/trace.go:171","msg":"trace[1387656024] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"299.008881ms","start":"2026-01-29T20:24:14.919783Z","end":"2026-01-29T20:24:15.218792Z","steps":["trace[1387656024] 'process raft request' (duration: 298.95093ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:24:15.699937Z","caller":"traceutil/trace.go:171","msg":"trace[1709449180] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"191.893745ms","start":"2026-01-29T20:24:15.50802Z","end":"2026-01-29T20:24:15.699914Z","steps":["trace[1709449180] 'process raft request' (duration: 191.781223ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:21.27677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"967.324402ms","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-01-29T20:24:21.276904Z","caller":"traceutil/trace.go:171","msg":"trace[1436812810] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:569; }","duration":"967.467205ms","start":"2026-01-29T20:24:20.309417Z","end":"2026-01-29T20:24:21.276885Z","steps":["trace[1436812810] 'range keys from in-memory index tree' (duration: 967.308101ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:21.277201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"771.946104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-tcwww\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-01-29T20:24:21.277268Z","caller":"traceutil/trace.go:171","msg":"trace[102222051] range","detail":"{range_begin:/registry/pods/kube-system/cilium-tcwww; range_end:; response_count:1; response_revision:569; }","duration":"772.055847ms","start":"2026-01-29T20:24:20.505199Z","end":"2026-01-29T20:24:21.277255Z","steps":["trace[102222051] 'range keys from in-memory index tree' (duration: 771.816141ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:21.277297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:24:20.505182Z","time spent":"772.108028ms","remote":"127.0.0.1:50384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16679,"request content":"key:\"/registry/pods/kube-system/cilium-tcwww\" "} {"level":"warn","ts":"2026-01-29T20:24:21.277659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.680491ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-29T20:24:21.27772Z","caller":"traceutil/trace.go:171","msg":"trace[15011913] linearizableReadLoop","detail":"{readStateIndex:592; appliedIndex:591; }","duration":"333.672968ms","start":"2026-01-29T20:24:20.944035Z","end":"2026-01-29T20:24:21.277708Z","steps":["trace[15011913] 'read index received' (duration: 141.846744ms)","trace[15011913] 'applied index is now lower than readState.Index' (duration: 191.825194ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T20:24:21.277795Z","caller":"traceutil/trace.go:171","msg":"trace[1686996556] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"602.62233ms","start":"2026-01-29T20:24:20.675162Z","end":"2026-01-29T20:24:21.277785Z","steps":["trace[1686996556] 'process raft request' (duration: 410.751545ms)","trace[1686996556] 'compare' (duration: 191.326652ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T20:24:21.277846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:24:20.675149Z","time spent":"602.669452ms","remote":"127.0.0.1:50484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T20:24:21.277859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.973308ms","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-01-29T20:24:21.277888Z","caller":"traceutil/trace.go:171","msg":"trace[574058561] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:570; }","duration":"234.033789ms","start":"2026-01-29T20:24:21.043845Z","end":"2026-01-29T20:24:21.277879Z","steps":["trace[574058561] 'agreement among raft nodes before linearized reading' (duration: 233.965778ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:21.277965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.930554ms","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-01-29T20:24:21.27798Z","caller":"traceutil/trace.go:171","msg":"trace[1491583872] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:570; }","duration":"333.972545ms","start":"2026-01-29T20:24:20.944003Z","end":"2026-01-29T20:24:21.277975Z","steps":["trace[1491583872] 'agreement among raft nodes before linearized reading' (duration: 333.930544ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:21.277995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:24:20.943988Z","time spent":"334.002926ms","remote":"127.0.0.1:50484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-01-29T20:24:21.866639Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9997599795692979410,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-29T20:24:22.367548Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9997599795692979410,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-29T20:24:22.867886Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9997599795692979410,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-29T20:24:22.940725Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.653499644s","expected-duration":"1s"} {"level":"info","ts":"2026-01-29T20:24:22.941649Z","caller":"traceutil/trace.go:171","msg":"trace[1151114287] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"1.654477358s","start":"2026-01-29T20:24:21.287149Z","end":"2026-01-29T20:24:22.941627Z","steps":["trace[1151114287] 'process raft request' (duration: 1.6537213s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:22.941756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:24:21.28713Z","time spent":"1.654557259s","remote":"127.0.0.1:50484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T20:24:22.957537Z","caller":"traceutil/trace.go:171","msg":"trace[337056560] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"1.668812936s","start":"2026-01-29T20:24:21.288706Z","end":"2026-01-29T20:24:22.957519Z","steps":["trace[337056560] 'process raft request' (duration: 1.66856421s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:22.957653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:24:21.28869Z","time spent":"1.668898557s","remote":"127.0.0.1:50484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T20:24:22.957647Z","caller":"traceutil/trace.go:171","msg":"trace[1269186367] linearizableReadLoop","detail":"{readStateIndex:595; appliedIndex:592; }","duration":"1.591981043s","start":"2026-01-29T20:24:21.36565Z","end":"2026-01-29T20:24:22.957631Z","steps":["trace[1269186367] 'read index received' (duration: 1.575231588s)","trace[1269186367] 'applied index is now lower than readState.Index' (duration: 16.748485ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T20:24:22.957815Z","caller":"traceutil/trace.go:171","msg":"trace[1753592495] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"1.661159035s","start":"2026-01-29T20:24:21.296648Z","end":"2026-01-29T20:24:22.957807Z","steps":["trace[1753592495] 'process raft request' (duration: 1.660816027s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:22.95791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:24:21.296636Z","time spent":"1.661198026s","remote":"127.0.0.1:50384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T20:24:22.957956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.21231505s","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-01-29T20:24:22.957992Z","caller":"traceutil/trace.go:171","msg":"trace[864587717] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:573; }","duration":"1.212378581s","start":"2026-01-29T20:24:21.745603Z","end":"2026-01-29T20:24:22.957981Z","steps":["trace[864587717] 'agreement among raft nodes before linearized reading' (duration: 1.21231659s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:22.958021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:24:21.745588Z","time spent":"1.212425382s","remote":"127.0.0.1:50484","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-01-29T20:24:22.958197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.592545507s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-01-29T20:24:22.958205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"948.518199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T20:24:22.958225Z","caller":"traceutil/trace.go:171","msg":"trace[1153625473] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:573; }","duration":"1.592570168s","start":"2026-01-29T20:24:21.365645Z","end":"2026-01-29T20:24:22.958215Z","steps":["trace[1153625473] 'agreement among raft nodes before linearized reading' (duration: 1.592470876s)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:24:22.958243Z","caller":"traceutil/trace.go:171","msg":"trace[494630533] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:573; }","duration":"948.58012ms","start":"2026-01-29T20:24:22.009655Z","end":"2026-01-29T20:24:22.958235Z","steps":["trace[494630533] 'agreement among raft nodes before linearized reading' (duration: 948.526559ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:22.958251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:24:21.365604Z","time spent":"1.59264211s","remote":"127.0.0.1:50484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-01-29T20:24:22.958265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:24:22.009641Z","time spent":"948.620091ms","remote":"127.0.0.1:50166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-29T20:24:22.958371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"998.538368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-01-29T20:24:22.958419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.977326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.218\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-01-29T20:24:22.958446Z","caller":"traceutil/trace.go:171","msg":"trace[26426961] range","detail":"{range_begin:/registry/masterleases/162.253.55.218; range_end:; response_count:1; response_revision:573; }","duration":"359.041907ms","start":"2026-01-29T20:24:22.599396Z","end":"2026-01-29T20:24:22.958438Z","steps":["trace[26426961] 'agreement among raft nodes before linearized reading' (duration: 358.943885ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:22.958464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:24:22.599373Z","time spent":"359.086159ms","remote":"127.0.0.1:50216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/162.253.55.218\" "} {"level":"info","ts":"2026-01-29T20:24:22.958397Z","caller":"traceutil/trace.go:171","msg":"trace[470857005] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:573; }","duration":"998.645961ms","start":"2026-01-29T20:24:21.959742Z","end":"2026-01-29T20:24:22.958388Z","steps":["trace[470857005] 'agreement among raft nodes before linearized reading' (duration: 998.60751ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:22.96768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:24:21.959729Z","time spent":"1.00793933s","remote":"127.0.0.1:50156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-01-29T20:24:24.712869Z","caller":"traceutil/trace.go:171","msg":"trace[1079201791] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"167.739255ms","start":"2026-01-29T20:24:24.545104Z","end":"2026-01-29T20:24:24.712843Z","steps":["trace[1079201791] 'process raft request' (duration: 167.594711ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:24:24.730095Z","caller":"traceutil/trace.go:171","msg":"trace[167498702] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"178.94488ms","start":"2026-01-29T20:24:24.551137Z","end":"2026-01-29T20:24:24.730082Z","steps":["trace[167498702] 'process raft request' (duration: 172.359564ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:25.126406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.97422ms","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-01-29T20:24:25.126461Z","caller":"traceutil/trace.go:171","msg":"trace[356875324] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:584; }","duration":"165.059473ms","start":"2026-01-29T20:24:24.96139Z","end":"2026-01-29T20:24:25.126449Z","steps":["trace[356875324] 'range keys from in-memory index tree' (duration: 164.884659ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:25.126504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.198576ms","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-01-29T20:24:25.126526Z","caller":"traceutil/trace.go:171","msg":"trace[1378078347] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:584; }","duration":"151.270598ms","start":"2026-01-29T20:24:24.975249Z","end":"2026-01-29T20:24:25.126519Z","steps":["trace[1378078347] 'range keys from in-memory index tree' (duration: 151.098094ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:25.126586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.072692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-01-29T20:24:25.126596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.454475ms","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-01-29T20:24:25.126598Z","caller":"traceutil/trace.go:171","msg":"trace[380125911] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:584; }","duration":"148.101142ms","start":"2026-01-29T20:24:24.978492Z","end":"2026-01-29T20:24:25.126594Z","steps":["trace[380125911] 'range keys from in-memory index tree' (duration: 148.024951ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:24:25.126638Z","caller":"traceutil/trace.go:171","msg":"trace[1091598606] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:584; }","duration":"180.498157ms","start":"2026-01-29T20:24:24.946108Z","end":"2026-01-29T20:24:25.126606Z","steps":["trace[1091598606] 'range keys from in-memory index tree' (duration: 180.398154ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:24:26.992006Z","caller":"traceutil/trace.go:171","msg":"trace[1200851700] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"284.449618ms","start":"2026-01-29T20:24:26.707529Z","end":"2026-01-29T20:24:26.991978Z","steps":["trace[1200851700] 'process raft request' (duration: 284.377816ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:24:28.090759Z","caller":"traceutil/trace.go:171","msg":"trace[297631883] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"363.448565ms","start":"2026-01-29T20:24:27.727295Z","end":"2026-01-29T20:24:28.090743Z","steps":["trace[297631883] 'process raft request' (duration: 361.625121ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:28.090858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:24:27.72728Z","time spent":"363.526516ms","remote":"127.0.0.1:50234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13205,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T20:24:28.726179Z","caller":"traceutil/trace.go:171","msg":"trace[1775027436] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"819.503686ms","start":"2026-01-29T20:24:27.906663Z","end":"2026-01-29T20:24:28.726166Z","steps":["trace[1775027436] 'process raft request' (duration: 819.470895ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:28.726261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:24:27.90664Z","time spent":"819.581788ms","remote":"127.0.0.1:50234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4234,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-29T20:24:28.726389Z","caller":"traceutil/trace.go:171","msg":"trace[489637952] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"997.635376ms","start":"2026-01-29T20:24:27.728748Z","end":"2026-01-29T20:24:28.726383Z","steps":["trace[489637952] 'process raft request' (duration: 901.893091ms)","trace[489637952] 'compare' (duration: 95.232653ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T20:24:28.726416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:24:27.72874Z","time spent":"997.661546ms","remote":"127.0.0.1:50262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-29T20:24:29.318869Z","caller":"traceutil/trace.go:171","msg":"trace[1503052815] linearizableReadLoop","detail":"{readStateIndex:662; appliedIndex:658; }","duration":"1.015575065s","start":"2026-01-29T20:24:28.303283Z","end":"2026-01-29T20:24:29.318858Z","steps":["trace[1503052815] 'read index received' (duration: 327.365584ms)","trace[1503052815] 'applied index is now lower than readState.Index' (duration: 688.208981ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T20:24:29.319019Z","caller":"traceutil/trace.go:171","msg":"trace[968336344] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"1.216010497s","start":"2026-01-29T20:24:28.103002Z","end":"2026-01-29T20:24:29.319012Z","steps":["trace[968336344] 'process raft request' (duration: 1.215649029s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:29.319082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:24:28.102983Z","time spent":"1.216052478s","remote":"127.0.0.1:50234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13214,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T20:24:29.319168Z","caller":"traceutil/trace.go:171","msg":"trace[1418151699] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"1.213916908s","start":"2026-01-29T20:24:28.105246Z","end":"2026-01-29T20:24:29.319163Z","steps":["trace[1418151699] 'process raft request' (duration: 1.213491308s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:29.319191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:24:28.105229Z","time spent":"1.213949248s","remote":"127.0.0.1:50234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-29T20:24:29.319251Z","caller":"traceutil/trace.go:171","msg":"trace[1650942009] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"766.669208ms","start":"2026-01-29T20:24:28.552577Z","end":"2026-01-29T20:24:29.319246Z","steps":["trace[1650942009] 'process raft request' (duration: 766.200007ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:29.31927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:24:28.552562Z","time spent":"766.698808ms","remote":"127.0.0.1:50234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187455,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-01-29T20:24:29.319332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.016049167s","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-01-29T20:24:29.319349Z","caller":"traceutil/trace.go:171","msg":"trace[1101223974] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:640; }","duration":"1.016088038s","start":"2026-01-29T20:24:28.303255Z","end":"2026-01-29T20:24:29.319343Z","steps":["trace[1101223974] 'agreement among raft nodes before linearized reading' (duration: 1.016062207s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:29.319372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:24:28.303243Z","time spent":"1.016126459s","remote":"127.0.0.1:50234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"info","ts":"2026-01-29T20:24:29.326971Z","caller":"traceutil/trace.go:171","msg":"trace[593034357] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"596.653275ms","start":"2026-01-29T20:24:28.730273Z","end":"2026-01-29T20:24:29.326927Z","steps":["trace[593034357] 'process raft request' (duration: 594.29391ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:29.327076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:24:28.730257Z","time spent":"596.760268ms","remote":"127.0.0.1:50234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5153,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T20:24:29.354524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"983.338913ms","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-01-29T20:24:29.354577Z","caller":"traceutil/trace.go:171","msg":"trace[2018062152] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:641; }","duration":"983.404944ms","start":"2026-01-29T20:24:28.37116Z","end":"2026-01-29T20:24:29.354565Z","steps":["trace[2018062152] 'agreement among raft nodes before linearized reading' (duration: 983.299402ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:29.354601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:24:28.371127Z","time spent":"983.469836ms","remote":"127.0.0.1:50484","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-01-29T20:24:29.354953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.420274ms","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-01-29T20:24:29.354977Z","caller":"traceutil/trace.go:171","msg":"trace[930423590] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:641; }","duration":"179.461506ms","start":"2026-01-29T20:24:29.17551Z","end":"2026-01-29T20:24:29.354972Z","steps":["trace[930423590] 'agreement among raft nodes before linearized reading' (duration: 179.395964ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:29.355076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.763043ms","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-01-29T20:24:29.355093Z","caller":"traceutil/trace.go:171","msg":"trace[698238853] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:641; }","duration":"179.794874ms","start":"2026-01-29T20:24:29.175294Z","end":"2026-01-29T20:24:29.355089Z","steps":["trace[698238853] 'agreement among raft nodes before linearized reading' (duration: 179.752753ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:29.355157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.47254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-01-29T20:24:29.355173Z","caller":"traceutil/trace.go:171","msg":"trace[1280126842] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:641; }","duration":"180.52619ms","start":"2026-01-29T20:24:29.174643Z","end":"2026-01-29T20:24:29.355169Z","steps":["trace[1280126842] 'agreement among raft nodes before linearized reading' (duration: 180.50066ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:29.355263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.181789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-01-29T20:24:29.355283Z","caller":"traceutil/trace.go:171","msg":"trace[103431736] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:641; }","duration":"187.228599ms","start":"2026-01-29T20:24:29.16805Z","end":"2026-01-29T20:24:29.355279Z","steps":["trace[103431736] 'agreement among raft nodes before linearized reading' (duration: 187.194288ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:29.355384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.113673ms","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-01-29T20:24:29.35541Z","caller":"traceutil/trace.go:171","msg":"trace[890027517] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:641; }","duration":"249.176885ms","start":"2026-01-29T20:24:29.106227Z","end":"2026-01-29T20:24:29.355404Z","steps":["trace[890027517] 'agreement among raft nodes before linearized reading' (duration: 249.117014ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:29.355478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.351457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T20:24:29.355494Z","caller":"traceutil/trace.go:171","msg":"trace[725960566] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:641; }","duration":"344.400497ms","start":"2026-01-29T20:24:29.011089Z","end":"2026-01-29T20:24:29.35549Z","steps":["trace[725960566] 'agreement among raft nodes before linearized reading' (duration: 344.376376ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:29.355505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:24:29.011073Z","time spent":"344.430038ms","remote":"127.0.0.1:50156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-29T20:24:29.35561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.967362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-01-29T20:24:29.355628Z","caller":"traceutil/trace.go:171","msg":"trace[764981750] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:641; }","duration":"452.007793ms","start":"2026-01-29T20:24:28.903616Z","end":"2026-01-29T20:24:29.355624Z","steps":["trace[764981750] 'agreement among raft nodes before linearized reading' (duration: 451.956212ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:29.355648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:24:28.903604Z","time spent":"452.034574ms","remote":"127.0.0.1:50234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4203,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" "} {"level":"warn","ts":"2026-01-29T20:24:29.355724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.166858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-01-29T20:24:29.355741Z","caller":"traceutil/trace.go:171","msg":"trace[1750974733] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:641; }","duration":"652.20617ms","start":"2026-01-29T20:24:28.70353Z","end":"2026-01-29T20:24:29.355737Z","steps":["trace[1750974733] 'agreement among raft nodes before linearized reading' (duration: 652.164759ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:29.355756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:24:28.703516Z","time spent":"652.23684ms","remote":"127.0.0.1:50234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":1,"response size":5609,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" "} {"level":"warn","ts":"2026-01-29T20:24:29.35584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"761.159037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-tcwww\" ","response":"range_response_count:1 size:17918"} {"level":"info","ts":"2026-01-29T20:24:29.355856Z","caller":"traceutil/trace.go:171","msg":"trace[1557600392] range","detail":"{range_begin:/registry/pods/kube-system/cilium-tcwww; range_end:; response_count:1; response_revision:641; }","duration":"761.198968ms","start":"2026-01-29T20:24:28.594653Z","end":"2026-01-29T20:24:29.355852Z","steps":["trace[1557600392] 'agreement among raft nodes before linearized reading' (duration: 761.150276ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:29.355867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:24:28.59464Z","time spent":"761.225029ms","remote":"127.0.0.1:50384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17941,"request content":"key:\"/registry/pods/kube-system/cilium-tcwww\" "} {"level":"warn","ts":"2026-01-29T20:24:33.947153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.082992ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-29T20:24:33.947224Z","caller":"traceutil/trace.go:171","msg":"trace[1338852177] linearizableReadLoop","detail":"{readStateIndex:714; appliedIndex:712; }","duration":"442.287104ms","start":"2026-01-29T20:24:33.504926Z","end":"2026-01-29T20:24:33.947213Z","steps":["trace[1338852177] 'read index received' (duration: 53.216649ms)","trace[1338852177] 'applied index is now lower than readState.Index' (duration: 389.069795ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T20:24:33.947274Z","caller":"traceutil/trace.go:171","msg":"trace[1272939380] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"447.682711ms","start":"2026-01-29T20:24:33.499585Z","end":"2026-01-29T20:24:33.947268Z","steps":["trace[1272939380] 'process raft request' (duration: 253.429645ms)","trace[1272939380] 'compare' (duration: 193.893038ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T20:24:33.947303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:24:33.499579Z","time spent":"447.708342ms","remote":"127.0.0.1:50484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T20:24:33.947351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.187674ms","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-01-29T20:24:33.947393Z","caller":"traceutil/trace.go:171","msg":"trace[2100301033] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:687; }","duration":"440.267096ms","start":"2026-01-29T20:24:33.507116Z","end":"2026-01-29T20:24:33.947383Z","steps":["trace[2100301033] 'agreement among raft nodes before linearized reading' (duration: 440.159243ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:33.947417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:24:33.507101Z","time spent":"440.312056ms","remote":"127.0.0.1:50484","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-01-29T20:24:33.947529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.332429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/cilium-tcwww.188f4d644fe5fc9c\" ","response":"range_response_count:1 size:795"} {"level":"warn","ts":"2026-01-29T20:24:33.94754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.945862ms","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-01-29T20:24:33.947548Z","caller":"traceutil/trace.go:171","msg":"trace[862936752] range","detail":"{range_begin:/registry/events/kube-system/cilium-tcwww.188f4d644fe5fc9c; range_end:; response_count:1; response_revision:687; }","duration":"126.38453ms","start":"2026-01-29T20:24:33.821158Z","end":"2026-01-29T20:24:33.947542Z","steps":["trace[862936752] 'agreement among raft nodes before linearized reading' (duration: 126.300838ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:24:33.947557Z","caller":"traceutil/trace.go:171","msg":"trace[1830683887] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:687; }","duration":"432.987964ms","start":"2026-01-29T20:24:33.514563Z","end":"2026-01-29T20:24:33.947551Z","steps":["trace[1830683887] 'agreement among raft nodes before linearized reading' (duration: 432.944183ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:33.947571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:24:33.514551Z","time spent":"433.017434ms","remote":"127.0.0.1:50484","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-01-29T20:24:33.947651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.726634ms","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-01-29T20:24:33.947666Z","caller":"traceutil/trace.go:171","msg":"trace[581530098] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:687; }","duration":"442.751744ms","start":"2026-01-29T20:24:33.50491Z","end":"2026-01-29T20:24:33.947662Z","steps":["trace[581530098] 'agreement among raft nodes before linearized reading' (duration: 442.705233ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:33.947679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:24:33.504905Z","time spent":"442.770395ms","remote":"127.0.0.1:50484","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":"info","ts":"2026-01-29T20:24:34.064944Z","caller":"traceutil/trace.go:171","msg":"trace[432430390] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"114.294104ms","start":"2026-01-29T20:24:33.950635Z","end":"2026-01-29T20:24:34.064929Z","steps":["trace[432430390] 'process raft request' (duration: 111.519758ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:24:34.066124Z","caller":"traceutil/trace.go:171","msg":"trace[642859817] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"113.612358ms","start":"2026-01-29T20:24:33.952485Z","end":"2026-01-29T20:24:34.066098Z","steps":["trace[642859817] 'process raft request' (duration: 113.370262ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:24:34.06619Z","caller":"traceutil/trace.go:171","msg":"trace[997597185] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"113.643309ms","start":"2026-01-29T20:24:33.952543Z","end":"2026-01-29T20:24:34.066186Z","steps":["trace[997597185] 'process raft request' (duration: 113.350252ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:24:34.066251Z","caller":"traceutil/trace.go:171","msg":"trace[1710782679] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"111.739804ms","start":"2026-01-29T20:24:33.954506Z","end":"2026-01-29T20:24:34.066246Z","steps":["trace[1710782679] 'process raft request' (duration: 111.404206ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:40.079775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.891501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-b5k5b\" ","response":"range_response_count:1 size:4821"} {"level":"info","ts":"2026-01-29T20:24:40.079836Z","caller":"traceutil/trace.go:171","msg":"trace[809728110] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-b5k5b; range_end:; response_count:1; response_revision:746; }","duration":"372.998074ms","start":"2026-01-29T20:24:39.706824Z","end":"2026-01-29T20:24:40.079822Z","steps":["trace[809728110] 'range keys from in-memory index tree' (duration: 372.785709ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:40.079866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:24:39.706801Z","time spent":"373.056455ms","remote":"127.0.0.1:50384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":4844,"request content":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-b5k5b\" "} {"level":"info","ts":"2026-01-29T20:24:40.26769Z","caller":"traceutil/trace.go:171","msg":"trace[1511427918] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"559.826524ms","start":"2026-01-29T20:24:39.707846Z","end":"2026-01-29T20:24:40.267672Z","steps":["trace[1511427918] 'process raft request' (duration: 559.686141ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:40.267797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:24:39.70783Z","time spent":"559.917906ms","remote":"127.0.0.1:50262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":723,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-29T20:24:40.268026Z","caller":"traceutil/trace.go:171","msg":"trace[299883159] linearizableReadLoop","detail":"{readStateIndex:776; appliedIndex:776; }","duration":"495.171104ms","start":"2026-01-29T20:24:39.772846Z","end":"2026-01-29T20:24:40.268017Z","steps":["trace[299883159] 'read index received' (duration: 495.168034ms)","trace[299883159] 'applied index is now lower than readState.Index' (duration: 2.39µs)"],"step_count":2} {"level":"warn","ts":"2026-01-29T20:24:40.268102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.329708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T20:24:40.268124Z","caller":"traceutil/trace.go:171","msg":"trace[1518498017] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:747; }","duration":"257.387569ms","start":"2026-01-29T20:24:40.010729Z","end":"2026-01-29T20:24:40.268117Z","steps":["trace[1518498017] 'agreement among raft nodes before linearized reading' (duration: 257.335958ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:40.268312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.461731ms","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-01-29T20:24:40.268338Z","caller":"traceutil/trace.go:171","msg":"trace[1615879879] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:747; }","duration":"495.507673ms","start":"2026-01-29T20:24:39.772824Z","end":"2026-01-29T20:24:40.268331Z","steps":["trace[1615879879] 'agreement among raft nodes before linearized reading' (duration: 495.447161ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:40.26839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:24:39.772814Z","time spent":"495.570444ms","remote":"127.0.0.1:50506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":28,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-01-29T20:24:40.268545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.781184ms","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-01-29T20:24:40.268564Z","caller":"traceutil/trace.go:171","msg":"trace[139813493] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:747; }","duration":"160.819225ms","start":"2026-01-29T20:24:40.107739Z","end":"2026-01-29T20:24:40.268558Z","steps":["trace[139813493] 'agreement among raft nodes before linearized reading' (duration: 160.749413ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:40.268682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.27534ms","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-01-29T20:24:40.268699Z","caller":"traceutil/trace.go:171","msg":"trace[1604192929] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:747; }","duration":"165.318811ms","start":"2026-01-29T20:24:40.103375Z","end":"2026-01-29T20:24:40.268694Z","steps":["trace[1604192929] 'agreement among raft nodes before linearized reading' (duration: 165.26417ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:40.268803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.843088ms","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-01-29T20:24:40.268821Z","caller":"traceutil/trace.go:171","msg":"trace[2102325882] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:747; }","duration":"166.886868ms","start":"2026-01-29T20:24:40.101929Z","end":"2026-01-29T20:24:40.268816Z","steps":["trace[2102325882] 'agreement among raft nodes before linearized reading' (duration: 166.838707ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:40.26893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.722021ms","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-01-29T20:24:40.268951Z","caller":"traceutil/trace.go:171","msg":"trace[1711490796] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:747; }","duration":"247.763122ms","start":"2026-01-29T20:24:40.021183Z","end":"2026-01-29T20:24:40.268946Z","steps":["trace[1711490796] 'agreement among raft nodes before linearized reading' (duration: 247.712961ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:40.85353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.83492ms","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-01-29T20:24:40.853654Z","caller":"traceutil/trace.go:171","msg":"trace[525095485] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:747; }","duration":"544.972513ms","start":"2026-01-29T20:24:40.308664Z","end":"2026-01-29T20:24:40.853637Z","steps":["trace[525095485] 'range keys from in-memory index tree' (duration: 544.815849ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:40.853835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.023793ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-29T20:24:40.854019Z","caller":"traceutil/trace.go:171","msg":"trace[1751994135] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"579.633313ms","start":"2026-01-29T20:24:40.274367Z","end":"2026-01-29T20:24:40.854Z","steps":["trace[1751994135] 'process raft request' (duration: 387.355604ms)","trace[1751994135] 'compare' (duration: 191.88734ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T20:24:40.854089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:24:40.274355Z","time spent":"579.702404ms","remote":"127.0.0.1:50484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T20:24:40.854222Z","caller":"traceutil/trace.go:171","msg":"trace[1223892218] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"578.875695ms","start":"2026-01-29T20:24:40.275331Z","end":"2026-01-29T20:24:40.854207Z","steps":["trace[1223892218] 'process raft request' (duration: 578.594218ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:40.85432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:24:40.275324Z","time spent":"578.935476ms","remote":"127.0.0.1:50484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T20:24:40.854566Z","caller":"traceutil/trace.go:171","msg":"trace[359617546] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"579.173032ms","start":"2026-01-29T20:24:40.275377Z","end":"2026-01-29T20:24:40.85455Z","steps":["trace[359617546] 'process raft request' (duration: 578.761022ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:40.854633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:24:40.275371Z","time spent":"579.227803ms","remote":"127.0.0.1:50484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T20:24:40.85473Z","caller":"traceutil/trace.go:171","msg":"trace[1720372435] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"577.260887ms","start":"2026-01-29T20:24:40.277458Z","end":"2026-01-29T20:24:40.854718Z","steps":["trace[1720372435] 'process raft request' (duration: 577.050072ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:40.854805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:24:40.27745Z","time spent":"577.300198ms","remote":"127.0.0.1:50484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T20:24:41.832774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"783.440084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T20:24:41.832847Z","caller":"traceutil/trace.go:171","msg":"trace[1297353931] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:751; }","duration":"783.528917ms","start":"2026-01-29T20:24:41.0493Z","end":"2026-01-29T20:24:41.832829Z","steps":["trace[1297353931] 'count revisions from in-memory index tree' (duration: 783.377873ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:41.832899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:24:41.049287Z","time spent":"783.596568ms","remote":"127.0.0.1:50342","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"info","ts":"2026-01-29T20:24:41.840047Z","caller":"traceutil/trace.go:171","msg":"trace[2052570318] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"346.18011ms","start":"2026-01-29T20:24:41.49385Z","end":"2026-01-29T20:24:41.840031Z","steps":["trace[2052570318] 'process raft request' (duration: 346.087407ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:41.840133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:24:41.493831Z","time spent":"346.262311ms","remote":"127.0.0.1:50262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":815,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-29T20:24:41.840427Z","caller":"traceutil/trace.go:171","msg":"trace[15732403] linearizableReadLoop","detail":"{readStateIndex:781; appliedIndex:781; }","duration":"229.242163ms","start":"2026-01-29T20:24:41.611177Z","end":"2026-01-29T20:24:41.840419Z","steps":["trace[15732403] 'read index received' (duration: 229.238793ms)","trace[15732403] 'applied index is now lower than readState.Index' (duration: 2.82µs)"],"step_count":2} {"level":"warn","ts":"2026-01-29T20:24:41.840551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.375566ms","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-01-29T20:24:41.840575Z","caller":"traceutil/trace.go:171","msg":"trace[398466513] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:752; }","duration":"229.427887ms","start":"2026-01-29T20:24:41.611139Z","end":"2026-01-29T20:24:41.840566Z","steps":["trace[398466513] 'agreement among raft nodes before linearized reading' (duration: 229.308494ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:24:41.840719Z","caller":"traceutil/trace.go:171","msg":"trace[1667336619] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"140.956274ms","start":"2026-01-29T20:24:41.699689Z","end":"2026-01-29T20:24:41.840646Z","steps":["trace[1667336619] 'process raft request' (duration: 140.854062ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:24:46.213963Z","caller":"traceutil/trace.go:171","msg":"trace[887139312] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"350.566533ms","start":"2026-01-29T20:24:45.863373Z","end":"2026-01-29T20:24:46.21394Z","steps":["trace[887139312] 'process raft request' (duration: 350.44412ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:46.214105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:24:45.863357Z","time spent":"350.675845ms","remote":"127.0.0.1:50484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T20:24:46.214661Z","caller":"traceutil/trace.go:171","msg":"trace[893284030] linearizableReadLoop","detail":"{readStateIndex:795; appliedIndex:795; }","duration":"204.755064ms","start":"2026-01-29T20:24:46.009893Z","end":"2026-01-29T20:24:46.214648Z","steps":["trace[893284030] 'read index received' (duration: 204.749843ms)","trace[893284030] 'applied index is now lower than readState.Index' (duration: 3.961µs)"],"step_count":2} {"level":"warn","ts":"2026-01-29T20:24:46.215709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.803288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T20:24:46.215755Z","caller":"traceutil/trace.go:171","msg":"trace[644267859] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:765; }","duration":"205.86605ms","start":"2026-01-29T20:24:46.009874Z","end":"2026-01-29T20:24:46.21574Z","steps":["trace[644267859] 'agreement among raft nodes before linearized reading' (duration: 204.846085ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:47.030713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.711801ms","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-01-29T20:24:47.03076Z","caller":"traceutil/trace.go:171","msg":"trace[804012398] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:765; }","duration":"146.854424ms","start":"2026-01-29T20:24:46.883895Z","end":"2026-01-29T20:24:47.030749Z","steps":["trace[804012398] 'range keys from in-memory index tree' (duration: 146.565267ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:47.030897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.48623ms","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-01-29T20:24:47.03091Z","caller":"traceutil/trace.go:171","msg":"trace[1471082886] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:765; }","duration":"139.519901ms","start":"2026-01-29T20:24:46.891386Z","end":"2026-01-29T20:24:47.030906Z","steps":["trace[1471082886] 'range keys from in-memory index tree' (duration: 139.419828ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:47.031001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.11842ms","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-01-29T20:24:47.031014Z","caller":"traceutil/trace.go:171","msg":"trace[1366265227] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:765; }","duration":"136.170851ms","start":"2026-01-29T20:24:46.894839Z","end":"2026-01-29T20:24:47.03101Z","steps":["trace[1366265227] 'range keys from in-memory index tree' (duration: 136.024328ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:47.031088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.521637ms","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-01-29T20:24:47.031099Z","caller":"traceutil/trace.go:171","msg":"trace[2076965340] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:765; }","duration":"130.546789ms","start":"2026-01-29T20:24:46.900549Z","end":"2026-01-29T20:24:47.031096Z","steps":["trace[2076965340] 'range keys from in-memory index tree' (duration: 130.473677ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:24:47.440391Z","caller":"traceutil/trace.go:171","msg":"trace[166521065] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"404.626042ms","start":"2026-01-29T20:24:47.035711Z","end":"2026-01-29T20:24:47.440337Z","steps":["trace[166521065] 'process raft request' (duration: 404.515109ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:47.440517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:24:47.035696Z","time spent":"404.756856ms","remote":"127.0.0.1:50484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T20:24:47.794679Z","caller":"traceutil/trace.go:171","msg":"trace[998090103] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"756.977488ms","start":"2026-01-29T20:24:47.037673Z","end":"2026-01-29T20:24:47.79465Z","steps":["trace[998090103] 'process raft request' (duration: 756.939847ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:47.794902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:24:47.037669Z","time spent":"757.08147ms","remote":"127.0.0.1:50484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T20:24:47.794962Z","caller":"traceutil/trace.go:171","msg":"trace[2072165539] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"757.355747ms","start":"2026-01-29T20:24:47.037576Z","end":"2026-01-29T20:24:47.794931Z","steps":["trace[2072165539] 'process raft request' (duration: 679.816632ms)","trace[2072165539] 'compare' (duration: 76.839558ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T20:24:47.795092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:24:47.037569Z","time spent":"757.453279ms","remote":"127.0.0.1:50484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T20:24:47.795104Z","caller":"traceutil/trace.go:171","msg":"trace[45953546] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"757.465189ms","start":"2026-01-29T20:24:47.037628Z","end":"2026-01-29T20:24:47.795093Z","steps":["trace[45953546] 'process raft request' (duration: 756.940417ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:47.795162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:24:47.037624Z","time spent":"757.50705ms","remote":"127.0.0.1:50484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T20:24:48.094479Z","caller":"traceutil/trace.go:171","msg":"trace[15981475] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"539.36528ms","start":"2026-01-29T20:24:47.555097Z","end":"2026-01-29T20:24:48.094462Z","steps":["trace[15981475] 'process raft request' (duration: 539.248017ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:48.094584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:24:47.555082Z","time spent":"539.449472ms","remote":"127.0.0.1:50484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T20:24:48.317377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.633322ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-01-29T20:24:48.317457Z","caller":"traceutil/trace.go:171","msg":"trace[1681753965] linearizableReadLoop","detail":"{readStateIndex:801; appliedIndex:800; }","duration":"309.154234ms","start":"2026-01-29T20:24:48.00829Z","end":"2026-01-29T20:24:48.317444Z","steps":["trace[1681753965] 'read index received' (duration: 86.587809ms)","trace[1681753965] 'applied index is now lower than readState.Index' (duration: 222.565405ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T20:24:48.31754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.242256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T20:24:48.317556Z","caller":"traceutil/trace.go:171","msg":"trace[762404609] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:770; }","duration":"309.286307ms","start":"2026-01-29T20:24:48.008264Z","end":"2026-01-29T20:24:48.31755Z","steps":["trace[762404609] 'agreement among raft nodes before linearized reading' (duration: 309.245066ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:48.317578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:24:48.008252Z","time spent":"309.321388ms","remote":"127.0.0.1:50156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-01-29T20:24:49.552226Z","caller":"traceutil/trace.go:171","msg":"trace[269409370] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"103.414317ms","start":"2026-01-29T20:24:49.448788Z","end":"2026-01-29T20:24:49.552203Z","steps":["trace[269409370] 'process raft request' (duration: 103.204852ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:24:49.934626Z","caller":"traceutil/trace.go:171","msg":"trace[180445195] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"112.45026ms","start":"2026-01-29T20:24:49.822156Z","end":"2026-01-29T20:24:49.934606Z","steps":["trace[180445195] 'process raft request' (duration: 112.338918ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:24:50.089712Z","caller":"traceutil/trace.go:171","msg":"trace[138624297] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"266.897034ms","start":"2026-01-29T20:24:49.822784Z","end":"2026-01-29T20:24:50.089681Z","steps":["trace[138624297] 'process raft request' (duration: 215.461398ms)","trace[138624297] 'compare' (duration: 51.295203ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T20:24:50.089941Z","caller":"traceutil/trace.go:171","msg":"trace[1900539138] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"266.093415ms","start":"2026-01-29T20:24:49.823832Z","end":"2026-01-29T20:24:50.089925Z","steps":["trace[1900539138] 'process raft request' (duration: 265.802618ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:50.830846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.369254ms","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-01-29T20:24:50.830978Z","caller":"traceutil/trace.go:171","msg":"trace[1548158361] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:775; }","duration":"521.530778ms","start":"2026-01-29T20:24:50.309429Z","end":"2026-01-29T20:24:50.83096Z","steps":["trace[1548158361] 'range keys from in-memory index tree' (duration: 521.349634ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:50.831109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.539434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-01-29T20:24:50.8312Z","caller":"traceutil/trace.go:171","msg":"trace[1106517072] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:775; }","duration":"476.666826ms","start":"2026-01-29T20:24:50.354514Z","end":"2026-01-29T20:24:50.831181Z","steps":["trace[1106517072] 'range keys from in-memory index tree' (duration: 476.396571ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:50.83124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:24:50.3545Z","time spent":"476.730339ms","remote":"127.0.0.1:50484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-01-29T20:24:50.831557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.164074ms","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-01-29T20:24:50.831594Z","caller":"traceutil/trace.go:171","msg":"trace[731376211] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:775; }","duration":"290.231736ms","start":"2026-01-29T20:24:50.541352Z","end":"2026-01-29T20:24:50.831583Z","steps":["trace[731376211] 'count revisions from in-memory index tree' (duration: 290.079923ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:24:51.151642Z","caller":"traceutil/trace.go:171","msg":"trace[1469015196] linearizableReadLoop","detail":"{readStateIndex:807; appliedIndex:806; }","duration":"142.690065ms","start":"2026-01-29T20:24:51.008935Z","end":"2026-01-29T20:24:51.151625Z","steps":["trace[1469015196] 'read index received' (duration: 142.524832ms)","trace[1469015196] 'applied index is now lower than readState.Index' (duration: 164.643µs)"],"step_count":2} {"level":"warn","ts":"2026-01-29T20:24:51.151736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.784158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T20:24:51.151757Z","caller":"traceutil/trace.go:171","msg":"trace[333652064] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:776; }","duration":"142.842879ms","start":"2026-01-29T20:24:51.008908Z","end":"2026-01-29T20:24:51.151751Z","steps":["trace[333652064] 'agreement among raft nodes before linearized reading' (duration: 142.780498ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:24:51.152023Z","caller":"traceutil/trace.go:171","msg":"trace[639207974] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"312.225927ms","start":"2026-01-29T20:24:50.839789Z","end":"2026-01-29T20:24:51.152015Z","steps":["trace[639207974] 'process raft request' (duration: 311.720345ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:24:51.15208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:24:50.839771Z","time spent":"312.269197ms","remote":"127.0.0.1:50484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T20:25:02.111933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.715639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T20:25:02.112004Z","caller":"traceutil/trace.go:171","msg":"trace[715617081] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:810; }","duration":"151.826622ms","start":"2026-01-29T20:25:01.960161Z","end":"2026-01-29T20:25:02.111988Z","steps":["trace[715617081] 'range keys from in-memory index tree' (duration: 151.649678ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:25:02.112456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.395332ms","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-01-29T20:25:02.112511Z","caller":"traceutil/trace.go:171","msg":"trace[522886151] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:810; }","duration":"140.468513ms","start":"2026-01-29T20:25:01.972032Z","end":"2026-01-29T20:25:02.112501Z","steps":["trace[522886151] 'range keys from in-memory index tree' (duration: 140.238658ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:25:02.112701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.687546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T20:25:02.112735Z","caller":"traceutil/trace.go:171","msg":"trace[1263544322] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:810; }","duration":"101.742498ms","start":"2026-01-29T20:25:02.010984Z","end":"2026-01-29T20:25:02.112727Z","steps":["trace[1263544322] 'range keys from in-memory index tree' (duration: 101.652475ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:25:02.749644Z","caller":"traceutil/trace.go:171","msg":"trace[2086739217] transaction","detail":"{read_only:false; response_revision:815; number_of_response:1; }","duration":"110.596436ms","start":"2026-01-29T20:25:02.639018Z","end":"2026-01-29T20:25:02.749615Z","steps":["trace[2086739217] 'process raft request' (duration: 85.903323ms)","trace[2086739217] 'compare' (duration: 24.55136ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T20:25:03.927214Z","caller":"traceutil/trace.go:171","msg":"trace[2027697131] linearizableReadLoop","detail":"{readStateIndex:851; appliedIndex:850; }","duration":"327.873905ms","start":"2026-01-29T20:25:03.599316Z","end":"2026-01-29T20:25:03.92719Z","steps":["trace[2027697131] 'read index received' (duration: 327.567398ms)","trace[2027697131] 'applied index is now lower than readState.Index' (duration: 304.857µs)"],"step_count":2} {"level":"info","ts":"2026-01-29T20:25:03.927416Z","caller":"traceutil/trace.go:171","msg":"trace[1209642855] transaction","detail":"{read_only:false; response_revision:816; number_of_response:1; }","duration":"641.574527ms","start":"2026-01-29T20:25:03.285815Z","end":"2026-01-29T20:25:03.927389Z","steps":["trace[1209642855] 'process raft request' (duration: 641.093265ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:25:03.927674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.336187ms","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-01-29T20:25:03.92841Z","caller":"traceutil/trace.go:171","msg":"trace[1299835709] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:816; }","duration":"329.120045ms","start":"2026-01-29T20:25:03.599274Z","end":"2026-01-29T20:25:03.928394Z","steps":["trace[1299835709] 'agreement among raft nodes before linearized reading' (duration: 328.273425ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:25:03.928526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:25:03.599258Z","time spent":"329.228088ms","remote":"127.0.0.1:50484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-01-29T20:25:03.932417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:25:03.285804Z","time spent":"642.255743ms","remote":"127.0.0.1:50484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T20:25:04.910671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.487949ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-29T20:25:04.910769Z","caller":"traceutil/trace.go:171","msg":"trace[193018950] linearizableReadLoop","detail":"{readStateIndex:852; appliedIndex:851; }","duration":"755.658127ms","start":"2026-01-29T20:25:04.155095Z","end":"2026-01-29T20:25:04.910753Z","steps":["trace[193018950] 'read index received' (duration: 339.940172ms)","trace[193018950] 'applied index is now lower than readState.Index' (duration: 415.712765ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T20:25:04.910959Z","caller":"traceutil/trace.go:171","msg":"trace[1050280206] transaction","detail":"{read_only:false; response_revision:817; number_of_response:1; }","duration":"968.218506ms","start":"2026-01-29T20:25:03.942729Z","end":"2026-01-29T20:25:04.910947Z","steps":["trace[1050280206] 'process raft request' (duration: 552.382148ms)","trace[1050280206] 'compare' (duration: 415.180532ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T20:25:04.911024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:25:03.942713Z","time spent":"968.279567ms","remote":"127.0.0.1:50484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T20:25:04.911258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"756.158049ms","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-01-29T20:25:04.911295Z","caller":"traceutil/trace.go:171","msg":"trace[938364554] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:817; }","duration":"756.220501ms","start":"2026-01-29T20:25:04.155063Z","end":"2026-01-29T20:25:04.911284Z","steps":["trace[938364554] 'agreement among raft nodes before linearized reading' (duration: 756.110408ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:25:04.911387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:25:04.155047Z","time spent":"756.330383ms","remote":"127.0.0.1:50484","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-01-29T20:25:04.911551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"724.092661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-29T20:25:04.911582Z","caller":"traceutil/trace.go:171","msg":"trace[247134859] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:817; }","duration":"724.141781ms","start":"2026-01-29T20:25:04.187428Z","end":"2026-01-29T20:25:04.91157Z","steps":["trace[247134859] 'agreement among raft nodes before linearized reading' (duration: 724.09474ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:25:04.911611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:25:04.187415Z","time spent":"724.187202ms","remote":"127.0.0.1:50676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":2,"response size":30,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-01-29T20:25:04.911874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.918423ms","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-01-29T20:25:04.911911Z","caller":"traceutil/trace.go:171","msg":"trace[1231722019] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:817; }","duration":"714.973614ms","start":"2026-01-29T20:25:04.196929Z","end":"2026-01-29T20:25:04.911902Z","steps":["trace[1231722019] 'agreement among raft nodes before linearized reading' (duration: 714.881472ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:25:04.911943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:25:04.196919Z","time spent":"715.018656ms","remote":"127.0.0.1:50484","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-01-29T20:25:04.912114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"719.768148ms","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-01-29T20:25:04.912146Z","caller":"traceutil/trace.go:171","msg":"trace[1037863946] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:817; }","duration":"719.82142ms","start":"2026-01-29T20:25:04.192317Z","end":"2026-01-29T20:25:04.912139Z","steps":["trace[1037863946] 'agreement among raft nodes before linearized reading' (duration: 719.737408ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:25:04.912178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:25:04.192308Z","time spent":"719.864201ms","remote":"127.0.0.1:50484","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-01-29T20:25:05.153734Z","caller":"traceutil/trace.go:171","msg":"trace[81910660] linearizableReadLoop","detail":"{readStateIndex:855; appliedIndex:852; }","duration":"143.33507ms","start":"2026-01-29T20:25:05.010357Z","end":"2026-01-29T20:25:05.153692Z","steps":["trace[81910660] 'read index received' (duration: 56.64929ms)","trace[81910660] 'applied index is now lower than readState.Index' (duration: 86.68472ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T20:25:05.153885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.512656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T20:25:05.153916Z","caller":"traceutil/trace.go:171","msg":"trace[1235540857] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:820; }","duration":"143.584567ms","start":"2026-01-29T20:25:05.01032Z","end":"2026-01-29T20:25:05.153905Z","steps":["trace[1235540857] 'agreement among raft nodes before linearized reading' (duration: 143.472954ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:25:05.154175Z","caller":"traceutil/trace.go:171","msg":"trace[902794853] transaction","detail":"{read_only:false; response_revision:818; number_of_response:1; }","duration":"237.729865ms","start":"2026-01-29T20:25:04.916434Z","end":"2026-01-29T20:25:05.154164Z","steps":["trace[902794853] 'process raft request' (duration: 150.562323ms)","trace[902794853] 'compare' (duration: 86.187068ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T20:25:05.154299Z","caller":"traceutil/trace.go:171","msg":"trace[1891900538] transaction","detail":"{read_only:false; response_revision:819; number_of_response:1; }","duration":"236.454364ms","start":"2026-01-29T20:25:04.917836Z","end":"2026-01-29T20:25:05.154291Z","steps":["trace[1891900538] 'process raft request' (duration: 235.783528ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:25:05.154403Z","caller":"traceutil/trace.go:171","msg":"trace[1817687984] transaction","detail":"{read_only:false; response_revision:820; number_of_response:1; }","duration":"233.793511ms","start":"2026-01-29T20:25:04.9206Z","end":"2026-01-29T20:25:05.154394Z","steps":["trace[1817687984] 'process raft request' (duration: 233.064024ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:25:18.496035Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9997599795692980131,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-29T20:25:19.159525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"614.275524ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-01-29T20:25:19.159941Z","caller":"traceutil/trace.go:171","msg":"trace[1921434192] linearizableReadLoop","detail":"{readStateIndex:944; appliedIndex:942; }","duration":"1.164268736s","start":"2026-01-29T20:25:17.995647Z","end":"2026-01-29T20:25:19.159916Z","steps":["trace[1921434192] 'read index received' (duration: 549.866758ms)","trace[1921434192] 'applied index is now lower than readState.Index' (duration: 614.400648ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T20:25:19.160032Z","caller":"traceutil/trace.go:171","msg":"trace[1405151849] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"674.97449ms","start":"2026-01-29T20:25:18.485039Z","end":"2026-01-29T20:25:19.160014Z","steps":["trace[1405151849] 'process raft request' (duration: 674.55931ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:25:19.160106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:25:18.485024Z","time spent":"675.037432ms","remote":"127.0.0.1:50484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T20:25:19.523496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.527837744s","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-01-29T20:25:19.523542Z","caller":"traceutil/trace.go:171","msg":"trace[1739711827] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:905; }","duration":"1.527925336s","start":"2026-01-29T20:25:17.995603Z","end":"2026-01-29T20:25:19.523529Z","steps":["trace[1739711827] 'agreement among raft nodes before linearized reading' (duration: 1.16444934s)","trace[1739711827] 'range keys from in-memory index tree' (duration: 363.351143ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T20:25:19.523565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:25:17.995592Z","time spent":"1.527967337s","remote":"127.0.0.1:50484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-01-29T20:25:19.523686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.512207444s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T20:25:19.523702Z","caller":"traceutil/trace.go:171","msg":"trace[648980116] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:905; }","duration":"1.512223784s","start":"2026-01-29T20:25:18.011472Z","end":"2026-01-29T20:25:19.523696Z","steps":["trace[648980116] 'agreement among raft nodes before linearized reading' (duration: 1.148612095s)","trace[648980116] 'range keys from in-memory index tree' (duration: 363.589519ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T20:25:19.523715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:25:18.011415Z","time spent":"1.512297366s","remote":"127.0.0.1:50156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-29T20:25:19.523809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.928015ms","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-01-29T20:25:19.523824Z","caller":"traceutil/trace.go:171","msg":"trace[1971918798] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:905; }","duration":"553.976685ms","start":"2026-01-29T20:25:18.969843Z","end":"2026-01-29T20:25:19.52382Z","steps":["trace[1971918798] 'agreement among raft nodes before linearized reading' (duration: 190.253423ms)","trace[1971918798] 'range keys from in-memory index tree' (duration: 363.684871ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T20:25:19.523836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:25:18.969828Z","time spent":"554.005576ms","remote":"127.0.0.1:50484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-01-29T20:25:19.523975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.336998ms","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-01-29T20:25:19.524Z","caller":"traceutil/trace.go:171","msg":"trace[800679502] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:905; }","duration":"298.371258ms","start":"2026-01-29T20:25:19.225621Z","end":"2026-01-29T20:25:19.523993Z","steps":["trace[800679502] 'range keys from in-memory index tree' (duration: 298.295767ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:25:19.524098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.0077382s","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-01-29T20:25:19.524115Z","caller":"traceutil/trace.go:171","msg":"trace[1233143499] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:905; }","duration":"1.007781032s","start":"2026-01-29T20:25:18.516326Z","end":"2026-01-29T20:25:19.524107Z","steps":["trace[1233143499] 'agreement among raft nodes before linearized reading' (duration: 643.775583ms)","trace[1233143499] 'count revisions from in-memory index tree' (duration: 363.984178ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T20:25:19.524129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:25:18.516313Z","time spent":"1.007811742s","remote":"127.0.0.1:50698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":3,"response size":30,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-01-29T20:25:19.524197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.274712026s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-29T20:25:19.524209Z","caller":"traceutil/trace.go:171","msg":"trace[1717668369] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:905; }","duration":"1.274746777s","start":"2026-01-29T20:25:18.249458Z","end":"2026-01-29T20:25:19.524205Z","steps":["trace[1717668369] 'agreement among raft nodes before linearized reading' (duration: 910.647666ms)","trace[1717668369] 'count revisions from in-memory index tree' (duration: 364.081081ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T20:25:19.524222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:25:18.249445Z","time spent":"1.274772667s","remote":"127.0.0.1:50588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":30,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-01-29T20:25:19.524345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.642191ms","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-01-29T20:25:19.52439Z","caller":"traceutil/trace.go:171","msg":"trace[2004406688] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:905; }","duration":"319.718013ms","start":"2026-01-29T20:25:19.204667Z","end":"2026-01-29T20:25:19.524385Z","steps":["trace[2004406688] 'range keys from in-memory index tree' (duration: 319.58096ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:25:19.524403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:25:19.204653Z","time spent":"319.745964ms","remote":"127.0.0.1:50484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-01-29T20:25:19.524478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.725828ms","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-01-29T20:25:19.52449Z","caller":"traceutil/trace.go:171","msg":"trace[101826878] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:905; }","duration":"301.760199ms","start":"2026-01-29T20:25:19.222726Z","end":"2026-01-29T20:25:19.524486Z","steps":["trace[101826878] 'range keys from in-memory index tree' (duration: 301.669176ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:25:19.524501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:25:19.222714Z","time spent":"301.784289ms","remote":"127.0.0.1:50484","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-01-29T20:25:30.123582Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9997599795692980216,"retry-timeout":"500ms"} {"level":"info","ts":"2026-01-29T20:25:30.263031Z","caller":"traceutil/trace.go:171","msg":"trace[1811982587] linearizableReadLoop","detail":"{readStateIndex:974; appliedIndex:973; }","duration":"640.074643ms","start":"2026-01-29T20:25:29.62294Z","end":"2026-01-29T20:25:30.263015Z","steps":["trace[1811982587] 'read index received' (duration: 639.989951ms)","trace[1811982587] 'applied index is now lower than readState.Index' (duration: 83.322µs)"],"step_count":2} {"level":"info","ts":"2026-01-29T20:25:30.263122Z","caller":"traceutil/trace.go:171","msg":"trace[2009407239] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"700.463821ms","start":"2026-01-29T20:25:29.562653Z","end":"2026-01-29T20:25:30.263117Z","steps":["trace[2009407239] 'process raft request' (duration: 700.255466ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:25:30.263189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:25:29.562623Z","time spent":"700.511752ms","remote":"127.0.0.1:50484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T20:25:30.263315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.38038ms","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-01-29T20:25:30.26334Z","caller":"traceutil/trace.go:171","msg":"trace[142883601] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:933; }","duration":"640.421311ms","start":"2026-01-29T20:25:29.622904Z","end":"2026-01-29T20:25:30.263326Z","steps":["trace[142883601] 'agreement among raft nodes before linearized reading' (duration: 640.347549ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:25:30.263357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:25:29.622889Z","time spent":"640.464382ms","remote":"127.0.0.1:50484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-01-29T20:25:30.263429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"611.873075ms","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-01-29T20:25:30.26344Z","caller":"traceutil/trace.go:171","msg":"trace[547306823] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:933; }","duration":"611.907507ms","start":"2026-01-29T20:25:29.651529Z","end":"2026-01-29T20:25:30.263437Z","steps":["trace[547306823] 'agreement among raft nodes before linearized reading' (duration: 611.871606ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:25:30.263452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:25:29.651496Z","time spent":"611.953177ms","remote":"127.0.0.1:50484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-01-29T20:25:30.263618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.949663ms","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-01-29T20:25:30.263634Z","caller":"traceutil/trace.go:171","msg":"trace[174055436] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:933; }","duration":"596.980883ms","start":"2026-01-29T20:25:29.666649Z","end":"2026-01-29T20:25:30.263629Z","steps":["trace[174055436] 'agreement among raft nodes before linearized reading' (duration: 596.937492ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:25:30.263649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:25:29.666639Z","time spent":"597.007134ms","remote":"127.0.0.1:50484","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-01-29T20:25:30.263733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.484786ms","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-01-29T20:25:30.263706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.64701ms","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-01-29T20:25:30.263746Z","caller":"traceutil/trace.go:171","msg":"trace[1704009826] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:933; }","duration":"597.553147ms","start":"2026-01-29T20:25:29.666189Z","end":"2026-01-29T20:25:30.263742Z","steps":["trace[1704009826] 'agreement among raft nodes before linearized reading' (duration: 597.515396ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:25:30.263784Z","caller":"traceutil/trace.go:171","msg":"trace[1519271790] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:933; }","duration":"507.761962ms","start":"2026-01-29T20:25:29.756003Z","end":"2026-01-29T20:25:30.263765Z","steps":["trace[1519271790] 'agreement among raft nodes before linearized reading' (duration: 507.553927ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:25:30.26381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:25:29.666158Z","time spent":"597.648309ms","remote":"127.0.0.1:50484","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-01-29T20:25:30.263826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:25:29.75599Z","time spent":"507.829965ms","remote":"127.0.0.1:50484","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-01-29T20:25:30.26394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.246382ms","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-01-29T20:25:30.263955Z","caller":"traceutil/trace.go:171","msg":"trace[830750139] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:933; }","duration":"491.280963ms","start":"2026-01-29T20:25:29.77267Z","end":"2026-01-29T20:25:30.263951Z","steps":["trace[830750139] 'agreement among raft nodes before linearized reading' (duration: 491.245472ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:25:30.263971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:25:29.772663Z","time spent":"491.303773ms","remote":"127.0.0.1:50328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"info","ts":"2026-01-29T20:25:32.421668Z","caller":"traceutil/trace.go:171","msg":"trace[1183166815] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"126.287108ms","start":"2026-01-29T20:25:32.295352Z","end":"2026-01-29T20:25:32.421639Z","steps":["trace[1183166815] 'process raft request' (duration: 126.133885ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:25:32.647803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.982732ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-29T20:25:32.647962Z","caller":"traceutil/trace.go:171","msg":"trace[578347606] linearizableReadLoop","detail":"{readStateIndex:982; appliedIndex:980; }","duration":"332.94671ms","start":"2026-01-29T20:25:32.314997Z","end":"2026-01-29T20:25:32.647944Z","steps":["trace[578347606] 'read index received' (duration: 107.351541ms)","trace[578347606] 'applied index is now lower than readState.Index' (duration: 225.594009ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T20:25:32.64815Z","caller":"traceutil/trace.go:171","msg":"trace[170088541] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"350.506655ms","start":"2026-01-29T20:25:32.297631Z","end":"2026-01-29T20:25:32.648137Z","steps":["trace[170088541] 'process raft request' (duration: 232.075863ms)","trace[170088541] 'compare' (duration: 117.881439ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T20:25:32.648185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:25:32.297615Z","time spent":"350.550706ms","remote":"127.0.0.1:50484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T20:25:32.648305Z","caller":"traceutil/trace.go:171","msg":"trace[216840018] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"335.112131ms","start":"2026-01-29T20:25:32.313188Z","end":"2026-01-29T20:25:32.6483Z","steps":["trace[216840018] 'process raft request' (duration: 334.709302ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:25:32.648332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:25:32.313177Z","time spent":"335.138262ms","remote":"127.0.0.1:50484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T20:25:32.648492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.495974ms","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-01-29T20:25:32.64851Z","caller":"traceutil/trace.go:171","msg":"trace[1385994980] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:941; }","duration":"333.528854ms","start":"2026-01-29T20:25:32.314973Z","end":"2026-01-29T20:25:32.648502Z","steps":["trace[1385994980] 'agreement among raft nodes before linearized reading' (duration: 333.453902ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:25:32.648533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:25:32.314966Z","time spent":"333.563705ms","remote":"127.0.0.1:50484","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-01-29T20:25:32.648634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.084773ms","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-01-29T20:25:32.648647Z","caller":"traceutil/trace.go:171","msg":"trace[1389614231] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:941; }","duration":"333.106694ms","start":"2026-01-29T20:25:32.315536Z","end":"2026-01-29T20:25:32.648643Z","steps":["trace[1389614231] 'agreement among raft nodes before linearized reading' (duration: 333.058943ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:25:32.648663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:25:32.315531Z","time spent":"333.126725ms","remote":"127.0.0.1:50484","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-01-29T20:25:33.218973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.045394ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-01-29T20:25:33.219122Z","caller":"traceutil/trace.go:171","msg":"trace[543838537] linearizableReadLoop","detail":"{readStateIndex:985; appliedIndex:982; }","duration":"164.08629ms","start":"2026-01-29T20:25:33.055026Z","end":"2026-01-29T20:25:33.219113Z","steps":["trace[543838537] 'read index received' (duration: 88.18236ms)","trace[543838537] 'applied index is now lower than readState.Index' (duration: 75.90315ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T20:25:33.219172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.141861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T20:25:33.219184Z","caller":"traceutil/trace.go:171","msg":"trace[1183214938] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:943; }","duration":"164.195192ms","start":"2026-01-29T20:25:33.054984Z","end":"2026-01-29T20:25:33.219179Z","steps":["trace[1183214938] 'agreement among raft nodes before linearized reading' (duration: 164.154631ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:25:33.219301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:25:32.650402Z","time spent":"568.897495ms","remote":"127.0.0.1:50216","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-01-29T20:25:33.219414Z","caller":"traceutil/trace.go:171","msg":"trace[63728642] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"562.764701ms","start":"2026-01-29T20:25:32.656645Z","end":"2026-01-29T20:25:33.219409Z","steps":["trace[63728642] 'process raft request' (duration: 562.389002ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:25:33.219463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:25:32.656629Z","time spent":"562.798312ms","remote":"127.0.0.1:50484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T20:25:33.219522Z","caller":"traceutil/trace.go:171","msg":"trace[504887180] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"561.746657ms","start":"2026-01-29T20:25:32.657768Z","end":"2026-01-29T20:25:33.219515Z","steps":["trace[504887180] 'process raft request' (duration: 561.325547ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:25:33.219547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:25:32.657762Z","time spent":"561.771258ms","remote":"127.0.0.1:50484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T20:25:33.767151Z","caller":"traceutil/trace.go:171","msg":"trace[1575372408] linearizableReadLoop","detail":"{readStateIndex:986; appliedIndex:985; }","duration":"539.007211ms","start":"2026-01-29T20:25:33.228125Z","end":"2026-01-29T20:25:33.767132Z","steps":["trace[1575372408] 'read index received' (duration: 448.816904ms)","trace[1575372408] 'applied index is now lower than readState.Index' (duration: 90.189377ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T20:25:33.767198Z","caller":"traceutil/trace.go:171","msg":"trace[456103061] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"542.085593ms","start":"2026-01-29T20:25:33.225074Z","end":"2026-01-29T20:25:33.767159Z","steps":["trace[456103061] 'process raft request' (duration: 451.859696ms)","trace[456103061] 'compare' (duration: 90.104014ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T20:25:33.767277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.155775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T20:25:33.767297Z","caller":"traceutil/trace.go:171","msg":"trace[48363611] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:944; }","duration":"539.239266ms","start":"2026-01-29T20:25:33.228051Z","end":"2026-01-29T20:25:33.767291Z","steps":["trace[48363611] 'agreement among raft nodes before linearized reading' (duration: 539.164205ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:25:33.767317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:25:33.227909Z","time spent":"539.40434ms","remote":"127.0.0.1:50166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-29T20:25:33.767364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:25:33.225063Z","time spent":"542.207916ms","remote":"127.0.0.1:50216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T20:25:34.247281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.955088ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-29T20:25:34.247383Z","caller":"traceutil/trace.go:171","msg":"trace[1576120214] linearizableReadLoop","detail":"{readStateIndex:987; appliedIndex:986; }","duration":"480.170713ms","start":"2026-01-29T20:25:33.767195Z","end":"2026-01-29T20:25:34.247366Z","steps":["trace[1576120214] 'read index received' (duration: 321.130963ms)","trace[1576120214] 'applied index is now lower than readState.Index' (duration: 159.03852ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T20:25:34.24748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"985.776346ms","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-01-29T20:25:34.247496Z","caller":"traceutil/trace.go:171","msg":"trace[1545614875] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:945; }","duration":"985.793856ms","start":"2026-01-29T20:25:33.261695Z","end":"2026-01-29T20:25:34.247489Z","steps":["trace[1545614875] 'agreement among raft nodes before linearized reading' (duration: 985.751135ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:25:34.247462Z","caller":"traceutil/trace.go:171","msg":"trace[151941637] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"995.816493ms","start":"2026-01-29T20:25:33.251615Z","end":"2026-01-29T20:25:34.247432Z","steps":["trace[151941637] 'process raft request' (duration: 836.618799ms)","trace[151941637] 'compare' (duration: 158.850276ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T20:25:34.247519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:25:33.261649Z","time spent":"985.864498ms","remote":"127.0.0.1:50684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-01-29T20:25:34.247622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:25:33.251607Z","time spent":"995.921055ms","remote":"127.0.0.1:50484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T20:25:34.247769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.708875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-01-29T20:25:34.247787Z","caller":"traceutil/trace.go:171","msg":"trace[986728440] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:945; }","duration":"477.769526ms","start":"2026-01-29T20:25:33.770012Z","end":"2026-01-29T20:25:34.247782Z","steps":["trace[986728440] 'agreement among raft nodes before linearized reading' (duration: 477.661954ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:25:34.247805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:25:33.769995Z","time spent":"477.806538ms","remote":"127.0.0.1:50360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-01-29T20:25:35.573347Z","caller":"traceutil/trace.go:171","msg":"trace[545124837] linearizableReadLoop","detail":"{readStateIndex:991; appliedIndex:990; }","duration":"323.944289ms","start":"2026-01-29T20:25:35.24937Z","end":"2026-01-29T20:25:35.573314Z","steps":["trace[545124837] 'read index received' (duration: 323.640452ms)","trace[545124837] 'applied index is now lower than readState.Index' (duration: 302.587µs)"],"step_count":2} {"level":"warn","ts":"2026-01-29T20:25:35.573651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.240046ms","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-01-29T20:25:35.573694Z","caller":"traceutil/trace.go:171","msg":"trace[1103942128] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:949; }","duration":"324.323778ms","start":"2026-01-29T20:25:35.249359Z","end":"2026-01-29T20:25:35.573683Z","steps":["trace[1103942128] 'agreement among raft nodes before linearized reading' (duration: 324.095582ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:25:35.573741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:25:35.249344Z","time spent":"324.38891ms","remote":"127.0.0.1:50484","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-01-29T20:25:35.574214Z","caller":"traceutil/trace.go:171","msg":"trace[1363489752] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"325.478225ms","start":"2026-01-29T20:25:35.248724Z","end":"2026-01-29T20:25:35.574202Z","steps":["trace[1363489752] 'process raft request' (duration: 324.385269ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:25:35.57432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:25:35.248711Z","time spent":"325.536587ms","remote":"127.0.0.1:50484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T20:25:36.780842Z","caller":"traceutil/trace.go:171","msg":"trace[1554973335] linearizableReadLoop","detail":"{readStateIndex:993; appliedIndex:992; }","duration":"113.984538ms","start":"2026-01-29T20:25:36.666834Z","end":"2026-01-29T20:25:36.780819Z","steps":["trace[1554973335] 'read index received' (duration: 113.775153ms)","trace[1554973335] 'applied index is now lower than readState.Index' (duration: 208.575µs)"],"step_count":2} {"level":"info","ts":"2026-01-29T20:25:36.781034Z","caller":"traceutil/trace.go:171","msg":"trace[1556053580] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"340.979971ms","start":"2026-01-29T20:25:36.440047Z","end":"2026-01-29T20:25:36.781027Z","steps":["trace[1556053580] 'process raft request' (duration: 340.632933ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:25:36.78114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:25:36.440034Z","time spent":"341.017552ms","remote":"127.0.0.1:50484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T20:25:36.781374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.543031ms","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-01-29T20:25:36.781395Z","caller":"traceutil/trace.go:171","msg":"trace[77684144] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:951; }","duration":"114.624374ms","start":"2026-01-29T20:25:36.666764Z","end":"2026-01-29T20:25:36.781388Z","steps":["trace[77684144] 'agreement among raft nodes before linearized reading' (duration: 114.520671ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:25:36.781499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.453059ms","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-01-29T20:25:36.781512Z","caller":"traceutil/trace.go:171","msg":"trace[1198949624] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:951; }","duration":"114.49012ms","start":"2026-01-29T20:25:36.667017Z","end":"2026-01-29T20:25:36.781507Z","steps":["trace[1198949624] 'agreement among raft nodes before linearized reading' (duration: 114.447409ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:25:36.941023Z","caller":"traceutil/trace.go:171","msg":"trace[498194409] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"115.725189ms","start":"2026-01-29T20:25:36.825268Z","end":"2026-01-29T20:25:36.940993Z","steps":["trace[498194409] 'process raft request' (duration: 115.676878ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:25:36.941553Z","caller":"traceutil/trace.go:171","msg":"trace[1174840235] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"155.049516ms","start":"2026-01-29T20:25:36.786491Z","end":"2026-01-29T20:25:36.94154Z","steps":["trace[1174840235] 'process raft request' (duration: 116.21926ms)","trace[1174840235] 'compare' (duration: 38.068798ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T20:25:37.845126Z","caller":"traceutil/trace.go:171","msg":"trace[1484294663] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"259.079249ms","start":"2026-01-29T20:25:37.586009Z","end":"2026-01-29T20:25:37.845088Z","steps":["trace[1484294663] 'process raft request' (duration: 258.850594ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:25:37.845516Z","caller":"traceutil/trace.go:171","msg":"trace[16404919] linearizableReadLoop","detail":"{readStateIndex:996; appliedIndex:996; }","duration":"247.875495ms","start":"2026-01-29T20:25:37.597627Z","end":"2026-01-29T20:25:37.845502Z","steps":["trace[16404919] 'read index received' (duration: 247.868985ms)","trace[16404919] 'applied index is now lower than readState.Index' (duration: 5.38µs)"],"step_count":2} {"level":"warn","ts":"2026-01-29T20:25:37.845734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.07551ms","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-01-29T20:25:37.84577Z","caller":"traceutil/trace.go:171","msg":"trace[393868912] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:954; }","duration":"248.150752ms","start":"2026-01-29T20:25:37.597609Z","end":"2026-01-29T20:25:37.84576Z","steps":["trace[393868912] 'agreement among raft nodes before linearized reading' (duration: 247.941877ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:25:38.180495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.591178ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-29T20:25:38.180573Z","caller":"traceutil/trace.go:171","msg":"trace[2027452181] linearizableReadLoop","detail":"{readStateIndex:997; appliedIndex:996; }","duration":"169.140749ms","start":"2026-01-29T20:25:38.011418Z","end":"2026-01-29T20:25:38.180559Z","steps":["trace[2027452181] 'read index received' (duration: 8.355567ms)","trace[2027452181] 'applied index is now lower than readState.Index' (duration: 160.784432ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T20:25:38.180639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.224891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T20:25:38.180653Z","caller":"traceutil/trace.go:171","msg":"trace[548900996] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:955; }","duration":"169.262672ms","start":"2026-01-29T20:25:38.011385Z","end":"2026-01-29T20:25:38.180648Z","steps":["trace[548900996] 'agreement among raft nodes before linearized reading' (duration: 169.206321ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:25:38.180855Z","caller":"traceutil/trace.go:171","msg":"trace[1664262050] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"315.702875ms","start":"2026-01-29T20:25:37.86514Z","end":"2026-01-29T20:25:38.180843Z","steps":["trace[1664262050] 'process raft request' (duration: 154.679327ms)","trace[1664262050] 'compare' (duration: 160.457554ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T20:25:38.180888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:25:37.86511Z","time spent":"315.761346ms","remote":"127.0.0.1:50484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T20:25:38.435242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.715374ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-01-29T20:25:40.687331Z","caller":"traceutil/trace.go:171","msg":"trace[612436329] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"120.354318ms","start":"2026-01-29T20:25:40.566793Z","end":"2026-01-29T20:25:40.687148Z","steps":["trace[612436329] 'process raft request' (duration: 120.062431ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:25:41.800678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.004101008s","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-01-29T20:25:41.800753Z","caller":"traceutil/trace.go:171","msg":"trace[851556989] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:969; }","duration":"1.004224531s","start":"2026-01-29T20:25:40.79651Z","end":"2026-01-29T20:25:41.800734Z","steps":["trace[851556989] 'range keys from in-memory index tree' (duration: 1.003845272s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:25:41.800799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:25:40.796487Z","time spent":"1.004303323s","remote":"127.0.0.1:50484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-01-29T20:25:41.801255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"843.207604ms","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-01-29T20:25:41.801288Z","caller":"traceutil/trace.go:171","msg":"trace[182810603] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:969; }","duration":"843.294276ms","start":"2026-01-29T20:25:40.957984Z","end":"2026-01-29T20:25:41.801278Z","steps":["trace[182810603] 'range keys from in-memory index tree' (duration: 843.077851ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:25:41.801316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:25:40.957956Z","time spent":"843.353608ms","remote":"127.0.0.1:50484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-01-29T20:25:41.801487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"829.00949ms","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-01-29T20:25:41.801515Z","caller":"traceutil/trace.go:171","msg":"trace[974353291] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:969; }","duration":"829.075861ms","start":"2026-01-29T20:25:40.97243Z","end":"2026-01-29T20:25:41.801506Z","steps":["trace[974353291] 'range keys from in-memory index tree' (duration: 828.856116ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:25:41.801541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:25:40.972409Z","time spent":"829.125822ms","remote":"127.0.0.1:50484","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-01-29T20:25:41.801666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"791.544706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T20:25:41.801691Z","caller":"traceutil/trace.go:171","msg":"trace[392772551] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:969; }","duration":"791.620027ms","start":"2026-01-29T20:25:41.010063Z","end":"2026-01-29T20:25:41.801683Z","steps":["trace[392772551] 'range keys from in-memory index tree' (duration: 791.492205ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:25:41.80172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:25:41.010043Z","time spent":"791.671529ms","remote":"127.0.0.1:50156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-29T20:25:41.801897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:25:41.152653Z","time spent":"649.24189ms","remote":"127.0.0.1:58428","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-01-29T20:25:42.214853Z","caller":"traceutil/trace.go:171","msg":"trace[1732334192] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"404.321734ms","start":"2026-01-29T20:25:41.810513Z","end":"2026-01-29T20:25:42.214835Z","steps":["trace[1732334192] 'process raft request' (duration: 404.17175ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:25:42.214981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:25:41.810478Z","time spent":"404.431837ms","remote":"127.0.0.1:50484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T20:25:42.214979Z","caller":"traceutil/trace.go:171","msg":"trace[1173453529] linearizableReadLoop","detail":"{readStateIndex:1016; appliedIndex:1014; }","duration":"349.775228ms","start":"2026-01-29T20:25:41.865185Z","end":"2026-01-29T20:25:42.214961Z","steps":["trace[1173453529] 'read index received' (duration: 349.437ms)","trace[1173453529] 'applied index is now lower than readState.Index' (duration: 337.488µs)"],"step_count":2} {"level":"info","ts":"2026-01-29T20:25:42.215132Z","caller":"traceutil/trace.go:171","msg":"trace[242581140] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"399.87107ms","start":"2026-01-29T20:25:41.815255Z","end":"2026-01-29T20:25:42.215126Z","steps":["trace[242581140] 'process raft request' (duration: 399.552012ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:25:42.215221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:25:41.815245Z","time spent":"399.90219ms","remote":"127.0.0.1:50484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T20:25:42.215381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.194158ms","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-01-29T20:25:42.215406Z","caller":"traceutil/trace.go:171","msg":"trace[1678205324] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:972; }","duration":"350.259349ms","start":"2026-01-29T20:25:41.865139Z","end":"2026-01-29T20:25:42.215399Z","steps":["trace[1678205324] 'agreement among raft nodes before linearized reading' (duration: 350.205768ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:25:42.215423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:25:41.865102Z","time spent":"350.317041ms","remote":"127.0.0.1:50484","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-01-29T20:25:42.500758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.501865ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-29T20:25:42.50103Z","caller":"traceutil/trace.go:171","msg":"trace[1101698158] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"279.580002ms","start":"2026-01-29T20:25:42.22143Z","end":"2026-01-29T20:25:42.50101Z","steps":["trace[1101698158] 'process raft request' (duration: 279.514011ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:25:42.503231Z","caller":"traceutil/trace.go:171","msg":"trace[1092922375] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"282.607254ms","start":"2026-01-29T20:25:42.22061Z","end":"2026-01-29T20:25:42.503217Z","steps":["trace[1092922375] 'process raft request' (duration: 105.521179ms)","trace[1092922375] 'compare' (duration: 174.30922ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T20:25:43.926226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.308918235s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.218\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-01-29T20:25:43.926332Z","caller":"traceutil/trace.go:171","msg":"trace[61759040] range","detail":"{range_begin:/registry/masterleases/162.253.55.218; range_end:; response_count:1; response_revision:974; }","duration":"1.309036998s","start":"2026-01-29T20:25:42.617269Z","end":"2026-01-29T20:25:43.926306Z","steps":["trace[61759040] 'range keys from in-memory index tree' (duration: 1.308641249s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:25:43.92637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:25:42.617211Z","time spent":"1.30915076s","remote":"127.0.0.1:50216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/162.253.55.218\" "} {"level":"warn","ts":"2026-01-29T20:25:43.926772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"915.346715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T20:25:43.926801Z","caller":"traceutil/trace.go:171","msg":"trace[1774659538] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:974; }","duration":"915.425016ms","start":"2026-01-29T20:25:43.011368Z","end":"2026-01-29T20:25:43.926793Z","steps":["trace[1774659538] 'range keys from in-memory index tree' (duration: 915.239042ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:25:43.92682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:25:43.011342Z","time spent":"915.474027ms","remote":"127.0.0.1:50156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-01-29T20:25:54.703574Z","caller":"traceutil/trace.go:171","msg":"trace[1182035127] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"125.147521ms","start":"2026-01-29T20:25:54.578397Z","end":"2026-01-29T20:25:54.703544Z","steps":["trace[1182035127] 'process raft request' (duration: 124.972346ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:25:54.704095Z","caller":"traceutil/trace.go:171","msg":"trace[993956914] linearizableReadLoop","detail":"{readStateIndex:1081; appliedIndex:1081; }","duration":"102.344413ms","start":"2026-01-29T20:25:54.601705Z","end":"2026-01-29T20:25:54.704049Z","steps":["trace[993956914] 'read index received' (duration: 102.327433ms)","trace[993956914] 'applied index is now lower than readState.Index' (duration: 12.53µs)"],"step_count":2} {"level":"warn","ts":"2026-01-29T20:25:54.704576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.833015ms","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-01-29T20:25:54.704637Z","caller":"traceutil/trace.go:171","msg":"trace[2118307335] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1034; }","duration":"102.971298ms","start":"2026-01-29T20:25:54.601649Z","end":"2026-01-29T20:25:54.704621Z","steps":["trace[2118307335] 'agreement among raft nodes before linearized reading' (duration: 102.591169ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:25:56.183296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.753183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T20:25:56.183424Z","caller":"traceutil/trace.go:171","msg":"trace[279915206] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1038; }","duration":"211.897286ms","start":"2026-01-29T20:25:55.971493Z","end":"2026-01-29T20:25:56.18339Z","steps":["trace[279915206] 'count revisions from in-memory index tree' (duration: 211.664791ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:25:56.184125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.769686ms","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-01-29T20:25:56.184169Z","caller":"traceutil/trace.go:171","msg":"trace[1223678895] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1038; }","duration":"178.843037ms","start":"2026-01-29T20:25:56.005312Z","end":"2026-01-29T20:25:56.184155Z","steps":["trace[1223678895] 'range keys from in-memory index tree' (duration: 178.607862ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:25:56.184558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.465324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T20:25:56.184667Z","caller":"traceutil/trace.go:171","msg":"trace[970821717] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1038; }","duration":"174.626648ms","start":"2026-01-29T20:25:56.010014Z","end":"2026-01-29T20:25:56.18464Z","steps":["trace[970821717] 'range keys from in-memory index tree' (duration: 174.395853ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:25:56.369229Z","caller":"traceutil/trace.go:171","msg":"trace[470744814] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"174.110516ms","start":"2026-01-29T20:25:56.195085Z","end":"2026-01-29T20:25:56.369196Z","steps":["trace[470744814] 'process raft request' (duration: 173.610274ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:25:57.073552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.262232ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-29T20:25:57.074468Z","caller":"traceutil/trace.go:171","msg":"trace[148123592] transaction","detail":"{read_only:false; response_revision:1041; number_of_response:1; }","duration":"698.05033ms","start":"2026-01-29T20:25:56.376319Z","end":"2026-01-29T20:25:57.074369Z","steps":["trace[148123592] 'process raft request' (duration: 697.989469ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:25:57.074532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:25:56.37631Z","time spent":"698.192815ms","remote":"127.0.0.1:50484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T20:25:57.074791Z","caller":"traceutil/trace.go:171","msg":"trace[733710402] transaction","detail":"{read_only:false; response_revision:1040; number_of_response:1; }","duration":"699.363872ms","start":"2026-01-29T20:25:56.375418Z","end":"2026-01-29T20:25:57.074782Z","steps":["trace[733710402] 'process raft request' (duration: 218.7833ms)","trace[733710402] 'compare' (duration: 478.909263ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T20:25:57.07483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:25:56.375391Z","time spent":"699.416553ms","remote":"127.0.0.1:50484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T20:25:57.105583Z","caller":"traceutil/trace.go:171","msg":"trace[315012719] linearizableReadLoop","detail":"{readStateIndex:1088; appliedIndex:1088; }","duration":"415.126599ms","start":"2026-01-29T20:25:56.690441Z","end":"2026-01-29T20:25:57.105568Z","steps":["trace[315012719] 'read index received' (duration: 415.112219ms)","trace[315012719] 'applied index is now lower than readState.Index' (duration: 13.09µs)"],"step_count":2} {"level":"warn","ts":"2026-01-29T20:25:57.105738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.291454ms","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-01-29T20:25:57.105811Z","caller":"traceutil/trace.go:171","msg":"trace[1299334294] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1041; }","duration":"415.415666ms","start":"2026-01-29T20:25:56.690388Z","end":"2026-01-29T20:25:57.105803Z","steps":["trace[1299334294] 'agreement among raft nodes before linearized reading' (duration: 415.237952ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:25:57.105888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.366065ms","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-01-29T20:25:57.105908Z","caller":"traceutil/trace.go:171","msg":"trace[412335462] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1041; }","duration":"387.401845ms","start":"2026-01-29T20:25:56.718501Z","end":"2026-01-29T20:25:57.105903Z","steps":["trace[412335462] 'agreement among raft nodes before linearized reading' (duration: 387.339554ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:25:57.105931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:25:56.718493Z","time spent":"387.430917ms","remote":"127.0.0.1:50484","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-01-29T20:25:57.106222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.686908ms","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-01-29T20:25:57.10625Z","caller":"traceutil/trace.go:171","msg":"trace[810961689] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1041; }","duration":"386.715689ms","start":"2026-01-29T20:25:56.719528Z","end":"2026-01-29T20:25:57.106244Z","steps":["trace[810961689] 'agreement among raft nodes before linearized reading' (duration: 386.652948ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:25:57.106267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:25:56.7195Z","time spent":"386.762561ms","remote":"127.0.0.1:50484","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-01-29T20:25:57.106511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:25:56.690366Z","time spent":"416.137573ms","remote":"127.0.0.1:50484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-01-29T20:25:57.550741Z","caller":"traceutil/trace.go:171","msg":"trace[1056913466] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"438.620453ms","start":"2026-01-29T20:25:57.1121Z","end":"2026-01-29T20:25:57.55072Z","steps":["trace[1056913466] 'process raft request' (duration: 438.597413ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:25:57.550867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:25:57.112092Z","time spent":"438.710645ms","remote":"127.0.0.1:50484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T20:25:57.55107Z","caller":"traceutil/trace.go:171","msg":"trace[1380092131] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"439.725699ms","start":"2026-01-29T20:25:57.111336Z","end":"2026-01-29T20:25:57.551062Z","steps":["trace[1380092131] 'process raft request' (duration: 416.024091ms)","trace[1380092131] 'compare' (duration: 22.91486ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T20:25:57.551105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:25:57.111316Z","time spent":"439.77042ms","remote":"127.0.0.1:50484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T20:25:57.551739Z","caller":"traceutil/trace.go:171","msg":"trace[774113976] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"440.160059ms","start":"2026-01-29T20:25:57.111526Z","end":"2026-01-29T20:25:57.551686Z","steps":["trace[774113976] 'process raft request' (duration: 439.133725ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T20:25:57.551781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T20:25:57.111516Z","time spent":"440.242122ms","remote":"127.0.0.1:50484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T20:27:33.787017Z","caller":"traceutil/trace.go:171","msg":"trace[1035101533] transaction","detail":"{read_only:false; response_revision:1456; number_of_response:1; }","duration":"108.513975ms","start":"2026-01-29T20:27:33.67847Z","end":"2026-01-29T20:27:33.786984Z","steps":["trace[1035101533] 'process raft request' (duration: 108.403113ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T20:27:33.819654Z","caller":"traceutil/trace.go:171","msg":"trace[1578974711] transaction","detail":"{read_only:false; response_revision:1457; number_of_response:1; }","duration":"140.43328ms","start":"2026-01-29T20:27:33.679197Z","end":"2026-01-29T20:27:33.819631Z","steps":["trace[1578974711] 'process raft request' (duration: 140.310357ms)"],"step_count":1}