{"level":"warn","ts":"2026-02-04T01:56:20.49799Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-04T01:56:20.498119Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.235:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.235:2380","--initial-cluster=instance=https://199.204.45.235:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.235:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.235:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-04T01:56:20.498211Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-04T01:56:20.498222Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.235:2380"]} {"level":"info","ts":"2026-02-04T01:56:20.498286Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-04T01:56:20.499386Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"]} {"level":"info","ts":"2026-02-04T01:56:20.499649Z","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://199.204.45.235:2380"],"listen-peer-urls":["https://199.204.45.235:2380"],"advertise-client-urls":["https://199.204.45.235:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.235:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-04T01:56:20.514064Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"13.361047ms"} {"level":"info","ts":"2026-02-04T01:56:20.534526Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"aab6040abcf11","cluster-id":"2bae7d3d8857c059"} {"level":"info","ts":"2026-02-04T01:56:20.534608Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=()"} {"level":"info","ts":"2026-02-04T01:56:20.534631Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became follower at term 0"} {"level":"info","ts":"2026-02-04T01:56:20.534669Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft aab6040abcf11 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-04T01:56:20.53468Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became follower at term 1"} {"level":"info","ts":"2026-02-04T01:56:20.534715Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=(3003179657318161)"} {"level":"warn","ts":"2026-02-04T01:56:20.554133Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-04T01:56:20.559543Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-04T01:56:20.563619Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-02-04T01:56:20.571411Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"aab6040abcf11","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-04T01:56:20.573681Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"aab6040abcf11","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-04T01:56:20.574246Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-04T01:56:20.574361Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-04T01:56:20.574371Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-04T01:56:20.575807Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=(3003179657318161)"} {"level":"info","ts":"2026-02-04T01:56:20.577994Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-04T01:56:20.578274Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"aab6040abcf11","initial-advertise-peer-urls":["https://199.204.45.235:2380"],"listen-peer-urls":["https://199.204.45.235:2380"],"advertise-client-urls":["https://199.204.45.235:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-04T01:56:20.578373Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-04T01:56:20.578537Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.235:2380"} {"level":"info","ts":"2026-02-04T01:56:20.578549Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.235:2380"} {"level":"info","ts":"2026-02-04T01:56:20.576949Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2bae7d3d8857c059","local-member-id":"aab6040abcf11","added-peer-id":"aab6040abcf11","added-peer-peer-urls":["https://199.204.45.235:2380"]} {"level":"info","ts":"2026-02-04T01:56:21.135209Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 is starting a new election at term 1"} {"level":"info","ts":"2026-02-04T01:56:21.135247Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-04T01:56:21.135298Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 received MsgPreVoteResp from aab6040abcf11 at term 1"} {"level":"info","ts":"2026-02-04T01:56:21.135316Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became candidate at term 2"} {"level":"info","ts":"2026-02-04T01:56:21.135324Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 received MsgVoteResp from aab6040abcf11 at term 2"} {"level":"info","ts":"2026-02-04T01:56:21.135332Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became leader at term 2"} {"level":"info","ts":"2026-02-04T01:56:21.13535Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: aab6040abcf11 elected leader aab6040abcf11 at term 2"} {"level":"info","ts":"2026-02-04T01:56:21.137826Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-04T01:56:21.139515Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"aab6040abcf11","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.235:2379]}","request-path":"/0/members/aab6040abcf11/attributes","cluster-id":"2bae7d3d8857c059","publish-timeout":"7s"} {"level":"info","ts":"2026-02-04T01:56:21.139763Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-04T01:56:21.145988Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-04T01:56:21.146185Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-04T01:56:21.1462Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-04T01:56:21.153096Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.235:2379"} {"level":"info","ts":"2026-02-04T01:56:21.153178Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2bae7d3d8857c059","local-member-id":"aab6040abcf11","cluster-version":"3.5"} {"level":"info","ts":"2026-02-04T01:56:21.153238Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-04T01:56:21.153255Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-04T01:56:21.160146Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-04T01:56:35.49045Z","caller":"traceutil/trace.go:171","msg":"trace[462933864] linearizableReadLoop","detail":"{readStateIndex:422; appliedIndex:421; }","duration":"116.67111ms","start":"2026-02-04T01:56:35.373761Z","end":"2026-02-04T01:56:35.490433Z","steps":["trace[462933864] 'read index received' (duration: 62.145961ms)","trace[462933864] 'applied index is now lower than readState.Index' (duration: 54.524569ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:56:35.49059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.810794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-04T01:56:35.490646Z","caller":"traceutil/trace.go:171","msg":"trace[1451932121] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:407; }","duration":"116.981737ms","start":"2026-02-04T01:56:35.373637Z","end":"2026-02-04T01:56:35.490619Z","steps":["trace[1451932121] 'agreement among raft nodes before linearized reading' (duration: 116.875525ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:56:35.49164Z","caller":"traceutil/trace.go:171","msg":"trace[516392167] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"153.194944ms","start":"2026-02-04T01:56:35.338418Z","end":"2026-02-04T01:56:35.491613Z","steps":["trace[516392167] 'process raft request' (duration: 97.505463ms)","trace[516392167] 'compare' (duration: 54.390056ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:56:35.626711Z","caller":"traceutil/trace.go:171","msg":"trace[638308343] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"129.896648ms","start":"2026-02-04T01:56:35.49679Z","end":"2026-02-04T01:56:35.626687Z","steps":["trace[638308343] 'process raft request' (duration: 127.310299ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:56:36.671393Z","caller":"traceutil/trace.go:171","msg":"trace[108352639] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"552.325631ms","start":"2026-02-04T01:56:36.119048Z","end":"2026-02-04T01:56:36.671373Z","steps":["trace[108352639] 'process raft request' (duration: 552.192869ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:56:36.671918Z","caller":"traceutil/trace.go:171","msg":"trace[844682532] linearizableReadLoop","detail":"{readStateIndex:424; appliedIndex:424; }","duration":"470.368889ms","start":"2026-02-04T01:56:36.201526Z","end":"2026-02-04T01:56:36.671895Z","steps":["trace[844682532] 'read index received' (duration: 470.363399ms)","trace[844682532] 'applied index is now lower than readState.Index' (duration: 4.57µs)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:56:36.672039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.504123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:56:36.672077Z","caller":"traceutil/trace.go:171","msg":"trace[1892313347] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:409; }","duration":"470.561133ms","start":"2026-02-04T01:56:36.201506Z","end":"2026-02-04T01:56:36.672067Z","steps":["trace[1892313347] 'agreement among raft nodes before linearized reading' (duration: 470.468232ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:36.672109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:36.201498Z","time spent":"470.604985ms","remote":"127.0.0.1:33456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T01:56:36.673333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:36.119022Z","time spent":"552.432293ms","remote":"127.0.0.1:33618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7222,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:56:37.099175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.545739ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:56:37.099261Z","caller":"traceutil/trace.go:171","msg":"trace[1763343966] linearizableReadLoop","detail":"{readStateIndex:425; appliedIndex:424; }","duration":"427.290716ms","start":"2026-02-04T01:56:36.671956Z","end":"2026-02-04T01:56:37.099247Z","steps":["trace[1763343966] 'read index received' (duration: 204.443091ms)","trace[1763343966] 'applied index is now lower than readState.Index' (duration: 222.846465ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:56:37.099481Z","caller":"traceutil/trace.go:171","msg":"trace[1973089292] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"813.258499ms","start":"2026-02-04T01:56:36.28621Z","end":"2026-02-04T01:56:37.099469Z","steps":["trace[1973089292] 'process raft request' (duration: 590.236541ms)","trace[1973089292] 'compare' (duration: 222.09823ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:56:37.099649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:36.286189Z","time spent":"813.421741ms","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:56:37.100416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.994737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-04T01:56:37.100465Z","caller":"traceutil/trace.go:171","msg":"trace[1369713683] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:410; }","duration":"505.080809ms","start":"2026-02-04T01:56:36.595371Z","end":"2026-02-04T01:56:37.100452Z","steps":["trace[1369713683] 'agreement among raft nodes before linearized reading' (duration: 504.968366ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:37.100694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:36.595351Z","time spent":"505.330563ms","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-04T01:56:37.101135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.7955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6898"} {"level":"info","ts":"2026-02-04T01:56:37.101178Z","caller":"traceutil/trace.go:171","msg":"trace[1308965200] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:410; }","duration":"423.858091ms","start":"2026-02-04T01:56:36.677309Z","end":"2026-02-04T01:56:37.101167Z","steps":["trace[1308965200] 'agreement among raft nodes before linearized reading' (duration: 423.78073ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:37.101856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:36.677299Z","time spent":"424.543045ms","remote":"127.0.0.1:33618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6919,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"warn","ts":"2026-02-04T01:56:37.552887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.318401ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:56:37.552953Z","caller":"traceutil/trace.go:171","msg":"trace[1540547068] linearizableReadLoop","detail":"{readStateIndex:427; appliedIndex:426; }","duration":"215.713261ms","start":"2026-02-04T01:56:37.33723Z","end":"2026-02-04T01:56:37.552943Z","steps":["trace[1540547068] 'read index received' (duration: 16.75µs)","trace[1540547068] 'applied index is now lower than readState.Index' (duration: 215.695921ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:56:37.553054Z","caller":"traceutil/trace.go:171","msg":"trace[786217817] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"445.110278ms","start":"2026-02-04T01:56:37.107938Z","end":"2026-02-04T01:56:37.553048Z","steps":["trace[786217817] 'process raft request' (duration: 219.575683ms)","trace[786217817] 'compare' (duration: 225.107798ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:56:37.553087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:37.107929Z","time spent":"445.14341ms","remote":"127.0.0.1:33618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6883,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:56:37.553204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.972936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-04T01:56:37.553223Z","caller":"traceutil/trace.go:171","msg":"trace[1157480551] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:412; }","duration":"216.012926ms","start":"2026-02-04T01:56:37.337205Z","end":"2026-02-04T01:56:37.553218Z","steps":["trace[1157480551] 'agreement among raft nodes before linearized reading' (duration: 215.976696ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:56:37.888272Z","caller":"traceutil/trace.go:171","msg":"trace[1717285720] linearizableReadLoop","detail":"{readStateIndex:430; appliedIndex:428; }","duration":"307.70636ms","start":"2026-02-04T01:56:37.580546Z","end":"2026-02-04T01:56:37.888253Z","steps":["trace[1717285720] 'read index received' (duration: 69.820715ms)","trace[1717285720] 'applied index is now lower than readState.Index' (duration: 237.884735ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:56:37.889348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.781961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:56:37.889402Z","caller":"traceutil/trace.go:171","msg":"trace[1325350336] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:415; }","duration":"308.871773ms","start":"2026-02-04T01:56:37.580516Z","end":"2026-02-04T01:56:37.889388Z","steps":["trace[1325350336] 'agreement among raft nodes before linearized reading' (duration: 308.766431ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:37.889442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:37.580504Z","time spent":"308.930364ms","remote":"127.0.0.1:33450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-04T01:56:37.890071Z","caller":"traceutil/trace.go:171","msg":"trace[608351761] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"322.820874ms","start":"2026-02-04T01:56:37.567213Z","end":"2026-02-04T01:56:37.890034Z","steps":["trace[608351761] 'process raft request' (duration: 320.888147ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:37.890369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:37.567207Z","time spent":"323.081418ms","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:56:37.890704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.162493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-04T01:56:37.891064Z","caller":"traceutil/trace.go:171","msg":"trace[1262450834] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:415; }","duration":"260.410887ms","start":"2026-02-04T01:56:37.630353Z","end":"2026-02-04T01:56:37.890764Z","steps":["trace[1262450834] 'agreement among raft nodes before linearized reading' (duration: 260.155162ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:56:37.891211Z","caller":"traceutil/trace.go:171","msg":"trace[315039030] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"320.310646ms","start":"2026-02-04T01:56:37.57089Z","end":"2026-02-04T01:56:37.8912Z","steps":["trace[315039030] 'process raft request' (duration: 317.31942ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:37.891787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:37.570884Z","time spent":"320.637722ms","remote":"127.0.0.1:33618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6673,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:56:38.161526Z","caller":"traceutil/trace.go:171","msg":"trace[1240678837] linearizableReadLoop","detail":"{readStateIndex:432; appliedIndex:431; }","duration":"104.3444ms","start":"2026-02-04T01:56:38.05716Z","end":"2026-02-04T01:56:38.161505Z","steps":["trace[1240678837] 'read index received' (duration: 104.162377ms)","trace[1240678837] 'applied index is now lower than readState.Index' (duration: 180.733µs)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:56:38.161674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.500033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4013"} {"level":"info","ts":"2026-02-04T01:56:38.16171Z","caller":"traceutil/trace.go:171","msg":"trace[81729727] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:417; }","duration":"104.601525ms","start":"2026-02-04T01:56:38.057099Z","end":"2026-02-04T01:56:38.161701Z","steps":["trace[81729727] 'agreement among raft nodes before linearized reading' (duration: 104.497233ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:56:38.162247Z","caller":"traceutil/trace.go:171","msg":"trace[2020881850] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"253.507648ms","start":"2026-02-04T01:56:37.908725Z","end":"2026-02-04T01:56:38.162233Z","steps":["trace[2020881850] 'process raft request' (duration: 252.663162ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:56:38.547404Z","caller":"traceutil/trace.go:171","msg":"trace[120489601] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"370.608056ms","start":"2026-02-04T01:56:38.17675Z","end":"2026-02-04T01:56:38.547358Z","steps":["trace[120489601] 'process raft request' (duration: 305.04601ms)","trace[120489601] 'compare' (duration: 64.671929ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:56:38.547791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:38.176724Z","time spent":"370.907102ms","remote":"127.0.0.1:33616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4005,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:56:40.082396Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920878729044670893,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T01:56:40.583203Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920878729044670893,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T01:56:41.08374Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920878729044670893,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T01:56:41.172082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.922628111s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4019"} {"level":"info","ts":"2026-02-04T01:56:41.172148Z","caller":"traceutil/trace.go:171","msg":"trace[828905188] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:418; }","duration":"1.922743213s","start":"2026-02-04T01:56:39.249388Z","end":"2026-02-04T01:56:41.172131Z","steps":["trace[828905188] 'range keys from in-memory index tree' (duration: 1.92256759s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:41.172191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:39.249374Z","time spent":"1.922801074s","remote":"127.0.0.1:33616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4040,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-04T01:56:41.172511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.972773107s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-04T01:56:41.172544Z","caller":"traceutil/trace.go:171","msg":"trace[1210519057] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:418; }","duration":"1.972843059s","start":"2026-02-04T01:56:39.199692Z","end":"2026-02-04T01:56:41.172535Z","steps":["trace[1210519057] 'range keys from in-memory index tree' (duration: 1.972709087s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:41.172571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:39.199676Z","time spent":"1.972889499s","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-04T01:56:41.262217Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.370180117s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-04T01:56:41.582513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000387784s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-04T01:56:41.58258Z","caller":"traceutil/trace.go:171","msg":"trace[1246408743] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000506546s","start":"2026-02-04T01:56:39.582058Z","end":"2026-02-04T01:56:41.582564Z","steps":["trace[1246408743] 'agreement among raft nodes before linearized reading' (duration: 2.000411014s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:41.582617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:39.582024Z","time spent":"2.000586667s","remote":"127.0.0.1:33456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T01:56:41.832018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.617215ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:26"} {"level":"info","ts":"2026-02-04T01:56:41.832204Z","caller":"traceutil/trace.go:171","msg":"trace[647849856] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"653.127816ms","start":"2026-02-04T01:56:41.179066Z","end":"2026-02-04T01:56:41.832194Z","steps":["trace[647849856] 'process raft request' (duration: 653.026114ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:41.832269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:41.179037Z","time spent":"653.192107ms","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:56:41.832434Z","caller":"traceutil/trace.go:171","msg":"trace[394298049] linearizableReadLoop","detail":"{readStateIndex:434; appliedIndex:433; }","duration":"2.250329525s","start":"2026-02-04T01:56:39.582099Z","end":"2026-02-04T01:56:41.832429Z","steps":["trace[394298049] 'read index received' (duration: 1.680545266s)","trace[394298049] 'applied index is now lower than readState.Index' (duration: 569.782519ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:56:42.332699Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920878729044670897,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-04T01:56:42.635801Z","caller":"traceutil/trace.go:171","msg":"trace[1794903198] linearizableReadLoop","detail":"{readStateIndex:436; appliedIndex:435; }","duration":"803.329953ms","start":"2026-02-04T01:56:41.832449Z","end":"2026-02-04T01:56:42.635779Z","steps":["trace[1794903198] 'read index received' (duration: 803.14709ms)","trace[1794903198] 'applied index is now lower than readState.Index' (duration: 181.833µs)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:56:42.635958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.474896003s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:56:42.636032Z","caller":"traceutil/trace.go:171","msg":"trace[1780106423] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:420; }","duration":"1.475021906s","start":"2026-02-04T01:56:41.160994Z","end":"2026-02-04T01:56:42.636016Z","steps":["trace[1780106423] 'agreement among raft nodes before linearized reading' (duration: 1.474809942s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:42.63608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:41.160975Z","time spent":"1.475097277s","remote":"127.0.0.1:33450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T01:56:42.636217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.69847582s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-04T01:56:42.636288Z","caller":"traceutil/trace.go:171","msg":"trace[806006270] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"1.05094699s","start":"2026-02-04T01:56:41.58533Z","end":"2026-02-04T01:56:42.636277Z","steps":["trace[806006270] 'process raft request' (duration: 1.050318569s)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:56:42.636296Z","caller":"traceutil/trace.go:171","msg":"trace[1379520693] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:420; }","duration":"2.698591001s","start":"2026-02-04T01:56:39.937692Z","end":"2026-02-04T01:56:42.636283Z","steps":["trace[1379520693] 'agreement among raft nodes before linearized reading' (duration: 2.698280906s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:42.636416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.049280889s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:56:42.636448Z","caller":"traceutil/trace.go:171","msg":"trace[970508242] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:420; }","duration":"1.04932959s","start":"2026-02-04T01:56:41.587111Z","end":"2026-02-04T01:56:42.63644Z","steps":["trace[970508242] 'agreement among raft nodes before linearized reading' (duration: 1.049282469s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:42.636474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:41.587103Z","time spent":"1.049364501s","remote":"127.0.0.1:33456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T01:56:42.636419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:39.93768Z","time spent":"2.698726464s","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-04T01:56:42.636816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.739149071s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-02-04T01:56:42.636901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"598.22734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4034"} {"level":"info","ts":"2026-02-04T01:56:42.636938Z","caller":"traceutil/trace.go:171","msg":"trace[1648058725] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:420; }","duration":"598.289121ms","start":"2026-02-04T01:56:42.038639Z","end":"2026-02-04T01:56:42.636928Z","steps":["trace[1648058725] 'agreement among raft nodes before linearized reading' (duration: 598.23982ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:42.636967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:42.038621Z","time spent":"598.339532ms","remote":"127.0.0.1:33898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":4055,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"info","ts":"2026-02-04T01:56:42.636913Z","caller":"traceutil/trace.go:171","msg":"trace[578150939] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:420; }","duration":"2.739272982s","start":"2026-02-04T01:56:39.897622Z","end":"2026-02-04T01:56:42.636895Z","steps":["trace[578150939] 'agreement among raft nodes before linearized reading' (duration: 2.73914492s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:42.637069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:39.897604Z","time spent":"2.739451675s","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-04T01:56:42.636849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.983536707s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-04T01:56:42.637411Z","caller":"traceutil/trace.go:171","msg":"trace[2023135889] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:420; }","duration":"2.984117667s","start":"2026-02-04T01:56:39.65328Z","end":"2026-02-04T01:56:42.637397Z","steps":["trace[2023135889] 'agreement among raft nodes before linearized reading' (duration: 2.983527947s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:42.63637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:41.585313Z","time spent":"1.051011811s","remote":"127.0.0.1:33528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-04T01:56:42.637561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:39.653268Z","time spent":"2.984203719s","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":453,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-04T01:56:43.012179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.800058ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:56:43.012918Z","caller":"traceutil/trace.go:171","msg":"trace[1214766795] linearizableReadLoop","detail":"{readStateIndex:437; appliedIndex:436; }","duration":"364.014973ms","start":"2026-02-04T01:56:42.64889Z","end":"2026-02-04T01:56:43.012905Z","steps":["trace[1214766795] 'read index received' (duration: 250.43868ms)","trace[1214766795] 'applied index is now lower than readState.Index' (duration: 113.573252ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:56:43.012966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.072033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:56:43.012983Z","caller":"traceutil/trace.go:171","msg":"trace[467332623] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:421; }","duration":"364.112565ms","start":"2026-02-04T01:56:42.648864Z","end":"2026-02-04T01:56:43.012977Z","steps":["trace[467332623] 'agreement among raft nodes before linearized reading' (duration: 364.076564ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:43.013003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:42.648852Z","time spent":"364.146805ms","remote":"127.0.0.1:33450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-04T01:56:43.013061Z","caller":"traceutil/trace.go:171","msg":"trace[778824430] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"364.357909ms","start":"2026-02-04T01:56:42.648675Z","end":"2026-02-04T01:56:43.013033Z","steps":["trace[778824430] 'process raft request' (duration: 250.643714ms)","trace[778824430] 'compare' (duration: 112.503833ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:56:43.013184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:42.648624Z","time spent":"364.491742ms","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:56:43.478328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.531308ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:56:43.478524Z","caller":"traceutil/trace.go:171","msg":"trace[797881437] linearizableReadLoop","detail":"{readStateIndex:440; appliedIndex:437; }","duration":"463.273418ms","start":"2026-02-04T01:56:43.015236Z","end":"2026-02-04T01:56:43.478509Z","steps":["trace[797881437] 'read index received' (duration: 147.419365ms)","trace[797881437] 'applied index is now lower than readState.Index' (duration: 315.853113ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:56:43.478692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.36573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:56:43.478724Z","caller":"traceutil/trace.go:171","msg":"trace[133877318] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:424; }","duration":"463.512912ms","start":"2026-02-04T01:56:43.0152Z","end":"2026-02-04T01:56:43.478713Z","steps":["trace[133877318] 'agreement among raft nodes before linearized reading' (duration: 463.353529ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:43.478754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:43.015183Z","time spent":"463.564142ms","remote":"127.0.0.1:33456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-04T01:56:43.479008Z","caller":"traceutil/trace.go:171","msg":"trace[1422614917] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"827.736318ms","start":"2026-02-04T01:56:42.65126Z","end":"2026-02-04T01:56:43.478996Z","steps":["trace[1422614917] 'process raft request' (duration: 511.462827ms)","trace[1422614917] 'compare' (duration: 315.073488ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:56:43.479079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:42.651253Z","time spent":"827.78036ms","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:56:43.479279Z","caller":"traceutil/trace.go:171","msg":"trace[1028999757] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"828.017354ms","start":"2026-02-04T01:56:42.651252Z","end":"2026-02-04T01:56:43.47927Z","steps":["trace[1028999757] 'process raft request' (duration: 827.163029ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:43.479347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:42.651242Z","time spent":"828.059394ms","remote":"127.0.0.1:33898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4158,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:56:43.479474Z","caller":"traceutil/trace.go:171","msg":"trace[429886836] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"824.982537ms","start":"2026-02-04T01:56:42.654483Z","end":"2026-02-04T01:56:43.479466Z","steps":["trace[429886836] 'process raft request' (duration: 823.988449ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:43.479521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:42.654474Z","time spent":"825.020449ms","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:56:44.074268Z","caller":"traceutil/trace.go:171","msg":"trace[652009763] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"585.97092ms","start":"2026-02-04T01:56:43.488279Z","end":"2026-02-04T01:56:44.07425Z","steps":["trace[652009763] 'process raft request' (duration: 585.879369ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:44.074393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:43.48826Z","time spent":"586.065513ms","remote":"127.0.0.1:33924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3656,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-04T01:56:44.561095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.061769ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:56:44.56125Z","caller":"traceutil/trace.go:171","msg":"trace[1115443382] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"916.430706ms","start":"2026-02-04T01:56:43.644796Z","end":"2026-02-04T01:56:44.561227Z","steps":["trace[1115443382] 'process raft request' (duration: 549.181173ms)","trace[1115443382] 'compare' (duration: 366.975068ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:56:44.56129Z","caller":"traceutil/trace.go:171","msg":"trace[262926645] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"478.997871ms","start":"2026-02-04T01:56:44.08228Z","end":"2026-02-04T01:56:44.561277Z","steps":["trace[262926645] 'process raft request' (duration: 478.96858ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:44.561334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:44.082273Z","time spent":"479.043253ms","remote":"127.0.0.1:33618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3541,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-04T01:56:44.56136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:43.644774Z","time spent":"916.524868ms","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:56:44.561446Z","caller":"traceutil/trace.go:171","msg":"trace[1974022121] linearizableReadLoop","detail":"{readStateIndex:442; appliedIndex:441; }","duration":"724.796825ms","start":"2026-02-04T01:56:43.836643Z","end":"2026-02-04T01:56:44.561439Z","steps":["trace[1974022121] 'read index received' (duration: 238.11963ms)","trace[1974022121] 'applied index is now lower than readState.Index' (duration: 486.676435ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:56:44.561503Z","caller":"traceutil/trace.go:171","msg":"trace[1188607776] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"480.177994ms","start":"2026-02-04T01:56:44.081319Z","end":"2026-02-04T01:56:44.561497Z","steps":["trace[1188607776] 'process raft request' (duration: 479.860318ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:44.56153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:44.081303Z","time spent":"480.212314ms","remote":"127.0.0.1:33528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":680,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-04T01:56:44.561685Z","caller":"traceutil/trace.go:171","msg":"trace[822145806] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"480.300027ms","start":"2026-02-04T01:56:44.081378Z","end":"2026-02-04T01:56:44.561678Z","steps":["trace[822145806] 'process raft request' (duration: 479.841078ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:44.56172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:44.081373Z","time spent":"480.327187ms","remote":"127.0.0.1:33898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4158,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:56:44.561907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"725.268774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-04T01:56:44.561933Z","caller":"traceutil/trace.go:171","msg":"trace[1962576667] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:429; }","duration":"725.331145ms","start":"2026-02-04T01:56:43.836595Z","end":"2026-02-04T01:56:44.561926Z","steps":["trace[1962576667] 'agreement among raft nodes before linearized reading' (duration: 725.289914ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:44.561951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:43.836578Z","time spent":"725.369326ms","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-04T01:56:44.888952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.489161ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:38"} {"level":"info","ts":"2026-02-04T01:56:44.889216Z","caller":"traceutil/trace.go:171","msg":"trace[1750256127] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"322.14433ms","start":"2026-02-04T01:56:44.567051Z","end":"2026-02-04T01:56:44.889195Z","steps":["trace[1750256127] 'process raft request' (duration: 321.990317ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:44.88932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:44.567036Z","time spent":"322.225911ms","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:56:44.889984Z","caller":"traceutil/trace.go:171","msg":"trace[193652734] linearizableReadLoop","detail":"{readStateIndex:452; appliedIndex:445; }","duration":"310.358791ms","start":"2026-02-04T01:56:44.579611Z","end":"2026-02-04T01:56:44.88997Z","steps":["trace[193652734] 'read index received' (duration: 81.851821ms)","trace[193652734] 'applied index is now lower than readState.Index' (duration: 228.50615ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:56:44.890124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.502103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:56:44.890166Z","caller":"traceutil/trace.go:171","msg":"trace[11407075] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:435; }","duration":"310.562334ms","start":"2026-02-04T01:56:44.579591Z","end":"2026-02-04T01:56:44.890153Z","steps":["trace[11407075] 'agreement among raft nodes before linearized reading' (duration: 310.428472ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:44.890199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:44.579584Z","time spent":"310.607625ms","remote":"127.0.0.1:33450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T01:56:44.890567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:44.564321Z","time spent":"326.243477ms","remote":"127.0.0.1:33470","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-04T01:56:44.891787Z","caller":"traceutil/trace.go:171","msg":"trace[1480503263] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"322.983915ms","start":"2026-02-04T01:56:44.568784Z","end":"2026-02-04T01:56:44.891768Z","steps":["trace[1480503263] 'process raft request' (duration: 320.373146ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:44.892069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:44.568777Z","time spent":"323.25093ms","remote":"127.0.0.1:33528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-04T01:56:44.892419Z","caller":"traceutil/trace.go:171","msg":"trace[314866087] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"321.388467ms","start":"2026-02-04T01:56:44.571023Z","end":"2026-02-04T01:56:44.892412Z","steps":["trace[314866087] 'process raft request' (duration: 318.785508ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:44.892486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:44.571017Z","time spent":"321.448288ms","remote":"127.0.0.1:33618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4015,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:56:44.892706Z","caller":"traceutil/trace.go:171","msg":"trace[1345881806] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"321.55775ms","start":"2026-02-04T01:56:44.571142Z","end":"2026-02-04T01:56:44.892699Z","steps":["trace[1345881806] 'process raft request' (duration: 318.722107ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:44.892815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:44.571137Z","time spent":"321.658062ms","remote":"127.0.0.1:33924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3773,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:56:44.892904Z","caller":"traceutil/trace.go:171","msg":"trace[1598446062] transaction","detail":"{read_only:false; response_revision:434; number_of_response:1; }","duration":"321.53481ms","start":"2026-02-04T01:56:44.571364Z","end":"2026-02-04T01:56:44.892899Z","steps":["trace[1598446062] 'process raft request' (duration: 318.537444ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:44.893024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:44.571358Z","time spent":"321.649702ms","remote":"127.0.0.1:33528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-04T01:56:44.893118Z","caller":"traceutil/trace.go:171","msg":"trace[1372408694] transaction","detail":"{read_only:false; response_revision:435; number_of_response:1; }","duration":"320.006869ms","start":"2026-02-04T01:56:44.573106Z","end":"2026-02-04T01:56:44.893113Z","steps":["trace[1372408694] 'process raft request' (duration: 316.82736ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:44.893249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:44.573092Z","time spent":"320.138303ms","remote":"127.0.0.1:33924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3782,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:56:45.204191Z","caller":"traceutil/trace.go:171","msg":"trace[1410091120] linearizableReadLoop","detail":"{readStateIndex:459; appliedIndex:458; }","duration":"254.658809ms","start":"2026-02-04T01:56:44.949517Z","end":"2026-02-04T01:56:45.204175Z","steps":["trace[1410091120] 'read index received' (duration: 19.721169ms)","trace[1410091120] 'applied index is now lower than readState.Index' (duration: 234.93696ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:56:45.204591Z","caller":"traceutil/trace.go:171","msg":"trace[636148652] transaction","detail":"{read_only:false; response_revision:442; number_of_response:1; }","duration":"255.143039ms","start":"2026-02-04T01:56:44.949437Z","end":"2026-02-04T01:56:45.20458Z","steps":["trace[636148652] 'process raft request' (duration: 157.83004ms)","trace[636148652] 'compare' (duration: 96.786198ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:56:45.205096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.582057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-04T01:56:45.205125Z","caller":"traceutil/trace.go:171","msg":"trace[1058455913] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:443; }","duration":"186.656449ms","start":"2026-02-04T01:56:45.018462Z","end":"2026-02-04T01:56:45.205118Z","steps":["trace[1058455913] 'agreement among raft nodes before linearized reading' (duration: 186.590757ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:56:45.205256Z","caller":"traceutil/trace.go:171","msg":"trace[1391967561] transaction","detail":"{read_only:false; response_revision:443; number_of_response:1; }","duration":"251.915638ms","start":"2026-02-04T01:56:44.953334Z","end":"2026-02-04T01:56:45.20525Z","steps":["trace[1391967561] 'process raft request' (duration: 250.805987ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:45.20534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.82024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-77cccfdc44\" ","response":"range_response_count:1 size:3788"} {"level":"info","ts":"2026-02-04T01:56:45.205356Z","caller":"traceutil/trace.go:171","msg":"trace[1104676543] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-77cccfdc44; range_end:; response_count:1; response_revision:443; }","duration":"255.865322ms","start":"2026-02-04T01:56:44.949486Z","end":"2026-02-04T01:56:45.205351Z","steps":["trace[1104676543] 'agreement among raft nodes before linearized reading' (duration: 255.835301ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:45.441459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.756584ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:56:45.441547Z","caller":"traceutil/trace.go:171","msg":"trace[833737723] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"226.841569ms","start":"2026-02-04T01:56:45.214687Z","end":"2026-02-04T01:56:45.441528Z","steps":["trace[833737723] 'process raft request' (duration: 119.904161ms)","trace[833737723] 'compare' (duration: 106.662103ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:56:45.492501Z","caller":"traceutil/trace.go:171","msg":"trace[1288893800] linearizableReadLoop","detail":"{readStateIndex:463; appliedIndex:460; }","duration":"274.795956ms","start":"2026-02-04T01:56:45.217687Z","end":"2026-02-04T01:56:45.492483Z","steps":["trace[1288893800] 'read index received' (duration: 116.911226ms)","trace[1288893800] 'applied index is now lower than readState.Index' (duration: 157.88407ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:56:45.49272Z","caller":"traceutil/trace.go:171","msg":"trace[1928900941] transaction","detail":"{read_only:false; response_revision:445; number_of_response:1; }","duration":"277.490556ms","start":"2026-02-04T01:56:45.215223Z","end":"2026-02-04T01:56:45.492714Z","steps":["trace[1928900941] 'process raft request' (duration: 277.119099ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:56:45.49284Z","caller":"traceutil/trace.go:171","msg":"trace[1682681311] transaction","detail":"{read_only:false; response_revision:446; number_of_response:1; }","duration":"276.226163ms","start":"2026-02-04T01:56:45.216609Z","end":"2026-02-04T01:56:45.492835Z","steps":["trace[1682681311] 'process raft request' (duration: 275.825055ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:56:45.492956Z","caller":"traceutil/trace.go:171","msg":"trace[374012753] transaction","detail":"{read_only:false; response_revision:447; number_of_response:1; }","duration":"274.132403ms","start":"2026-02-04T01:56:45.218819Z","end":"2026-02-04T01:56:45.492951Z","steps":["trace[374012753] 'process raft request' (duration: 273.636813ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:45.493035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.334946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-5dd5756b68\" ","response":"range_response_count:1 size:3797"} {"level":"info","ts":"2026-02-04T01:56:45.493052Z","caller":"traceutil/trace.go:171","msg":"trace[1657135559] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-5dd5756b68; range_end:; response_count:1; response_revision:447; }","duration":"275.376037ms","start":"2026-02-04T01:56:45.21767Z","end":"2026-02-04T01:56:45.493046Z","steps":["trace[1657135559] 'agreement among raft nodes before linearized reading' (duration: 275.332376ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:56:45.731391Z","caller":"traceutil/trace.go:171","msg":"trace[1007020684] linearizableReadLoop","detail":"{readStateIndex:465; appliedIndex:464; }","duration":"238.821143ms","start":"2026-02-04T01:56:45.492548Z","end":"2026-02-04T01:56:45.731369Z","steps":["trace[1007020684] 'read index received' (duration: 169.214742ms)","trace[1007020684] 'applied index is now lower than readState.Index' (duration: 69.605201ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:56:45.731496Z","caller":"traceutil/trace.go:171","msg":"trace[202960527] transaction","detail":"{read_only:false; response_revision:448; number_of_response:1; }","duration":"284.280412ms","start":"2026-02-04T01:56:45.447205Z","end":"2026-02-04T01:56:45.731485Z","steps":["trace[202960527] 'process raft request' (duration: 214.548469ms)","trace[202960527] 'compare' (duration: 69.474559ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:56:45.731603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.944961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-04T01:56:45.73165Z","caller":"traceutil/trace.go:171","msg":"trace[1658546807] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:448; }","duration":"242.019673ms","start":"2026-02-04T01:56:45.489619Z","end":"2026-02-04T01:56:45.731638Z","steps":["trace[1658546807] 'agreement among raft nodes before linearized reading' (duration: 241.900141ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:45.731768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.459669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-04T01:56:45.731803Z","caller":"traceutil/trace.go:171","msg":"trace[1742184611] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:448; }","duration":"243.535031ms","start":"2026-02-04T01:56:45.488258Z","end":"2026-02-04T01:56:45.731793Z","steps":["trace[1742184611] 'agreement among raft nodes before linearized reading' (duration: 243.45589ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:56:45.946962Z","caller":"traceutil/trace.go:171","msg":"trace[2093828807] linearizableReadLoop","detail":"{readStateIndex:466; appliedIndex:465; }","duration":"215.481217ms","start":"2026-02-04T01:56:45.731459Z","end":"2026-02-04T01:56:45.946941Z","steps":["trace[2093828807] 'read index received' (duration: 191.551ms)","trace[2093828807] 'applied index is now lower than readState.Index' (duration: 23.929237ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:56:45.947167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.101145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-5dd5756b68\" ","response":"range_response_count:1 size:3797"} {"level":"info","ts":"2026-02-04T01:56:45.947218Z","caller":"traceutil/trace.go:171","msg":"trace[251670317] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-5dd5756b68; range_end:; response_count:1; response_revision:449; }","duration":"447.196187ms","start":"2026-02-04T01:56:45.500007Z","end":"2026-02-04T01:56:45.947203Z","steps":["trace[251670317] 'agreement among raft nodes before linearized reading' (duration: 447.028574ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:45.947264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:45.5Z","time spent":"447.253928ms","remote":"127.0.0.1:33924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":3818,"request content":"key:\"/registry/replicasets/kube-system/coredns-5dd5756b68\" "} {"level":"info","ts":"2026-02-04T01:56:45.947651Z","caller":"traceutil/trace.go:171","msg":"trace[1214646941] transaction","detail":"{read_only:false; response_revision:449; number_of_response:1; }","duration":"448.112795ms","start":"2026-02-04T01:56:45.499525Z","end":"2026-02-04T01:56:45.947638Z","steps":["trace[1214646941] 'process raft request' (duration: 423.475345ms)","trace[1214646941] 'compare' (duration: 23.826305ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:56:45.947751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:45.49951Z","time spent":"448.171625ms","remote":"127.0.0.1:33898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4158,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:56:45.947949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.544111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:56:45.948014Z","caller":"traceutil/trace.go:171","msg":"trace[1260010761] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:449; }","duration":"365.604153ms","start":"2026-02-04T01:56:45.582371Z","end":"2026-02-04T01:56:45.947975Z","steps":["trace[1260010761] 'agreement among raft nodes before linearized reading' (duration: 365.552192ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:45.948042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:45.582355Z","time spent":"365.679894ms","remote":"127.0.0.1:33450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T01:56:46.377889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.239506ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:56:46.378097Z","caller":"traceutil/trace.go:171","msg":"trace[570749560] linearizableReadLoop","detail":"{readStateIndex:472; appliedIndex:466; }","duration":"426.702535ms","start":"2026-02-04T01:56:45.951385Z","end":"2026-02-04T01:56:46.378088Z","steps":["trace[570749560] 'read index received' (duration: 267.165463ms)","trace[570749560] 'applied index is now lower than readState.Index' (duration: 159.536642ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:56:46.378157Z","caller":"traceutil/trace.go:171","msg":"trace[115925779] transaction","detail":"{read_only:false; response_revision:453; number_of_response:1; }","duration":"634.497698ms","start":"2026-02-04T01:56:45.743633Z","end":"2026-02-04T01:56:46.37813Z","steps":["trace[115925779] 'process raft request' (duration: 634.392916ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:56:46.37829Z","caller":"traceutil/trace.go:171","msg":"trace[1513038766] transaction","detail":"{read_only:false; response_revision:454; number_of_response:1; }","duration":"632.925158ms","start":"2026-02-04T01:56:45.745359Z","end":"2026-02-04T01:56:46.378284Z","steps":["trace[1513038766] 'process raft request' (duration: 632.688544ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:46.378271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:45.743628Z","time spent":"634.586099ms","remote":"127.0.0.1:33528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-04T01:56:46.378325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:45.745353Z","time spent":"632.954599ms","remote":"127.0.0.1:33618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4015,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:56:46.378476Z","caller":"traceutil/trace.go:171","msg":"trace[343877518] transaction","detail":"{read_only:false; response_revision:450; number_of_response:1; }","duration":"642.890145ms","start":"2026-02-04T01:56:45.735573Z","end":"2026-02-04T01:56:46.378463Z","steps":["trace[343877518] 'process raft request' (duration: 483.023667ms)","trace[343877518] 'compare' (duration: 159.121174ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:56:46.378511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.125312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:56:46.378525Z","caller":"traceutil/trace.go:171","msg":"trace[2071285211] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:455; }","duration":"427.167704ms","start":"2026-02-04T01:56:45.951353Z","end":"2026-02-04T01:56:46.378521Z","steps":["trace[2071285211] 'agreement among raft nodes before linearized reading' (duration: 427.144893ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:46.37854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:45.951338Z","time spent":"427.199664ms","remote":"127.0.0.1:33456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T01:56:46.378542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:45.735554Z","time spent":"642.948215ms","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:56:46.37866Z","caller":"traceutil/trace.go:171","msg":"trace[529735653] transaction","detail":"{read_only:false; response_revision:451; number_of_response:1; }","duration":"637.073236ms","start":"2026-02-04T01:56:45.741578Z","end":"2026-02-04T01:56:46.378651Z","steps":["trace[529735653] 'process raft request' (duration: 636.374113ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:46.378708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:45.741555Z","time spent":"637.125726ms","remote":"127.0.0.1:33528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-04T01:56:46.378795Z","caller":"traceutil/trace.go:171","msg":"trace[1451093107] transaction","detail":"{read_only:false; response_revision:452; number_of_response:1; }","duration":"635.251981ms","start":"2026-02-04T01:56:45.743537Z","end":"2026-02-04T01:56:46.378789Z","steps":["trace[1451093107] 'process raft request' (duration: 634.457617ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:56:46.378488Z","caller":"traceutil/trace.go:171","msg":"trace[1538400608] transaction","detail":"{read_only:false; response_revision:455; number_of_response:1; }","duration":"631.594304ms","start":"2026-02-04T01:56:45.746888Z","end":"2026-02-04T01:56:46.378482Z","steps":["trace[1538400608] 'process raft request' (duration: 631.180136ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:46.378826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:45.743529Z","time spent":"635.280832ms","remote":"127.0.0.1:33924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3818,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:56:46.378876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:45.74688Z","time spent":"631.95517ms","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:56:46.687252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.982381ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:56:46.687324Z","caller":"traceutil/trace.go:171","msg":"trace[1353992392] linearizableReadLoop","detail":"{readStateIndex:473; appliedIndex:472; }","duration":"309.193849ms","start":"2026-02-04T01:56:46.378119Z","end":"2026-02-04T01:56:46.687313Z","steps":["trace[1353992392] 'read index received' (duration: 120.094765ms)","trace[1353992392] 'applied index is now lower than readState.Index' (duration: 189.098404ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:56:46.687468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"727.939845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4173"} {"level":"info","ts":"2026-02-04T01:56:46.687501Z","caller":"traceutil/trace.go:171","msg":"trace[1838069920] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:456; }","duration":"727.982405ms","start":"2026-02-04T01:56:45.959511Z","end":"2026-02-04T01:56:46.687493Z","steps":["trace[1838069920] 'agreement among raft nodes before linearized reading' (duration: 727.832502ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:46.687524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:45.959504Z","time spent":"728.014866ms","remote":"127.0.0.1:33898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":4194,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"info","ts":"2026-02-04T01:56:46.687693Z","caller":"traceutil/trace.go:171","msg":"trace[258066735] transaction","detail":"{read_only:false; response_revision:456; number_of_response:1; }","duration":"728.075067ms","start":"2026-02-04T01:56:45.959609Z","end":"2026-02-04T01:56:46.687684Z","steps":["trace[258066735] 'process raft request' (duration: 538.595116ms)","trace[258066735] 'compare' (duration: 188.89365ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:56:46.687728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:45.959602Z","time spent":"728.107737ms","remote":"127.0.0.1:33924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3782,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:56:46.815799Z","caller":"traceutil/trace.go:171","msg":"trace[187652987] transaction","detail":"{read_only:false; response_revision:457; number_of_response:1; }","duration":"426.47738ms","start":"2026-02-04T01:56:46.389297Z","end":"2026-02-04T01:56:46.815774Z","steps":["trace[187652987] 'process raft request' (duration: 418.023422ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:46.818017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:46.389281Z","time spent":"426.752816ms","remote":"127.0.0.1:33924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3818,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:56:46.849814Z","caller":"traceutil/trace.go:171","msg":"trace[986141124] transaction","detail":"{read_only:false; response_revision:458; number_of_response:1; }","duration":"151.752946ms","start":"2026-02-04T01:56:46.698043Z","end":"2026-02-04T01:56:46.849796Z","steps":["trace[986141124] 'process raft request' (duration: 151.657635ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:56:47.126719Z","caller":"traceutil/trace.go:171","msg":"trace[2135022770] linearizableReadLoop","detail":"{readStateIndex:476; appliedIndex:475; }","duration":"226.606184ms","start":"2026-02-04T01:56:46.90007Z","end":"2026-02-04T01:56:47.126676Z","steps":["trace[2135022770] 'read index received' (duration: 183.0374ms)","trace[2135022770] 'applied index is now lower than readState.Index' (duration: 43.567724ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:56:47.126893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.811859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-04T01:56:47.126932Z","caller":"traceutil/trace.go:171","msg":"trace[1143558699] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:459; }","duration":"226.89041ms","start":"2026-02-04T01:56:46.900032Z","end":"2026-02-04T01:56:47.126923Z","steps":["trace[1143558699] 'agreement among raft nodes before linearized reading' (duration: 226.746918ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:56:47.127198Z","caller":"traceutil/trace.go:171","msg":"trace[1633618290] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"270.153279ms","start":"2026-02-04T01:56:46.857032Z","end":"2026-02-04T01:56:47.127185Z","steps":["trace[1633618290] 'process raft request' (duration: 226.133366ms)","trace[1633618290] 'compare' (duration: 43.289389ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:56:47.677639Z","caller":"traceutil/trace.go:171","msg":"trace[1452302254] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"172.009365ms","start":"2026-02-04T01:56:47.505615Z","end":"2026-02-04T01:56:47.677624Z","steps":["trace[1452302254] 'process raft request' (duration: 171.671769ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:48.674834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.181224ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:56:48.675686Z","caller":"traceutil/trace.go:171","msg":"trace[1099442] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"281.277287ms","start":"2026-02-04T01:56:48.394387Z","end":"2026-02-04T01:56:48.675664Z","steps":["trace[1099442] 'process raft request' (duration: 135.203386ms)","trace[1099442] 'compare' (duration: 144.95762ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:56:54.875701Z","caller":"traceutil/trace.go:171","msg":"trace[1884472226] linearizableReadLoop","detail":"{readStateIndex:497; appliedIndex:496; }","duration":"145.693633ms","start":"2026-02-04T01:56:54.729988Z","end":"2026-02-04T01:56:54.875682Z","steps":["trace[1884472226] 'read index received' (duration: 7.796665ms)","trace[1884472226] 'applied index is now lower than readState.Index' (duration: 137.896208ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:56:54.875885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.877116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:56:54.875934Z","caller":"traceutil/trace.go:171","msg":"trace[1651085803] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:478; }","duration":"145.964838ms","start":"2026-02-04T01:56:54.729955Z","end":"2026-02-04T01:56:54.87592Z","steps":["trace[1651085803] 'agreement among raft nodes before linearized reading' (duration: 145.824715ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:56:54.876184Z","caller":"traceutil/trace.go:171","msg":"trace[48207723] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"165.268428ms","start":"2026-02-04T01:56:54.710902Z","end":"2026-02-04T01:56:54.876171Z","steps":["trace[48207723] 'process raft request' (duration: 125.254782ms)","trace[48207723] 'compare' (duration: 39.344305ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:56:55.096348Z","caller":"traceutil/trace.go:171","msg":"trace[415268775] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"215.841925ms","start":"2026-02-04T01:56:54.880483Z","end":"2026-02-04T01:56:55.096325Z","steps":["trace[415268775] 'process raft request' (duration: 215.243093ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:55.397882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.58339ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:56:55.398212Z","caller":"traceutil/trace.go:171","msg":"trace[956031049] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"266.605823ms","start":"2026-02-04T01:56:55.131591Z","end":"2026-02-04T01:56:55.398196Z","steps":["trace[956031049] 'process raft request' (duration: 157.242389ms)","trace[956031049] 'compare' (duration: 108.452857ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:56:55.398439Z","caller":"traceutil/trace.go:171","msg":"trace[755248685] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"266.635593ms","start":"2026-02-04T01:56:55.131794Z","end":"2026-02-04T01:56:55.398429Z","steps":["trace[755248685] 'process raft request' (duration: 266.186525ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:56:55.398129Z","caller":"traceutil/trace.go:171","msg":"trace[733713804] linearizableReadLoop","detail":"{readStateIndex:500; appliedIndex:498; }","duration":"203.147736ms","start":"2026-02-04T01:56:55.194964Z","end":"2026-02-04T01:56:55.398112Z","steps":["trace[733713804] 'read index received' (duration: 93.824973ms)","trace[733713804] 'applied index is now lower than readState.Index' (duration: 109.321823ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:56:55.398908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.941832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-04T01:56:55.398948Z","caller":"traceutil/trace.go:171","msg":"trace[1959247712] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:481; }","duration":"204.058694ms","start":"2026-02-04T01:56:55.194879Z","end":"2026-02-04T01:56:55.398938Z","steps":["trace[1959247712] 'agreement among raft nodes before linearized reading' (duration: 203.888571ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:55.762144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.344136ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:56:55.762469Z","caller":"traceutil/trace.go:171","msg":"trace[1152760289] linearizableReadLoop","detail":"{readStateIndex:502; appliedIndex:501; }","duration":"179.963823ms","start":"2026-02-04T01:56:55.582488Z","end":"2026-02-04T01:56:55.762451Z","steps":["trace[1152760289] 'read index received' (duration: 49.155779ms)","trace[1152760289] 'applied index is now lower than readState.Index' (duration: 130.806884ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:56:55.762658Z","caller":"traceutil/trace.go:171","msg":"trace[1185230991] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"358.52996ms","start":"2026-02-04T01:56:55.404114Z","end":"2026-02-04T01:56:55.762644Z","steps":["trace[1185230991] 'process raft request' (duration: 227.626084ms)","trace[1185230991] 'compare' (duration: 130.157423ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:56:55.762841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:55.404097Z","time spent":"358.661953ms","remote":"127.0.0.1:33554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6519,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-04T01:56:55.763165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.659386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:56:55.763247Z","caller":"traceutil/trace.go:171","msg":"trace[919234997] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:483; }","duration":"180.764078ms","start":"2026-02-04T01:56:55.582465Z","end":"2026-02-04T01:56:55.763229Z","steps":["trace[919234997] 'agreement among raft nodes before linearized reading' (duration: 180.660736ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:56.17036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.700141ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:56:56.170481Z","caller":"traceutil/trace.go:171","msg":"trace[619288336] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"402.590164ms","start":"2026-02-04T01:56:55.767874Z","end":"2026-02-04T01:56:56.170464Z","steps":["trace[619288336] 'process raft request' (duration: 292.72619ms)","trace[619288336] 'compare' (duration: 109.570549ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:56:56.170534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:55.767863Z","time spent":"402.651335ms","remote":"127.0.0.1:33806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2364,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-04T01:56:56.175217Z","caller":"traceutil/trace.go:171","msg":"trace[634754773] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"405.654301ms","start":"2026-02-04T01:56:55.769544Z","end":"2026-02-04T01:56:56.175198Z","steps":["trace[634754773] 'process raft request' (duration: 401.892211ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:56.175965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:55.769536Z","time spent":"406.374055ms","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:56:56.17692Z","caller":"traceutil/trace.go:171","msg":"trace[501454189] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"406.277772ms","start":"2026-02-04T01:56:55.770623Z","end":"2026-02-04T01:56:56.176901Z","steps":["trace[501454189] 'process raft request' (duration: 406.15359ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:56.17707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:55.770615Z","time spent":"406.366944ms","remote":"127.0.0.1:33806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1639,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-04T01:56:56.439761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.973105ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:56:56.439864Z","caller":"traceutil/trace.go:171","msg":"trace[2018330007] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"258.824187ms","start":"2026-02-04T01:56:56.181019Z","end":"2026-02-04T01:56:56.439843Z","steps":["trace[2018330007] 'process raft request' (duration: 102.7088ms)","trace[2018330007] 'compare' (duration: 155.834052ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:56:56.50913Z","caller":"traceutil/trace.go:171","msg":"trace[1654491883] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"326.726916ms","start":"2026-02-04T01:56:56.182383Z","end":"2026-02-04T01:56:56.50911Z","steps":["trace[1654491883] 'process raft request' (duration: 326.371209ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:56.509233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:56.182375Z","time spent":"326.809609ms","remote":"127.0.0.1:33820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":734,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-04T01:56:56.622822Z","caller":"traceutil/trace.go:171","msg":"trace[1995552214] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"109.63603ms","start":"2026-02-04T01:56:56.513169Z","end":"2026-02-04T01:56:56.622805Z","steps":["trace[1995552214] 'process raft request' (duration: 104.380681ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:56:56.658796Z","caller":"traceutil/trace.go:171","msg":"trace[1048567232] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"144.951799ms","start":"2026-02-04T01:56:56.513789Z","end":"2026-02-04T01:56:56.65874Z","steps":["trace[1048567232] 'process raft request' (duration: 144.289627ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:56:56.867544Z","caller":"traceutil/trace.go:171","msg":"trace[156517566] linearizableReadLoop","detail":"{readStateIndex:511; appliedIndex:510; }","duration":"126.23106ms","start":"2026-02-04T01:56:56.741283Z","end":"2026-02-04T01:56:56.867514Z","steps":["trace[156517566] 'read index received' (duration: 90.879379ms)","trace[156517566] 'applied index is now lower than readState.Index' (duration: 35.350671ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:56:56.867858Z","caller":"traceutil/trace.go:171","msg":"trace[1488241497] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"173.126465ms","start":"2026-02-04T01:56:56.694697Z","end":"2026-02-04T01:56:56.867823Z","steps":["trace[1488241497] 'process raft request' (duration: 137.477179ms)","trace[1488241497] 'compare' (duration: 35.081715ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:56:56.868112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.81587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-04T01:56:56.868597Z","caller":"traceutil/trace.go:171","msg":"trace[622244176] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:492; }","duration":"127.33909ms","start":"2026-02-04T01:56:56.741241Z","end":"2026-02-04T01:56:56.86858Z","steps":["trace[622244176] 'agreement among raft nodes before linearized reading' (duration: 126.688208ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:56:57.182287Z","caller":"traceutil/trace.go:171","msg":"trace[1834047163] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"300.342392ms","start":"2026-02-04T01:56:56.881917Z","end":"2026-02-04T01:56:57.182259Z","steps":["trace[1834047163] 'process raft request' (duration: 300.23489ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:56:57.182426Z","caller":"traceutil/trace.go:171","msg":"trace[235149694] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"304.776315ms","start":"2026-02-04T01:56:56.877617Z","end":"2026-02-04T01:56:57.182393Z","steps":["trace[235149694] 'process raft request' (duration: 304.253135ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:57.182491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:56.881907Z","time spent":"300.488915ms","remote":"127.0.0.1:33940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10217,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-04T01:56:57.182578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:56.877609Z","time spent":"304.891998ms","remote":"127.0.0.1:33898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3805,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-04T01:56:57.374236Z","caller":"traceutil/trace.go:171","msg":"trace[3277605] linearizableReadLoop","detail":"{readStateIndex:515; appliedIndex:514; }","duration":"176.128481ms","start":"2026-02-04T01:56:57.19807Z","end":"2026-02-04T01:56:57.374198Z","steps":["trace[3277605] 'read index received' (duration: 116.760272ms)","trace[3277605] 'applied index is now lower than readState.Index' (duration: 59.367299ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:56:57.374401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.303475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:56:57.374384Z","caller":"traceutil/trace.go:171","msg":"trace[773602104] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"181.091135ms","start":"2026-02-04T01:56:57.193262Z","end":"2026-02-04T01:56:57.374353Z","steps":["trace[773602104] 'process raft request' (duration: 121.558022ms)","trace[773602104] 'compare' (duration: 59.237097ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:56:57.374439Z","caller":"traceutil/trace.go:171","msg":"trace[340850578] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:496; }","duration":"176.383156ms","start":"2026-02-04T01:56:57.198044Z","end":"2026-02-04T01:56:57.374427Z","steps":["trace[340850578] 'agreement among raft nodes before linearized reading' (duration: 176.258724ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:57.606097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.518477ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:56:57.606311Z","caller":"traceutil/trace.go:171","msg":"trace[373060965] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"334.446761ms","start":"2026-02-04T01:56:57.271837Z","end":"2026-02-04T01:56:57.606284Z","steps":["trace[373060965] 'process raft request' (duration: 217.630548ms)","trace[373060965] 'compare' (duration: 116.305754ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:56:57.606404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:57.271818Z","time spent":"334.545672ms","remote":"127.0.0.1:33544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":317259,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:56:58.308936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.130006ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:56:58.309094Z","caller":"traceutil/trace.go:171","msg":"trace[1484016553] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"928.806098ms","start":"2026-02-04T01:56:57.380272Z","end":"2026-02-04T01:56:58.309079Z","steps":["trace[1484016553] 'process raft request' (duration: 603.479498ms)","trace[1484016553] 'compare' (duration: 324.692188ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:56:58.30914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:57.380246Z","time spent":"928.876678ms","remote":"127.0.0.1:33618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14860,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-04T01:56:58.318516Z","caller":"traceutil/trace.go:171","msg":"trace[1772750338] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"650.941835ms","start":"2026-02-04T01:56:57.667561Z","end":"2026-02-04T01:56:58.318503Z","steps":["trace[1772750338] 'process raft request' (duration: 650.911445ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:58.319287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:57.66754Z","time spent":"651.693018ms","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:56:58.31948Z","caller":"traceutil/trace.go:171","msg":"trace[272694421] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"936.083005ms","start":"2026-02-04T01:56:57.38339Z","end":"2026-02-04T01:56:58.319473Z","steps":["trace[272694421] 'process raft request' (duration: 925.632909ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:58.319511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:57.383376Z","time spent":"936.117945ms","remote":"127.0.0.1:33898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4298,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:56:58.319572Z","caller":"traceutil/trace.go:171","msg":"trace[208566858] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"935.115885ms","start":"2026-02-04T01:56:57.384452Z","end":"2026-02-04T01:56:58.319568Z","steps":["trace[208566858] 'process raft request' (duration: 933.958744ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:58.319592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:57.384431Z","time spent":"935.150187ms","remote":"127.0.0.1:33528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":712,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-04T01:56:58.31978Z","caller":"traceutil/trace.go:171","msg":"trace[634369847] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"935.157497ms","start":"2026-02-04T01:56:57.384599Z","end":"2026-02-04T01:56:58.319756Z","steps":["trace[634369847] 'process raft request' (duration: 933.842222ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:58.31987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:57.384593Z","time spent":"935.233008ms","remote":"127.0.0.1:33618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3554,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-04T01:56:58.320047Z","caller":"traceutil/trace.go:171","msg":"trace[2071667527] linearizableReadLoop","detail":"{readStateIndex:520; appliedIndex:515; }","duration":"886.233663ms","start":"2026-02-04T01:56:57.433804Z","end":"2026-02-04T01:56:58.320038Z","steps":["trace[2071667527] 'read index received' (duration: 55.486298ms)","trace[2071667527] 'applied index is now lower than readState.Index' (duration: 830.746275ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:56:58.320407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"886.598619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-04T01:56:58.320495Z","caller":"traceutil/trace.go:171","msg":"trace[148038697] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:502; }","duration":"886.714621ms","start":"2026-02-04T01:56:57.433772Z","end":"2026-02-04T01:56:58.320487Z","steps":["trace[148038697] 'agreement among raft nodes before linearized reading' (duration: 886.580669ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:58.320591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:57.433759Z","time spent":"886.820143ms","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-04T01:56:58.916869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.790437ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:56:58.91705Z","caller":"traceutil/trace.go:171","msg":"trace[1822623570] linearizableReadLoop","detail":"{readStateIndex:522; appliedIndex:521; }","duration":"596.901494ms","start":"2026-02-04T01:56:58.320127Z","end":"2026-02-04T01:56:58.917028Z","steps":["trace[1822623570] 'read index received' (duration: 231.958165ms)","trace[1822623570] 'applied index is now lower than readState.Index' (duration: 364.941099ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:56:58.917146Z","caller":"traceutil/trace.go:171","msg":"trace[948202209] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"598.977223ms","start":"2026-02-04T01:56:58.31816Z","end":"2026-02-04T01:56:58.917137Z","steps":["trace[948202209] 'process raft request' (duration: 233.85749ms)","trace[948202209] 'compare' (duration: 364.687745ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:56:58.917229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:58.318139Z","time spent":"599.024734ms","remote":"127.0.0.1:33528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":653,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-04T01:56:58.917256Z","caller":"traceutil/trace.go:171","msg":"trace[1699666096] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"587.903937ms","start":"2026-02-04T01:56:58.329336Z","end":"2026-02-04T01:56:58.91724Z","steps":["trace[1699666096] 'process raft request' (duration: 587.866815ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:58.917329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:58.329332Z","time spent":"587.960178ms","remote":"127.0.0.1:33528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":736,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-04T01:56:58.917538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"737.34458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-04T01:56:58.917566Z","caller":"traceutil/trace.go:171","msg":"trace[1511713134] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:509; }","duration":"737.421452ms","start":"2026-02-04T01:56:58.180137Z","end":"2026-02-04T01:56:58.917559Z","steps":["trace[1511713134] 'agreement among raft nodes before linearized reading' (duration: 737.268839ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:58.917586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:58.180114Z","time spent":"737.466882ms","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":453,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-04T01:56:58.917629Z","caller":"traceutil/trace.go:171","msg":"trace[1481407876] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"596.680141ms","start":"2026-02-04T01:56:58.320937Z","end":"2026-02-04T01:56:58.917617Z","steps":["trace[1481407876] 'process raft request' (duration: 596.025308ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:58.917695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:58.320926Z","time spent":"596.730302ms","remote":"127.0.0.1:33920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14294,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:56:58.917947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.235981659s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329932"} {"level":"info","ts":"2026-02-04T01:56:58.917973Z","caller":"traceutil/trace.go:171","msg":"trace[1257939906] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:509; }","duration":"1.236035409s","start":"2026-02-04T01:56:57.68193Z","end":"2026-02-04T01:56:58.917965Z","steps":["trace[1257939906] 'agreement among raft nodes before linearized reading' (duration: 1.235791535s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:58.917994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:57.681914Z","time spent":"1.236072871s","remote":"127.0.0.1:33544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":4,"response size":329953,"request content":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" "} {"level":"info","ts":"2026-02-04T01:56:58.918193Z","caller":"traceutil/trace.go:171","msg":"trace[1150041733] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"597.110939ms","start":"2026-02-04T01:56:58.321069Z","end":"2026-02-04T01:56:58.91818Z","steps":["trace[1150041733] 'process raft request' (duration: 595.965457ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:58.918346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:58.321065Z","time spent":"597.244611ms","remote":"127.0.0.1:33618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14906,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:56:58.918476Z","caller":"traceutil/trace.go:171","msg":"trace[960987625] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"589.67518ms","start":"2026-02-04T01:56:58.328791Z","end":"2026-02-04T01:56:58.918466Z","steps":["trace[960987625] 'process raft request' (duration: 588.311445ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:58.918518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.33557149s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-04T01:56:58.918525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:58.328765Z","time spent":"589.731781ms","remote":"127.0.0.1:33618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3600,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:56:58.918547Z","caller":"traceutil/trace.go:171","msg":"trace[74526923] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:509; }","duration":"1.335700353s","start":"2026-02-04T01:56:57.582839Z","end":"2026-02-04T01:56:58.918539Z","steps":["trace[74526923] 'agreement among raft nodes before linearized reading' (duration: 1.335589511s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:58.918569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:57.582818Z","time spent":"1.335745383s","remote":"127.0.0.1:33456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-04T01:56:58.918734Z","caller":"traceutil/trace.go:171","msg":"trace[368222162] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"589.802024ms","start":"2026-02-04T01:56:58.328921Z","end":"2026-02-04T01:56:58.918723Z","steps":["trace[368222162] 'process raft request' (duration: 588.213613ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:58.918786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:58.328917Z","time spent":"589.840824ms","remote":"127.0.0.1:33924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4272,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:56:58.918812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.378738ms","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-02-04T01:56:58.918834Z","caller":"traceutil/trace.go:171","msg":"trace[1612419127] range","detail":"{range_begin:/registry/deployments/kube-system/cilium-operator; range_end:; response_count:1; response_revision:509; }","duration":"587.416609ms","start":"2026-02-04T01:56:58.331411Z","end":"2026-02-04T01:56:58.918827Z","steps":["trace[1612419127] 'agreement among raft nodes before linearized reading' (duration: 587.351258ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:58.918852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:58.331404Z","time spent":"587.444439ms","remote":"127.0.0.1:33898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":4334,"request content":"key:\"/registry/deployments/kube-system/cilium-operator\" "} {"level":"warn","ts":"2026-02-04T01:56:58.919056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.930784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-04T01:56:58.919079Z","caller":"traceutil/trace.go:171","msg":"trace[1134835710] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:509; }","duration":"254.953634ms","start":"2026-02-04T01:56:58.664119Z","end":"2026-02-04T01:56:58.919072Z","steps":["trace[1134835710] 'agreement among raft nodes before linearized reading' (duration: 254.911483ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:56:58.919085Z","caller":"traceutil/trace.go:171","msg":"trace[1199562718] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"589.783862ms","start":"2026-02-04T01:56:58.329289Z","end":"2026-02-04T01:56:58.919073Z","steps":["trace[1199562718] 'process raft request' (duration: 587.883416ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:58.919144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:58.329284Z","time spent":"589.829603ms","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:57:00.763993Z","caller":"traceutil/trace.go:171","msg":"trace[500659209] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"120.862919ms","start":"2026-02-04T01:57:00.643107Z","end":"2026-02-04T01:57:00.76397Z","steps":["trace[500659209] 'process raft request' (duration: 120.707916ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:03.555413Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920878729044671107,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-04T01:57:03.587488Z","caller":"traceutil/trace.go:171","msg":"trace[1509079409] linearizableReadLoop","detail":"{readStateIndex:547; appliedIndex:546; }","duration":"532.148744ms","start":"2026-02-04T01:57:03.055322Z","end":"2026-02-04T01:57:03.587471Z","steps":["trace[1509079409] 'read index received' (duration: 532.033842ms)","trace[1509079409] 'applied index is now lower than readState.Index' (duration: 114.312µs)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:57:03.587563Z","caller":"traceutil/trace.go:171","msg":"trace[963336367] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"643.007917ms","start":"2026-02-04T01:57:02.944548Z","end":"2026-02-04T01:57:03.587556Z","steps":["trace[963336367] 'process raft request' (duration: 642.827254ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:03.587645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:02.944528Z","time spent":"643.052357ms","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:57:03.587686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.736534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-04T01:57:03.58771Z","caller":"traceutil/trace.go:171","msg":"trace[914470082] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:527; }","duration":"525.788106ms","start":"2026-02-04T01:57:03.061914Z","end":"2026-02-04T01:57:03.587703Z","steps":["trace[914470082] 'agreement among raft nodes before linearized reading' (duration: 525.708714ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:03.587727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:03.061898Z","time spent":"525.824276ms","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-04T01:57:03.587845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.543372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-04T01:57:03.58787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.428053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-04T01:57:03.587872Z","caller":"traceutil/trace.go:171","msg":"trace[563718089] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:527; }","duration":"532.567432ms","start":"2026-02-04T01:57:03.055294Z","end":"2026-02-04T01:57:03.587861Z","steps":["trace[563718089] 'agreement among raft nodes before linearized reading' (duration: 532.496061ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:57:03.587891Z","caller":"traceutil/trace.go:171","msg":"trace[1223236478] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:527; }","duration":"521.466985ms","start":"2026-02-04T01:57:03.066418Z","end":"2026-02-04T01:57:03.587885Z","steps":["trace[1223236478] 'agreement among raft nodes before linearized reading' (duration: 521.409314ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:03.587894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:03.055269Z","time spent":"532.620833ms","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-04T01:57:03.587911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:03.06641Z","time spent":"521.496935ms","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":452,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-04T01:57:04.249987Z","caller":"traceutil/trace.go:171","msg":"trace[295242709] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"657.515887ms","start":"2026-02-04T01:57:03.592451Z","end":"2026-02-04T01:57:04.249966Z","steps":["trace[295242709] 'process raft request' (duration: 657.415985ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:04.25011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:03.592428Z","time spent":"657.610738ms","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:57:04.263242Z","caller":"traceutil/trace.go:171","msg":"trace[527461300] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"669.111324ms","start":"2026-02-04T01:57:03.594111Z","end":"2026-02-04T01:57:04.263222Z","steps":["trace[527461300] 'process raft request' (duration: 668.994722ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:57:04.263378Z","caller":"traceutil/trace.go:171","msg":"trace[604488408] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"667.81238ms","start":"2026-02-04T01:57:03.595551Z","end":"2026-02-04T01:57:04.263363Z","steps":["trace[604488408] 'process raft request' (duration: 667.641657ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:04.263384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:03.594096Z","time spent":"669.199926ms","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:57:04.263458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:03.595538Z","time spent":"667.871831ms","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:57:05.334895Z","caller":"traceutil/trace.go:171","msg":"trace[282024959] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"423.013556ms","start":"2026-02-04T01:57:04.911866Z","end":"2026-02-04T01:57:05.334879Z","steps":["trace[282024959] 'process raft request' (duration: 422.912624ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:05.334996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:04.911848Z","time spent":"423.092097ms","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:57:07.723388Z","caller":"traceutil/trace.go:171","msg":"trace[984360272] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"122.192914ms","start":"2026-02-04T01:57:07.601169Z","end":"2026-02-04T01:57:07.723362Z","steps":["trace[984360272] 'process raft request' (duration: 122.064442ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:57:08.476961Z","caller":"traceutil/trace.go:171","msg":"trace[1808020406] linearizableReadLoop","detail":"{readStateIndex:559; appliedIndex:558; }","duration":"204.301978ms","start":"2026-02-04T01:57:08.272637Z","end":"2026-02-04T01:57:08.476939Z","steps":["trace[1808020406] 'read index received' (duration: 204.086124ms)","trace[1808020406] 'applied index is now lower than readState.Index' (duration: 214.774µs)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:57:08.477071Z","caller":"traceutil/trace.go:171","msg":"trace[1087659548] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"214.157383ms","start":"2026-02-04T01:57:08.262904Z","end":"2026-02-04T01:57:08.477061Z","steps":["trace[1087659548] 'process raft request' (duration: 213.883008ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:08.477356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.120855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-04T01:57:08.477407Z","caller":"traceutil/trace.go:171","msg":"trace[1704671038] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:538; }","duration":"204.208557ms","start":"2026-02-04T01:57:08.273185Z","end":"2026-02-04T01:57:08.477393Z","steps":["trace[1704671038] 'agreement among raft nodes before linearized reading' (duration: 204.009602ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:08.4776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.938201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-04T01:57:08.477643Z","caller":"traceutil/trace.go:171","msg":"trace[1242055845] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:538; }","duration":"205.009772ms","start":"2026-02-04T01:57:08.272621Z","end":"2026-02-04T01:57:08.477631Z","steps":["trace[1242055845] 'agreement among raft nodes before linearized reading' (duration: 204.856899ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:13.053011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.613647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-04T01:57:13.053066Z","caller":"traceutil/trace.go:171","msg":"trace[1841255809] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:549; }","duration":"545.719698ms","start":"2026-02-04T01:57:12.507334Z","end":"2026-02-04T01:57:13.053054Z","steps":["trace[1841255809] 'range keys from in-memory index tree' (duration: 545.461283ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:13.053116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:12.507308Z","time spent":"545.79936ms","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-04T01:57:13.053382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"761.567252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-nbptd\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-04T01:57:13.053411Z","caller":"traceutil/trace.go:171","msg":"trace[2919692] range","detail":"{range_begin:/registry/pods/kube-system/cilium-nbptd; range_end:; response_count:1; response_revision:549; }","duration":"761.622014ms","start":"2026-02-04T01:57:12.291782Z","end":"2026-02-04T01:57:13.053404Z","steps":["trace[2919692] 'range keys from in-memory index tree' (duration: 761.46692ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:13.053433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:12.291753Z","time spent":"761.675644ms","remote":"127.0.0.1:33618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16677,"request content":"key:\"/registry/pods/kube-system/cilium-nbptd\" "} {"level":"warn","ts":"2026-02-04T01:57:13.054376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.498592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:57:13.054424Z","caller":"traceutil/trace.go:171","msg":"trace[859768813] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:549; }","duration":"471.585303ms","start":"2026-02-04T01:57:12.582832Z","end":"2026-02-04T01:57:13.054417Z","steps":["trace[859768813] 'range keys from in-memory index tree' (duration: 471.304828ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:13.054446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:12.582808Z","time spent":"471.632824ms","remote":"127.0.0.1:33456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T01:57:13.054819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.766792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-04T01:57:13.054847Z","caller":"traceutil/trace.go:171","msg":"trace[1289669647] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:549; }","duration":"566.830144ms","start":"2026-02-04T01:57:12.48801Z","end":"2026-02-04T01:57:13.054841Z","steps":["trace[1289669647] 'range keys from in-memory index tree' (duration: 566.6798ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:13.054864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:12.48799Z","time spent":"566.869334ms","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-04T01:57:13.054966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.493111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-04T01:57:13.054985Z","caller":"traceutil/trace.go:171","msg":"trace[1199966842] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:549; }","duration":"548.551292ms","start":"2026-02-04T01:57:12.506427Z","end":"2026-02-04T01:57:13.054979Z","steps":["trace[1199966842] 'range keys from in-memory index tree' (duration: 548.375018ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:13.055003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:12.506387Z","time spent":"548.611482ms","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":453,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-04T01:57:13.159479Z","caller":"traceutil/trace.go:171","msg":"trace[575783153] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"100.632811ms","start":"2026-02-04T01:57:13.058825Z","end":"2026-02-04T01:57:13.159458Z","steps":["trace[575783153] 'process raft request' (duration: 100.498957ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:57:13.568026Z","caller":"traceutil/trace.go:171","msg":"trace[244266716] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"499.334481ms","start":"2026-02-04T01:57:13.068672Z","end":"2026-02-04T01:57:13.568006Z","steps":["trace[244266716] 'process raft request' (duration: 499.292721ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:13.56813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:13.068636Z","time spent":"499.440074ms","remote":"127.0.0.1:33618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:57:13.568324Z","caller":"traceutil/trace.go:171","msg":"trace[964077802] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"506.246902ms","start":"2026-02-04T01:57:13.062052Z","end":"2026-02-04T01:57:13.568299Z","steps":["trace[964077802] 'process raft request' (duration: 505.856464ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:13.568451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:13.062045Z","time spent":"506.338703ms","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:57:13.568538Z","caller":"traceutil/trace.go:171","msg":"trace[1383118130] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"506.571108ms","start":"2026-02-04T01:57:13.061958Z","end":"2026-02-04T01:57:13.56853Z","steps":["trace[1383118130] 'process raft request' (duration: 505.683531ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:13.568578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:13.061945Z","time spent":"506.611218ms","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:57:15.784435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.187812ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:57:15.784525Z","caller":"traceutil/trace.go:171","msg":"trace[1568617042] linearizableReadLoop","detail":"{readStateIndex:591; appliedIndex:590; }","duration":"205.078022ms","start":"2026-02-04T01:57:15.579432Z","end":"2026-02-04T01:57:15.78451Z","steps":["trace[1568617042] 'read index received' (duration: 96.685937ms)","trace[1568617042] 'applied index is now lower than readState.Index' (duration: 108.391175ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:57:15.784826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.386578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-04T01:57:15.784868Z","caller":"traceutil/trace.go:171","msg":"trace[1732817314] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:568; }","duration":"205.4592ms","start":"2026-02-04T01:57:15.579398Z","end":"2026-02-04T01:57:15.784857Z","steps":["trace[1732817314] 'agreement among raft nodes before linearized reading' (duration: 205.335198ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:15.785095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.609772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-04T01:57:15.785179Z","caller":"traceutil/trace.go:171","msg":"trace[818165306] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:568; }","duration":"205.708175ms","start":"2026-02-04T01:57:15.579465Z","end":"2026-02-04T01:57:15.785173Z","steps":["trace[818165306] 'agreement among raft nodes before linearized reading' (duration: 205.591303ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:15.78591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.740536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-04T01:57:15.78594Z","caller":"traceutil/trace.go:171","msg":"trace[1066288116] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:568; }","duration":"120.772637ms","start":"2026-02-04T01:57:15.665161Z","end":"2026-02-04T01:57:15.785934Z","steps":["trace[1066288116] 'agreement among raft nodes before linearized reading' (duration: 120.38472ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:15.786161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.831174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:57:15.786186Z","caller":"traceutil/trace.go:171","msg":"trace[1262082177] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:568; }","duration":"200.907265ms","start":"2026-02-04T01:57:15.585274Z","end":"2026-02-04T01:57:15.786181Z","steps":["trace[1262082177] 'agreement among raft nodes before linearized reading' (duration: 200.867524ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:57:15.788662Z","caller":"traceutil/trace.go:171","msg":"trace[345811658] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"291.451527ms","start":"2026-02-04T01:57:15.497182Z","end":"2026-02-04T01:57:15.788633Z","steps":["trace[345811658] 'process raft request' (duration: 178.988696ms)","trace[345811658] 'compare' (duration: 108.050119ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:57:21.198097Z","caller":"traceutil/trace.go:171","msg":"trace[2006859662] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"349.664165ms","start":"2026-02-04T01:57:20.848399Z","end":"2026-02-04T01:57:21.198063Z","steps":["trace[2006859662] 'process raft request' (duration: 349.568223ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:21.198197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:20.848383Z","time spent":"349.761337ms","remote":"127.0.0.1:33616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4159,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:57:21.207655Z","caller":"traceutil/trace.go:171","msg":"trace[1142340562] linearizableReadLoop","detail":"{readStateIndex:668; appliedIndex:666; }","duration":"343.589642ms","start":"2026-02-04T01:57:20.86405Z","end":"2026-02-04T01:57:21.207639Z","steps":["trace[1142340562] 'read index received' (duration: 334.38666ms)","trace[1142340562] 'applied index is now lower than readState.Index' (duration: 9.202312ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:57:21.207813Z","caller":"traceutil/trace.go:171","msg":"trace[1895555786] transaction","detail":"{read_only:false; number_of_response:1; response_revision:642; }","duration":"346.624808ms","start":"2026-02-04T01:57:20.861173Z","end":"2026-02-04T01:57:21.207798Z","steps":["trace[1895555786] 'process raft request' (duration: 346.434304ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:21.207903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:20.861167Z","time spent":"346.695919ms","remote":"127.0.0.1:33616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30,"response count":0,"response size":4205,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:57:21.208035Z","caller":"traceutil/trace.go:171","msg":"trace[1100832016] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"354.7762ms","start":"2026-02-04T01:57:20.853251Z","end":"2026-02-04T01:57:21.208027Z","steps":["trace[1100832016] 'process raft request' (duration: 354.261111ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:21.208085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:20.853228Z","time spent":"354.841851ms","remote":"127.0.0.1:33528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":614,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-04T01:57:21.208386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.332655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:57:21.208431Z","caller":"traceutil/trace.go:171","msg":"trace[508244286] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:0; response_revision:642; }","duration":"344.395536ms","start":"2026-02-04T01:57:20.864029Z","end":"2026-02-04T01:57:21.208425Z","steps":["trace[508244286] 'agreement among raft nodes before linearized reading' (duration: 344.318685ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:21.20846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:20.864023Z","time spent":"344.431117ms","remote":"127.0.0.1:54952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":26,"request content":"key:\"/registry/cilium.io/ciliumnodes/instance\" limit:500 "} {"level":"warn","ts":"2026-02-04T01:57:21.208753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.157082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:57:21.208794Z","caller":"traceutil/trace.go:171","msg":"trace[921942037] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:642; }","duration":"298.221013ms","start":"2026-02-04T01:57:20.910568Z","end":"2026-02-04T01:57:21.208789Z","steps":["trace[921942037] 'agreement among raft nodes before linearized reading' (duration: 298.167572ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:21.209777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.515688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:57:21.209836Z","caller":"traceutil/trace.go:171","msg":"trace[467867479] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:642; }","duration":"345.736871ms","start":"2026-02-04T01:57:20.864066Z","end":"2026-02-04T01:57:21.209803Z","steps":["trace[467867479] 'agreement among raft nodes before linearized reading' (duration: 344.492628ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:21.209868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:20.864063Z","time spent":"345.795292ms","remote":"127.0.0.1:54952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":0,"response size":26,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 "} {"level":"warn","ts":"2026-02-04T01:57:21.210034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.025668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:57:21.210065Z","caller":"traceutil/trace.go:171","msg":"trace[89012138] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:642; }","duration":"322.057569ms","start":"2026-02-04T01:57:20.888002Z","end":"2026-02-04T01:57:21.21006Z","steps":["trace[89012138] 'agreement among raft nodes before linearized reading' (duration: 322.013288ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:21.210088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:20.887992Z","time spent":"322.092609ms","remote":"127.0.0.1:54964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":91,"response count":0,"response size":26,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-02-04T01:57:21.210187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.194382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:57:21.21022Z","caller":"traceutil/trace.go:171","msg":"trace[1452799656] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:642; }","duration":"322.233852ms","start":"2026-02-04T01:57:20.887982Z","end":"2026-02-04T01:57:21.210216Z","steps":["trace[1452799656] 'agreement among raft nodes before linearized reading' (duration: 322.193222ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:21.210238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:20.887979Z","time spent":"322.254843ms","remote":"127.0.0.1:54964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":26,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-04T01:57:21.210315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.341924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:57:21.210332Z","caller":"traceutil/trace.go:171","msg":"trace[688058231] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:642; }","duration":"322.368825ms","start":"2026-02-04T01:57:20.88796Z","end":"2026-02-04T01:57:21.210329Z","steps":["trace[688058231] 'agreement among raft nodes before linearized reading' (duration: 322.343415ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:21.210346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:20.887955Z","time spent":"322.388216ms","remote":"127.0.0.1:54964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":26,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10 "} {"level":"warn","ts":"2026-02-04T01:57:21.210388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.756691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:57:21.210921Z","caller":"traceutil/trace.go:171","msg":"trace[78169451] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:642; }","duration":"300.301942ms","start":"2026-02-04T01:57:20.910611Z","end":"2026-02-04T01:57:21.210913Z","steps":["trace[78169451] 'agreement among raft nodes before linearized reading' (duration: 299.758452ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:21.210956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:20.910606Z","time spent":"300.343063ms","remote":"127.0.0.1:54978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":113,"response count":0,"response size":26,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-02-04T01:57:21.210423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.69182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:57:21.211054Z","caller":"traceutil/trace.go:171","msg":"trace[2012066774] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:642; }","duration":"346.326602ms","start":"2026-02-04T01:57:20.864719Z","end":"2026-02-04T01:57:21.211046Z","steps":["trace[2012066774] 'agreement among raft nodes before linearized reading' (duration: 345.689021ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:21.211082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:20.864717Z","time spent":"346.357843ms","remote":"127.0.0.1:54952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":26,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-02-04T01:57:21.210456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.742262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:57:21.211409Z","caller":"traceutil/trace.go:171","msg":"trace[25738389] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:642; }","duration":"346.699449ms","start":"2026-02-04T01:57:20.864703Z","end":"2026-02-04T01:57:21.211402Z","steps":["trace[25738389] 'agreement among raft nodes before linearized reading' (duration: 345.731541ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:21.211445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:20.864699Z","time spent":"346.738119ms","remote":"127.0.0.1:54952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":0,"response size":26,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:500 "} {"level":"warn","ts":"2026-02-04T01:57:21.210199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.542788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:57:21.212572Z","caller":"traceutil/trace.go:171","msg":"trace[1102980456] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:642; }","duration":"301.927602ms","start":"2026-02-04T01:57:20.910636Z","end":"2026-02-04T01:57:21.212564Z","steps":["trace[1102980456] 'agreement among raft nodes before linearized reading' (duration: 299.542488ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:21.212613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:20.910632Z","time spent":"301.972132ms","remote":"127.0.0.1:54978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":26,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"info","ts":"2026-02-04T01:57:24.308615Z","caller":"traceutil/trace.go:171","msg":"trace[2101930784] linearizableReadLoop","detail":"{readStateIndex:685; appliedIndex:684; }","duration":"338.512805ms","start":"2026-02-04T01:57:23.97009Z","end":"2026-02-04T01:57:24.308603Z","steps":["trace[2101930784] 'read index received' (duration: 338.397283ms)","trace[2101930784] 'applied index is now lower than readState.Index' (duration: 114.932µs)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:57:24.308814Z","caller":"traceutil/trace.go:171","msg":"trace[972080248] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"411.388568ms","start":"2026-02-04T01:57:23.89742Z","end":"2026-02-04T01:57:24.308809Z","steps":["trace[972080248] 'process raft request' (duration: 411.099912ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:24.308883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:23.89741Z","time spent":"411.423368ms","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:57:24.308956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.054268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-04T01:57:24.308999Z","caller":"traceutil/trace.go:171","msg":"trace[877557007] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:657; }","duration":"338.131739ms","start":"2026-02-04T01:57:23.970857Z","end":"2026-02-04T01:57:24.308989Z","steps":["trace[877557007] 'agreement among raft nodes before linearized reading' (duration: 337.967606ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:24.309025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:23.970831Z","time spent":"338.187901ms","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-04T01:57:24.309071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.979605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-04T01:57:24.309093Z","caller":"traceutil/trace.go:171","msg":"trace[557103180] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:657; }","duration":"339.026116ms","start":"2026-02-04T01:57:23.97006Z","end":"2026-02-04T01:57:24.309086Z","steps":["trace[557103180] 'agreement among raft nodes before linearized reading' (duration: 338.953265ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:24.309108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:23.970028Z","time spent":"339.076496ms","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":453,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-04T01:57:24.309168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.043146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-04T01:57:24.309179Z","caller":"traceutil/trace.go:171","msg":"trace[2076514154] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:657; }","duration":"224.077687ms","start":"2026-02-04T01:57:24.085097Z","end":"2026-02-04T01:57:24.309175Z","steps":["trace[2076514154] 'agreement among raft nodes before linearized reading' (duration: 224.042047ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:25.036921Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920878729044671378,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T01:57:25.509177Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.194095216s","expected-duration":"1s"} {"level":"info","ts":"2026-02-04T01:57:25.509412Z","caller":"traceutil/trace.go:171","msg":"trace[567509323] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"1.194365271s","start":"2026-02-04T01:57:24.315033Z","end":"2026-02-04T01:57:25.509398Z","steps":["trace[567509323] 'process raft request' (duration: 1.194285649s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:25.509484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:24.31502Z","time spent":"1.194414632s","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:57:25.513599Z","caller":"traceutil/trace.go:171","msg":"trace[807491983] linearizableReadLoop","detail":"{readStateIndex:688; appliedIndex:686; }","duration":"976.831126ms","start":"2026-02-04T01:57:24.53676Z","end":"2026-02-04T01:57:25.513591Z","steps":["trace[807491983] 'read index received' (duration: 973.017695ms)","trace[807491983] 'applied index is now lower than readState.Index' (duration: 3.813011ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:57:25.513708Z","caller":"traceutil/trace.go:171","msg":"trace[1778811915] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"1.194284849s","start":"2026-02-04T01:57:24.319417Z","end":"2026-02-04T01:57:25.513702Z","steps":["trace[1778811915] 'process raft request' (duration: 1.194097246s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:25.513742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:24.319411Z","time spent":"1.194309159s","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:57:25.513809Z","caller":"traceutil/trace.go:171","msg":"trace[1178674164] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"1.194191988s","start":"2026-02-04T01:57:24.319613Z","end":"2026-02-04T01:57:25.513805Z","steps":["trace[1178674164] 'process raft request' (duration: 1.193961384s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:25.513832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:24.319608Z","time spent":"1.194210928s","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:57:25.513934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"977.178132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.235\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-04T01:57:25.51395Z","caller":"traceutil/trace.go:171","msg":"trace[1109839009] range","detail":"{range_begin:/registry/masterleases/199.204.45.235; range_end:; response_count:1; response_revision:660; }","duration":"977.214532ms","start":"2026-02-04T01:57:24.536729Z","end":"2026-02-04T01:57:25.513944Z","steps":["trace[1109839009] 'agreement among raft nodes before linearized reading' (duration: 977.157541ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:25.513963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:24.536717Z","time spent":"977.243443ms","remote":"127.0.0.1:33470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.235\" "} {"level":"warn","ts":"2026-02-04T01:57:25.514036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"933.397583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:57:25.514048Z","caller":"traceutil/trace.go:171","msg":"trace[1647885546] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:660; }","duration":"933.409623ms","start":"2026-02-04T01:57:24.580635Z","end":"2026-02-04T01:57:25.514044Z","steps":["trace[1647885546] 'agreement among raft nodes before linearized reading' (duration: 933.391913ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:25.514348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:24.580594Z","time spent":"933.74802ms","remote":"127.0.0.1:33450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T01:57:25.51453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.88318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-04T01:57:25.514544Z","caller":"traceutil/trace.go:171","msg":"trace[71180510] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:660; }","duration":"242.91482ms","start":"2026-02-04T01:57:25.271625Z","end":"2026-02-04T01:57:25.51454Z","steps":["trace[71180510] 'agreement among raft nodes before linearized reading' (duration: 242.871819ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:25.514619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.00008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-nbptd\" ","response":"range_response_count:1 size:18250"} {"level":"info","ts":"2026-02-04T01:57:25.514632Z","caller":"traceutil/trace.go:171","msg":"trace[1254935703] range","detail":"{range_begin:/registry/pods/kube-system/cilium-nbptd; range_end:; response_count:1; response_revision:660; }","duration":"282.034341ms","start":"2026-02-04T01:57:25.232593Z","end":"2026-02-04T01:57:25.514627Z","steps":["trace[1254935703] 'agreement among raft nodes before linearized reading' (duration: 281.99705ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:25.51472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.019399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-04T01:57:25.514732Z","caller":"traceutil/trace.go:171","msg":"trace[1272251974] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:660; }","duration":"481.032899ms","start":"2026-02-04T01:57:25.033695Z","end":"2026-02-04T01:57:25.514728Z","steps":["trace[1272251974] 'agreement among raft nodes before linearized reading' (duration: 481.010529ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:25.514748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:25.033651Z","time spent":"481.090291ms","remote":"127.0.0.1:33920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":2,"response size":28,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-02-04T01:57:25.514842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"836.554983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-04T01:57:25.514854Z","caller":"traceutil/trace.go:171","msg":"trace[2032779094] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:660; }","duration":"836.567744ms","start":"2026-02-04T01:57:24.678282Z","end":"2026-02-04T01:57:25.51485Z","steps":["trace[2032779094] 'agreement among raft nodes before linearized reading' (duration: 836.519713ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:25.514866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:24.678248Z","time spent":"836.615576ms","remote":"127.0.0.1:33954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":84,"response size":28,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-02-04T01:57:26.529571Z","caller":"traceutil/trace.go:171","msg":"trace[1584798576] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"214.836695ms","start":"2026-02-04T01:57:26.314718Z","end":"2026-02-04T01:57:26.529555Z","steps":["trace[1584798576] 'process raft request' (duration: 208.939385ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:30.946201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.020424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-04T01:57:30.946279Z","caller":"traceutil/trace.go:171","msg":"trace[1383456755] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:740; }","duration":"375.131496ms","start":"2026-02-04T01:57:30.571129Z","end":"2026-02-04T01:57:30.946261Z","steps":["trace[1383456755] 'range keys from in-memory index tree' (duration: 374.876881ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:30.946341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:30.571113Z","time spent":"375.218237ms","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-04T01:57:30.946544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.225586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:57:30.946578Z","caller":"traceutil/trace.go:171","msg":"trace[1580291236] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:740; }","duration":"365.274727ms","start":"2026-02-04T01:57:30.581294Z","end":"2026-02-04T01:57:30.946569Z","steps":["trace[1580291236] 'range keys from in-memory index tree' (duration: 365.165905ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:30.94661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:30.581284Z","time spent":"365.320708ms","remote":"127.0.0.1:33450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-04T01:57:35.719906Z","caller":"traceutil/trace.go:171","msg":"trace[1044699287] linearizableReadLoop","detail":"{readStateIndex:788; appliedIndex:786; }","duration":"139.850059ms","start":"2026-02-04T01:57:35.580037Z","end":"2026-02-04T01:57:35.719887Z","steps":["trace[1044699287] 'read index received' (duration: 11.022009ms)","trace[1044699287] 'applied index is now lower than readState.Index' (duration: 128.82743ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:57:35.720012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.95369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:57:35.720034Z","caller":"traceutil/trace.go:171","msg":"trace[1441115379] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:756; }","duration":"140.005271ms","start":"2026-02-04T01:57:35.580022Z","end":"2026-02-04T01:57:35.720027Z","steps":["trace[1441115379] 'agreement among raft nodes before linearized reading' (duration: 139.93389ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:57:35.720166Z","caller":"traceutil/trace.go:171","msg":"trace[170254990] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"144.015994ms","start":"2026-02-04T01:57:35.576129Z","end":"2026-02-04T01:57:35.720145Z","steps":["trace[170254990] 'process raft request' (duration: 91.979977ms)","trace[170254990] 'compare' (duration: 51.61813ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:57:35.720233Z","caller":"traceutil/trace.go:171","msg":"trace[1462741277] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"144.049484ms","start":"2026-02-04T01:57:35.576176Z","end":"2026-02-04T01:57:35.720225Z","steps":["trace[1462741277] 'process raft request' (duration: 143.673728ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:57:36.129202Z","caller":"traceutil/trace.go:171","msg":"trace[558338349] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"218.908652ms","start":"2026-02-04T01:57:35.910275Z","end":"2026-02-04T01:57:36.129183Z","steps":["trace[558338349] 'process raft request' (duration: 218.81049ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:57:36.164882Z","caller":"traceutil/trace.go:171","msg":"trace[316165877] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"204.256757ms","start":"2026-02-04T01:57:35.960609Z","end":"2026-02-04T01:57:36.164865Z","steps":["trace[316165877] 'process raft request' (duration: 204.167196ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:57:39.749884Z","caller":"traceutil/trace.go:171","msg":"trace[540297023] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"119.860189ms","start":"2026-02-04T01:57:39.630002Z","end":"2026-02-04T01:57:39.749862Z","steps":["trace[540297023] 'process raft request' (duration: 84.319099ms)","trace[540297023] 'compare' (duration: 35.185344ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:57:50.042314Z","caller":"traceutil/trace.go:171","msg":"trace[2064732160] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"161.269134ms","start":"2026-02-04T01:57:49.881022Z","end":"2026-02-04T01:57:50.042291Z","steps":["trace[2064732160] 'process raft request' (duration: 161.069921ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:50.203731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.036457ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:26"} {"level":"info","ts":"2026-02-04T01:57:51.450632Z","caller":"traceutil/trace.go:171","msg":"trace[1078109297] linearizableReadLoop","detail":"{readStateIndex:839; appliedIndex:838; }","duration":"245.601563ms","start":"2026-02-04T01:57:51.205007Z","end":"2026-02-04T01:57:51.450608Z","steps":["trace[1078109297] 'read index received' (duration: 245.376689ms)","trace[1078109297] 'applied index is now lower than readState.Index' (duration: 223.994µs)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:57:51.450819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.753946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:57:51.450858Z","caller":"traceutil/trace.go:171","msg":"trace[1828280286] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:803; }","duration":"245.891418ms","start":"2026-02-04T01:57:51.204954Z","end":"2026-02-04T01:57:51.450845Z","steps":["trace[1828280286] 'agreement among raft nodes before linearized reading' (duration: 245.761686ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:57:51.451273Z","caller":"traceutil/trace.go:171","msg":"trace[1875716999] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"279.302219ms","start":"2026-02-04T01:57:51.17196Z","end":"2026-02-04T01:57:51.451262Z","steps":["trace[1875716999] 'process raft request' (duration: 278.486775ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:51.839254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.213135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:57:51.839341Z","caller":"traceutil/trace.go:171","msg":"trace[815824770] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:803; }","duration":"255.368878ms","start":"2026-02-04T01:57:51.583951Z","end":"2026-02-04T01:57:51.83932Z","steps":["trace[815824770] 'range keys from in-memory index tree' (duration: 255.032512ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:51.83973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.511747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-04T01:57:51.839769Z","caller":"traceutil/trace.go:171","msg":"trace[1236321012] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:803; }","duration":"187.618459ms","start":"2026-02-04T01:57:51.652141Z","end":"2026-02-04T01:57:51.83976Z","steps":["trace[1236321012] 'range keys from in-memory index tree' (duration: 187.215201ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:57:52.031758Z","caller":"traceutil/trace.go:171","msg":"trace[1509607857] linearizableReadLoop","detail":"{readStateIndex:843; appliedIndex:840; }","duration":"111.336136ms","start":"2026-02-04T01:57:51.920403Z","end":"2026-02-04T01:57:52.03174Z","steps":["trace[1509607857] 'read index received' (duration: 3.772999ms)","trace[1509607857] 'applied index is now lower than readState.Index' (duration: 107.562397ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:57:52.031886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.457528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-04T01:57:52.031915Z","caller":"traceutil/trace.go:171","msg":"trace[1127755238] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:807; }","duration":"111.57077ms","start":"2026-02-04T01:57:51.920333Z","end":"2026-02-04T01:57:52.031904Z","steps":["trace[1127755238] 'agreement among raft nodes before linearized reading' (duration: 111.479648ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:57:52.03213Z","caller":"traceutil/trace.go:171","msg":"trace[123014853] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"183.074867ms","start":"2026-02-04T01:57:51.849045Z","end":"2026-02-04T01:57:52.03212Z","steps":["trace[123014853] 'process raft request' (duration: 117.260862ms)","trace[123014853] 'compare' (duration: 65.238305ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:57:52.032226Z","caller":"traceutil/trace.go:171","msg":"trace[884144420] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"182.296083ms","start":"2026-02-04T01:57:51.849923Z","end":"2026-02-04T01:57:52.032219Z","steps":["trace[884144420] 'process raft request' (duration: 181.751874ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:57:52.032298Z","caller":"traceutil/trace.go:171","msg":"trace[445649944] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"182.338663ms","start":"2026-02-04T01:57:51.849954Z","end":"2026-02-04T01:57:52.032293Z","steps":["trace[445649944] 'process raft request' (duration: 181.755493ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:58:10.07716Z","caller":"traceutil/trace.go:171","msg":"trace[1680908127] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"463.044858ms","start":"2026-02-04T01:58:09.614096Z","end":"2026-02-04T01:58:10.07714Z","steps":["trace[1680908127] 'process raft request' (duration: 462.938556ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:58:10.077307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:58:09.614087Z","time spent":"463.146761ms","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:58:12.47745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.472965ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:26"} {"level":"info","ts":"2026-02-04T01:58:12.478259Z","caller":"traceutil/trace.go:171","msg":"trace[1157999803] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"1.774430602s","start":"2026-02-04T01:58:10.703811Z","end":"2026-02-04T01:58:12.478242Z","steps":["trace[1157999803] 'process raft request' (duration: 1.77430589s)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:58:12.478445Z","caller":"traceutil/trace.go:171","msg":"trace[2141858440] linearizableReadLoop","detail":"{readStateIndex:955; appliedIndex:954; }","duration":"2.442017804s","start":"2026-02-04T01:58:10.036415Z","end":"2026-02-04T01:58:12.478433Z","steps":["trace[2141858440] 'read index received' (duration: 41.248963ms)","trace[2141858440] 'applied index is now lower than readState.Index' (duration: 2.400767041s)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:58:12.478638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.442207237s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:58:12.47881Z","caller":"traceutil/trace.go:171","msg":"trace[1618235824] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:915; }","duration":"2.442277799s","start":"2026-02-04T01:58:10.036389Z","end":"2026-02-04T01:58:12.478667Z","steps":["trace[1618235824] 'agreement among raft nodes before linearized reading' (duration: 2.442165057s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:58:12.478902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:58:10.036377Z","time spent":"2.442469683s","remote":"127.0.0.1:33756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":26,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-04T01:58:12.479219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.433249556s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-04T01:58:12.479305Z","caller":"traceutil/trace.go:171","msg":"trace[424058580] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:915; }","duration":"2.433296217s","start":"2026-02-04T01:58:10.04595Z","end":"2026-02-04T01:58:12.479246Z","steps":["trace[424058580] 'agreement among raft nodes before linearized reading' (duration: 2.433180835s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:58:12.479347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:58:10.045945Z","time spent":"2.433392199s","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-04T01:58:12.479784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.639506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-04T01:58:12.479822Z","caller":"traceutil/trace.go:171","msg":"trace[1521713812] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:915; }","duration":"399.704528ms","start":"2026-02-04T01:58:12.080109Z","end":"2026-02-04T01:58:12.479813Z","steps":["trace[1521713812] 'agreement among raft nodes before linearized reading' (duration: 399.605266ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:58:12.479973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:58:12.080096Z","time spent":"399.823079ms","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-04T01:58:12.480246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.512284833s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-04T01:58:12.480284Z","caller":"traceutil/trace.go:171","msg":"trace[1360414957] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:915; }","duration":"1.512364285s","start":"2026-02-04T01:58:10.967911Z","end":"2026-02-04T01:58:12.480275Z","steps":["trace[1360414957] 'agreement among raft nodes before linearized reading' (duration: 1.512278593s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:58:12.480506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:58:10.703785Z","time spent":"1.774517513s","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:58:12.480682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:58:10.967897Z","time spent":"1.512736791s","remote":"127.0.0.1:33638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":42,"response size":28,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-02-04T01:58:12.481367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.898545287s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:58:12.482005Z","caller":"traceutil/trace.go:171","msg":"trace[1798416323] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:915; }","duration":"1.899162789s","start":"2026-02-04T01:58:10.582783Z","end":"2026-02-04T01:58:12.481946Z","steps":["trace[1798416323] 'agreement among raft nodes before linearized reading' (duration: 1.898554818s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:58:12.482039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:58:10.582763Z","time spent":"1.899268181s","remote":"127.0.0.1:33450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T01:58:12.482269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.313286466s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-02-04T01:58:12.482709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.315357122s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-04T01:58:12.482295Z","caller":"traceutil/trace.go:171","msg":"trace[351619049] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:915; }","duration":"2.313332236s","start":"2026-02-04T01:58:10.168955Z","end":"2026-02-04T01:58:12.482288Z","steps":["trace[351619049] 'agreement among raft nodes before linearized reading' (duration: 2.313265015s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:58:12.482861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:58:10.168945Z","time spent":"2.313877447s","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":512,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-04T01:58:12.483463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.368042061s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:58:12.483495Z","caller":"traceutil/trace.go:171","msg":"trace[2069626270] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:915; }","duration":"2.368103492s","start":"2026-02-04T01:58:10.115384Z","end":"2026-02-04T01:58:12.483488Z","steps":["trace[2069626270] 'agreement among raft nodes before linearized reading' (duration: 2.368052811s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:58:12.483515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:58:10.115341Z","time spent":"2.368168394s","remote":"127.0.0.1:33682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":26,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-02-04T01:58:12.483645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.320460115s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-04T01:58:12.483663Z","caller":"traceutil/trace.go:171","msg":"trace[2029476339] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:915; }","duration":"2.320501916s","start":"2026-02-04T01:58:10.163156Z","end":"2026-02-04T01:58:12.483658Z","steps":["trace[2029476339] 'agreement among raft nodes before linearized reading' (duration: 2.320444985s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:58:12.483681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:58:10.163143Z","time spent":"2.320532816s","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-04T01:58:12.482738Z","caller":"traceutil/trace.go:171","msg":"trace[617234403] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:915; }","duration":"2.315418534s","start":"2026-02-04T01:58:10.167312Z","end":"2026-02-04T01:58:12.48273Z","steps":["trace[617234403] 'agreement among raft nodes before linearized reading' (duration: 2.315352453s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:58:12.48459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:58:10.167301Z","time spent":"2.317278948s","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":452,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-04T01:58:12.994Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920878729044671946,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T01:58:13.766367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.239758ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:58:13.766603Z","caller":"traceutil/trace.go:171","msg":"trace[1291571347] linearizableReadLoop","detail":"{readStateIndex:961; appliedIndex:956; }","duration":"1.272726819s","start":"2026-02-04T01:58:12.493862Z","end":"2026-02-04T01:58:13.766589Z","steps":["trace[1291571347] 'read index received' (duration: 898.251666ms)","trace[1291571347] 'applied index is now lower than readState.Index' (duration: 374.474293ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:58:13.767407Z","caller":"traceutil/trace.go:171","msg":"trace[1848620656] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"1.281107789s","start":"2026-02-04T01:58:12.486283Z","end":"2026-02-04T01:58:13.767391Z","steps":["trace[1848620656] 'process raft request' (duration: 905.760541ms)","trace[1848620656] 'compare' (duration: 373.928983ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:58:13.767501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:58:12.486226Z","time spent":"1.281236402s","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:58:13.767665Z","caller":"traceutil/trace.go:171","msg":"trace[1885964148] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"1.274612012s","start":"2026-02-04T01:58:12.493044Z","end":"2026-02-04T01:58:13.767656Z","steps":["trace[1885964148] 'process raft request' (duration: 1.27340762s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:58:13.76778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:58:12.493035Z","time spent":"1.274658593s","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:58:13.767975Z","caller":"traceutil/trace.go:171","msg":"trace[2115710410] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"1.274875296s","start":"2026-02-04T01:58:12.493089Z","end":"2026-02-04T01:58:13.767965Z","steps":["trace[2115710410] 'process raft request' (duration: 1.27341498s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:58:13.769173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:58:12.493085Z","time spent":"1.276048398s","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:58:13.769463Z","caller":"traceutil/trace.go:171","msg":"trace[32186954] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"1.27390341s","start":"2026-02-04T01:58:12.495547Z","end":"2026-02-04T01:58:13.769451Z","steps":["trace[32186954] 'process raft request' (duration: 1.270987008s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:58:13.769606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:58:12.495541Z","time spent":"1.273955851s","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:58:13.769774Z","caller":"traceutil/trace.go:171","msg":"trace[1818273113] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"1.273580655s","start":"2026-02-04T01:58:12.496183Z","end":"2026-02-04T01:58:13.769764Z","steps":["trace[1818273113] 'process raft request' (duration: 1.270377066s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:58:13.769904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:58:12.496178Z","time spent":"1.273627065s","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:58:13.770132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.276258343s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:58:13.770168Z","caller":"traceutil/trace.go:171","msg":"trace[653770051] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:920; }","duration":"1.276309453s","start":"2026-02-04T01:58:12.493849Z","end":"2026-02-04T01:58:13.770158Z","steps":["trace[653770051] 'agreement among raft nodes before linearized reading' (duration: 1.276247462s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:58:13.770436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.111018756s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-04T01:58:13.770474Z","caller":"traceutil/trace.go:171","msg":"trace[393274335] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:920; }","duration":"1.111092468s","start":"2026-02-04T01:58:12.659372Z","end":"2026-02-04T01:58:13.770465Z","steps":["trace[393274335] 'agreement among raft nodes before linearized reading' (duration: 1.111029147s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:58:13.77055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:58:12.493844Z","time spent":"1.276352724s","remote":"127.0.0.1:33450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T01:58:13.77386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:58:12.659356Z","time spent":"1.111144869s","remote":"127.0.0.1:33562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":4,"response size":28,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-02-04T01:58:13.774108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"938.00886ms","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-02-04T01:58:13.774148Z","caller":"traceutil/trace.go:171","msg":"trace[223911761] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:920; }","duration":"938.076352ms","start":"2026-02-04T01:58:12.836061Z","end":"2026-02-04T01:58:13.774137Z","steps":["trace[223911761] 'agreement among raft nodes before linearized reading' (duration: 938.019281ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:58:13.774183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:58:12.836049Z","time spent":"938.126423ms","remote":"127.0.0.1:33592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":26,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"info","ts":"2026-02-04T01:58:17.125792Z","caller":"traceutil/trace.go:171","msg":"trace[68569197] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"151.341425ms","start":"2026-02-04T01:58:16.974435Z","end":"2026-02-04T01:58:17.125776Z","steps":["trace[68569197] 'process raft request' (duration: 151.233873ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:58:18.422463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.133276737s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-04T01:58:18.422521Z","caller":"traceutil/trace.go:171","msg":"trace[350612799] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:927; }","duration":"1.133369509s","start":"2026-02-04T01:58:17.289139Z","end":"2026-02-04T01:58:18.422508Z","steps":["trace[350612799] 'count revisions from in-memory index tree' (duration: 1.133228627s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:58:18.422549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:58:17.289126Z","time spent":"1.13341614s","remote":"127.0.0.1:33730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":4,"response size":28,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-02-04T01:58:18.423079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.424044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-04T01:58:18.423102Z","caller":"traceutil/trace.go:171","msg":"trace[622812131] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:927; }","duration":"624.470204ms","start":"2026-02-04T01:58:17.798625Z","end":"2026-02-04T01:58:18.423095Z","steps":["trace[622812131] 'range keys from in-memory index tree' (duration: 624.303232ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:58:18.423136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:58:17.798611Z","time spent":"624.506696ms","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-04T01:58:18.423264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.451754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-04T01:58:18.423285Z","caller":"traceutil/trace.go:171","msg":"trace[1904198486] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:927; }","duration":"589.494585ms","start":"2026-02-04T01:58:17.833784Z","end":"2026-02-04T01:58:18.423279Z","steps":["trace[1904198486] 'range keys from in-memory index tree' (duration: 589.375753ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:58:18.423304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:58:17.833771Z","time spent":"589.528665ms","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-04T01:58:18.423421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.831901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-04T01:58:18.423441Z","caller":"traceutil/trace.go:171","msg":"trace[1309397884] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:927; }","duration":"579.857791ms","start":"2026-02-04T01:58:17.843577Z","end":"2026-02-04T01:58:18.423435Z","steps":["trace[1309397884] 'range keys from in-memory index tree' (duration: 579.78161ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:58:18.42346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:58:17.843575Z","time spent":"579.880752ms","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":453,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-04T01:58:18.423577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.034785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-04T01:58:18.423598Z","caller":"traceutil/trace.go:171","msg":"trace[73695358] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:927; }","duration":"580.077245ms","start":"2026-02-04T01:58:17.843513Z","end":"2026-02-04T01:58:18.423591Z","steps":["trace[73695358] 'range keys from in-memory index tree' (duration: 579.927412ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:58:18.423616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:58:17.8435Z","time spent":"580.110675ms","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-04T01:58:18.423732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.036715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-04T01:58:18.423755Z","caller":"traceutil/trace.go:171","msg":"trace[214445218] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:927; }","duration":"580.058856ms","start":"2026-02-04T01:58:17.84369Z","end":"2026-02-04T01:58:18.423749Z","steps":["trace[214445218] 'range keys from in-memory index tree' (duration: 579.986953ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:58:18.423773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:58:17.843678Z","time spent":"580.089606ms","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-04T01:58:19.085027Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920878729044671983,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T01:58:19.586069Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920878729044671983,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T01:58:20.086507Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920878729044671983,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T01:58:20.388105Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.956569071s","expected-duration":"1s"} {"level":"info","ts":"2026-02-04T01:58:20.388369Z","caller":"traceutil/trace.go:171","msg":"trace[278989118] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"1.956900039s","start":"2026-02-04T01:58:18.431456Z","end":"2026-02-04T01:58:20.388356Z","steps":["trace[278989118] 'process raft request' (duration: 1.956810077s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:58:20.388444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:58:18.431437Z","time spent":"1.95695865s","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:58:20.391389Z","caller":"traceutil/trace.go:171","msg":"trace[724359321] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"1.955626485s","start":"2026-02-04T01:58:18.435754Z","end":"2026-02-04T01:58:20.39138Z","steps":["trace[724359321] 'process raft request' (duration: 1.955472102s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:58:20.391456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:58:18.435747Z","time spent":"1.955666486s","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:58:20.391548Z","caller":"traceutil/trace.go:171","msg":"trace[1489992714] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"1.955850259s","start":"2026-02-04T01:58:18.435693Z","end":"2026-02-04T01:58:20.391544Z","steps":["trace[1489992714] 'process raft request' (duration: 1.955486643s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:58:20.391575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:58:18.435678Z","time spent":"1.95588459s","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:58:20.392319Z","caller":"traceutil/trace.go:171","msg":"trace[1421269292] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"1.955795537s","start":"2026-02-04T01:58:18.436516Z","end":"2026-02-04T01:58:20.392311Z","steps":["trace[1421269292] 'process raft request' (duration: 1.954725509s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:58:20.392356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:58:18.436508Z","time spent":"1.955830659s","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:58:20.3925Z","caller":"traceutil/trace.go:171","msg":"trace[510160900] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"1.955045245s","start":"2026-02-04T01:58:18.43745Z","end":"2026-02-04T01:58:20.392496Z","steps":["trace[510160900] 'process raft request' (duration: 1.953805443s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:58:20.39253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:58:18.437443Z","time spent":"1.955068806s","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:58:20.507524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.239503ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:26"} {"level":"info","ts":"2026-02-04T01:58:20.507585Z","caller":"traceutil/trace.go:171","msg":"trace[1153516971] linearizableReadLoop","detail":"{readStateIndex:975; appliedIndex:970; }","duration":"1.923172581s","start":"2026-02-04T01:58:18.584401Z","end":"2026-02-04T01:58:20.507574Z","steps":["trace[1153516971] 'read index received' (duration: 1.804302261s)","trace[1153516971] 'applied index is now lower than readState.Index' (duration: 118.86944ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:58:20.507649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.923240463s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:58:20.507665Z","caller":"traceutil/trace.go:171","msg":"trace[855924996] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:932; }","duration":"1.923287313s","start":"2026-02-04T01:58:18.584374Z","end":"2026-02-04T01:58:20.507661Z","steps":["trace[855924996] 'agreement among raft nodes before linearized reading' (duration: 1.923244252s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:58:20.507687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:58:18.58436Z","time spent":"1.923322654s","remote":"127.0.0.1:33450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T01:58:26.834677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.95419ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:58:26.834737Z","caller":"traceutil/trace.go:171","msg":"trace[2050863416] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"357.912586ms","start":"2026-02-04T01:58:26.476814Z","end":"2026-02-04T01:58:26.834727Z","steps":["trace[2050863416] 'process raft request' (duration: 237.861934ms)","trace[2050863416] 'compare' (duration: 119.858318ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:58:26.834767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:58:26.476802Z","time spent":"357.952286ms","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:58:27.030348Z","caller":"traceutil/trace.go:171","msg":"trace[1008537650] linearizableReadLoop","detail":"{readStateIndex:993; appliedIndex:989; }","duration":"449.203948ms","start":"2026-02-04T01:58:26.581123Z","end":"2026-02-04T01:58:27.030327Z","steps":["trace[1008537650] 'read index received' (duration: 133.572585ms)","trace[1008537650] 'applied index is now lower than readState.Index' (duration: 315.630513ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:58:27.030531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.390822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:58:27.030582Z","caller":"traceutil/trace.go:171","msg":"trace[340393456] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:949; }","duration":"449.452243ms","start":"2026-02-04T01:58:26.581119Z","end":"2026-02-04T01:58:27.030571Z","steps":["trace[340393456] 'agreement among raft nodes before linearized reading' (duration: 449.341061ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:58:27.030618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:58:26.581083Z","time spent":"449.527104ms","remote":"127.0.0.1:33450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-04T01:58:27.030901Z","caller":"traceutil/trace.go:171","msg":"trace[1790696290] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"554.016847ms","start":"2026-02-04T01:58:26.476868Z","end":"2026-02-04T01:58:27.030885Z","steps":["trace[1790696290] 'process raft request' (duration: 467.078822ms)","trace[1790696290] 'compare' (duration: 86.12048ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:58:27.031007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:58:26.476853Z","time spent":"554.110368ms","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:58:27.031099Z","caller":"traceutil/trace.go:171","msg":"trace[232818804] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"551.090604ms","start":"2026-02-04T01:58:26.480003Z","end":"2026-02-04T01:58:27.031093Z","steps":["trace[232818804] 'process raft request' (duration: 550.246369ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:58:27.031133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:58:26.479996Z","time spent":"551.120645ms","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:58:27.031212Z","caller":"traceutil/trace.go:171","msg":"trace[1919372191] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"549.885442ms","start":"2026-02-04T01:58:26.481321Z","end":"2026-02-04T01:58:27.031207Z","steps":["trace[1919372191] 'process raft request' (duration: 548.970515ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:58:27.031247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:58:26.481315Z","time spent":"549.913773ms","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:58:27.490077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.072305ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:58:27.490195Z","caller":"traceutil/trace.go:171","msg":"trace[1666808622] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:949; }","duration":"349.201777ms","start":"2026-02-04T01:58:27.140978Z","end":"2026-02-04T01:58:27.49018Z","steps":["trace[1666808622] 'range keys from in-memory index tree' (duration: 349.033965ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:58:27.504225Z","caller":"traceutil/trace.go:171","msg":"trace[678977260] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"139.36787ms","start":"2026-02-04T01:58:27.36484Z","end":"2026-02-04T01:58:27.504208Z","steps":["trace[678977260] 'process raft request' (duration: 139.259458ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:58:28.685222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.849372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:58:28.6853Z","caller":"traceutil/trace.go:171","msg":"trace[292556672] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:958; }","duration":"103.001164ms","start":"2026-02-04T01:58:28.582281Z","end":"2026-02-04T01:58:28.685282Z","steps":["trace[292556672] 'range keys from in-memory index tree' (duration: 102.77602ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:58:28.850354Z","caller":"traceutil/trace.go:171","msg":"trace[1613438407] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"157.651288ms","start":"2026-02-04T01:58:28.692685Z","end":"2026-02-04T01:58:28.850336Z","steps":["trace[1613438407] 'process raft request' (duration: 157.15467ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:58:29.306072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.190596ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:58:29.306364Z","caller":"traceutil/trace.go:171","msg":"trace[2063766332] linearizableReadLoop","detail":"{readStateIndex:1008; appliedIndex:1004; }","duration":"269.302838ms","start":"2026-02-04T01:58:29.037047Z","end":"2026-02-04T01:58:29.306349Z","steps":["trace[2063766332] 'read index received' (duration: 146.866934ms)","trace[2063766332] 'applied index is now lower than readState.Index' (duration: 122.435044ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:58:29.306556Z","caller":"traceutil/trace.go:171","msg":"trace[711838121] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"448.117949ms","start":"2026-02-04T01:58:28.858427Z","end":"2026-02-04T01:58:29.306545Z","steps":["trace[711838121] 'process raft request' (duration: 62.386433ms)","trace[711838121] 'compare' (duration: 384.689637ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:58:29.306617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:58:28.858405Z","time spent":"448.17941ms","remote":"127.0.0.1:33730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1507,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:58:29.306828Z","caller":"traceutil/trace.go:171","msg":"trace[232048729] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"442.161251ms","start":"2026-02-04T01:58:28.864658Z","end":"2026-02-04T01:58:29.306819Z","steps":["trace[232048729] 'process raft request' (duration: 441.532881ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:58:29.306881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:58:28.864646Z","time spent":"442.205212ms","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:58:29.307092Z","caller":"traceutil/trace.go:171","msg":"trace[629904574] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"442.382666ms","start":"2026-02-04T01:58:28.864699Z","end":"2026-02-04T01:58:29.307081Z","steps":["trace[629904574] 'process raft request' (duration: 441.552531ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:58:29.307141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:58:28.864695Z","time spent":"442.419157ms","remote":"127.0.0.1:33606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":914,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:58:29.307392Z","caller":"traceutil/trace.go:171","msg":"trace[649155355] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"439.074117ms","start":"2026-02-04T01:58:28.868309Z","end":"2026-02-04T01:58:29.307383Z","steps":["trace[649155355] 'process raft request' (duration: 438.003508ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:58:29.307446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:58:28.868299Z","time spent":"439.117048ms","remote":"127.0.0.1:33920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8757,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:58:29.308512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.458559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-04T01:58:29.308549Z","caller":"traceutil/trace.go:171","msg":"trace[923598614] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:963; }","duration":"271.527669ms","start":"2026-02-04T01:58:29.037011Z","end":"2026-02-04T01:58:29.308539Z","steps":["trace[923598614] 'agreement among raft nodes before linearized reading' (duration: 271.404407ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:58:29.311158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.506624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-04T01:58:29.311192Z","caller":"traceutil/trace.go:171","msg":"trace[157276395] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:963; }","duration":"273.582537ms","start":"2026-02-04T01:58:29.037602Z","end":"2026-02-04T01:58:29.311185Z","steps":["trace[157276395] 'agreement among raft nodes before linearized reading' (duration: 271.111392ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:58:29.311558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.765823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-04T01:58:29.311585Z","caller":"traceutil/trace.go:171","msg":"trace[1357187337] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:963; }","duration":"271.820115ms","start":"2026-02-04T01:58:29.039759Z","end":"2026-02-04T01:58:29.311579Z","steps":["trace[1357187337] 'agreement among raft nodes before linearized reading' (duration: 271.741693ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:58:29.918387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.14981ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:58:29.91857Z","caller":"traceutil/trace.go:171","msg":"trace[290337789] linearizableReadLoop","detail":"{readStateIndex:1011; appliedIndex:1009; }","duration":"578.171801ms","start":"2026-02-04T01:58:29.34038Z","end":"2026-02-04T01:58:29.918552Z","steps":["trace[290337789] 'read index received' (duration: 74.728526ms)","trace[290337789] 'applied index is now lower than readState.Index' (duration: 503.442355ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:58:29.918774Z","caller":"traceutil/trace.go:171","msg":"trace[1767798182] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"600.807989ms","start":"2026-02-04T01:58:29.317954Z","end":"2026-02-04T01:58:29.918762Z","steps":["trace[1767798182] 'process raft request' (duration: 97.212181ms)","trace[1767798182] 'compare' (duration: 502.698182ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:58:29.918845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:58:29.317948Z","time spent":"600.86187ms","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:58:29.919023Z","caller":"traceutil/trace.go:171","msg":"trace[1557417716] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"583.477237ms","start":"2026-02-04T01:58:29.335537Z","end":"2026-02-04T01:58:29.919014Z","steps":["trace[1557417716] 'process raft request' (duration: 582.933777ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:58:29.919072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:58:29.335523Z","time spent":"583.523027ms","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:58:29.919324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.937015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5114"} {"level":"info","ts":"2026-02-04T01:58:29.919365Z","caller":"traceutil/trace.go:171","msg":"trace[1833097607] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:966; }","duration":"578.994536ms","start":"2026-02-04T01:58:29.34036Z","end":"2026-02-04T01:58:29.919355Z","steps":["trace[1833097607] 'agreement among raft nodes before linearized reading' (duration: 578.869984ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:58:29.919392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:58:29.340353Z","time spent":"579.033256ms","remote":"127.0.0.1:33616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":5135,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-04T01:58:29.919553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.468442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:58:29.919591Z","caller":"traceutil/trace.go:171","msg":"trace[359826305] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:966; }","duration":"329.533514ms","start":"2026-02-04T01:58:29.590049Z","end":"2026-02-04T01:58:29.919583Z","steps":["trace[359826305] 'agreement among raft nodes before linearized reading' (duration: 329.477933ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:58:29.91962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:58:29.590035Z","time spent":"329.577724ms","remote":"127.0.0.1:33450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-04T01:58:30.069825Z","caller":"traceutil/trace.go:171","msg":"trace[896539117] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"138.382932ms","start":"2026-02-04T01:58:29.931418Z","end":"2026-02-04T01:58:30.069801Z","steps":["trace[896539117] 'process raft request' (duration: 138.242199ms)"],"step_count":1}