{"level":"warn","ts":"2026-04-09T02:03:38.330902Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-09T02:03:38.330996Z","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-04-09T02:03:38.33106Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-09T02:03:38.331067Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.235:2380"]} {"level":"info","ts":"2026-04-09T02:03:38.331087Z","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-04-09T02:03:38.331675Z","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-04-09T02:03:38.331775Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.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-04-09T02:03:38.33981Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.841383ms"} {"level":"info","ts":"2026-04-09T02:03:38.353388Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"aab6040abcf11","cluster-id":"2bae7d3d8857c059"} {"level":"info","ts":"2026-04-09T02:03:38.353505Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=()"} {"level":"info","ts":"2026-04-09T02:03:38.353532Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became follower at term 0"} {"level":"info","ts":"2026-04-09T02:03:38.35357Z","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-04-09T02:03:38.353591Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became follower at term 1"} {"level":"info","ts":"2026-04-09T02:03:38.353701Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=(3003179657318161)"} {"level":"warn","ts":"2026-04-09T02:03:38.362309Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-09T02:03:38.365583Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-09T02:03:38.368893Z","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-04-09T02:03:38.377436Z","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-04-09T02:03:38.377721Z","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-04-09T02:03:38.377702Z","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-04-09T02:03:38.377884Z","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-04-09T02:03:38.377932Z","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-04-09T02:03:38.378846Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=(3003179657318161)"} {"level":"info","ts":"2026-04-09T02:03:38.379528Z","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-04-09T02:03:38.383242Z","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-04-09T02:03:38.383381Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.235:2380"} {"level":"info","ts":"2026-04-09T02:03:38.383436Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.235:2380"} {"level":"info","ts":"2026-04-09T02:03:38.383528Z","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-04-09T02:03:38.383582Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-09T02:03:39.057391Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 is starting a new election at term 1"} {"level":"info","ts":"2026-04-09T02:03:39.057434Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-09T02:03:39.057456Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 received MsgPreVoteResp from aab6040abcf11 at term 1"} {"level":"info","ts":"2026-04-09T02:03:39.057471Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became candidate at term 2"} {"level":"info","ts":"2026-04-09T02:03:39.057476Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 received MsgVoteResp from aab6040abcf11 at term 2"} {"level":"info","ts":"2026-04-09T02:03:39.057485Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became leader at term 2"} {"level":"info","ts":"2026-04-09T02:03:39.057491Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: aab6040abcf11 elected leader aab6040abcf11 at term 2"} {"level":"info","ts":"2026-04-09T02:03:39.059657Z","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-04-09T02:03:39.059723Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-09T02:03:39.059861Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-09T02:03:39.059883Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-09T02:03:39.059749Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-09T02:03:39.060176Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T02:03:39.062256Z","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-04-09T02:03:39.062403Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T02:03:39.062442Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T02:03:39.062451Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.235:2379"} {"level":"info","ts":"2026-04-09T02:03:39.062983Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-09T02:03:40.749214Z","caller":"traceutil/trace.go:171","msg":"trace[360506755] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"366.779614ms","start":"2026-04-09T02:03:40.382405Z","end":"2026-04-09T02:03:40.749185Z","steps":["trace[360506755] 'process raft request' (duration: 366.533289ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:03:40.749842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:03:40.382395Z","time spent":"366.920777ms","remote":"127.0.0.1:42958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":100,"response count":0,"response size":35,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T02:03:42.038115Z","caller":"traceutil/trace.go:171","msg":"trace[727064556] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"1.65545072s","start":"2026-04-09T02:03:40.382634Z","end":"2026-04-09T02:03:42.038084Z","steps":["trace[727064556] 'process raft request' (duration: 1.655080202s)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:03:42.038204Z","caller":"traceutil/trace.go:171","msg":"trace[1918303636] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:4; }","duration":"1.645527387s","start":"2026-04-09T02:03:40.392652Z","end":"2026-04-09T02:03:42.038179Z","steps":["trace[1918303636] 'read index received' (duration: 356.415882ms)","trace[1918303636] 'applied index is now lower than readState.Index' (duration: 1.289110565s)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:03:42.038278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:03:40.38262Z","time spent":"1.655585673s","remote":"127.0.0.1:42960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105,"response count":0,"response size":35,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T02:03:42.038327Z","caller":"traceutil/trace.go:171","msg":"trace[929432368] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"1.581274644s","start":"2026-04-09T02:03:40.457042Z","end":"2026-04-09T02:03:42.038317Z","steps":["trace[929432368] 'process raft request' (duration: 1.580894495s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:03:42.038438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:03:40.457024Z","time spent":"1.581341506s","remote":"127.0.0.1:43344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":35,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T02:03:42.038701Z","caller":"traceutil/trace.go:171","msg":"trace[553760369] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"1.571972394s","start":"2026-04-09T02:03:40.466713Z","end":"2026-04-09T02:03:42.038685Z","steps":["trace[553760369] 'process raft request' (duration: 1.571415701s)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:03:42.038687Z","caller":"traceutil/trace.go:171","msg":"trace[1796627049] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"1.572180039s","start":"2026-04-09T02:03:40.466451Z","end":"2026-04-09T02:03:42.038631Z","steps":["trace[1796627049] 'process raft request' (duration: 1.571592605s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:03:42.038779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:03:40.466703Z","time spent":"1.572048775s","remote":"127.0.0.1:43424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":35,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T02:03:42.03883Z","caller":"traceutil/trace.go:171","msg":"trace[396301473] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"1.572751171s","start":"2026-04-09T02:03:40.46605Z","end":"2026-04-09T02:03:42.038801Z","steps":["trace[396301473] 'process raft request' (duration: 1.571922993s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:03:42.038864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:03:40.466435Z","time spent":"1.572349822s","remote":"127.0.0.1:43424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":35,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T02:03:42.038835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.646160001s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-09T02:03:42.038903Z","caller":"traceutil/trace.go:171","msg":"trace[732351922] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"1.569013828s","start":"2026-04-09T02:03:40.469865Z","end":"2026-04-09T02:03:42.038879Z","steps":["trace[732351922] 'process raft request' (duration: 1.568284772s)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:03:42.038955Z","caller":"traceutil/trace.go:171","msg":"trace[22339300] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"1.646300095s","start":"2026-04-09T02:03:40.392632Z","end":"2026-04-09T02:03:42.038932Z","steps":["trace[22339300] 'agreement among raft nodes before linearized reading' (duration: 1.64607949s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:03:42.039026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:03:40.469849Z","time spent":"1.56909882s","remote":"127.0.0.1:43154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":845,"response count":0,"response size":35,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T02:03:42.039032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:03:40.392622Z","time spent":"1.646400487s","remote":"127.0.0.1:43290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":25,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-04-09T02:03:42.039037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.602815357s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-09T02:03:42.039077Z","caller":"traceutil/trace.go:171","msg":"trace[216957134] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"1.602924609s","start":"2026-04-09T02:03:40.436142Z","end":"2026-04-09T02:03:42.039066Z","steps":["trace[216957134] 'agreement among raft nodes before linearized reading' (duration: 1.602812537s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:03:42.039106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:03:40.436128Z","time spent":"1.602971751s","remote":"127.0.0.1:42992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":25,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-09T02:03:42.039303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.389901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-09T02:03:42.03933Z","caller":"traceutil/trace.go:171","msg":"trace[1030851156] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11; }","duration":"344.436893ms","start":"2026-04-09T02:03:41.694885Z","end":"2026-04-09T02:03:42.039322Z","steps":["trace[1030851156] 'agreement among raft nodes before linearized reading' (duration: 344.387722ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:03:42.039342Z","caller":"traceutil/trace.go:171","msg":"trace[830624964] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"1.572850243s","start":"2026-04-09T02:03:40.466475Z","end":"2026-04-09T02:03:42.039325Z","steps":["trace[830624964] 'process raft request' (duration: 1.571602755s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:03:42.039387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.585194021s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.235\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-09T02:03:42.039393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.512982148s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-09T02:03:42.039407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:03:40.466452Z","time spent":"1.572924695s","remote":"127.0.0.1:43060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3159,"response count":0,"response size":35,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T02:03:42.039429Z","caller":"traceutil/trace.go:171","msg":"trace[1746116255] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"1.51306257s","start":"2026-04-09T02:03:40.526355Z","end":"2026-04-09T02:03:42.039417Z","steps":["trace[1746116255] 'agreement among raft nodes before linearized reading' (duration: 1.513003219s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:03:42.039459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:03:40.526334Z","time spent":"1.513118611s","remote":"127.0.0.1:42992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":25,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-09T02:03:42.039354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:03:41.694872Z","time spent":"344.477873ms","remote":"127.0.0.1:42914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":25,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-09T02:03:42.039612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"683.016722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-09T02:03:42.03964Z","caller":"traceutil/trace.go:171","msg":"trace[1881900290] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:11; }","duration":"683.090024ms","start":"2026-04-09T02:03:41.356542Z","end":"2026-04-09T02:03:42.039632Z","steps":["trace[1881900290] 'agreement among raft nodes before linearized reading' (duration: 683.049553ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:03:42.039664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:03:41.35653Z","time spent":"683.129224ms","remote":"127.0.0.1:43270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":25,"request content":"key:\"/registry/priorityclasses/system-node-critical\" "} {"level":"warn","ts":"2026-04-09T02:03:42.039718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.60340332s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-09T02:03:42.03976Z","caller":"traceutil/trace.go:171","msg":"trace[1274147873] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"1.603496442s","start":"2026-04-09T02:03:40.436251Z","end":"2026-04-09T02:03:42.039747Z","steps":["trace[1274147873] 'agreement among raft nodes before linearized reading' (duration: 1.603439271s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:03:42.03979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:03:40.436213Z","time spent":"1.603571264s","remote":"127.0.0.1:42992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":25,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-09T02:03:42.039799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.483445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-09T02:03:42.039827Z","caller":"traceutil/trace.go:171","msg":"trace[1905198329] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:11; }","duration":"684.540207ms","start":"2026-04-09T02:03:41.355278Z","end":"2026-04-09T02:03:42.039819Z","steps":["trace[1905198329] 'agreement among raft nodes before linearized reading' (duration: 684.491566ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:03:42.040317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.532851596s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-09T02:03:42.04032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.583003343s","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:4"} {"level":"info","ts":"2026-04-09T02:03:42.040358Z","caller":"traceutil/trace.go:171","msg":"trace[1428147458] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"1.532977889s","start":"2026-04-09T02:03:40.507369Z","end":"2026-04-09T02:03:42.040346Z","steps":["trace[1428147458] 'agreement among raft nodes before linearized reading' (duration: 1.532901177s)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:03:42.040367Z","caller":"traceutil/trace.go:171","msg":"trace[1833805805] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"1.583076514s","start":"2026-04-09T02:03:40.457278Z","end":"2026-04-09T02:03:42.040354Z","steps":["trace[1833805805] 'agreement among raft nodes before linearized reading' (duration: 1.583001082s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:03:42.04039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:03:40.507346Z","time spent":"1.533038069s","remote":"127.0.0.1:42992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":25,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-09T02:03:42.0404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:03:40.457271Z","time spent":"1.583122075s","remote":"127.0.0.1:42972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":25,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"info","ts":"2026-04-09T02:03:42.040493Z","caller":"traceutil/trace.go:171","msg":"trace[525896765] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"1.572315842s","start":"2026-04-09T02:03:40.466137Z","end":"2026-04-09T02:03:42.038453Z","steps":["trace[525896765] 'process raft request' (duration: 1.571861021s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:03:42.040619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:03:40.466128Z","time spent":"1.57444582s","remote":"127.0.0.1:43424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":35,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T02:03:42.039365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.583602236s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-09T02:03:42.040749Z","caller":"traceutil/trace.go:171","msg":"trace[1639877928] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"1.585038418s","start":"2026-04-09T02:03:40.455698Z","end":"2026-04-09T02:03:42.040736Z","steps":["trace[1639877928] 'agreement among raft nodes before linearized reading' (duration: 1.583624336s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:03:42.040749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:03:40.466025Z","time spent":"1.572872653s","remote":"127.0.0.1:43424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":35,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T02:03:42.04078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:03:40.455683Z","time spent":"1.585086829s","remote":"127.0.0.1:42992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":25,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-09T02:03:42.040917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:03:41.355257Z","time spent":"684.592779ms","remote":"127.0.0.1:43246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":25,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" "} {"level":"info","ts":"2026-04-09T02:03:42.039411Z","caller":"traceutil/trace.go:171","msg":"trace[2091582971] range","detail":"{range_begin:/registry/masterleases/199.204.45.235; range_end:; response_count:0; response_revision:11; }","duration":"1.585235093s","start":"2026-04-09T02:03:40.454169Z","end":"2026-04-09T02:03:42.039404Z","steps":["trace[2091582971] 'agreement among raft nodes before linearized reading' (duration: 1.585202122s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:03:42.041587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:03:40.454155Z","time spent":"1.587413382s","remote":"127.0.0.1:42942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":25,"request content":"key:\"/registry/masterleases/199.204.45.235\" "} {"level":"info","ts":"2026-04-09T02:03:42.04178Z","caller":"traceutil/trace.go:171","msg":"trace[1769635675] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"1.573216142s","start":"2026-04-09T02:03:40.466655Z","end":"2026-04-09T02:03:42.039872Z","steps":["trace[1769635675] 'process raft request' (duration: 1.571452042s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:03:42.04216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:03:40.466646Z","time spent":"1.575391621s","remote":"127.0.0.1:43424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":35,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T02:05:03.245916Z","caller":"traceutil/trace.go:171","msg":"trace[703247189] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"499.16541ms","start":"2026-04-09T02:05:02.746723Z","end":"2026-04-09T02:05:03.245888Z","steps":["trace[703247189] 'process raft request' (duration: 498.954365ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:03.24609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:05:02.746702Z","time spent":"499.293842ms","remote":"127.0.0.1:42948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T02:05:03.253975Z","caller":"traceutil/trace.go:171","msg":"trace[154163022] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"501.498702ms","start":"2026-04-09T02:05:02.75244Z","end":"2026-04-09T02:05:03.253939Z","steps":["trace[154163022] 'process raft request' (duration: 500.985061ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:03.254Z","caller":"traceutil/trace.go:171","msg":"trace[1360778760] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"492.920891ms","start":"2026-04-09T02:05:02.76101Z","end":"2026-04-09T02:05:03.253931Z","steps":["trace[1360778760] 'process raft request' (duration: 492.564643ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:03.254047Z","caller":"traceutil/trace.go:171","msg":"trace[2025919059] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"501.623684ms","start":"2026-04-09T02:05:02.752381Z","end":"2026-04-09T02:05:03.254005Z","steps":["trace[2025919059] 'process raft request' (duration: 500.910999ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:03.254074Z","caller":"traceutil/trace.go:171","msg":"trace[1630712429] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"493.076344ms","start":"2026-04-09T02:05:02.760962Z","end":"2026-04-09T02:05:03.254039Z","steps":["trace[1630712429] 'process raft request' (duration: 492.508442ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:03.254088Z","caller":"traceutil/trace.go:171","msg":"trace[1403748808] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"490.089547ms","start":"2026-04-09T02:05:02.763962Z","end":"2026-04-09T02:05:03.254051Z","steps":["trace[1403748808] 'process raft request' (duration: 489.696289ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:03.254126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:05:02.752435Z","time spent":"501.625195ms","remote":"127.0.0.1:42948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T02:05:03.254179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:05:02.76095Z","time spent":"493.183046ms","remote":"127.0.0.1:42948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T02:05:03.254179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:05:02.752363Z","time spent":"501.753158ms","remote":"127.0.0.1:42948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T02:05:03.254188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:05:02.763956Z","time spent":"490.180889ms","remote":"127.0.0.1:42948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T02:05:03.25423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:05:02.761007Z","time spent":"493.115665ms","remote":"127.0.0.1:42948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T02:05:03.254273Z","caller":"traceutil/trace.go:171","msg":"trace[657242498] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"485.674978ms","start":"2026-04-09T02:05:02.76859Z","end":"2026-04-09T02:05:03.254265Z","steps":["trace[657242498] 'process raft request' (duration: 485.110486ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:03.254271Z","caller":"traceutil/trace.go:171","msg":"trace[2001190045] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"493.017013ms","start":"2026-04-09T02:05:02.761241Z","end":"2026-04-09T02:05:03.254257Z","steps":["trace[2001190045] 'process raft request' (duration: 492.376409ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:03.254306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:05:02.768581Z","time spent":"485.708159ms","remote":"127.0.0.1:42948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T02:05:03.254322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:05:02.761236Z","time spent":"493.060014ms","remote":"127.0.0.1:42948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T02:05:03.254374Z","caller":"traceutil/trace.go:171","msg":"trace[886466434] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"472.721968ms","start":"2026-04-09T02:05:02.781641Z","end":"2026-04-09T02:05:03.254363Z","steps":["trace[886466434] 'process raft request' (duration: 472.127465ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:03.254429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:05:02.781627Z","time spent":"472.77538ms","remote":"127.0.0.1:42948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T02:05:03.25464Z","caller":"traceutil/trace.go:171","msg":"trace[205135684] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"450.518283ms","start":"2026-04-09T02:05:02.804106Z","end":"2026-04-09T02:05:03.254624Z","steps":["trace[205135684] 'process raft request' (duration: 450.320929ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:03.254681Z","caller":"traceutil/trace.go:171","msg":"trace[857576460] linearizableReadLoop","detail":"{readStateIndex:618; appliedIndex:613; }","duration":"493.544885ms","start":"2026-04-09T02:05:02.761124Z","end":"2026-04-09T02:05:03.254669Z","steps":["trace[857576460] 'read index received' (duration: 484.598394ms)","trace[857576460] 'applied index is now lower than readState.Index' (duration: 8.945471ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:05:03.254708Z","caller":"traceutil/trace.go:171","msg":"trace[896711646] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"438.120506ms","start":"2026-04-09T02:05:02.816573Z","end":"2026-04-09T02:05:03.254694Z","steps":["trace[896711646] 'process raft request' (duration: 437.928382ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:03.254725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:05:02.804089Z","time spent":"450.600175ms","remote":"127.0.0.1:42948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T02:05:03.254748Z","caller":"traceutil/trace.go:171","msg":"trace[1262819734] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"336.902406ms","start":"2026-04-09T02:05:02.917826Z","end":"2026-04-09T02:05:03.254728Z","steps":["trace[1262819734] 'process raft request' (duration: 336.774373ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:03.254802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:05:02.917806Z","time spent":"336.975247ms","remote":"127.0.0.1:42948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T02:05:03.254772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:05:02.816565Z","time spent":"438.177478ms","remote":"127.0.0.1:42948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T02:05:03.254891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.75592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-09T02:05:03.25492Z","caller":"traceutil/trace.go:171","msg":"trace[87580690] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:597; }","duration":"493.806291ms","start":"2026-04-09T02:05:02.761107Z","end":"2026-04-09T02:05:03.254913Z","steps":["trace[87580690] 'agreement among raft nodes before linearized reading' (duration: 493.662528ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:03.254941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:05:02.761102Z","time spent":"493.833222ms","remote":"127.0.0.1:43168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-09T02:05:03.260961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.23187ms","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-04-09T02:05:03.261267Z","caller":"traceutil/trace.go:171","msg":"trace[132316009] range","detail":"{range_begin:/registry/masterleases/199.204.45.235; range_end:; response_count:1; response_revision:599; }","duration":"229.538337ms","start":"2026-04-09T02:05:03.03172Z","end":"2026-04-09T02:05:03.261258Z","steps":["trace[132316009] 'agreement among raft nodes before linearized reading' (duration: 229.200919ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:06.435075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.210503ms","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-04-09T02:05:06.435179Z","caller":"traceutil/trace.go:171","msg":"trace[636848661] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:668; }","duration":"577.448899ms","start":"2026-04-09T02:05:05.857703Z","end":"2026-04-09T02:05:06.435152Z","steps":["trace[636848661] 'range keys from in-memory index tree' (duration: 576.927368ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:06.435238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:05:05.85768Z","time spent":"577.547291ms","remote":"127.0.0.1:43168","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-04-09T02:05:06.435265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"745.068683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:05:06.435349Z","caller":"traceutil/trace.go:171","msg":"trace[877566516] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:668; }","duration":"745.205666ms","start":"2026-04-09T02:05:05.690125Z","end":"2026-04-09T02:05:06.43533Z","steps":["trace[877566516] 'count revisions from in-memory index tree' (duration: 744.94289ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:06.435408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:05:05.690106Z","time spent":"745.286927ms","remote":"127.0.0.1:43010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":26,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"info","ts":"2026-04-09T02:05:10.867053Z","caller":"traceutil/trace.go:171","msg":"trace[435696803] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"403.601765ms","start":"2026-04-09T02:05:10.463401Z","end":"2026-04-09T02:05:10.867003Z","steps":["trace[435696803] 'process raft request' (duration: 403.278588ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:10.867289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:05:10.463391Z","time spent":"403.776389ms","remote":"127.0.0.1:43168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T02:05:11.505944Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920880144734353199,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-09T02:05:11.584002Z","caller":"traceutil/trace.go:171","msg":"trace[775224970] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"1.118679748s","start":"2026-04-09T02:05:10.46528Z","end":"2026-04-09T02:05:11.58396Z","steps":["trace[775224970] 'process raft request' (duration: 1.118356591s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:11.58419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:05:10.465271Z","time spent":"1.118830212s","remote":"127.0.0.1:43168","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-04-09T02:05:11.584599Z","caller":"traceutil/trace.go:171","msg":"trace[1803926441] linearizableReadLoop","detail":"{readStateIndex:734; appliedIndex:732; }","duration":"578.977163ms","start":"2026-04-09T02:05:11.005593Z","end":"2026-04-09T02:05:11.584571Z","steps":["trace[1803926441] 'read index received' (duration: 578.073293ms)","trace[1803926441] 'applied index is now lower than readState.Index' (duration: 902.93µs)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:05:11.584986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.378131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-hgfzn\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-09T02:05:11.585051Z","caller":"traceutil/trace.go:171","msg":"trace[1826094926] range","detail":"{range_begin:/registry/pods/kube-system/cilium-hgfzn; range_end:; response_count:1; response_revision:699; }","duration":"579.485814ms","start":"2026-04-09T02:05:11.005547Z","end":"2026-04-09T02:05:11.585033Z","steps":["trace[1826094926] 'agreement among raft nodes before linearized reading' (duration: 579.28997ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:11.584997Z","caller":"traceutil/trace.go:171","msg":"trace[1852341045] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"1.016017144s","start":"2026-04-09T02:05:10.568942Z","end":"2026-04-09T02:05:11.584959Z","steps":["trace[1852341045] 'process raft request' (duration: 1.015168606s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:11.585095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:05:11.005523Z","time spent":"579.564536ms","remote":"127.0.0.1:43072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16986,"request content":"key:\"/registry/pods/kube-system/cilium-hgfzn\" "} {"level":"warn","ts":"2026-04-09T02:05:11.585083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.739599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-09T02:05:11.585234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.81688ms","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-04-09T02:05:11.585235Z","caller":"traceutil/trace.go:171","msg":"trace[2005039609] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:699; }","duration":"245.910372ms","start":"2026-04-09T02:05:11.339291Z","end":"2026-04-09T02:05:11.585201Z","steps":["trace[2005039609] 'agreement among raft nodes before linearized reading' (duration: 245.694747ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:11.585287Z","caller":"traceutil/trace.go:171","msg":"trace[319449733] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:699; }","duration":"155.924802ms","start":"2026-04-09T02:05:11.429347Z","end":"2026-04-09T02:05:11.585272Z","steps":["trace[319449733] 'agreement among raft nodes before linearized reading' (duration: 155.8015ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:11.585407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:05:10.568905Z","time spent":"1.01627276s","remote":"127.0.0.1:42976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T02:05:11.58552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.580834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:05:11.585682Z","caller":"traceutil/trace.go:171","msg":"trace[1741375440] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:699; }","duration":"189.84825ms","start":"2026-04-09T02:05:11.3958Z","end":"2026-04-09T02:05:11.585648Z","steps":["trace[1741375440] 'agreement among raft nodes before linearized reading' (duration: 189.40728ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:11.986339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.25975ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T02:05:11.986448Z","caller":"traceutil/trace.go:171","msg":"trace[1459118239] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"394.951852ms","start":"2026-04-09T02:05:11.591478Z","end":"2026-04-09T02:05:11.98643Z","steps":["trace[1459118239] 'process raft request' (duration: 54.402265ms)","trace[1459118239] 'compare' (duration: 340.097956ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:05:11.986508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:05:11.591454Z","time spent":"395.027674ms","remote":"127.0.0.1:43168","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-04-09T02:05:11.991134Z","caller":"traceutil/trace.go:171","msg":"trace[1523766311] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"384.805515ms","start":"2026-04-09T02:05:11.606303Z","end":"2026-04-09T02:05:11.991109Z","steps":["trace[1523766311] 'process raft request' (duration: 384.746223ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:11.991157Z","caller":"traceutil/trace.go:171","msg":"trace[174178435] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"398.070692ms","start":"2026-04-09T02:05:11.593047Z","end":"2026-04-09T02:05:11.991118Z","steps":["trace[174178435] 'process raft request' (duration: 397.889198ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:11.991306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:05:11.593009Z","time spent":"398.221914ms","remote":"127.0.0.1:43168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T02:05:11.991327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:05:11.60628Z","time spent":"384.929828ms","remote":"127.0.0.1:43072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T02:05:12.004096Z","caller":"traceutil/trace.go:171","msg":"trace[1074405530] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"345.445987ms","start":"2026-04-09T02:05:11.658616Z","end":"2026-04-09T02:05:12.004062Z","steps":["trace[1074405530] 'process raft request' (duration: 345.16569ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:12.004174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:05:11.658588Z","time spent":"345.550068ms","remote":"127.0.0.1:43132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T02:05:16.913413Z","caller":"traceutil/trace.go:171","msg":"trace[986732532] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"166.679832ms","start":"2026-04-09T02:05:16.746705Z","end":"2026-04-09T02:05:16.913385Z","steps":["trace[986732532] 'process raft request' (duration: 166.478548ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:17.230732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.897317ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-09T02:05:17.230855Z","caller":"traceutil/trace.go:171","msg":"trace[1055485011] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"178.298143ms","start":"2026-04-09T02:05:17.052539Z","end":"2026-04-09T02:05:17.230837Z","steps":["trace[1055485011] 'process raft request' (duration: 76.222813ms)","trace[1055485011] 'compare' (duration: 101.744703ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:05:17.242697Z","caller":"traceutil/trace.go:171","msg":"trace[725868063] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"127.088019ms","start":"2026-04-09T02:05:17.115588Z","end":"2026-04-09T02:05:17.242676Z","steps":["trace[725868063] 'process raft request' (duration: 126.264781ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:17.314717Z","caller":"traceutil/trace.go:171","msg":"trace[13989709] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"148.294662ms","start":"2026-04-09T02:05:17.166398Z","end":"2026-04-09T02:05:17.314693Z","steps":["trace[13989709] 'process raft request' (duration: 148.125178ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:17.412493Z","caller":"traceutil/trace.go:171","msg":"trace[625114659] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"163.290127ms","start":"2026-04-09T02:05:17.249181Z","end":"2026-04-09T02:05:17.412471Z","steps":["trace[625114659] 'process raft request' (duration: 150.692606ms)","trace[625114659] 'compare' (duration: 12.431148ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:05:17.492209Z","caller":"traceutil/trace.go:171","msg":"trace[1907333663] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"104.636167ms","start":"2026-04-09T02:05:17.387551Z","end":"2026-04-09T02:05:17.492187Z","steps":["trace[1907333663] 'process raft request' (duration: 104.175536ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:17.556096Z","caller":"traceutil/trace.go:171","msg":"trace[273403785] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"120.081533ms","start":"2026-04-09T02:05:17.435987Z","end":"2026-04-09T02:05:17.556069Z","steps":["trace[273403785] 'process raft request' (duration: 119.717465ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:17.714578Z","caller":"traceutil/trace.go:171","msg":"trace[79584378] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"141.262035ms","start":"2026-04-09T02:05:17.573286Z","end":"2026-04-09T02:05:17.714548Z","steps":["trace[79584378] 'process raft request' (duration: 140.815525ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:17.714673Z","caller":"traceutil/trace.go:171","msg":"trace[686326355] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"127.266923ms","start":"2026-04-09T02:05:17.587385Z","end":"2026-04-09T02:05:17.714652Z","steps":["trace[686326355] 'process raft request' (duration: 127.047438ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:17.714659Z","caller":"traceutil/trace.go:171","msg":"trace[328318874] linearizableReadLoop","detail":"{readStateIndex:818; appliedIndex:815; }","duration":"105.409824ms","start":"2026-04-09T02:05:17.609223Z","end":"2026-04-09T02:05:17.714633Z","steps":["trace[328318874] 'read index received' (duration: 30.682086ms)","trace[328318874] 'applied index is now lower than readState.Index' (duration: 74.726338ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:05:17.714876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.66856ms","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-04-09T02:05:17.714936Z","caller":"traceutil/trace.go:171","msg":"trace[1635035688] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:782; }","duration":"105.786672ms","start":"2026-04-09T02:05:17.609134Z","end":"2026-04-09T02:05:17.714921Z","steps":["trace[1635035688] 'agreement among raft nodes before linearized reading' (duration: 105.579518ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:17.756237Z","caller":"traceutil/trace.go:171","msg":"trace[851896551] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"107.44177ms","start":"2026-04-09T02:05:17.648775Z","end":"2026-04-09T02:05:17.756217Z","steps":["trace[851896551] 'process raft request' (duration: 107.350768ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:18.193398Z","caller":"traceutil/trace.go:171","msg":"trace[1263435148] linearizableReadLoop","detail":"{readStateIndex:822; appliedIndex:821; }","duration":"356.716897ms","start":"2026-04-09T02:05:17.836636Z","end":"2026-04-09T02:05:18.193353Z","steps":["trace[1263435148] 'read index received' (duration: 356.447491ms)","trace[1263435148] 'applied index is now lower than readState.Index' (duration: 268.456µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:05:18.19352Z","caller":"traceutil/trace.go:171","msg":"trace[1530531543] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"399.515644ms","start":"2026-04-09T02:05:17.793994Z","end":"2026-04-09T02:05:18.193509Z","steps":["trace[1530531543] 'process raft request' (duration: 399.146186ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:18.193669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:05:17.793977Z","time spent":"399.622796ms","remote":"127.0.0.1:42948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188228,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T02:05:18.193795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.739688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:05:18.193921Z","caller":"traceutil/trace.go:171","msg":"trace[34910233] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:786; }","duration":"356.898292ms","start":"2026-04-09T02:05:17.836982Z","end":"2026-04-09T02:05:18.193881Z","steps":["trace[34910233] 'agreement among raft nodes before linearized reading' (duration: 356.714568ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:18.194029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:05:17.836962Z","time spent":"357.000714ms","remote":"127.0.0.1:53560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":26,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:500 "} {"level":"warn","ts":"2026-04-09T02:05:18.194079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.181036ms","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-04-09T02:05:18.194124Z","caller":"traceutil/trace.go:171","msg":"trace[2039010011] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:786; }","duration":"172.255547ms","start":"2026-04-09T02:05:18.021857Z","end":"2026-04-09T02:05:18.194113Z","steps":["trace[2039010011] 'agreement among raft nodes before linearized reading' (duration: 172.115424ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:18.194329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.653779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:05:18.194372Z","caller":"traceutil/trace.go:171","msg":"trace[1807012977] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:786; }","duration":"357.754552ms","start":"2026-04-09T02:05:17.836607Z","end":"2026-04-09T02:05:18.194361Z","steps":["trace[1807012977] 'agreement among raft nodes before linearized reading' (duration: 357.66091ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:18.194407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:05:17.83659Z","time spent":"357.808472ms","remote":"127.0.0.1:53560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":26,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-04-09T02:05:18.194418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.055149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:05:18.194495Z","caller":"traceutil/trace.go:171","msg":"trace[961405877] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:786; }","duration":"200.151592ms","start":"2026-04-09T02:05:17.994304Z","end":"2026-04-09T02:05:18.194456Z","steps":["trace[961405877] 'agreement among raft nodes before linearized reading' (duration: 200.046099ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:18.194528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.613651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:05:18.194582Z","caller":"traceutil/trace.go:171","msg":"trace[1290786624] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:786; }","duration":"101.692282ms","start":"2026-04-09T02:05:18.09288Z","end":"2026-04-09T02:05:18.194572Z","steps":["trace[1290786624] 'agreement among raft nodes before linearized reading' (duration: 101.6139ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:18.194609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.831395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-hgfzn\" ","response":"range_response_count:1 size:18337"} {"level":"info","ts":"2026-04-09T02:05:18.194637Z","caller":"traceutil/trace.go:171","msg":"trace[2049719624] range","detail":"{range_begin:/registry/pods/kube-system/cilium-hgfzn; range_end:; response_count:1; response_revision:786; }","duration":"131.859645ms","start":"2026-04-09T02:05:18.06277Z","end":"2026-04-09T02:05:18.194629Z","steps":["trace[2049719624] 'agreement among raft nodes before linearized reading' (duration: 131.770914ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:18.194777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.004341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:05:18.194817Z","caller":"traceutil/trace.go:171","msg":"trace[955106846] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:786; }","duration":"154.084892ms","start":"2026-04-09T02:05:18.040722Z","end":"2026-04-09T02:05:18.194806Z","steps":["trace[955106846] 'agreement among raft nodes before linearized reading' (duration: 154.027081ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:18.194814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.942798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:05:18.194876Z","caller":"traceutil/trace.go:171","msg":"trace[811131276] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:786; }","duration":"201.022111ms","start":"2026-04-09T02:05:17.993841Z","end":"2026-04-09T02:05:18.194863Z","steps":["trace[811131276] 'agreement among raft nodes before linearized reading' (duration: 200.954899ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:18.194961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.294367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:05:18.194999Z","caller":"traceutil/trace.go:171","msg":"trace[552516013] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:786; }","duration":"154.346607ms","start":"2026-04-09T02:05:18.040644Z","end":"2026-04-09T02:05:18.19499Z","steps":["trace[552516013] 'agreement among raft nodes before linearized reading' (duration: 154.295256ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:18.195077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.270012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:05:18.195117Z","caller":"traceutil/trace.go:171","msg":"trace[1704586912] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:786; }","duration":"101.321783ms","start":"2026-04-09T02:05:18.093778Z","end":"2026-04-09T02:05:18.1951Z","steps":["trace[1704586912] 'agreement among raft nodes before linearized reading' (duration: 101.279742ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:18.195183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.250394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:05:18.195224Z","caller":"traceutil/trace.go:171","msg":"trace[1468021191] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:786; }","duration":"102.317506ms","start":"2026-04-09T02:05:18.092896Z","end":"2026-04-09T02:05:18.195214Z","steps":["trace[1468021191] 'agreement among raft nodes before linearized reading' (duration: 102.251245ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:18.195336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.095875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:05:18.19543Z","caller":"traceutil/trace.go:171","msg":"trace[1480304101] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:786; }","duration":"335.210088ms","start":"2026-04-09T02:05:17.860197Z","end":"2026-04-09T02:05:18.195407Z","steps":["trace[1480304101] 'agreement among raft nodes before linearized reading' (duration: 335.041314ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:18.195437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.630924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T02:05:18.195465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.403466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:05:18.195476Z","caller":"traceutil/trace.go:171","msg":"trace[1669139294] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:786; }","duration":"308.691215ms","start":"2026-04-09T02:05:17.886772Z","end":"2026-04-09T02:05:18.195463Z","steps":["trace[1669139294] 'agreement among raft nodes before linearized reading' (duration: 308.640594ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:18.195483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:05:17.860168Z","time spent":"335.30211ms","remote":"127.0.0.1:53570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":26,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"info","ts":"2026-04-09T02:05:18.195511Z","caller":"traceutil/trace.go:171","msg":"trace[1873155608] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:786; }","duration":"358.484937ms","start":"2026-04-09T02:05:17.836999Z","end":"2026-04-09T02:05:18.195484Z","steps":["trace[1873155608] 'agreement among raft nodes before linearized reading' (duration: 358.415696ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:18.195569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:05:17.836982Z","time spent":"358.57875ms","remote":"127.0.0.1:53560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":26,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-09T02:05:18.195626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.247941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188495"} {"level":"info","ts":"2026-04-09T02:05:18.195662Z","caller":"traceutil/trace.go:171","msg":"trace[978328278] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:786; }","duration":"255.310463ms","start":"2026-04-09T02:05:17.940344Z","end":"2026-04-09T02:05:18.195654Z","steps":["trace[978328278] 'agreement among raft nodes before linearized reading' (duration: 255.18414ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:18.195508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:05:17.886754Z","time spent":"308.745997ms","remote":"127.0.0.1:53576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":26,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 "} {"level":"warn","ts":"2026-04-09T02:05:18.195736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.968492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:05:18.19577Z","caller":"traceutil/trace.go:171","msg":"trace[1596283396] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:786; }","duration":"309.031482ms","start":"2026-04-09T02:05:17.886729Z","end":"2026-04-09T02:05:18.195761Z","steps":["trace[1596283396] 'agreement among raft nodes before linearized reading' (duration: 308.976011ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:18.195807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:05:17.886716Z","time spent":"309.083294ms","remote":"127.0.0.1:53576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":26,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"warn","ts":"2026-04-09T02:05:18.195904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.671568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:05:18.195936Z","caller":"traceutil/trace.go:171","msg":"trace[1840095668] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:786; }","duration":"335.730099ms","start":"2026-04-09T02:05:17.860198Z","end":"2026-04-09T02:05:18.195928Z","steps":["trace[1840095668] 'agreement among raft nodes before linearized reading' (duration: 335.683958ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:18.195968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:05:17.860184Z","time spent":"335.775521ms","remote":"127.0.0.1:53570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":26,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:500 "} {"level":"warn","ts":"2026-04-09T02:05:18.195371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.219828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:05:18.196145Z","caller":"traceutil/trace.go:171","msg":"trace[1737336760] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:786; }","duration":"335.992306ms","start":"2026-04-09T02:05:17.860135Z","end":"2026-04-09T02:05:18.196127Z","steps":["trace[1737336760] 'agreement among raft nodes before linearized reading' (duration: 335.198588ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:18.1962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:05:17.86012Z","time spent":"336.064237ms","remote":"127.0.0.1:53570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":26,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:10000 "} {"level":"warn","ts":"2026-04-09T02:05:18.195899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.032391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:05:18.196377Z","caller":"traceutil/trace.go:171","msg":"trace[2034572921] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:786; }","duration":"308.587413ms","start":"2026-04-09T02:05:17.887779Z","end":"2026-04-09T02:05:18.196366Z","steps":["trace[2034572921] 'agreement among raft nodes before linearized reading' (duration: 308.02943ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:18.19642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:05:17.887773Z","time spent":"308.633724ms","remote":"127.0.0.1:53576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":26,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 "} {"level":"warn","ts":"2026-04-09T02:05:18.196659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.266332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-09T02:05:18.196689Z","caller":"traceutil/trace.go:171","msg":"trace[2129985289] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:786; }","duration":"161.318583ms","start":"2026-04-09T02:05:18.035363Z","end":"2026-04-09T02:05:18.196682Z","steps":["trace[2129985289] 'agreement among raft nodes before linearized reading' (duration: 161.241681ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:18.194772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.875127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:05:18.196852Z","caller":"traceutil/trace.go:171","msg":"trace[404126327] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:786; }","duration":"202.963713ms","start":"2026-04-09T02:05:17.993875Z","end":"2026-04-09T02:05:18.196839Z","steps":["trace[404126327] 'agreement among raft nodes before linearized reading' (duration: 200.840226ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:18.320074Z","caller":"traceutil/trace.go:171","msg":"trace[1833174365] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"116.89261ms","start":"2026-04-09T02:05:18.203156Z","end":"2026-04-09T02:05:18.320048Z","steps":["trace[1833174365] 'process raft request' (duration: 104.996645ms)","trace[1833174365] 'compare' (duration: 11.780073ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:05:18.357354Z","caller":"traceutil/trace.go:171","msg":"trace[725934883] linearizableReadLoop","detail":"{readStateIndex:825; appliedIndex:822; }","duration":"109.892315ms","start":"2026-04-09T02:05:18.247443Z","end":"2026-04-09T02:05:18.357335Z","steps":["trace[725934883] 'read index received' (duration: 60.785817ms)","trace[725934883] 'applied index is now lower than readState.Index' (duration: 49.105988ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:05:18.35754Z","caller":"traceutil/trace.go:171","msg":"trace[1555399049] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"152.047326ms","start":"2026-04-09T02:05:18.20543Z","end":"2026-04-09T02:05:18.357478Z","steps":["trace[1555399049] 'process raft request' (duration: 151.750849ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:18.357837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.383055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188243"} {"level":"info","ts":"2026-04-09T02:05:18.357897Z","caller":"traceutil/trace.go:171","msg":"trace[930151983] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:789; }","duration":"110.452957ms","start":"2026-04-09T02:05:18.247429Z","end":"2026-04-09T02:05:18.357882Z","steps":["trace[930151983] 'agreement among raft nodes before linearized reading' (duration: 110.191821ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:18.35789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.918731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:05:18.358037Z","caller":"traceutil/trace.go:171","msg":"trace[1027799846] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:789; }","duration":"104.097785ms","start":"2026-04-09T02:05:18.253857Z","end":"2026-04-09T02:05:18.357955Z","steps":["trace[1027799846] 'agreement among raft nodes before linearized reading' (duration: 103.915331ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:18.358133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.298668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:05:18.3582Z","caller":"traceutil/trace.go:171","msg":"trace[441000820] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:789; }","duration":"103.383449ms","start":"2026-04-09T02:05:18.254802Z","end":"2026-04-09T02:05:18.358185Z","steps":["trace[441000820] 'agreement among raft nodes before linearized reading' (duration: 103.276997ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:18.358055Z","caller":"traceutil/trace.go:171","msg":"trace[351930180] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"148.831364ms","start":"2026-04-09T02:05:18.209214Z","end":"2026-04-09T02:05:18.358046Z","steps":["trace[351930180] 'process raft request' (duration: 148.086898ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:18.358285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.33207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:05:18.35831Z","caller":"traceutil/trace.go:171","msg":"trace[1064213884] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:789; }","duration":"104.390362ms","start":"2026-04-09T02:05:18.25391Z","end":"2026-04-09T02:05:18.358301Z","steps":["trace[1064213884] 'agreement among raft nodes before linearized reading' (duration: 104.32029ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:18.674576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.959585ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:26"} {"level":"info","ts":"2026-04-09T02:05:18.674753Z","caller":"traceutil/trace.go:171","msg":"trace[686035254] linearizableReadLoop","detail":"{readStateIndex:826; appliedIndex:825; }","duration":"185.037293ms","start":"2026-04-09T02:05:18.489629Z","end":"2026-04-09T02:05:18.674666Z","steps":["trace[686035254] 'read index received' (duration: 23.872323ms)","trace[686035254] 'applied index is now lower than readState.Index' (duration: 161.16317ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:05:18.674918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.259358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:05:18.675032Z","caller":"traceutil/trace.go:171","msg":"trace[1347314673] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:789; }","duration":"185.38887ms","start":"2026-04-09T02:05:18.489625Z","end":"2026-04-09T02:05:18.675014Z","steps":["trace[1347314673] 'agreement among raft nodes before linearized reading' (duration: 185.223977ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:18.675144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.241757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:05:18.675244Z","caller":"traceutil/trace.go:171","msg":"trace[175180944] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:789; }","duration":"185.432621ms","start":"2026-04-09T02:05:18.489757Z","end":"2026-04-09T02:05:18.67519Z","steps":["trace[175180944] 'agreement among raft nodes before linearized reading' (duration: 185.232087ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:19.197924Z","caller":"traceutil/trace.go:171","msg":"trace[1923280232] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"123.668033ms","start":"2026-04-09T02:05:19.074228Z","end":"2026-04-09T02:05:19.197896Z","steps":["trace[1923280232] 'process raft request' (duration: 123.507159ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:19.213282Z","caller":"traceutil/trace.go:171","msg":"trace[1777009769] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"128.09645ms","start":"2026-04-09T02:05:19.085165Z","end":"2026-04-09T02:05:19.213261Z","steps":["trace[1777009769] 'process raft request' (duration: 126.777041ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:19.213651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.388017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:05:19.213728Z","caller":"traceutil/trace.go:171","msg":"trace[254337198] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:793; }","duration":"128.496149ms","start":"2026-04-09T02:05:19.085215Z","end":"2026-04-09T02:05:19.213711Z","steps":["trace[254337198] 'agreement among raft nodes before linearized reading' (duration: 128.346736ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:19.213962Z","caller":"traceutil/trace.go:171","msg":"trace[1846570107] linearizableReadLoop","detail":"{readStateIndex:829; appliedIndex:828; }","duration":"128.169112ms","start":"2026-04-09T02:05:19.085219Z","end":"2026-04-09T02:05:19.213388Z","steps":["trace[1846570107] 'read index received' (duration: 112.771609ms)","trace[1846570107] 'applied index is now lower than readState.Index' (duration: 15.396483ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:05:19.214267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.913459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:05:19.214337Z","caller":"traceutil/trace.go:171","msg":"trace[998054567] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:793; }","duration":"129.034993ms","start":"2026-04-09T02:05:19.085283Z","end":"2026-04-09T02:05:19.214318Z","steps":["trace[998054567] 'agreement among raft nodes before linearized reading' (duration: 128.874329ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:19.214905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.250687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:05:19.214951Z","caller":"traceutil/trace.go:171","msg":"trace[2062382724] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:793; }","duration":"129.332648ms","start":"2026-04-09T02:05:19.085609Z","end":"2026-04-09T02:05:19.214941Z","steps":["trace[2062382724] 'agreement among raft nodes before linearized reading' (duration: 129.257377ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:19.21524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.186638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:05:19.215291Z","caller":"traceutil/trace.go:171","msg":"trace[806541276] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:793; }","duration":"100.296731ms","start":"2026-04-09T02:05:19.114982Z","end":"2026-04-09T02:05:19.215278Z","steps":["trace[806541276] 'agreement among raft nodes before linearized reading' (duration: 100.099426ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:19.214833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.171337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T02:05:19.215429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.244399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:05:19.215567Z","caller":"traceutil/trace.go:171","msg":"trace[1442975020] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:793; }","duration":"117.401713ms","start":"2026-04-09T02:05:19.098109Z","end":"2026-04-09T02:05:19.215511Z","steps":["trace[1442975020] 'agreement among raft nodes before linearized reading' (duration: 117.216599ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:19.215458Z","caller":"traceutil/trace.go:171","msg":"trace[1805523415] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:793; }","duration":"117.855292ms","start":"2026-04-09T02:05:19.097585Z","end":"2026-04-09T02:05:19.21544Z","steps":["trace[1805523415] 'agreement among raft nodes before linearized reading' (duration: 117.140827ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:19.216048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.307552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:05:19.216307Z","caller":"traceutil/trace.go:171","msg":"trace[2078177555] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:793; }","duration":"118.575638ms","start":"2026-04-09T02:05:19.097718Z","end":"2026-04-09T02:05:19.216294Z","steps":["trace[2078177555] 'agreement among raft nodes before linearized reading' (duration: 118.290832ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:19.215334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.120237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:05:19.217153Z","caller":"traceutil/trace.go:171","msg":"trace[554298702] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:793; }","duration":"101.936188ms","start":"2026-04-09T02:05:19.115194Z","end":"2026-04-09T02:05:19.21713Z","steps":["trace[554298702] 'agreement among raft nodes before linearized reading' (duration: 100.109066ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:23.740082Z","caller":"traceutil/trace.go:171","msg":"trace[2076375871] transaction","detail":"{read_only:false; response_revision:818; number_of_response:1; }","duration":"132.316346ms","start":"2026-04-09T02:05:23.607733Z","end":"2026-04-09T02:05:23.74005Z","steps":["trace[2076375871] 'process raft request' (duration: 132.152412ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:23.921907Z","caller":"traceutil/trace.go:171","msg":"trace[302986104] linearizableReadLoop","detail":"{readStateIndex:860; appliedIndex:858; }","duration":"127.415615ms","start":"2026-04-09T02:05:23.794466Z","end":"2026-04-09T02:05:23.921882Z","steps":["trace[302986104] 'read index received' (duration: 118.66432ms)","trace[302986104] 'applied index is now lower than readState.Index' (duration: 8.750295ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:05:23.922168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.682271ms","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-04-09T02:05:23.922333Z","caller":"traceutil/trace.go:171","msg":"trace[133520018] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:821; }","duration":"127.865375ms","start":"2026-04-09T02:05:23.794409Z","end":"2026-04-09T02:05:23.922274Z","steps":["trace[133520018] 'agreement among raft nodes before linearized reading' (duration: 127.582509ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:23.922367Z","caller":"traceutil/trace.go:171","msg":"trace[673925441] transaction","detail":"{read_only:false; response_revision:821; number_of_response:1; }","duration":"167.862229ms","start":"2026-04-09T02:05:23.754478Z","end":"2026-04-09T02:05:23.922341Z","steps":["trace[673925441] 'process raft request' (duration: 167.304367ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:23.922535Z","caller":"traceutil/trace.go:171","msg":"trace[1407703719] transaction","detail":"{read_only:false; response_revision:820; number_of_response:1; }","duration":"172.866061ms","start":"2026-04-09T02:05:23.749644Z","end":"2026-04-09T02:05:23.92251Z","steps":["trace[1407703719] 'process raft request' (duration: 163.444891ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:24.117123Z","caller":"traceutil/trace.go:171","msg":"trace[1762905115] transaction","detail":"{read_only:false; response_revision:823; number_of_response:1; }","duration":"189.106414ms","start":"2026-04-09T02:05:23.927993Z","end":"2026-04-09T02:05:24.117099Z","steps":["trace[1762905115] 'process raft request' (duration: 188.90134ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:24.117553Z","caller":"traceutil/trace.go:171","msg":"trace[1323673670] transaction","detail":"{read_only:false; response_revision:824; number_of_response:1; }","duration":"187.903927ms","start":"2026-04-09T02:05:23.929636Z","end":"2026-04-09T02:05:24.11754Z","steps":["trace[1323673670] 'process raft request' (duration: 187.415996ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:24.117627Z","caller":"traceutil/trace.go:171","msg":"trace[612390293] transaction","detail":"{read_only:false; response_revision:825; number_of_response:1; }","duration":"187.644731ms","start":"2026-04-09T02:05:23.929954Z","end":"2026-04-09T02:05:24.117599Z","steps":["trace[612390293] 'process raft request' (duration: 187.501298ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:24.253679Z","caller":"traceutil/trace.go:171","msg":"trace[1865983727] transaction","detail":"{read_only:false; response_revision:826; number_of_response:1; }","duration":"128.95607ms","start":"2026-04-09T02:05:24.124689Z","end":"2026-04-09T02:05:24.253645Z","steps":["trace[1865983727] 'process raft request' (duration: 117.801981ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:24.266271Z","caller":"traceutil/trace.go:171","msg":"trace[374166734] transaction","detail":"{read_only:false; response_revision:827; number_of_response:1; }","duration":"135.040186ms","start":"2026-04-09T02:05:24.130956Z","end":"2026-04-09T02:05:24.265997Z","steps":["trace[374166734] 'process raft request' (duration: 134.909173ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:27.225668Z","caller":"traceutil/trace.go:171","msg":"trace[1802403409] linearizableReadLoop","detail":"{readStateIndex:893; appliedIndex:892; }","duration":"189.042432ms","start":"2026-04-09T02:05:27.036574Z","end":"2026-04-09T02:05:27.225617Z","steps":["trace[1802403409] 'read index received' (duration: 188.815337ms)","trace[1802403409] 'applied index is now lower than readState.Index' (duration: 225.635µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:05:27.225784Z","caller":"traceutil/trace.go:171","msg":"trace[436204989] transaction","detail":"{read_only:false; response_revision:854; number_of_response:1; }","duration":"215.529263ms","start":"2026-04-09T02:05:27.01022Z","end":"2026-04-09T02:05:27.225749Z","steps":["trace[436204989] 'process raft request' (duration: 215.230877ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:27.225837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.213704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-09T02:05:27.22584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.248997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:05:27.225871Z","caller":"traceutil/trace.go:171","msg":"trace[354164824] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:854; }","duration":"132.309616ms","start":"2026-04-09T02:05:27.093552Z","end":"2026-04-09T02:05:27.225861Z","steps":["trace[354164824] 'agreement among raft nodes before linearized reading' (duration: 132.230595ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:27.225898Z","caller":"traceutil/trace.go:171","msg":"trace[1254756175] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:854; }","duration":"189.34432ms","start":"2026-04-09T02:05:27.03654Z","end":"2026-04-09T02:05:27.225884Z","steps":["trace[1254756175] 'agreement among raft nodes before linearized reading' (duration: 189.227847ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:28.10792Z","caller":"traceutil/trace.go:171","msg":"trace[1024208068] transaction","detail":"{read_only:false; response_revision:857; number_of_response:1; }","duration":"150.310367ms","start":"2026-04-09T02:05:27.95758Z","end":"2026-04-09T02:05:28.107891Z","steps":["trace[1024208068] 'process raft request' (duration: 150.152953ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:30.347481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.891908ms","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-04-09T02:05:30.347588Z","caller":"traceutil/trace.go:171","msg":"trace[793397962] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:869; }","duration":"235.033451ms","start":"2026-04-09T02:05:30.112521Z","end":"2026-04-09T02:05:30.347555Z","steps":["trace[793397962] 'range keys from in-memory index tree' (duration: 234.666592ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:30.3478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.077081ms","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-04-09T02:05:30.347874Z","caller":"traceutil/trace.go:171","msg":"trace[1160510573] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:869; }","duration":"543.243385ms","start":"2026-04-09T02:05:29.804613Z","end":"2026-04-09T02:05:30.347857Z","steps":["trace[1160510573] 'range keys from in-memory index tree' (duration: 542.859206ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:30.347914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:05:29.804593Z","time spent":"543.311526ms","remote":"127.0.0.1:43168","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-04-09T02:05:30.348074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.43599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-09T02:05:30.348117Z","caller":"traceutil/trace.go:171","msg":"trace[724134071] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:869; }","duration":"505.512802ms","start":"2026-04-09T02:05:29.842592Z","end":"2026-04-09T02:05:30.348105Z","steps":["trace[724134071] 'count revisions from in-memory index tree' (duration: 505.336738ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:30.348153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:05:29.842573Z","time spent":"505.569263ms","remote":"127.0.0.1:43320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":28,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-04-09T02:05:30.348527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.761794ms","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-04-09T02:05:30.348617Z","caller":"traceutil/trace.go:171","msg":"trace[156589298] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:869; }","duration":"392.854756ms","start":"2026-04-09T02:05:29.955748Z","end":"2026-04-09T02:05:30.348603Z","steps":["trace[156589298] 'range keys from in-memory index tree' (duration: 392.750183ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:30.458683Z","caller":"traceutil/trace.go:171","msg":"trace[75059987] transaction","detail":"{read_only:false; response_revision:870; number_of_response:1; }","duration":"316.589061ms","start":"2026-04-09T02:05:30.142061Z","end":"2026-04-09T02:05:30.45865Z","steps":["trace[75059987] 'process raft request' (duration: 316.357855ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:30.458849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:05:30.142036Z","time spent":"316.728545ms","remote":"127.0.0.1:43132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T02:05:30.460983Z","caller":"traceutil/trace.go:171","msg":"trace[1246586238] transaction","detail":"{read_only:false; response_revision:871; number_of_response:1; }","duration":"109.362632ms","start":"2026-04-09T02:05:30.351608Z","end":"2026-04-09T02:05:30.46097Z","steps":["trace[1246586238] 'process raft request' (duration: 109.23339ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:30.461242Z","caller":"traceutil/trace.go:171","msg":"trace[1485328836] transaction","detail":"{read_only:false; response_revision:872; number_of_response:1; }","duration":"108.137435ms","start":"2026-04-09T02:05:30.353054Z","end":"2026-04-09T02:05:30.461192Z","steps":["trace[1485328836] 'process raft request' (duration: 107.88444ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:34.324523Z","caller":"traceutil/trace.go:171","msg":"trace[2042804887] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"147.705769ms","start":"2026-04-09T02:05:34.176794Z","end":"2026-04-09T02:05:34.3245Z","steps":["trace[2042804887] 'process raft request' (duration: 147.562705ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:35.770851Z","caller":"traceutil/trace.go:171","msg":"trace[161433933] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"107.056801ms","start":"2026-04-09T02:05:35.663769Z","end":"2026-04-09T02:05:35.770826Z","steps":["trace[161433933] 'process raft request' (duration: 106.785745ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:36.242801Z","caller":"traceutil/trace.go:171","msg":"trace[627753911] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"151.449393ms","start":"2026-04-09T02:05:36.091329Z","end":"2026-04-09T02:05:36.242778Z","steps":["trace[627753911] 'process raft request' (duration: 112.613935ms)","trace[627753911] 'compare' (duration: 38.608543ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:05:56.781547Z","caller":"traceutil/trace.go:171","msg":"trace[1427016170] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"110.067613ms","start":"2026-04-09T02:05:56.671457Z","end":"2026-04-09T02:05:56.781525Z","steps":["trace[1427016170] 'process raft request' (duration: 106.190645ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:56.796802Z","caller":"traceutil/trace.go:171","msg":"trace[1385473430] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"124.788786ms","start":"2026-04-09T02:05:56.671995Z","end":"2026-04-09T02:05:56.796784Z","steps":["trace[1385473430] 'process raft request' (duration: 124.660173ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:56.797126Z","caller":"traceutil/trace.go:171","msg":"trace[2086377644] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"124.321177ms","start":"2026-04-09T02:05:56.672771Z","end":"2026-04-09T02:05:56.797092Z","steps":["trace[2086377644] 'process raft request' (duration: 123.974868ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:07.829807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.506339ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T02:06:07.829899Z","caller":"traceutil/trace.go:171","msg":"trace[1095622703] linearizableReadLoop","detail":"{readStateIndex:1138; appliedIndex:1137; }","duration":"879.268005ms","start":"2026-04-09T02:06:06.950615Z","end":"2026-04-09T02:06:07.829883Z","steps":["trace[1095622703] 'read index received' (duration: 339.50348ms)","trace[1095622703] 'applied index is now lower than readState.Index' (duration: 539.763365ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:06:07.82998Z","caller":"traceutil/trace.go:171","msg":"trace[1538514492] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"973.107669ms","start":"2026-04-09T02:06:06.856861Z","end":"2026-04-09T02:06:07.829969Z","steps":["trace[1538514492] 'process raft request' (duration: 433.313513ms)","trace[1538514492] 'compare' (duration: 539.012418ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:06:07.830031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:06.856829Z","time spent":"973.175161ms","remote":"127.0.0.1:43168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T02:06:07.830277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"878.875636ms","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-04-09T02:06:07.830366Z","caller":"traceutil/trace.go:171","msg":"trace[1535226299] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1091; }","duration":"879.011068ms","start":"2026-04-09T02:06:06.951334Z","end":"2026-04-09T02:06:07.830346Z","steps":["trace[1535226299] 'agreement among raft nodes before linearized reading' (duration: 878.763873ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:07.830407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:06.951315Z","time spent":"879.08157ms","remote":"127.0.0.1:43168","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-04-09T02:06:07.830402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.181938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-09T02:06:07.830439Z","caller":"traceutil/trace.go:171","msg":"trace[2048316571] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1091; }","duration":"428.25568ms","start":"2026-04-09T02:06:07.402172Z","end":"2026-04-09T02:06:07.830428Z","steps":["trace[2048316571] 'agreement among raft nodes before linearized reading' (duration: 428.122757ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:07.830477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:07.402151Z","time spent":"428.315681ms","remote":"127.0.0.1:43168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-09T02:06:07.830449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"879.816497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-09T02:06:07.830544Z","caller":"traceutil/trace.go:171","msg":"trace[1238793827] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1091; }","duration":"879.96746ms","start":"2026-04-09T02:06:06.950554Z","end":"2026-04-09T02:06:07.830522Z","steps":["trace[1238793827] 'agreement among raft nodes before linearized reading' (duration: 879.686094ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:07.830599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:06.950534Z","time spent":"880.054522ms","remote":"127.0.0.1:43168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-09T02:06:08.675588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.211364ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T02:06:08.675677Z","caller":"traceutil/trace.go:171","msg":"trace[819421218] transaction","detail":"{read_only:false; response_revision:1092; number_of_response:1; }","duration":"841.349216ms","start":"2026-04-09T02:06:07.834308Z","end":"2026-04-09T02:06:08.675657Z","steps":["trace[819421218] 'process raft request' (duration: 493.995479ms)","trace[819421218] 'compare' (duration: 347.01936ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:06:08.675765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:07.834283Z","time spent":"841.451798ms","remote":"127.0.0.1:43168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T02:06:08.686434Z","caller":"traceutil/trace.go:171","msg":"trace[1488893504] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"851.562217ms","start":"2026-04-09T02:06:07.834842Z","end":"2026-04-09T02:06:08.686404Z","steps":["trace[1488893504] 'process raft request' (duration: 851.325401ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:08.686648Z","caller":"traceutil/trace.go:171","msg":"trace[343604495] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"850.985604ms","start":"2026-04-09T02:06:07.835639Z","end":"2026-04-09T02:06:08.686625Z","steps":["trace[343604495] 'process raft request' (duration: 850.721618ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:08.686797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:07.83563Z","time spent":"851.100886ms","remote":"127.0.0.1:43168","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-04-09T02:06:08.686663Z","caller":"traceutil/trace.go:171","msg":"trace[764929629] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"781.468189ms","start":"2026-04-09T02:06:07.90517Z","end":"2026-04-09T02:06:08.686638Z","steps":["trace[764929629] 'process raft request' (duration: 781.328606ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:08.686908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:07.905149Z","time spent":"781.694283ms","remote":"127.0.0.1:43168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T02:06:08.687225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:07.83482Z","time spent":"851.689659ms","remote":"127.0.0.1:43168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T02:06:08.689912Z","caller":"traceutil/trace.go:171","msg":"trace[1063169208] linearizableReadLoop","detail":"{readStateIndex:1142; appliedIndex:1138; }","duration":"700.927365ms","start":"2026-04-09T02:06:07.988975Z","end":"2026-04-09T02:06:08.689902Z","steps":["trace[1063169208] 'read index received' (duration: 339.339445ms)","trace[1063169208] 'applied index is now lower than readState.Index' (duration: 361.58733ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:06:08.690059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.224316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:06:08.690086Z","caller":"traceutil/trace.go:171","msg":"trace[1543955135] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1096; }","duration":"295.281687ms","start":"2026-04-09T02:06:08.394796Z","end":"2026-04-09T02:06:08.690078Z","steps":["trace[1543955135] 'agreement among raft nodes before linearized reading' (duration: 295.212766ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:08.690113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"701.130599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:06:08.690172Z","caller":"traceutil/trace.go:171","msg":"trace[1233842178] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1096; }","duration":"701.229131ms","start":"2026-04-09T02:06:07.988929Z","end":"2026-04-09T02:06:08.690158Z","steps":["trace[1233842178] 'agreement among raft nodes before linearized reading' (duration: 701.053137ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:08.690201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:07.988909Z","time spent":"701.286042ms","remote":"127.0.0.1:42914","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-04-09T02:06:08.690215Z","caller":"traceutil/trace.go:171","msg":"trace[565940975] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"345.533715ms","start":"2026-04-09T02:06:08.344675Z","end":"2026-04-09T02:06:08.690208Z","steps":["trace[565940975] 'process raft request' (duration: 345.174337ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:08.690259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:08.344649Z","time spent":"345.582367ms","remote":"127.0.0.1:43168","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-04-09T02:06:22.941058Z","caller":"traceutil/trace.go:171","msg":"trace[1736103884] linearizableReadLoop","detail":"{readStateIndex:1189; appliedIndex:1188; }","duration":"172.587008ms","start":"2026-04-09T02:06:22.768446Z","end":"2026-04-09T02:06:22.941033Z","steps":["trace[1736103884] 'read index received' (duration: 46.637696ms)","trace[1736103884] 'applied index is now lower than readState.Index' (duration: 125.948242ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:06:22.941237Z","caller":"traceutil/trace.go:171","msg":"trace[2005848433] transaction","detail":"{read_only:false; response_revision:1139; number_of_response:1; }","duration":"183.181318ms","start":"2026-04-09T02:06:22.758043Z","end":"2026-04-09T02:06:22.941224Z","steps":["trace[2005848433] 'process raft request' (duration: 119.752851ms)","trace[2005848433] 'compare' (duration: 63.079219ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:06:22.941481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.859138ms","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-04-09T02:06:22.94156Z","caller":"traceutil/trace.go:171","msg":"trace[418176194] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1139; }","duration":"166.9424ms","start":"2026-04-09T02:06:22.774598Z","end":"2026-04-09T02:06:22.941541Z","steps":["trace[418176194] 'agreement among raft nodes before linearized reading' (duration: 166.744485ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:22.941773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.301184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-09T02:06:22.941851Z","caller":"traceutil/trace.go:171","msg":"trace[1783997069] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1139; }","duration":"173.413457ms","start":"2026-04-09T02:06:22.768421Z","end":"2026-04-09T02:06:22.941834Z","steps":["trace[1783997069] 'agreement among raft nodes before linearized reading' (duration: 173.170652ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:23.167732Z","caller":"traceutil/trace.go:171","msg":"trace[1894061463] linearizableReadLoop","detail":"{readStateIndex:1192; appliedIndex:1190; }","duration":"131.424406ms","start":"2026-04-09T02:06:23.036275Z","end":"2026-04-09T02:06:23.1677Z","steps":["trace[1894061463] 'read index received' (duration: 129.361519ms)","trace[1894061463] 'applied index is now lower than readState.Index' (duration: 2.062027ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:06:23.167904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.616371ms","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-04-09T02:06:23.16793Z","caller":"traceutil/trace.go:171","msg":"trace[1713911556] range","detail":"{range_begin:/registry/masterleases/199.204.45.235; range_end:; response_count:1; response_revision:1142; }","duration":"131.699983ms","start":"2026-04-09T02:06:23.036223Z","end":"2026-04-09T02:06:23.167923Z","steps":["trace[1713911556] 'agreement among raft nodes before linearized reading' (duration: 131.57185ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:23.167913Z","caller":"traceutil/trace.go:171","msg":"trace[1721593010] transaction","detail":"{read_only:false; response_revision:1141; number_of_response:1; }","duration":"222.326695ms","start":"2026-04-09T02:06:22.945527Z","end":"2026-04-09T02:06:23.167854Z","steps":["trace[1721593010] 'process raft request' (duration: 220.162605ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:23.168058Z","caller":"traceutil/trace.go:171","msg":"trace[334118151] transaction","detail":"{read_only:false; response_revision:1142; number_of_response:1; }","duration":"222.231903ms","start":"2026-04-09T02:06:22.945643Z","end":"2026-04-09T02:06:23.167875Z","steps":["trace[334118151] 'process raft request' (duration: 221.964507ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:27.070639Z","caller":"traceutil/trace.go:171","msg":"trace[1511797354] transaction","detail":"{read_only:false; response_revision:1152; number_of_response:1; }","duration":"238.813508ms","start":"2026-04-09T02:06:26.831802Z","end":"2026-04-09T02:06:27.070615Z","steps":["trace[1511797354] 'process raft request' (duration: 238.677825ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:27.076892Z","caller":"traceutil/trace.go:171","msg":"trace[1484667044] transaction","detail":"{read_only:false; response_revision:1153; number_of_response:1; }","duration":"114.680538ms","start":"2026-04-09T02:06:26.96219Z","end":"2026-04-09T02:06:27.076871Z","steps":["trace[1484667044] 'process raft request' (duration: 114.430922ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:27.361333Z","caller":"traceutil/trace.go:171","msg":"trace[1887109344] transaction","detail":"{read_only:false; response_revision:1156; number_of_response:1; }","duration":"100.612349ms","start":"2026-04-09T02:06:27.260667Z","end":"2026-04-09T02:06:27.361279Z","steps":["trace[1887109344] 'process raft request' (duration: 29.197441ms)","trace[1887109344] 'compare' (duration: 71.248004ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:06:31.201656Z","caller":"traceutil/trace.go:171","msg":"trace[7527985] transaction","detail":"{read_only:false; response_revision:1165; number_of_response:1; }","duration":"106.812189ms","start":"2026-04-09T02:06:31.094812Z","end":"2026-04-09T02:06:31.201624Z","steps":["trace[7527985] 'process raft request' (duration: 106.648515ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:31.810598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.966592ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T02:06:31.810695Z","caller":"traceutil/trace.go:171","msg":"trace[777644290] linearizableReadLoop","detail":"{readStateIndex:1218; appliedIndex:1216; }","duration":"705.953369ms","start":"2026-04-09T02:06:31.104726Z","end":"2026-04-09T02:06:31.810679Z","steps":["trace[777644290] 'read index received' (duration: 96.821113ms)","trace[777644290] 'applied index is now lower than readState.Index' (duration: 609.130956ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:06:31.810812Z","caller":"traceutil/trace.go:171","msg":"trace[1867508367] transaction","detail":"{read_only:false; response_revision:1166; number_of_response:1; }","duration":"713.576281ms","start":"2026-04-09T02:06:31.097179Z","end":"2026-04-09T02:06:31.810755Z","steps":["trace[1867508367] 'process raft request' (duration: 512.346253ms)","trace[1867508367] 'compare' (duration: 200.831909ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:06:31.810885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.86318ms","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-04-09T02:06:31.810939Z","caller":"traceutil/trace.go:171","msg":"trace[1953075220] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1166; }","duration":"238.921811ms","start":"2026-04-09T02:06:31.572004Z","end":"2026-04-09T02:06:31.810926Z","steps":["trace[1953075220] 'agreement among raft nodes before linearized reading' (duration: 238.832639ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:31.81096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.003648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-09T02:06:31.810961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.408219ms","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-04-09T02:06:31.810988Z","caller":"traceutil/trace.go:171","msg":"trace[131123096] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1166; }","duration":"437.028848ms","start":"2026-04-09T02:06:31.373951Z","end":"2026-04-09T02:06:31.810979Z","steps":["trace[131123096] 'agreement among raft nodes before linearized reading' (duration: 436.954627ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:31.811035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:31.373891Z","time spent":"437.13724ms","remote":"127.0.0.1:43168","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":"info","ts":"2026-04-09T02:06:31.811039Z","caller":"traceutil/trace.go:171","msg":"trace[22093537] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1166; }","duration":"540.534152ms","start":"2026-04-09T02:06:31.270485Z","end":"2026-04-09T02:06:31.811019Z","steps":["trace[22093537] 'agreement among raft nodes before linearized reading' (duration: 540.312647ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:31.811057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:31.097158Z","time spent":"713.765355ms","remote":"127.0.0.1:43168","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-04-09T02:06:31.811093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:31.270465Z","time spent":"540.612914ms","remote":"127.0.0.1:43168","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-04-09T02:06:31.810873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"706.143563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-09T02:06:31.811257Z","caller":"traceutil/trace.go:171","msg":"trace[1120045326] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1166; }","duration":"706.541472ms","start":"2026-04-09T02:06:31.104698Z","end":"2026-04-09T02:06:31.81124Z","steps":["trace[1120045326] 'agreement among raft nodes before linearized reading' (duration: 706.066761ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:31.811296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:31.104689Z","time spent":"706.596413ms","remote":"127.0.0.1:43168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-09T02:06:33.402833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:33.037602Z","time spent":"365.227303ms","remote":"127.0.0.1:42942","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-09T02:06:33.870301Z","caller":"traceutil/trace.go:171","msg":"trace[1829461009] transaction","detail":"{read_only:false; response_revision:1171; number_of_response:1; }","duration":"663.491105ms","start":"2026-04-09T02:06:33.206787Z","end":"2026-04-09T02:06:33.870278Z","steps":["trace[1829461009] 'process raft request' (duration: 650.642415ms)","trace[1829461009] 'compare' (duration: 12.644486ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:06:33.870422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:33.206762Z","time spent":"663.592808ms","remote":"127.0.0.1:43168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T02:06:33.880861Z","caller":"traceutil/trace.go:171","msg":"trace[114541350] linearizableReadLoop","detail":"{readStateIndex:1225; appliedIndex:1223; }","duration":"372.508297ms","start":"2026-04-09T02:06:33.508334Z","end":"2026-04-09T02:06:33.880843Z","steps":["trace[114541350] 'read index received' (duration: 349.110827ms)","trace[114541350] 'applied index is now lower than readState.Index' (duration: 23.39626ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:06:33.880916Z","caller":"traceutil/trace.go:171","msg":"trace[1407269102] transaction","detail":"{read_only:false; response_revision:1172; number_of_response:1; }","duration":"476.811859ms","start":"2026-04-09T02:06:33.404066Z","end":"2026-04-09T02:06:33.880878Z","steps":["trace[1407269102] 'process raft request' (duration: 476.667786ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:33.881086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.742362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:06:33.881139Z","caller":"traceutil/trace.go:171","msg":"trace[1781016385] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1172; }","duration":"372.843594ms","start":"2026-04-09T02:06:33.508281Z","end":"2026-04-09T02:06:33.881124Z","steps":["trace[1781016385] 'agreement among raft nodes before linearized reading' (duration: 372.66907ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:33.881183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:33.508257Z","time spent":"372.915526ms","remote":"127.0.0.1:53652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":26,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-04-09T02:06:33.881176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:33.404032Z","time spent":"476.959101ms","remote":"127.0.0.1:42942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T02:06:34.035284Z","caller":"traceutil/trace.go:171","msg":"trace[1555997333] transaction","detail":"{read_only:false; response_revision:1174; number_of_response:1; }","duration":"149.915935ms","start":"2026-04-09T02:06:33.88534Z","end":"2026-04-09T02:06:34.035256Z","steps":["trace[1555997333] 'process raft request' (duration: 149.742231ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:34.035586Z","caller":"traceutil/trace.go:171","msg":"trace[61222728] linearizableReadLoop","detail":"{readStateIndex:1228; appliedIndex:1226; }","duration":"149.831093ms","start":"2026-04-09T02:06:33.885732Z","end":"2026-04-09T02:06:34.035563Z","steps":["trace[61222728] 'read index received' (duration: 81.593408ms)","trace[61222728] 'applied index is now lower than readState.Index' (duration: 68.236745ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:06:34.035661Z","caller":"traceutil/trace.go:171","msg":"trace[1724975356] transaction","detail":"{read_only:false; response_revision:1176; number_of_response:1; }","duration":"149.783001ms","start":"2026-04-09T02:06:33.88585Z","end":"2026-04-09T02:06:34.035633Z","steps":["trace[1724975356] 'process raft request' (duration: 149.601337ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:34.035741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.978657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-04-09T02:06:34.035746Z","caller":"traceutil/trace.go:171","msg":"trace[964926757] transaction","detail":"{read_only:false; response_revision:1175; number_of_response:1; }","duration":"150.308735ms","start":"2026-04-09T02:06:33.88542Z","end":"2026-04-09T02:06:34.035729Z","steps":["trace[964926757] 'process raft request' (duration: 149.797942ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:34.035776Z","caller":"traceutil/trace.go:171","msg":"trace[206651260] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1176; }","duration":"150.053559ms","start":"2026-04-09T02:06:33.885711Z","end":"2026-04-09T02:06:34.035765Z","steps":["trace[206651260] 'agreement among raft nodes before linearized reading' (duration: 149.960316ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:36.220356Z","caller":"traceutil/trace.go:171","msg":"trace[1509798929] linearizableReadLoop","detail":"{readStateIndex:1231; appliedIndex:1230; }","duration":"249.50324ms","start":"2026-04-09T02:06:35.970831Z","end":"2026-04-09T02:06:36.220334Z","steps":["trace[1509798929] 'read index received' (duration: 249.278975ms)","trace[1509798929] 'applied index is now lower than readState.Index' (duration: 223.295µs)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:06:36.22064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.792627ms","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-04-09T02:06:36.220739Z","caller":"traceutil/trace.go:171","msg":"trace[1990102906] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1178; }","duration":"249.882739ms","start":"2026-04-09T02:06:35.970819Z","end":"2026-04-09T02:06:36.220702Z","steps":["trace[1990102906] 'agreement among raft nodes before linearized reading' (duration: 249.658904ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:36.220435Z","caller":"traceutil/trace.go:171","msg":"trace[995457060] transaction","detail":"{read_only:false; response_revision:1178; number_of_response:1; }","duration":"345.576706ms","start":"2026-04-09T02:06:35.874822Z","end":"2026-04-09T02:06:36.220399Z","steps":["trace[995457060] 'process raft request' (duration: 345.371981ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:36.221099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.38128ms","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-04-09T02:06:36.221151Z","caller":"traceutil/trace.go:171","msg":"trace[296237252] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1178; }","duration":"114.469392ms","start":"2026-04-09T02:06:36.106667Z","end":"2026-04-09T02:06:36.221137Z","steps":["trace[296237252] 'agreement among raft nodes before linearized reading' (duration: 114.319679ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:36.221106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:35.874802Z","time spent":"346.217141ms","remote":"127.0.0.1:43168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T02:06:36.221288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.515511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-09T02:06:36.221572Z","caller":"traceutil/trace.go:171","msg":"trace[1090803333] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1178; }","duration":"181.833878ms","start":"2026-04-09T02:06:36.039717Z","end":"2026-04-09T02:06:36.221551Z","steps":["trace[1090803333] 'agreement among raft nodes before linearized reading' (duration: 181.45892ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:36.221276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.507531ms","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-04-09T02:06:36.221687Z","caller":"traceutil/trace.go:171","msg":"trace[211058764] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1178; }","duration":"181.94541ms","start":"2026-04-09T02:06:36.039728Z","end":"2026-04-09T02:06:36.221674Z","steps":["trace[211058764] 'agreement among raft nodes before linearized reading' (duration: 181.407208ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:36.221079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.598323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-09T02:06:36.221807Z","caller":"traceutil/trace.go:171","msg":"trace[52368738] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1178; }","duration":"182.36297ms","start":"2026-04-09T02:06:36.039434Z","end":"2026-04-09T02:06:36.221797Z","steps":["trace[52368738] 'agreement among raft nodes before linearized reading' (duration: 181.547162ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:36.479588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.0921ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T02:06:36.47969Z","caller":"traceutil/trace.go:171","msg":"trace[1892281002] transaction","detail":"{read_only:false; response_revision:1179; number_of_response:1; }","duration":"255.884305ms","start":"2026-04-09T02:06:36.223786Z","end":"2026-04-09T02:06:36.47967Z","steps":["trace[1892281002] 'process raft request' (duration: 82.629111ms)","trace[1892281002] 'compare' (duration: 172.989158ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:06:36.513949Z","caller":"traceutil/trace.go:171","msg":"trace[490425243] linearizableReadLoop","detail":"{readStateIndex:1236; appliedIndex:1232; }","duration":"118.241078ms","start":"2026-04-09T02:06:36.39568Z","end":"2026-04-09T02:06:36.513921Z","steps":["trace[490425243] 'read index received' (duration: 117.573613ms)","trace[490425243] 'applied index is now lower than readState.Index' (duration: 665.835µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:06:36.514353Z","caller":"traceutil/trace.go:171","msg":"trace[181918417] transaction","detail":"{read_only:false; response_revision:1181; number_of_response:1; }","duration":"288.664518ms","start":"2026-04-09T02:06:36.225659Z","end":"2026-04-09T02:06:36.514324Z","steps":["trace[181918417] 'process raft request' (duration: 287.758697ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:36.514395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.696677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:06:36.514454Z","caller":"traceutil/trace.go:171","msg":"trace[1718381307] transaction","detail":"{read_only:false; response_revision:1182; number_of_response:1; }","duration":"288.506394ms","start":"2026-04-09T02:06:36.225851Z","end":"2026-04-09T02:06:36.514357Z","steps":["trace[1718381307] 'process raft request' (duration: 287.914881ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:36.514594Z","caller":"traceutil/trace.go:171","msg":"trace[1704319050] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1183; }","duration":"118.80972ms","start":"2026-04-09T02:06:36.395627Z","end":"2026-04-09T02:06:36.514437Z","steps":["trace[1704319050] 'agreement among raft nodes before linearized reading' (duration: 118.411591ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:36.514622Z","caller":"traceutil/trace.go:171","msg":"trace[1311316471] transaction","detail":"{read_only:false; response_revision:1183; number_of_response:1; }","duration":"286.620581ms","start":"2026-04-09T02:06:36.227937Z","end":"2026-04-09T02:06:36.514558Z","steps":["trace[1311316471] 'process raft request' (duration: 285.914095ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:36.51461Z","caller":"traceutil/trace.go:171","msg":"trace[1099838102] transaction","detail":"{read_only:false; response_revision:1180; number_of_response:1; }","duration":"290.177782ms","start":"2026-04-09T02:06:36.224419Z","end":"2026-04-09T02:06:36.514596Z","steps":["trace[1099838102] 'process raft request' (duration: 288.829792ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:40.614584Z","caller":"traceutil/trace.go:171","msg":"trace[902657499] transaction","detail":"{read_only:false; response_revision:1193; number_of_response:1; }","duration":"116.99301ms","start":"2026-04-09T02:06:40.497561Z","end":"2026-04-09T02:06:40.614554Z","steps":["trace[902657499] 'process raft request' (duration: 116.757655ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:45.090959Z","caller":"traceutil/trace.go:171","msg":"trace[1082495154] transaction","detail":"{read_only:false; response_revision:1210; number_of_response:1; }","duration":"325.497171ms","start":"2026-04-09T02:06:44.765435Z","end":"2026-04-09T02:06:45.090932Z","steps":["trace[1082495154] 'process raft request' (duration: 325.45074ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:45.091048Z","caller":"traceutil/trace.go:171","msg":"trace[103695783] transaction","detail":"{read_only:false; response_revision:1208; number_of_response:1; }","duration":"326.837923ms","start":"2026-04-09T02:06:44.764163Z","end":"2026-04-09T02:06:45.091001Z","steps":["trace[103695783] 'process raft request' (duration: 234.009681ms)","trace[103695783] 'compare' (duration: 92.479564ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:06:45.091113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:44.765419Z","time spent":"325.609825ms","remote":"127.0.0.1:43168","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-04-09T02:06:45.091169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:44.764139Z","time spent":"326.968405ms","remote":"127.0.0.1:43168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T02:06:45.091253Z","caller":"traceutil/trace.go:171","msg":"trace[1680807713] transaction","detail":"{read_only:false; response_revision:1209; number_of_response:1; }","duration":"326.358242ms","start":"2026-04-09T02:06:44.764872Z","end":"2026-04-09T02:06:45.09123Z","steps":["trace[1680807713] 'process raft request' (duration: 325.963063ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:45.091437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:44.764771Z","time spent":"326.577647ms","remote":"127.0.0.1:43168","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-04-09T02:06:49.01112Z","caller":"traceutil/trace.go:171","msg":"trace[1515973862] linearizableReadLoop","detail":"{readStateIndex:1327; appliedIndex:1326; }","duration":"354.522448ms","start":"2026-04-09T02:06:48.656552Z","end":"2026-04-09T02:06:49.011075Z","steps":["trace[1515973862] 'read index received' (duration: 308.109528ms)","trace[1515973862] 'applied index is now lower than readState.Index' (duration: 46.41108ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:06:49.011281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.707853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:06:49.01137Z","caller":"traceutil/trace.go:171","msg":"trace[86214917] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:1269; }","duration":"354.840936ms","start":"2026-04-09T02:06:48.656504Z","end":"2026-04-09T02:06:49.011345Z","steps":["trace[86214917] 'agreement among raft nodes before linearized reading' (duration: 354.694143ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:49.011432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:48.656486Z","time spent":"354.933008ms","remote":"127.0.0.1:53720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":26,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-09T02:06:49.011455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.934674ms","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-04-09T02:06:49.011512Z","caller":"traceutil/trace.go:171","msg":"trace[1144644533] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1269; }","duration":"327.041647ms","start":"2026-04-09T02:06:48.684452Z","end":"2026-04-09T02:06:49.011494Z","steps":["trace[1144644533] 'agreement among raft nodes before linearized reading' (duration: 326.830682ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:49.011563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:48.684433Z","time spent":"327.115758ms","remote":"127.0.0.1:43168","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-04-09T02:06:49.011905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.771392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T02:06:49.011955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.523964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-09T02:06:49.011997Z","caller":"traceutil/trace.go:171","msg":"trace[1598887207] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1269; }","duration":"144.586635ms","start":"2026-04-09T02:06:48.867399Z","end":"2026-04-09T02:06:49.011985Z","steps":["trace[1598887207] 'agreement among raft nodes before linearized reading' (duration: 144.443932ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:49.011959Z","caller":"traceutil/trace.go:171","msg":"trace[853132876] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1269; }","duration":"209.872484ms","start":"2026-04-09T02:06:48.802074Z","end":"2026-04-09T02:06:49.011946Z","steps":["trace[853132876] 'agreement among raft nodes before linearized reading' (duration: 209.772412ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:51.625591Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920880144734354476,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-09T02:06:51.877622Z","caller":"traceutil/trace.go:171","msg":"trace[789315859] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"846.640185ms","start":"2026-04-09T02:06:51.03096Z","end":"2026-04-09T02:06:51.8776Z","steps":["trace[789315859] 'process raft request' (duration: 846.503012ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:51.877751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:51.030949Z","time spent":"846.734868ms","remote":"127.0.0.1:43168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T02:06:52.467229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.513408ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T02:06:52.467711Z","caller":"traceutil/trace.go:171","msg":"trace[1865325896] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"1.436576976s","start":"2026-04-09T02:06:51.031076Z","end":"2026-04-09T02:06:52.467653Z","steps":["trace[1865325896] 'process raft request' (duration: 1.327550526s)","trace[1865325896] 'compare' (duration: 108.14896ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:06:52.467727Z","caller":"traceutil/trace.go:171","msg":"trace[1999886486] linearizableReadLoop","detail":"{readStateIndex:1336; appliedIndex:1334; }","duration":"1.342300101s","start":"2026-04-09T02:06:51.125394Z","end":"2026-04-09T02:06:52.467695Z","steps":["trace[1999886486] 'read index received' (duration: 752.128024ms)","trace[1999886486] 'applied index is now lower than readState.Index' (duration: 590.169307ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:06:52.467798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:51.031069Z","time spent":"1.436686399s","remote":"127.0.0.1:43168","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-04-09T02:06:52.467987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.566736ms","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":"warn","ts":"2026-04-09T02:06:52.468005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.342625678s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-09T02:06:52.468035Z","caller":"traceutil/trace.go:171","msg":"trace[1330336293] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1279; }","duration":"390.686858ms","start":"2026-04-09T02:06:52.077331Z","end":"2026-04-09T02:06:52.468018Z","steps":["trace[1330336293] 'agreement among raft nodes before linearized reading' (duration: 390.603286ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:52.468044Z","caller":"traceutil/trace.go:171","msg":"trace[2098523883] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1279; }","duration":"1.34271434s","start":"2026-04-09T02:06:51.125317Z","end":"2026-04-09T02:06:52.468031Z","steps":["trace[2098523883] 'agreement among raft nodes before linearized reading' (duration: 1.342524716s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:52.468073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:52.077309Z","time spent":"390.75448ms","remote":"127.0.0.1:43128","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-04-09T02:06:52.468082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:51.125289Z","time spent":"1.342785511s","remote":"127.0.0.1:43168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-09T02:06:52.468068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.339046817s","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-04-09T02:06:52.468152Z","caller":"traceutil/trace.go:171","msg":"trace[151787309] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1279; }","duration":"1.339166639s","start":"2026-04-09T02:06:51.128963Z","end":"2026-04-09T02:06:52.46813Z","steps":["trace[151787309] 'agreement among raft nodes before linearized reading' (duration: 1.338912904s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:52.468196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:51.128948Z","time spent":"1.339239042s","remote":"127.0.0.1:43168","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-04-09T02:06:52.468267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.338181056s","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-04-09T02:06:52.468293Z","caller":"traceutil/trace.go:171","msg":"trace[939490347] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1279; }","duration":"1.338215968s","start":"2026-04-09T02:06:51.130069Z","end":"2026-04-09T02:06:52.468285Z","steps":["trace[939490347] 'agreement among raft nodes before linearized reading' (duration: 1.338134866s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:52.469189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:51.130063Z","time spent":"1.339102319s","remote":"127.0.0.1:43168","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":"info","ts":"2026-04-09T02:07:12.759111Z","caller":"traceutil/trace.go:171","msg":"trace[1613810769] transaction","detail":"{read_only:false; response_revision:1377; number_of_response:1; }","duration":"562.552385ms","start":"2026-04-09T02:07:12.19654Z","end":"2026-04-09T02:07:12.759092Z","steps":["trace[1613810769] 'process raft request' (duration: 562.441331ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:07:12.759231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:07:12.196522Z","time spent":"562.644027ms","remote":"127.0.0.1:43168","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-04-09T02:07:13.064446Z","caller":"traceutil/trace.go:171","msg":"trace[350826931] linearizableReadLoop","detail":"{readStateIndex:1440; appliedIndex:1438; }","duration":"416.74587ms","start":"2026-04-09T02:07:12.647673Z","end":"2026-04-09T02:07:13.064419Z","steps":["trace[350826931] 'read index received' (duration: 111.391481ms)","trace[350826931] 'applied index is now lower than readState.Index' (duration: 305.353449ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:07:13.064685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.888372ms","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-04-09T02:07:13.064717Z","caller":"traceutil/trace.go:171","msg":"trace[1076437490] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1378; }","duration":"399.992194ms","start":"2026-04-09T02:07:12.664715Z","end":"2026-04-09T02:07:13.064707Z","steps":["trace[1076437490] 'agreement among raft nodes before linearized reading' (duration: 399.841ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:07:13.06475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:07:12.66469Z","time spent":"400.053126ms","remote":"127.0.0.1:43168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-09T02:07:13.064713Z","caller":"traceutil/trace.go:171","msg":"trace[296203795] transaction","detail":"{read_only:false; response_revision:1378; number_of_response:1; }","duration":"433.339033ms","start":"2026-04-09T02:07:12.631339Z","end":"2026-04-09T02:07:13.064678Z","steps":["trace[296203795] 'process raft request' (duration: 379.694337ms)","trace[296203795] 'compare' (duration: 53.038432ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:07:13.06474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.052647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-09T02:07:13.064818Z","caller":"traceutil/trace.go:171","msg":"trace[1321698045] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1378; }","duration":"417.17284ms","start":"2026-04-09T02:07:12.647626Z","end":"2026-04-09T02:07:13.064799Z","steps":["trace[1321698045] 'agreement among raft nodes before linearized reading' (duration: 416.935655ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:07:13.064866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:07:12.64761Z","time spent":"417.240691ms","remote":"127.0.0.1:43168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-09T02:07:13.064899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.466078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-09T02:07:13.064889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:07:12.631312Z","time spent":"433.490506ms","remote":"127.0.0.1:43168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T02:07:13.064974Z","caller":"traceutil/trace.go:171","msg":"trace[1346619353] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1378; }","duration":"393.59392ms","start":"2026-04-09T02:07:12.671363Z","end":"2026-04-09T02:07:13.064957Z","steps":["trace[1346619353] 'agreement among raft nodes before linearized reading' (duration: 393.420876ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:07:13.065026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:07:12.671352Z","time spent":"393.657291ms","remote":"127.0.0.1:43168","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-04-09T02:07:13.064903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.602276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-09T02:07:13.065159Z","caller":"traceutil/trace.go:171","msg":"trace[416335179] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1378; }","duration":"395.899952ms","start":"2026-04-09T02:07:12.669223Z","end":"2026-04-09T02:07:13.065123Z","steps":["trace[416335179] 'agreement among raft nodes before linearized reading' (duration: 395.592125ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:07:13.065199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:07:12.669201Z","time spent":"395.990654ms","remote":"127.0.0.1:43168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-09T02:07:14.531007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.789126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:07:14.53111Z","caller":"traceutil/trace.go:171","msg":"trace[425276670] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1385; }","duration":"662.985291ms","start":"2026-04-09T02:07:13.868098Z","end":"2026-04-09T02:07:14.531083Z","steps":["trace[425276670] 'range keys from in-memory index tree' (duration: 662.665523ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:07:14.531165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:07:13.868056Z","time spent":"663.094552ms","remote":"127.0.0.1:43168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":0,"response size":26,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-04-09T02:07:14.864663Z","caller":"traceutil/trace.go:171","msg":"trace[1506232685] transaction","detail":"{read_only:false; response_revision:1386; number_of_response:1; }","duration":"546.34925ms","start":"2026-04-09T02:07:14.31829Z","end":"2026-04-09T02:07:14.864639Z","steps":["trace[1506232685] 'process raft request' (duration: 546.203407ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:07:14.864806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:07:14.318264Z","time spent":"546.480924ms","remote":"127.0.0.1:42970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T02:07:15.155285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.544502ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-09T02:07:15.155453Z","caller":"traceutil/trace.go:171","msg":"trace[393753802] transaction","detail":"{read_only:false; response_revision:1388; number_of_response:1; }","duration":"285.792139ms","start":"2026-04-09T02:07:14.869644Z","end":"2026-04-09T02:07:15.155436Z","steps":["trace[393753802] 'process raft request' (duration: 285.736597ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:07:15.155452Z","caller":"traceutil/trace.go:171","msg":"trace[1351827576] transaction","detail":"{read_only:false; response_revision:1387; number_of_response:1; }","duration":"620.025585ms","start":"2026-04-09T02:07:14.535393Z","end":"2026-04-09T02:07:15.155419Z","steps":["trace[1351827576] 'process raft request' (duration: 420.282499ms)","trace[1351827576] 'compare' (duration: 199.417459ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:07:15.155587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:07:14.535372Z","time spent":"620.155628ms","remote":"127.0.0.1:43168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T02:07:15.155652Z","caller":"traceutil/trace.go:171","msg":"trace[364883277] linearizableReadLoop","detail":"{readStateIndex:1450; appliedIndex:1448; }","duration":"602.788428ms","start":"2026-04-09T02:07:14.552841Z","end":"2026-04-09T02:07:15.155629Z","steps":["trace[364883277] 'read index received' (duration: 311.732721ms)","trace[364883277] 'applied index is now lower than readState.Index' (duration: 291.053277ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:07:15.15592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.059164ms","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-04-09T02:07:15.15597Z","caller":"traceutil/trace.go:171","msg":"trace[1023912054] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1388; }","duration":"603.148356ms","start":"2026-04-09T02:07:14.552806Z","end":"2026-04-09T02:07:15.155955Z","steps":["trace[1023912054] 'agreement among raft nodes before linearized reading' (duration: 602.917261ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:07:15.156015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:07:14.552792Z","time spent":"603.208877ms","remote":"127.0.0.1:43408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":127,"response size":28,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-04-09T02:07:15.219904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.049222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T02:07:15.219955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.966248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-04-09T02:07:15.219992Z","caller":"traceutil/trace.go:171","msg":"trace[1747363242] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1389; }","duration":"533.212285ms","start":"2026-04-09T02:07:14.686758Z","end":"2026-04-09T02:07:15.21997Z","steps":["trace[1747363242] 'agreement among raft nodes before linearized reading' (duration: 533.064732ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:07:15.22003Z","caller":"traceutil/trace.go:171","msg":"trace[1152768977] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1389; }","duration":"410.08675ms","start":"2026-04-09T02:07:14.809923Z","end":"2026-04-09T02:07:15.22001Z","steps":["trace[1152768977] 'agreement among raft nodes before linearized reading' (duration: 409.843334ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:07:15.220038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:07:14.686736Z","time spent":"533.290197ms","remote":"127.0.0.1:53620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":26,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"info","ts":"2026-04-09T02:07:15.220007Z","caller":"traceutil/trace.go:171","msg":"trace[768844445] transaction","detail":"{read_only:false; response_revision:1389; number_of_response:1; }","duration":"149.203711ms","start":"2026-04-09T02:07:15.070755Z","end":"2026-04-09T02:07:15.219959Z","steps":["trace[768844445] 'process raft request' (duration: 148.73405ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:07:15.220076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:07:14.809908Z","time spent":"410.160452ms","remote":"127.0.0.1:43168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":495,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-09T02:07:15.22072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.437453ms","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-04-09T02:07:15.220773Z","caller":"traceutil/trace.go:171","msg":"trace[1688654508] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1389; }","duration":"457.529945ms","start":"2026-04-09T02:07:14.763229Z","end":"2026-04-09T02:07:15.220759Z","steps":["trace[1688654508] 'agreement among raft nodes before linearized reading' (duration: 457.28655ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:07:15.220817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:07:14.763209Z","time spent":"457.601248ms","remote":"127.0.0.1:43168","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-04-09T02:07:15.291102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.08716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-04-09T02:07:15.291169Z","caller":"traceutil/trace.go:171","msg":"trace[766647915] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1390; }","duration":"129.175451ms","start":"2026-04-09T02:07:15.161975Z","end":"2026-04-09T02:07:15.29115Z","steps":["trace[766647915] 'agreement among raft nodes before linearized reading' (duration: 128.990527ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:07:15.291236Z","caller":"traceutil/trace.go:171","msg":"trace[1129147565] transaction","detail":"{read_only:false; response_revision:1390; number_of_response:1; }","duration":"128.128967ms","start":"2026-04-09T02:07:15.163082Z","end":"2026-04-09T02:07:15.291211Z","steps":["trace[1129147565] 'process raft request' (duration: 98.912922ms)","trace[1129147565] 'compare' (duration: 28.821507ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:07:16.478707Z","caller":"traceutil/trace.go:171","msg":"trace[1759206215] transaction","detail":"{read_only:false; response_revision:1398; number_of_response:1; }","duration":"247.460597ms","start":"2026-04-09T02:07:16.231212Z","end":"2026-04-09T02:07:16.478672Z","steps":["trace[1759206215] 'process raft request' (duration: 247.239523ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:07:17.332778Z","caller":"traceutil/trace.go:171","msg":"trace[1841777874] transaction","detail":"{read_only:false; response_revision:1399; number_of_response:1; }","duration":"107.570775ms","start":"2026-04-09T02:07:17.225169Z","end":"2026-04-09T02:07:17.33274Z","steps":["trace[1841777874] 'process raft request' (duration: 107.229068ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:07:17.896863Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920880144734354736,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-09T02:07:18.389136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.318253ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T02:07:18.389832Z","caller":"traceutil/trace.go:171","msg":"trace[1569140887] transaction","detail":"{read_only:false; response_revision:1403; number_of_response:1; }","duration":"1.051235829s","start":"2026-04-09T02:07:17.338562Z","end":"2026-04-09T02:07:18.389798Z","steps":["trace[1569140887] 'process raft request' (duration: 1.050909952s)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:07:18.389832Z","caller":"traceutil/trace.go:171","msg":"trace[136798053] transaction","detail":"{read_only:false; response_revision:1401; number_of_response:1; }","duration":"1.051604518s","start":"2026-04-09T02:07:17.338188Z","end":"2026-04-09T02:07:18.389793Z","steps":["trace[136798053] 'process raft request' (duration: 1.051128937s)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:07:18.389851Z","caller":"traceutil/trace.go:171","msg":"trace[411112197] transaction","detail":"{read_only:false; response_revision:1400; number_of_response:1; }","duration":"1.051964835s","start":"2026-04-09T02:07:17.337839Z","end":"2026-04-09T02:07:18.389804Z","steps":["trace[411112197] 'process raft request' (duration: 897.887265ms)","trace[411112197] 'compare' (duration: 152.883594ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:07:18.389877Z","caller":"traceutil/trace.go:171","msg":"trace[11541680] transaction","detail":"{read_only:false; response_revision:1404; number_of_response:1; }","duration":"1.051112958s","start":"2026-04-09T02:07:17.338726Z","end":"2026-04-09T02:07:18.389839Z","steps":["trace[11541680] 'process raft request' (duration: 1.05078075s)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:07:18.389924Z","caller":"traceutil/trace.go:171","msg":"trace[1730505627] transaction","detail":"{read_only:false; response_revision:1402; number_of_response:1; }","duration":"1.051643769s","start":"2026-04-09T02:07:17.338256Z","end":"2026-04-09T02:07:18.3899Z","steps":["trace[1730505627] 'process raft request' (duration: 1.051177708s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:07:18.389989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:07:17.338533Z","time spent":"1.051372243s","remote":"127.0.0.1:43168","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-04-09T02:07:18.390007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:07:17.338178Z","time spent":"1.051740682s","remote":"127.0.0.1:43168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T02:07:18.390015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:07:17.337813Z","time spent":"1.05211699s","remote":"127.0.0.1:43168","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-04-09T02:07:18.390018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:07:17.338227Z","time spent":"1.051748272s","remote":"127.0.0.1:43168","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-04-09T02:07:18.390061Z","caller":"traceutil/trace.go:171","msg":"trace[279866226] transaction","detail":"{read_only:false; response_revision:1405; number_of_response:1; }","duration":"623.415371ms","start":"2026-04-09T02:07:17.766616Z","end":"2026-04-09T02:07:18.390032Z","steps":["trace[279866226] 'process raft request' (duration: 622.922279ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:07:18.390102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:07:17.338694Z","time spent":"1.051270101s","remote":"127.0.0.1:43168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T02:07:18.390203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:07:17.766596Z","time spent":"623.528384ms","remote":"127.0.0.1:43168","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-04-09T02:07:18.415391Z","caller":"traceutil/trace.go:171","msg":"trace[2090295782] linearizableReadLoop","detail":"{readStateIndex:1468; appliedIndex:1462; }","duration":"1.019516437s","start":"2026-04-09T02:07:17.395853Z","end":"2026-04-09T02:07:18.41537Z","steps":["trace[2090295782] 'read index received' (duration: 839.883382ms)","trace[2090295782] 'applied index is now lower than readState.Index' (duration: 179.631715ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:07:18.415527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.0196749s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T02:07:18.415669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.934333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-09T02:07:18.415708Z","caller":"traceutil/trace.go:171","msg":"trace[2060303615] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1405; }","duration":"1.019907786s","start":"2026-04-09T02:07:17.395779Z","end":"2026-04-09T02:07:18.415687Z","steps":["trace[2060303615] 'agreement among raft nodes before linearized reading' (duration: 1.019693722s)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:07:18.415737Z","caller":"traceutil/trace.go:171","msg":"trace[1754864694] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1405; }","duration":"140.069146ms","start":"2026-04-09T02:07:18.27565Z","end":"2026-04-09T02:07:18.415719Z","steps":["trace[1754864694] 'agreement among raft nodes before linearized reading' (duration: 139.935763ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:07:18.415848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:07:17.395745Z","time spent":"1.02008947s","remote":"127.0.0.1:42914","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-04-09T02:07:21.661115Z","caller":"traceutil/trace.go:171","msg":"trace[1935518368] linearizableReadLoop","detail":"{readStateIndex:1494; appliedIndex:1493; }","duration":"261.062353ms","start":"2026-04-09T02:07:21.400028Z","end":"2026-04-09T02:07:21.661091Z","steps":["trace[1935518368] 'read index received' (duration: 260.90234ms)","trace[1935518368] 'applied index is now lower than readState.Index' (duration: 159.043µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:07:21.66125Z","caller":"traceutil/trace.go:171","msg":"trace[295606910] transaction","detail":"{read_only:false; response_revision:1430; number_of_response:1; }","duration":"308.021099ms","start":"2026-04-09T02:07:21.353173Z","end":"2026-04-09T02:07:21.661194Z","steps":["trace[295606910] 'process raft request' (duration: 307.753163ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:07:21.661274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.217587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:07:21.661426Z","caller":"traceutil/trace.go:171","msg":"trace[1033160987] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1430; }","duration":"261.422022ms","start":"2026-04-09T02:07:21.399991Z","end":"2026-04-09T02:07:21.661413Z","steps":["trace[1033160987] 'agreement among raft nodes before linearized reading' (duration: 261.223017ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:07:21.661523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:07:21.353151Z","time spent":"308.235583ms","remote":"127.0.0.1:43168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T02:07:21.662072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.256599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-snwzs\" ","response":"range_response_count:1 size:12642"} {"level":"info","ts":"2026-04-09T02:07:21.662194Z","caller":"traceutil/trace.go:171","msg":"trace[671769307] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-snwzs; range_end:; response_count:1; response_revision:1430; }","duration":"115.418243ms","start":"2026-04-09T02:07:21.546757Z","end":"2026-04-09T02:07:21.662175Z","steps":["trace[671769307] 'agreement among raft nodes before linearized reading' (duration: 115.134957ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:07:21.662315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.697596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-09T02:07:21.662375Z","caller":"traceutil/trace.go:171","msg":"trace[1911650781] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1430; }","duration":"179.759477ms","start":"2026-04-09T02:07:21.4826Z","end":"2026-04-09T02:07:21.66236Z","steps":["trace[1911650781] 'agreement among raft nodes before linearized reading' (duration: 179.238716ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:07:22.511021Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920880144734354789,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-09T02:07:23.012153Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920880144734354789,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-09T02:07:23.026991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.166022928s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T02:07:23.027106Z","caller":"traceutil/trace.go:171","msg":"trace[827421381] transaction","detail":"{read_only:false; response_revision:1431; number_of_response:1; }","duration":"1.359646657s","start":"2026-04-09T02:07:21.667433Z","end":"2026-04-09T02:07:23.02708Z","steps":["trace[827421381] 'process raft request' (duration: 193.416574ms)","trace[827421381] 'compare' (duration: 1.16563829s)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:07:23.027202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:07:21.667404Z","time spent":"1.359753919s","remote":"127.0.0.1:43168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":493,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T02:07:23.141643Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.285768038s","expected-duration":"1s"} {"level":"info","ts":"2026-04-09T02:07:23.196484Z","caller":"traceutil/trace.go:171","msg":"trace[1693830450] linearizableReadLoop","detail":"{readStateIndex:1496; appliedIndex:1495; }","duration":"1.186309203s","start":"2026-04-09T02:07:22.010141Z","end":"2026-04-09T02:07:23.196451Z","steps":["trace[1693830450] 'read index received' (duration: 1.131668917s)","trace[1693830450] 'applied index is now lower than readState.Index' (duration: 54.639326ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:07:23.196558Z","caller":"traceutil/trace.go:171","msg":"trace[843959002] transaction","detail":"{read_only:false; response_revision:1432; number_of_response:1; }","duration":"1.506854133s","start":"2026-04-09T02:07:21.689659Z","end":"2026-04-09T02:07:23.196513Z","steps":["trace[843959002] 'process raft request' (duration: 1.452295578s)","trace[843959002] 'compare' (duration: 54.374631ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:07:23.196654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.186502167s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:07:23.196692Z","caller":"traceutil/trace.go:171","msg":"trace[638385293] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1432; }","duration":"1.18661485s","start":"2026-04-09T02:07:22.010067Z","end":"2026-04-09T02:07:23.196682Z","steps":["trace[638385293] 'agreement among raft nodes before linearized reading' (duration: 1.186530119s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:07:23.196729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:07:22.009972Z","time spent":"1.186747893s","remote":"127.0.0.1:43132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":26,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-04-09T02:07:23.19677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:07:21.689644Z","time spent":"1.506984545s","remote":"127.0.0.1:43072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13706,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T02:07:23.196903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"780.453908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-09T02:07:23.196918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.079729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:07:23.196962Z","caller":"traceutil/trace.go:171","msg":"trace[609647308] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1432; }","duration":"780.55455ms","start":"2026-04-09T02:07:22.416387Z","end":"2026-04-09T02:07:23.196942Z","steps":["trace[609647308] 'agreement among raft nodes before linearized reading' (duration: 780.382086ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:07:23.196977Z","caller":"traceutil/trace.go:171","msg":"trace[2086660579] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1432; }","duration":"296.148281ms","start":"2026-04-09T02:07:22.900809Z","end":"2026-04-09T02:07:23.196958Z","steps":["trace[2086660579] 'agreement among raft nodes before linearized reading' (duration: 296.051838ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:07:23.196986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"696.033143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-09T02:07:23.196999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:07:22.416367Z","time spent":"780.624403ms","remote":"127.0.0.1:43168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-09T02:07:23.19702Z","caller":"traceutil/trace.go:171","msg":"trace[950411763] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1432; }","duration":"696.067234ms","start":"2026-04-09T02:07:22.500944Z","end":"2026-04-09T02:07:23.197011Z","steps":["trace[950411763] 'agreement among raft nodes before linearized reading' (duration: 695.92634ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:07:23.197047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:07:22.500903Z","time spent":"696.138785ms","remote":"127.0.0.1:43168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-09T02:07:23.197133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.733026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.235\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-04-09T02:07:23.197193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.567936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-04-09T02:07:23.197217Z","caller":"traceutil/trace.go:171","msg":"trace[1691420725] range","detail":"{range_begin:/registry/masterleases/199.204.45.235; range_end:; response_count:1; response_revision:1432; }","duration":"158.870889ms","start":"2026-04-09T02:07:23.038324Z","end":"2026-04-09T02:07:23.197195Z","steps":["trace[1691420725] 'agreement among raft nodes before linearized reading' (duration: 158.587472ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:07:23.197237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"696.391001ms","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-04-09T02:07:23.197234Z","caller":"traceutil/trace.go:171","msg":"trace[1621810046] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1432; }","duration":"363.642447ms","start":"2026-04-09T02:07:22.833578Z","end":"2026-04-09T02:07:23.19722Z","steps":["trace[1621810046] 'agreement among raft nodes before linearized reading' (duration: 363.492604ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:07:23.197273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:07:22.833556Z","time spent":"363.710609ms","remote":"127.0.0.1:43168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":553,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-04-09T02:07:23.197276Z","caller":"traceutil/trace.go:171","msg":"trace[792747386] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1432; }","duration":"696.458522ms","start":"2026-04-09T02:07:22.500801Z","end":"2026-04-09T02:07:23.197259Z","steps":["trace[792747386] 'agreement among raft nodes before linearized reading' (duration: 696.386011ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:07:23.197344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"696.550565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-09T02:07:23.197169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"696.293829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-09T02:07:23.197402Z","caller":"traceutil/trace.go:171","msg":"trace[1887064730] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1432; }","duration":"696.632816ms","start":"2026-04-09T02:07:22.500761Z","end":"2026-04-09T02:07:23.197394Z","steps":["trace[1887064730] 'agreement among raft nodes before linearized reading' (duration: 696.532334ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:07:23.197402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:07:22.500779Z","time spent":"696.611596ms","remote":"127.0.0.1:43168","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":"info","ts":"2026-04-09T02:07:23.197418Z","caller":"traceutil/trace.go:171","msg":"trace[462560710] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1432; }","duration":"696.581206ms","start":"2026-04-09T02:07:22.500825Z","end":"2026-04-09T02:07:23.197407Z","steps":["trace[462560710] 'agreement among raft nodes before linearized reading' (duration: 696.279639ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:07:23.197432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:07:22.500748Z","time spent":"696.677708ms","remote":"127.0.0.1:43168","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-04-09T02:07:23.197455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:07:22.500804Z","time spent":"696.640616ms","remote":"127.0.0.1:43168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-09T02:07:35.561118Z","caller":"traceutil/trace.go:171","msg":"trace[565060763] transaction","detail":"{read_only:false; response_revision:1498; number_of_response:1; }","duration":"130.001769ms","start":"2026-04-09T02:07:35.431091Z","end":"2026-04-09T02:07:35.561093Z","steps":["trace[565060763] 'process raft request' (duration: 63.213409ms)","trace[565060763] 'compare' (duration: 66.650057ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:08:03.234965Z","caller":"traceutil/trace.go:171","msg":"trace[254210525] transaction","detail":"{read_only:false; response_revision:1622; number_of_response:1; }","duration":"124.802894ms","start":"2026-04-09T02:08:03.110123Z","end":"2026-04-09T02:08:03.234926Z","steps":["trace[254210525] 'process raft request' (duration: 123.884813ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:08:15.821149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.87485ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T02:08:15.821433Z","caller":"traceutil/trace.go:171","msg":"trace[344683667] linearizableReadLoop","detail":"{readStateIndex:1753; appliedIndex:1751; }","duration":"230.156909ms","start":"2026-04-09T02:08:15.591259Z","end":"2026-04-09T02:08:15.821416Z","steps":["trace[344683667] 'read index received' (duration: 24.442258ms)","trace[344683667] 'applied index is now lower than readState.Index' (duration: 205.713761ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:08:15.821559Z","caller":"traceutil/trace.go:171","msg":"trace[527680327] transaction","detail":"{read_only:false; response_revision:1679; number_of_response:1; }","duration":"229.365882ms","start":"2026-04-09T02:08:15.592167Z","end":"2026-04-09T02:08:15.821533Z","steps":["trace[527680327] 'process raft request' (duration: 229.157797ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:08:15.821706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.440315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-09T02:08:15.821772Z","caller":"traceutil/trace.go:171","msg":"trace[2003419119] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1679; }","duration":"230.533168ms","start":"2026-04-09T02:08:15.591228Z","end":"2026-04-09T02:08:15.821761Z","steps":["trace[2003419119] 'agreement among raft nodes before linearized reading' (duration: 230.334433ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:08:15.821833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.481553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-04-09T02:08:15.8219Z","caller":"traceutil/trace.go:171","msg":"trace[206278092] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1679; }","duration":"192.579775ms","start":"2026-04-09T02:08:15.6293Z","end":"2026-04-09T02:08:15.82188Z","steps":["trace[206278092] 'agreement among raft nodes before linearized reading' (duration: 192.454103ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:08:15.821991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.356333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-04-09T02:08:15.82172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.277581ms","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-04-09T02:08:15.822029Z","caller":"traceutil/trace.go:171","msg":"trace[1988844850] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1679; }","duration":"230.424274ms","start":"2026-04-09T02:08:15.591594Z","end":"2026-04-09T02:08:15.822019Z","steps":["trace[1988844850] 'agreement among raft nodes before linearized reading' (duration: 230.22723ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:08:15.822042Z","caller":"traceutil/trace.go:171","msg":"trace[1417193628] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1679; }","duration":"230.62142ms","start":"2026-04-09T02:08:15.591407Z","end":"2026-04-09T02:08:15.822029Z","steps":["trace[1417193628] 'agreement among raft nodes before linearized reading' (duration: 230.18874ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:08:15.821444Z","caller":"traceutil/trace.go:171","msg":"trace[1338383842] transaction","detail":"{read_only:false; response_revision:1678; number_of_response:1; }","duration":"230.20249ms","start":"2026-04-09T02:08:15.591219Z","end":"2026-04-09T02:08:15.821421Z","steps":["trace[1338383842] 'process raft request' (duration: 110.968742ms)","trace[1338383842] 'compare' (duration: 118.756628ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:09:58.574321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.130865ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T02:09:58.574556Z","caller":"traceutil/trace.go:171","msg":"trace[341648281] linearizableReadLoop","detail":"{readStateIndex:2249; appliedIndex:2248; }","duration":"132.932673ms","start":"2026-04-09T02:09:58.441572Z","end":"2026-04-09T02:09:58.574505Z","steps":["trace[341648281] 'read index received' (duration: 29.374009ms)","trace[341648281] 'applied index is now lower than readState.Index' (duration: 103.557404ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:09:58.57476Z","caller":"traceutil/trace.go:171","msg":"trace[28155886] transaction","detail":"{read_only:false; response_revision:2153; number_of_response:1; }","duration":"165.970775ms","start":"2026-04-09T02:09:58.408752Z","end":"2026-04-09T02:09:58.574723Z","steps":["trace[28155886] 'process raft request' (duration: 62.220156ms)","trace[28155886] 'compare' (duration: 103.023043ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:09:58.575017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.448625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:09:58.575089Z","caller":"traceutil/trace.go:171","msg":"trace[999062766] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2153; }","duration":"133.553927ms","start":"2026-04-09T02:09:58.441514Z","end":"2026-04-09T02:09:58.575068Z","steps":["trace[999062766] 'agreement among raft nodes before linearized reading' (duration: 133.467925ms)"],"step_count":1}