{"level":"warn","ts":"2026-09-22T02:01:19.198633Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-22T02:01:19.198718Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.20:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.20:2380","--initial-cluster=instance=https://162.253.55.20:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.20:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.20: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-09-22T02:01:19.198835Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-22T02:01:19.19885Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.20:2380"]} {"level":"info","ts":"2026-09-22T02:01:19.198881Z","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-09-22T02:01:19.200559Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.20:2379"]} {"level":"info","ts":"2026-09-22T02:01:19.201821Z","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://162.253.55.20:2380"],"listen-peer-urls":["https://162.253.55.20:2380"],"advertise-client-urls":["https://162.253.55.20:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.20:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.20: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-09-22T02:01:19.206701Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.421708ms"} {"level":"info","ts":"2026-09-22T02:01:19.215858Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"eae99266654eff69","cluster-id":"6d4184aac664e4ac"} {"level":"info","ts":"2026-09-22T02:01:19.215927Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eae99266654eff69 switched to configuration voters=()"} {"level":"info","ts":"2026-09-22T02:01:19.215966Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eae99266654eff69 became follower at term 0"} {"level":"info","ts":"2026-09-22T02:01:19.215987Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft eae99266654eff69 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-22T02:01:19.216062Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eae99266654eff69 became follower at term 1"} {"level":"info","ts":"2026-09-22T02:01:19.21616Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eae99266654eff69 switched to configuration voters=(16927221642932715369)"} {"level":"warn","ts":"2026-09-22T02:01:19.22402Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-22T02:01:19.225653Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-22T02:01:19.227713Z","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-09-22T02:01:19.22996Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"eae99266654eff69","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-22T02:01:19.232614Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"eae99266654eff69","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-22T02:01:19.232586Z","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-09-22T02:01:19.232716Z","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-09-22T02:01:19.232732Z","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-09-22T02:01:19.23416Z","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-09-22T02:01:19.234366Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.20:2380"} {"level":"info","ts":"2026-09-22T02:01:19.234396Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.20:2380"} {"level":"info","ts":"2026-09-22T02:01:19.234711Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eae99266654eff69 switched to configuration voters=(16927221642932715369)"} {"level":"info","ts":"2026-09-22T02:01:19.235077Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"eae99266654eff69","initial-advertise-peer-urls":["https://162.253.55.20:2380"],"listen-peer-urls":["https://162.253.55.20:2380"],"advertise-client-urls":["https://162.253.55.20:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.20:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-22T02:01:19.235116Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-22T02:01:19.235112Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6d4184aac664e4ac","local-member-id":"eae99266654eff69","added-peer-id":"eae99266654eff69","added-peer-peer-urls":["https://162.253.55.20:2380"]} {"level":"info","ts":"2026-09-22T02:01:19.517083Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eae99266654eff69 is starting a new election at term 1"} {"level":"info","ts":"2026-09-22T02:01:19.517205Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eae99266654eff69 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-22T02:01:19.517262Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eae99266654eff69 received MsgPreVoteResp from eae99266654eff69 at term 1"} {"level":"info","ts":"2026-09-22T02:01:19.517334Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eae99266654eff69 became candidate at term 2"} {"level":"info","ts":"2026-09-22T02:01:19.51742Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eae99266654eff69 received MsgVoteResp from eae99266654eff69 at term 2"} {"level":"info","ts":"2026-09-22T02:01:19.517494Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eae99266654eff69 became leader at term 2"} {"level":"info","ts":"2026-09-22T02:01:19.517559Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: eae99266654eff69 elected leader eae99266654eff69 at term 2"} {"level":"info","ts":"2026-09-22T02:01:19.519053Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-22T02:01:19.520657Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"eae99266654eff69","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.20:2379]}","request-path":"/0/members/eae99266654eff69/attributes","cluster-id":"6d4184aac664e4ac","publish-timeout":"7s"} {"level":"info","ts":"2026-09-22T02:01:19.520684Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-22T02:01:19.520755Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-22T02:01:19.520981Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-22T02:01:19.521015Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-22T02:01:19.520989Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6d4184aac664e4ac","local-member-id":"eae99266654eff69","cluster-version":"3.5"} {"level":"info","ts":"2026-09-22T02:01:19.521168Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-22T02:01:19.521334Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-22T02:01:19.525127Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.20:2379"} {"level":"info","ts":"2026-09-22T02:01:19.525325Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-22T02:01:21.487497Z","caller":"traceutil/trace.go:171","msg":"trace[340349307] linearizableReadLoop","detail":"{readStateIndex:5; appliedIndex:4; }","duration":"309.411215ms","start":"2026-09-22T02:01:21.178058Z","end":"2026-09-22T02:01:21.48747Z","steps":["trace[340349307] 'read index received' (duration: 309.2087ms)","trace[340349307] 'applied index is now lower than readState.Index' (duration: 201.285µs)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:01:21.487695Z","caller":"traceutil/trace.go:171","msg":"trace[499293950] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"309.726433ms","start":"2026-09-22T02:01:21.177955Z","end":"2026-09-22T02:01:21.487682Z","steps":["trace[499293950] 'process raft request' (duration: 309.248541ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:01:21.487954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.810615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-22T02:01:21.488134Z","caller":"traceutil/trace.go:171","msg":"trace[1809193966] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:2; }","duration":"310.076002ms","start":"2026-09-22T02:01:21.178037Z","end":"2026-09-22T02:01:21.488113Z","steps":["trace[1809193966] 'agreement among raft nodes before linearized reading' (duration: 309.678252ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:01:21.48818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:01:21.17803Z","time spent":"310.139393ms","remote":"127.0.0.1:60632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-09-22T02:01:21.488576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:01:21.177941Z","time spent":"309.906097ms","remote":"127.0.0.1:60490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-22T02:01:21.758187Z","caller":"traceutil/trace.go:171","msg":"trace[833633184] linearizableReadLoop","detail":"{readStateIndex:12; appliedIndex:5; }","duration":"270.502926ms","start":"2026-09-22T02:01:21.487645Z","end":"2026-09-22T02:01:21.758148Z","steps":["trace[833633184] 'read index received' (duration: 269.818801ms)","trace[833633184] 'applied index is now lower than readState.Index' (duration: 682.575µs)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:01:21.758269Z","caller":"traceutil/trace.go:171","msg":"trace[112419540] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"499.269769ms","start":"2026-09-22T02:01:21.258925Z","end":"2026-09-22T02:01:21.758195Z","steps":["trace[112419540] 'process raft request' (duration: 498.992432ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:01:21.758346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.333257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-09-22T02:01:21.758503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:01:21.258901Z","time spent":"499.503255ms","remote":"127.0.0.1:60758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-22T02:01:21.758507Z","caller":"traceutil/trace.go:171","msg":"trace[627985413] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"499.130666ms","start":"2026-09-22T02:01:21.259347Z","end":"2026-09-22T02:01:21.758477Z","steps":["trace[627985413] 'process raft request' (duration: 498.705255ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:01:21.758581Z","caller":"traceutil/trace.go:171","msg":"trace[1651388841] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"499.393893ms","start":"2026-09-22T02:01:21.259127Z","end":"2026-09-22T02:01:21.758521Z","steps":["trace[1651388841] 'process raft request' (duration: 498.892391ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:01:21.758612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:01:21.25934Z","time spent":"499.235769ms","remote":"127.0.0.1:60758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-22T02:01:21.758715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:01:21.259116Z","time spent":"499.520416ms","remote":"127.0.0.1:60758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-22T02:01:21.758746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.86773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-22T02:01:21.758778Z","caller":"traceutil/trace.go:171","msg":"trace[181860091] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:0; response_revision:9; }","duration":"576.907941ms","start":"2026-09-22T02:01:21.181858Z","end":"2026-09-22T02:01:21.758766Z","steps":["trace[181860091] 'agreement among raft nodes before linearized reading' (duration: 576.847659ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:01:21.758804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:01:21.181854Z","time spent":"576.944311ms","remote":"127.0.0.1:60264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":0,"response size":27,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"info","ts":"2026-09-22T02:01:21.758815Z","caller":"traceutil/trace.go:171","msg":"trace[1948556402] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"499.948775ms","start":"2026-09-22T02:01:21.258849Z","end":"2026-09-22T02:01:21.758798Z","steps":["trace[1948556402] 'process raft request' (duration: 498.973562ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:01:21.758817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.670879ms","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-09-22T02:01:21.75888Z","caller":"traceutil/trace.go:171","msg":"trace[1893462397] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:9; }","duration":"499.808692ms","start":"2026-09-22T02:01:21.259051Z","end":"2026-09-22T02:01:21.75886Z","steps":["trace[1893462397] 'agreement among raft nodes before linearized reading' (duration: 499.649928ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:01:21.758892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:01:21.258829Z","time spent":"500.020447ms","remote":"127.0.0.1:60758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-22T02:01:21.758898Z","caller":"traceutil/trace.go:171","msg":"trace[1379616826] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"500.014306ms","start":"2026-09-22T02:01:21.258869Z","end":"2026-09-22T02:01:21.758884Z","steps":["trace[1379616826] 'process raft request' (duration: 498.998452ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:01:21.758927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:01:21.259042Z","time spent":"499.872163ms","remote":"127.0.0.1:60294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"info","ts":"2026-09-22T02:01:21.758965Z","caller":"traceutil/trace.go:171","msg":"trace[1283960314] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"500.10461ms","start":"2026-09-22T02:01:21.258796Z","end":"2026-09-22T02:01:21.758901Z","steps":["trace[1283960314] 'process raft request' (duration: 498.721577ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:01:21.758997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.171561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.20\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-09-22T02:01:21.759012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:01:21.258862Z","time spent":"500.108999ms","remote":"127.0.0.1:60758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-22T02:01:21.759032Z","caller":"traceutil/trace.go:171","msg":"trace[1478672424] range","detail":"{range_begin:/registry/masterleases/162.253.55.20; range_end:; response_count:0; response_revision:9; }","duration":"500.202442ms","start":"2026-09-22T02:01:21.258818Z","end":"2026-09-22T02:01:21.75902Z","steps":["trace[1478672424] 'agreement among raft nodes before linearized reading' (duration: 500.15371ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:01:21.759059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:01:21.258806Z","time spent":"500.247163ms","remote":"127.0.0.1:60228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":27,"request content":"key:\"/registry/masterleases/162.253.55.20\" "} {"level":"warn","ts":"2026-09-22T02:01:21.759102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.985418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3170"} {"level":"info","ts":"2026-09-22T02:01:21.759139Z","caller":"traceutil/trace.go:171","msg":"trace[2111427107] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:9; }","duration":"581.030199ms","start":"2026-09-22T02:01:21.178096Z","end":"2026-09-22T02:01:21.759126Z","steps":["trace[2111427107] 'agreement among raft nodes before linearized reading' (duration: 580.948547ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:01:21.759112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:01:21.258765Z","time spent":"500.259404ms","remote":"127.0.0.1:60690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-22T02:01:21.759172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:01:21.177879Z","time spent":"581.284755ms","remote":"127.0.0.1:60392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3193,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-09-22T02:01:21.759227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.48528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-22T02:01:21.759254Z","caller":"traceutil/trace.go:171","msg":"trace[1694944324] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:9; }","duration":"500.537561ms","start":"2026-09-22T02:01:21.258707Z","end":"2026-09-22T02:01:21.759245Z","steps":["trace[1694944324] 'agreement among raft nodes before linearized reading' (duration: 500.483558ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:01:21.759278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:01:21.258688Z","time spent":"500.583992ms","remote":"127.0.0.1:60326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-09-22T02:01:21.759388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.977626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-09-22T02:01:21.759413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.304735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3170"} {"level":"info","ts":"2026-09-22T02:01:21.759425Z","caller":"traceutil/trace.go:171","msg":"trace[279430624] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9; }","duration":"578.020727ms","start":"2026-09-22T02:01:21.181392Z","end":"2026-09-22T02:01:21.759413Z","steps":["trace[279430624] 'agreement among raft nodes before linearized reading' (duration: 577.964005ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:01:21.759431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.997621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-22T02:01:21.759451Z","caller":"traceutil/trace.go:171","msg":"trace[894255918] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:9; }","duration":"268.371056ms","start":"2026-09-22T02:01:21.491068Z","end":"2026-09-22T02:01:21.759439Z","steps":["trace[894255918] 'agreement among raft nodes before linearized reading' (duration: 268.290314ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:01:21.759462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:01:21.181388Z","time spent":"578.066668ms","remote":"127.0.0.1:60192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-22T02:01:21.759479Z","caller":"traceutil/trace.go:171","msg":"trace[388030106] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:0; response_revision:9; }","duration":"579.058942ms","start":"2026-09-22T02:01:21.180405Z","end":"2026-09-22T02:01:21.759464Z","steps":["trace[388030106] 'agreement among raft nodes before linearized reading' (duration: 578.98215ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:01:21.759525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:01:21.180395Z","time spent":"579.120363ms","remote":"127.0.0.1:60258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"warn","ts":"2026-09-22T02:01:21.759541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.293551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-22T02:01:21.759667Z","caller":"traceutil/trace.go:171","msg":"trace[1554386844] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:9; }","duration":"575.331852ms","start":"2026-09-22T02:01:21.184233Z","end":"2026-09-22T02:01:21.759565Z","steps":["trace[1554386844] 'agreement among raft nodes before linearized reading' (duration: 575.276151ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:01:21.759478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.517548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-09-22T02:01:21.759695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:01:21.18423Z","time spent":"575.458655ms","remote":"127.0.0.1:60500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-22T02:01:21.759624Z","caller":"traceutil/trace.go:171","msg":"trace[1700640830] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"488.783197ms","start":"2026-09-22T02:01:21.270827Z","end":"2026-09-22T02:01:21.75961Z","steps":["trace[1700640830] 'process raft request' (duration: 487.25669ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:01:21.759773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.553562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-sfdcn\" ","response":"range_response_count:1 size:861"} {"level":"warn","ts":"2026-09-22T02:01:21.759802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:01:21.270814Z","time spent":"488.953942ms","remote":"127.0.0.1:60392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3158,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-22T02:01:21.759909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.663419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-22T02:01:21.759708Z","caller":"traceutil/trace.go:171","msg":"trace[1350034383] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:9; }","duration":"524.825495ms","start":"2026-09-22T02:01:21.23487Z","end":"2026-09-22T02:01:21.759696Z","steps":["trace[1350034383] 'agreement among raft nodes before linearized reading' (duration: 524.563539ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:01:21.759984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:01:21.234833Z","time spent":"525.142543ms","remote":"127.0.0.1:60326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-09-22T02:01:21.759989Z","caller":"traceutil/trace.go:171","msg":"trace[1572028246] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:9; }","duration":"575.756642ms","start":"2026-09-22T02:01:21.184208Z","end":"2026-09-22T02:01:21.759965Z","steps":["trace[1572028246] 'agreement among raft nodes before linearized reading' (duration: 575.624789ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:01:21.760054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:01:21.184202Z","time spent":"575.841254ms","remote":"127.0.0.1:60500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-09-22T02:01:21.758438Z","caller":"traceutil/trace.go:171","msg":"trace[1767703611] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:9; }","duration":"449.46124ms","start":"2026-09-22T02:01:21.308952Z","end":"2026-09-22T02:01:21.758413Z","steps":["trace[1767703611] 'agreement among raft nodes before linearized reading' (duration: 449.222893ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:01:21.759809Z","caller":"traceutil/trace.go:171","msg":"trace[578774043] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-sfdcn; range_end:; response_count:1; response_revision:9; }","duration":"266.616543ms","start":"2026-09-22T02:01:21.493182Z","end":"2026-09-22T02:01:21.759798Z","steps":["trace[578774043] 'agreement among raft nodes before linearized reading' (duration: 266.542392ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:01:21.760193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:01:21.308892Z","time spent":"451.288904ms","remote":"127.0.0.1:60326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-09-22T02:02:01.148273Z","caller":"traceutil/trace.go:171","msg":"trace[83416567] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"190.408588ms","start":"2026-09-22T02:02:00.957832Z","end":"2026-09-22T02:02:01.14824Z","steps":["trace[83416567] 'process raft request' (duration: 190.157903ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:02:01.159095Z","caller":"traceutil/trace.go:171","msg":"trace[559901977] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"196.440769ms","start":"2026-09-22T02:02:00.96262Z","end":"2026-09-22T02:02:01.159061Z","steps":["trace[559901977] 'process raft request' (duration: 196.144943ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:02:01.159861Z","caller":"traceutil/trace.go:171","msg":"trace[1918420714] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"184.378715ms","start":"2026-09-22T02:02:00.975445Z","end":"2026-09-22T02:02:01.159824Z","steps":["trace[1918420714] 'process raft request' (duration: 183.437157ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:02:01.160231Z","caller":"traceutil/trace.go:171","msg":"trace[1660733227] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"164.801722ms","start":"2026-09-22T02:02:00.995413Z","end":"2026-09-22T02:02:01.160215Z","steps":["trace[1660733227] 'process raft request' (duration: 163.840354ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:02:01.160591Z","caller":"traceutil/trace.go:171","msg":"trace[1853195274] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"168.637153ms","start":"2026-09-22T02:02:00.991944Z","end":"2026-09-22T02:02:01.160581Z","steps":["trace[1853195274] 'process raft request' (duration: 167.252407ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:02:01.160303Z","caller":"traceutil/trace.go:171","msg":"trace[1068762864] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"153.640334ms","start":"2026-09-22T02:02:01.006647Z","end":"2026-09-22T02:02:01.160287Z","steps":["trace[1068762864] 'process raft request' (duration: 152.693046ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:02:01.160346Z","caller":"traceutil/trace.go:171","msg":"trace[1328466190] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"153.605325ms","start":"2026-09-22T02:02:01.00673Z","end":"2026-09-22T02:02:01.160335Z","steps":["trace[1328466190] 'process raft request' (duration: 152.661527ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:02:01.160379Z","caller":"traceutil/trace.go:171","msg":"trace[353586710] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"130.005939ms","start":"2026-09-22T02:02:01.030365Z","end":"2026-09-22T02:02:01.160371Z","steps":["trace[353586710] 'process raft request' (duration: 129.077132ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:02:01.160506Z","caller":"traceutil/trace.go:171","msg":"trace[1851219547] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"174.804668ms","start":"2026-09-22T02:02:00.985602Z","end":"2026-09-22T02:02:01.160407Z","steps":["trace[1851219547] 'process raft request' (duration: 173.468173ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:02:01.160542Z","caller":"traceutil/trace.go:171","msg":"trace[1037317245] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"181.365959ms","start":"2026-09-22T02:02:00.979164Z","end":"2026-09-22T02:02:01.16053Z","steps":["trace[1037317245] 'process raft request' (duration: 179.856211ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:02:01.160573Z","caller":"traceutil/trace.go:171","msg":"trace[882025144] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"172.951553ms","start":"2026-09-22T02:02:00.98761Z","end":"2026-09-22T02:02:01.160562Z","steps":["trace[882025144] 'process raft request' (duration: 171.527046ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:02:01.515127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.574978ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-22T02:02:01.515415Z","caller":"traceutil/trace.go:171","msg":"trace[618258914] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"320.772878ms","start":"2026-09-22T02:02:01.194629Z","end":"2026-09-22T02:02:01.515401Z","steps":["trace[618258914] 'process raft request' (duration: 320.718437ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:02:01.515463Z","caller":"traceutil/trace.go:171","msg":"trace[308646871] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"329.208355ms","start":"2026-09-22T02:02:01.186168Z","end":"2026-09-22T02:02:01.515377Z","steps":["trace[308646871] 'process raft request' (duration: 191.227209ms)","trace[308646871] 'compare' (duration: 137.449486ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:02:01.515605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:02:01.186144Z","time spent":"329.394688ms","remote":"127.0.0.1:60758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-22T02:02:01.515621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:02:01.19462Z","time spent":"320.854441ms","remote":"127.0.0.1:60250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:02:01.51578Z","caller":"traceutil/trace.go:171","msg":"trace[390994510] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"329.51722ms","start":"2026-09-22T02:02:01.186254Z","end":"2026-09-22T02:02:01.515771Z","steps":["trace[390994510] 'process raft request' (duration: 328.94867ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:02:01.515811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:02:01.18625Z","time spent":"329.54626ms","remote":"127.0.0.1:60758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-22T02:02:01.516015Z","caller":"traceutil/trace.go:171","msg":"trace[1797753623] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"329.249275ms","start":"2026-09-22T02:02:01.186758Z","end":"2026-09-22T02:02:01.516008Z","steps":["trace[1797753623] 'process raft request' (duration: 328.47707ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:02:01.516048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:02:01.186751Z","time spent":"329.277055ms","remote":"127.0.0.1:60250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:02:01.516044Z","caller":"traceutil/trace.go:171","msg":"trace[105867467] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"322.854546ms","start":"2026-09-22T02:02:01.193043Z","end":"2026-09-22T02:02:01.515898Z","steps":["trace[105867467] 'process raft request' (duration: 322.233835ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:02:01.5162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:02:01.193015Z","time spent":"323.114621ms","remote":"127.0.0.1:60250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T02:02:02.194457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.243818ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-22T02:02:02.194802Z","caller":"traceutil/trace.go:171","msg":"trace[1862389845] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"954.228658ms","start":"2026-09-22T02:02:01.240552Z","end":"2026-09-22T02:02:02.194781Z","steps":["trace[1862389845] 'process raft request' (duration: 954.158017ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:02:02.194826Z","caller":"traceutil/trace.go:171","msg":"trace[176762793] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"997.084347ms","start":"2026-09-22T02:02:01.197699Z","end":"2026-09-22T02:02:02.194784Z","steps":["trace[176762793] 'process raft request' (duration: 430.443602ms)","trace[176762793] 'compare' (duration: 566.072334ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:02:02.19486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:02:01.24053Z","time spent":"954.304609ms","remote":"127.0.0.1:60250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-22T02:02:02.194844Z","caller":"traceutil/trace.go:171","msg":"trace[1215418382] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"981.078722ms","start":"2026-09-22T02:02:01.21372Z","end":"2026-09-22T02:02:02.194799Z","steps":["trace[1215418382] 'process raft request' (duration: 980.843187ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:02:02.194955Z","caller":"traceutil/trace.go:171","msg":"trace[787011121] linearizableReadLoop","detail":"{readStateIndex:542; appliedIndex:537; }","duration":"770.333723ms","start":"2026-09-22T02:02:01.424579Z","end":"2026-09-22T02:02:02.194913Z","steps":["trace[787011121] 'read index received' (duration: 203.578166ms)","trace[787011121] 'applied index is now lower than readState.Index' (duration: 566.754527ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:02:02.194995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:02:01.197691Z","time spent":"997.206759ms","remote":"127.0.0.1:60758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-22T02:02:02.195072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:02:01.213675Z","time spent":"981.270915ms","remote":"127.0.0.1:60250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:02:02.195129Z","caller":"traceutil/trace.go:171","msg":"trace[282269649] transaction","detail":"{read_only:false; number_of_response:1; response_revision:522; }","duration":"946.432293ms","start":"2026-09-22T02:02:01.248686Z","end":"2026-09-22T02:02:02.195118Z","steps":["trace[282269649] 'process raft request' (duration: 946.059676ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:02:02.195214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:02:01.248658Z","time spent":"946.496453ms","remote":"127.0.0.1:60250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":101,"response count":0,"response size":10112,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:02:02.195277Z","caller":"traceutil/trace.go:171","msg":"trace[1165186147] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"960.192255ms","start":"2026-09-22T02:02:01.235071Z","end":"2026-09-22T02:02:02.195263Z","steps":["trace[1165186147] 'process raft request' (duration: 959.576923ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:02:02.195322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"770.74424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-22T02:02:02.195338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:02:01.235017Z","time spent":"960.288967ms","remote":"127.0.0.1:60250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58844,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:02:02.195352Z","caller":"traceutil/trace.go:171","msg":"trace[773206607] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:522; }","duration":"770.834611ms","start":"2026-09-22T02:02:01.424507Z","end":"2026-09-22T02:02:02.195342Z","steps":["trace[773206607] 'agreement among raft nodes before linearized reading' (duration: 770.789481ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:02:02.19538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:02:01.424475Z","time spent":"770.900024ms","remote":"127.0.0.1:60192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-22T02:02:02.277939Z","caller":"traceutil/trace.go:171","msg":"trace[1424123182] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"737.403268ms","start":"2026-09-22T02:02:01.54051Z","end":"2026-09-22T02:02:02.277913Z","steps":["trace[1424123182] 'process raft request' (duration: 736.783896ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:02:02.278135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:02:01.540465Z","time spent":"737.56508ms","remote":"127.0.0.1:60250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:02:02.278263Z","caller":"traceutil/trace.go:171","msg":"trace[1110087607] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"730.219575ms","start":"2026-09-22T02:02:01.548029Z","end":"2026-09-22T02:02:02.278249Z","steps":["trace[1110087607] 'process raft request' (duration: 729.454001ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:02:02.278213Z","caller":"traceutil/trace.go:171","msg":"trace[135421172] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"687.321873ms","start":"2026-09-22T02:02:01.59087Z","end":"2026-09-22T02:02:02.278192Z","steps":["trace[135421172] 'process raft request' (duration: 686.837934ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:02:02.278376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:02:01.547993Z","time spent":"730.329526ms","remote":"127.0.0.1:60250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79947,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T02:02:02.278468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.568977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-09-22T02:02:02.278561Z","caller":"traceutil/trace.go:171","msg":"trace[363961791] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:525; }","duration":"250.7053ms","start":"2026-09-22T02:02:02.027836Z","end":"2026-09-22T02:02:02.278541Z","steps":["trace[363961791] 'agreement among raft nodes before linearized reading' (duration: 250.495346ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:02:02.278474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:02:01.59083Z","time spent":"687.545957ms","remote":"127.0.0.1:60250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T02:02:02.278597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.27522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-22T02:02:02.278687Z","caller":"traceutil/trace.go:171","msg":"trace[83541128] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:525; }","duration":"251.401363ms","start":"2026-09-22T02:02:02.027237Z","end":"2026-09-22T02:02:02.278638Z","steps":["trace[83541128] 'agreement among raft nodes before linearized reading' (duration: 251.272971ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:02:02.279087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.038724ms","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-09-22T02:02:02.279186Z","caller":"traceutil/trace.go:171","msg":"trace[1079763273] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:525; }","duration":"477.162446ms","start":"2026-09-22T02:02:01.802001Z","end":"2026-09-22T02:02:02.279163Z","steps":["trace[1079763273] 'agreement among raft nodes before linearized reading' (duration: 476.350721ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:02:02.279238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:02:01.801983Z","time spent":"477.240298ms","remote":"127.0.0.1:60500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-22T02:02:02.279518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"722.192497ms","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-09-22T02:02:02.279638Z","caller":"traceutil/trace.go:171","msg":"trace[554902200] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:525; }","duration":"722.338479ms","start":"2026-09-22T02:02:01.557234Z","end":"2026-09-22T02:02:02.279572Z","steps":["trace[554902200] 'agreement among raft nodes before linearized reading' (duration: 721.190438ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:02:02.279735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:02:01.557197Z","time spent":"722.485463ms","remote":"127.0.0.1:60500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-22T02:02:02.315288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.414935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T02:02:02.31536Z","caller":"traceutil/trace.go:171","msg":"trace[108023008] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:527; }","duration":"117.505146ms","start":"2026-09-22T02:02:02.197839Z","end":"2026-09-22T02:02:02.315344Z","steps":["trace[108023008] 'agreement among raft nodes before linearized reading' (duration: 117.367574ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:02:02.315695Z","caller":"traceutil/trace.go:171","msg":"trace[2015303288] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"107.646055ms","start":"2026-09-22T02:02:02.208039Z","end":"2026-09-22T02:02:02.315685Z","steps":["trace[2015303288] 'process raft request' (duration: 101.652165ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:02:02.315916Z","caller":"traceutil/trace.go:171","msg":"trace[1015559043] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"105.070988ms","start":"2026-09-22T02:02:02.210835Z","end":"2026-09-22T02:02:02.315906Z","steps":["trace[1015559043] 'process raft request' (duration: 104.311425ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:02:07.989989Z","caller":"traceutil/trace.go:171","msg":"trace[2065144197] linearizableReadLoop","detail":"{readStateIndex:625; appliedIndex:624; }","duration":"122.631721ms","start":"2026-09-22T02:02:07.867313Z","end":"2026-09-22T02:02:07.989945Z","steps":["trace[2065144197] 'read index received' (duration: 121.629784ms)","trace[2065144197] 'applied index is now lower than readState.Index' (duration: 1.001127ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:02:07.990257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.929416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-7plpm\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-09-22T02:02:07.990313Z","caller":"traceutil/trace.go:171","msg":"trace[60643064] range","detail":"{range_begin:/registry/pods/kube-system/cilium-7plpm; range_end:; response_count:1; response_revision:604; }","duration":"123.040798ms","start":"2026-09-22T02:02:07.86726Z","end":"2026-09-22T02:02:07.990301Z","steps":["trace[60643064] 'agreement among raft nodes before linearized reading' (duration: 122.830375ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:02:07.990292Z","caller":"traceutil/trace.go:171","msg":"trace[631084554] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"189.354313ms","start":"2026-09-22T02:02:07.80089Z","end":"2026-09-22T02:02:07.990244Z","steps":["trace[631084554] 'process raft request' (duration: 188.363356ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:02:08.153715Z","caller":"traceutil/trace.go:171","msg":"trace[279041669] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"134.965549ms","start":"2026-09-22T02:02:08.018723Z","end":"2026-09-22T02:02:08.153688Z","steps":["trace[279041669] 'process raft request' (duration: 134.818337ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:02:08.802584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.915071ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-09-22T02:02:08.802879Z","caller":"traceutil/trace.go:171","msg":"trace[1817710548] linearizableReadLoop","detail":"{readStateIndex:628; appliedIndex:627; }","duration":"439.613506ms","start":"2026-09-22T02:02:08.363156Z","end":"2026-09-22T02:02:08.80277Z","steps":["trace[1817710548] 'read index received' (duration: 274.43297ms)","trace[1817710548] 'applied index is now lower than readState.Index' (duration: 165.179036ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:02:08.803771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.154375ms","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-09-22T02:02:08.803905Z","caller":"traceutil/trace.go:171","msg":"trace[1603241528] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:606; }","duration":"440.775316ms","start":"2026-09-22T02:02:08.363116Z","end":"2026-09-22T02:02:08.803891Z","steps":["trace[1603241528] 'agreement among raft nodes before linearized reading' (duration: 439.850449ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:02:08.804003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:02:08.363077Z","time spent":"440.911769ms","remote":"127.0.0.1:60500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-22T02:02:08.80965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.941471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-09-22T02:02:08.809781Z","caller":"traceutil/trace.go:171","msg":"trace[529206415] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:607; }","duration":"440.110714ms","start":"2026-09-22T02:02:08.369651Z","end":"2026-09-22T02:02:08.809762Z","steps":["trace[529206415] 'agreement among raft nodes before linearized reading' (duration: 439.891621ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:02:08.809797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.632646ms","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-09-22T02:02:08.809673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.003876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T02:02:08.809908Z","caller":"traceutil/trace.go:171","msg":"trace[751992875] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:607; }","duration":"439.774138ms","start":"2026-09-22T02:02:08.37011Z","end":"2026-09-22T02:02:08.809884Z","steps":["trace[751992875] 'agreement among raft nodes before linearized reading' (duration: 439.600085ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:02:08.809962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:02:08.370089Z","time spent":"439.860509ms","remote":"127.0.0.1:60500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-09-22T02:02:08.809955Z","caller":"traceutil/trace.go:171","msg":"trace[1483454385] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:607; }","duration":"384.300902ms","start":"2026-09-22T02:02:08.425597Z","end":"2026-09-22T02:02:08.809898Z","steps":["trace[1483454385] 'agreement among raft nodes before linearized reading' (duration: 384.006597ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:02:08.809682Z","caller":"traceutil/trace.go:171","msg":"trace[434970275] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"269.843458ms","start":"2026-09-22T02:02:08.539816Z","end":"2026-09-22T02:02:08.809659Z","steps":["trace[434970275] 'process raft request' (duration: 268.384321ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:02:08.810028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:02:08.425578Z","time spent":"384.427773ms","remote":"127.0.0.1:60192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-22T02:02:08.809986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:02:08.369642Z","time spent":"440.227526ms","remote":"127.0.0.1:60500","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-09-22T02:02:08.810909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.984262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-09-22T02:02:08.811063Z","caller":"traceutil/trace.go:171","msg":"trace[1159428611] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:607; }","duration":"440.104034ms","start":"2026-09-22T02:02:08.370895Z","end":"2026-09-22T02:02:08.810999Z","steps":["trace[1159428611] 'agreement among raft nodes before linearized reading' (duration: 439.377822ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:02:08.811115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:02:08.370888Z","time spent":"440.215207ms","remote":"127.0.0.1:60500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-22T02:02:25.02595Z","caller":"traceutil/trace.go:171","msg":"trace[623278268] linearizableReadLoop","detail":"{readStateIndex:800; appliedIndex:799; }","duration":"128.546599ms","start":"2026-09-22T02:02:24.897338Z","end":"2026-09-22T02:02:25.025885Z","steps":["trace[623278268] 'read index received' (duration: 128.275964ms)","trace[623278268] 'applied index is now lower than readState.Index' (duration: 266.455µs)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:02:25.026003Z","caller":"traceutil/trace.go:171","msg":"trace[426246409] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"138.247229ms","start":"2026-09-22T02:02:24.88773Z","end":"2026-09-22T02:02:25.025977Z","steps":["trace[426246409] 'process raft request' (duration: 137.949924ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:02:25.026293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.920935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-09-22T02:02:25.026311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.665128ms","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-09-22T02:02:25.026356Z","caller":"traceutil/trace.go:171","msg":"trace[334529611] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:773; }","duration":"129.038717ms","start":"2026-09-22T02:02:24.897302Z","end":"2026-09-22T02:02:25.026341Z","steps":["trace[334529611] 'agreement among raft nodes before linearized reading' (duration: 128.770252ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:02:25.02637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.35277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-09-22T02:02:25.026413Z","caller":"traceutil/trace.go:171","msg":"trace[708748212] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:773; }","duration":"123.83122ms","start":"2026-09-22T02:02:24.902555Z","end":"2026-09-22T02:02:25.026386Z","steps":["trace[708748212] 'agreement among raft nodes before linearized reading' (duration: 123.561096ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:02:25.026444Z","caller":"traceutil/trace.go:171","msg":"trace[2043039123] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:773; }","duration":"121.486053ms","start":"2026-09-22T02:02:24.904934Z","end":"2026-09-22T02:02:25.02642Z","steps":["trace[2043039123] 'agreement among raft nodes before linearized reading' (duration: 121.278949ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:02:25.178028Z","caller":"traceutil/trace.go:171","msg":"trace[903489301] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"146.207292ms","start":"2026-09-22T02:02:25.031792Z","end":"2026-09-22T02:02:25.177999Z","steps":["trace[903489301] 'process raft request' (duration: 138.610498ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:02:25.18036Z","caller":"traceutil/trace.go:171","msg":"trace[1063226519] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"148.382077ms","start":"2026-09-22T02:02:25.031963Z","end":"2026-09-22T02:02:25.180345Z","steps":["trace[1063226519] 'process raft request' (duration: 148.197404ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:02:25.180591Z","caller":"traceutil/trace.go:171","msg":"trace[364234595] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"148.017972ms","start":"2026-09-22T02:02:25.032562Z","end":"2026-09-22T02:02:25.18058Z","steps":["trace[364234595] 'process raft request' (duration: 147.682756ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:02:25.18059Z","caller":"traceutil/trace.go:171","msg":"trace[2045721750] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"143.69242ms","start":"2026-09-22T02:02:25.036859Z","end":"2026-09-22T02:02:25.180552Z","steps":["trace[2045721750] 'process raft request' (duration: 143.430495ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:02:39.397074Z","caller":"traceutil/trace.go:171","msg":"trace[85627612] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"104.420554ms","start":"2026-09-22T02:02:39.292625Z","end":"2026-09-22T02:02:39.397046Z","steps":["trace[85627612] 'process raft request' (duration: 104.375573ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:02:39.397197Z","caller":"traceutil/trace.go:171","msg":"trace[1710294650] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"106.765991ms","start":"2026-09-22T02:02:39.290411Z","end":"2026-09-22T02:02:39.397177Z","steps":["trace[1710294650] 'process raft request' (duration: 88.986205ms)","trace[1710294650] 'compare' (duration: 17.509401ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:02:57.614822Z","caller":"traceutil/trace.go:171","msg":"trace[1234391456] transaction","detail":"{read_only:false; response_revision:1182; number_of_response:1; }","duration":"150.919455ms","start":"2026-09-22T02:02:57.463864Z","end":"2026-09-22T02:02:57.614784Z","steps":["trace[1234391456] 'process raft request' (duration: 150.56739ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:03:13.009045Z","caller":"traceutil/trace.go:171","msg":"trace[443978681] linearizableReadLoop","detail":"{readStateIndex:1352; appliedIndex:1351; }","duration":"171.812456ms","start":"2026-09-22T02:03:12.837205Z","end":"2026-09-22T02:03:13.009018Z","steps":["trace[443978681] 'read index received' (duration: 97.443295ms)","trace[443978681] 'applied index is now lower than readState.Index' (duration: 74.367911ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:03:13.009208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.975978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-22T02:03:13.009238Z","caller":"traceutil/trace.go:171","msg":"trace[1305224694] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1312; }","duration":"172.057109ms","start":"2026-09-22T02:03:12.837172Z","end":"2026-09-22T02:03:13.009229Z","steps":["trace[1305224694] 'agreement among raft nodes before linearized reading' (duration: 171.944078ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:03:13.00927Z","caller":"traceutil/trace.go:171","msg":"trace[1387973689] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"193.550742ms","start":"2026-09-22T02:03:12.81567Z","end":"2026-09-22T02:03:13.009221Z","steps":["trace[1387973689] 'process raft request' (duration: 118.971059ms)","trace[1387973689] 'compare' (duration: 74.270389ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:03:18.576586Z","caller":"traceutil/trace.go:171","msg":"trace[733928227] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1347; }","duration":"128.512119ms","start":"2026-09-22T02:03:18.448048Z","end":"2026-09-22T02:03:18.57656Z","steps":["trace[733928227] 'process raft request' (duration: 125.405876ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:03:18.706908Z","caller":"traceutil/trace.go:171","msg":"trace[885279606] transaction","detail":"{read_only:false; response_revision:1350; number_of_response:1; }","duration":"105.51976ms","start":"2026-09-22T02:03:18.601361Z","end":"2026-09-22T02:03:18.706881Z","steps":["trace[885279606] 'process raft request' (duration: 104.541337ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:03:49.957796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.406113ms","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-09-22T02:03:49.957905Z","caller":"traceutil/trace.go:171","msg":"trace[791055519] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1866; }","duration":"391.572895ms","start":"2026-09-22T02:03:49.566309Z","end":"2026-09-22T02:03:49.957882Z","steps":["trace[791055519] 'count revisions from in-memory index tree' (duration: 391.313152ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:03:49.95818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:03:49.566285Z","time spent":"391.877259ms","remote":"127.0.0.1:45372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-09-22T02:03:49.958324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.694023ms","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-09-22T02:03:49.958379Z","caller":"traceutil/trace.go:171","msg":"trace[1262302310] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1866; }","duration":"377.816334ms","start":"2026-09-22T02:03:49.580548Z","end":"2026-09-22T02:03:49.958365Z","steps":["trace[1262302310] 'range keys from in-memory index tree' (duration: 377.405069ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:03:49.958408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:03:49.580509Z","time spent":"377.891195ms","remote":"127.0.0.1:60500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-22T02:03:49.959327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.366624ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-22T02:03:49.959476Z","caller":"traceutil/trace.go:171","msg":"trace[319651925] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"350.884199ms","start":"2026-09-22T02:03:49.608576Z","end":"2026-09-22T02:03:49.95946Z","steps":["trace[319651925] 'process raft request' (duration: 140.307412ms)","trace[319651925] 'compare' (duration: 208.790633ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:03:49.959562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:03:49.608555Z","time spent":"350.95968ms","remote":"127.0.0.1:60500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:03:49.959426Z","caller":"traceutil/trace.go:171","msg":"trace[658477160] linearizableReadLoop","detail":"{readStateIndex:1938; appliedIndex:1937; }","duration":"251.239634ms","start":"2026-09-22T02:03:49.708171Z","end":"2026-09-22T02:03:49.959411Z","steps":["trace[658477160] 'read index received' (duration: 40.695077ms)","trace[658477160] 'applied index is now lower than readState.Index' (duration: 210.542956ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:03:49.959704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.519248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-22T02:03:49.959768Z","caller":"traceutil/trace.go:171","msg":"trace[1270100646] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1867; }","duration":"251.623509ms","start":"2026-09-22T02:03:49.708126Z","end":"2026-09-22T02:03:49.95975Z","steps":["trace[1270100646] 'agreement among raft nodes before linearized reading' (duration: 251.429636ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:04:02.844157Z","caller":"traceutil/trace.go:171","msg":"trace[686667405] transaction","detail":"{read_only:false; response_revision:1971; number_of_response:1; }","duration":"147.640157ms","start":"2026-09-22T02:04:02.696492Z","end":"2026-09-22T02:04:02.844132Z","steps":["trace[686667405] 'process raft request' (duration: 147.506384ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:04:24.552281Z","caller":"traceutil/trace.go:171","msg":"trace[640627204] linearizableReadLoop","detail":"{readStateIndex:2184; appliedIndex:2183; }","duration":"127.081673ms","start":"2026-09-22T02:04:24.425176Z","end":"2026-09-22T02:04:24.552257Z","steps":["trace[640627204] 'read index received' (duration: 126.83929ms)","trace[640627204] 'applied index is now lower than readState.Index' (duration: 241.503µs)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:04:24.552428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.242055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T02:04:24.55248Z","caller":"traceutil/trace.go:171","msg":"trace[244059630] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2106; }","duration":"127.322676ms","start":"2026-09-22T02:04:24.425124Z","end":"2026-09-22T02:04:24.552447Z","steps":["trace[244059630] 'agreement among raft nodes before linearized reading' (duration: 127.222335ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:04:24.552616Z","caller":"traceutil/trace.go:171","msg":"trace[1171246811] transaction","detail":"{read_only:false; response_revision:2106; number_of_response:1; }","duration":"152.363403ms","start":"2026-09-22T02:04:24.400218Z","end":"2026-09-22T02:04:24.552581Z","steps":["trace[1171246811] 'process raft request' (duration: 151.857558ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:04:25.354971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.098939ms","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-09-22T02:04:25.355072Z","caller":"traceutil/trace.go:171","msg":"trace[874839454] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2108; }","duration":"144.28661ms","start":"2026-09-22T02:04:25.210761Z","end":"2026-09-22T02:04:25.355048Z","steps":["trace[874839454] 'range keys from in-memory index tree' (duration: 143.892815ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:06:12.975604Z","caller":"traceutil/trace.go:171","msg":"trace[475613390] transaction","detail":"{read_only:false; response_revision:2790; number_of_response:1; }","duration":"108.872862ms","start":"2026-09-22T02:06:12.866703Z","end":"2026-09-22T02:06:12.975576Z","steps":["trace[475613390] 'process raft request' (duration: 108.699307ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:06:13.038132Z","caller":"traceutil/trace.go:171","msg":"trace[2145901531] linearizableReadLoop","detail":"{readStateIndex:2893; appliedIndex:2891; }","duration":"126.686557ms","start":"2026-09-22T02:06:12.911413Z","end":"2026-09-22T02:06:13.0381Z","steps":["trace[2145901531] 'read index received' (duration: 63.989561ms)","trace[2145901531] 'applied index is now lower than readState.Index' (duration: 62.695826ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:06:13.038325Z","caller":"traceutil/trace.go:171","msg":"trace[572597099] transaction","detail":"{read_only:false; response_revision:2791; number_of_response:1; }","duration":"166.641595ms","start":"2026-09-22T02:06:12.871645Z","end":"2026-09-22T02:06:13.038287Z","steps":["trace[572597099] 'process raft request' (duration: 151.15828ms)","trace[572597099] 'compare' (duration: 15.164334ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:06:13.03846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.389493ms","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-09-22T02:06:13.038555Z","caller":"traceutil/trace.go:171","msg":"trace[717674205] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2791; }","duration":"122.476906ms","start":"2026-09-22T02:06:12.916047Z","end":"2026-09-22T02:06:13.038523Z","steps":["trace[717674205] 'agreement among raft nodes before linearized reading' (duration: 122.296181ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:06:13.038706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.290346ms","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-09-22T02:06:13.038737Z","caller":"traceutil/trace.go:171","msg":"trace[840891421] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2791; }","duration":"127.355218ms","start":"2026-09-22T02:06:12.911374Z","end":"2026-09-22T02:06:13.038729Z","steps":["trace[840891421] 'agreement among raft nodes before linearized reading' (duration: 127.277556ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:06:13.434573Z","caller":"traceutil/trace.go:171","msg":"trace[1869022779] linearizableReadLoop","detail":"{readStateIndex:2896; appliedIndex:2895; }","duration":"230.440326ms","start":"2026-09-22T02:06:13.204102Z","end":"2026-09-22T02:06:13.434542Z","steps":["trace[1869022779] 'read index received' (duration: 230.269681ms)","trace[1869022779] 'applied index is now lower than readState.Index' (duration: 168.615µs)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:06:13.434588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:06:13.095213Z","time spent":"339.368556ms","remote":"127.0.0.1:60228","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-09-22T02:06:13.434996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.873949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-09-22T02:06:13.435081Z","caller":"traceutil/trace.go:171","msg":"trace[1232824024] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2793; }","duration":"231.008174ms","start":"2026-09-22T02:06:13.204052Z","end":"2026-09-22T02:06:13.43506Z","steps":["trace[1232824024] 'agreement among raft nodes before linearized reading' (duration: 230.616531ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:06:13.4363Z","caller":"traceutil/trace.go:171","msg":"trace[2071249089] transaction","detail":"{read_only:false; response_revision:2794; number_of_response:1; }","duration":"106.810516ms","start":"2026-09-22T02:06:13.32947Z","end":"2026-09-22T02:06:13.43628Z","steps":["trace[2071249089] 'process raft request' (duration: 106.63547ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:10:42.731292Z","caller":"traceutil/trace.go:171","msg":"trace[2027041159] transaction","detail":"{read_only:false; response_revision:4899; number_of_response:1; }","duration":"153.323878ms","start":"2026-09-22T02:10:42.577945Z","end":"2026-09-22T02:10:42.731269Z","steps":["trace[2027041159] 'process raft request' (duration: 153.204226ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:10:42.734113Z","caller":"traceutil/trace.go:171","msg":"trace[1915108959] transaction","detail":"{read_only:false; response_revision:4900; number_of_response:1; }","duration":"152.063336ms","start":"2026-09-22T02:10:42.58203Z","end":"2026-09-22T02:10:42.734094Z","steps":["trace[1915108959] 'process raft request' (duration: 151.835301ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:10:47.79699Z","caller":"traceutil/trace.go:171","msg":"trace[967453664] transaction","detail":"{read_only:false; response_revision:4988; number_of_response:1; }","duration":"319.166263ms","start":"2026-09-22T02:10:47.477798Z","end":"2026-09-22T02:10:47.796964Z","steps":["trace[967453664] 'process raft request' (duration: 319.039141ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:10:47.79719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:10:47.477778Z","time spent":"319.343575ms","remote":"127.0.0.1:60404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3747,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:10:47.847191Z","caller":"traceutil/trace.go:171","msg":"trace[336232581] linearizableReadLoop","detail":"{readStateIndex:5175; appliedIndex:5173; }","duration":"293.413972ms","start":"2026-09-22T02:10:47.553754Z","end":"2026-09-22T02:10:47.847168Z","steps":["trace[336232581] 'read index received' (duration: 243.137996ms)","trace[336232581] 'applied index is now lower than readState.Index' (duration: 50.274866ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:10:47.847326Z","caller":"traceutil/trace.go:171","msg":"trace[420437974] transaction","detail":"{read_only:false; response_revision:4989; number_of_response:1; }","duration":"366.650901ms","start":"2026-09-22T02:10:47.480624Z","end":"2026-09-22T02:10:47.847275Z","steps":["trace[420437974] 'process raft request' (duration: 335.483046ms)","trace[420437974] 'compare' (duration: 30.764838ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:10:47.847456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:10:47.480598Z","time spent":"366.797704ms","remote":"127.0.0.1:60280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":878,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-22T02:10:47.847506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.755589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-22T02:10:47.847548Z","caller":"traceutil/trace.go:171","msg":"trace[2063872939] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4990; }","duration":"293.84684ms","start":"2026-09-22T02:10:47.55369Z","end":"2026-09-22T02:10:47.847537Z","steps":["trace[2063872939] 'agreement among raft nodes before linearized reading' (duration: 293.698508ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:10:47.847649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.708032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7aee58d0-377c-49e1-a81c-e83e42350ecf\" ","response":"range_response_count:1 size:3762"} {"level":"info","ts":"2026-09-22T02:10:47.847708Z","caller":"traceutil/trace.go:171","msg":"trace[161391507] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7aee58d0-377c-49e1-a81c-e83e42350ecf; range_end:; response_count:1; response_revision:4990; }","duration":"248.808284ms","start":"2026-09-22T02:10:47.598888Z","end":"2026-09-22T02:10:47.847697Z","steps":["trace[161391507] 'agreement among raft nodes before linearized reading' (duration: 248.690712ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:11:00.255924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.584173ms","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-09-22T02:11:00.256001Z","caller":"traceutil/trace.go:171","msg":"trace[2146311027] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5150; }","duration":"205.701284ms","start":"2026-09-22T02:11:00.050282Z","end":"2026-09-22T02:11:00.255984Z","steps":["trace[2146311027] 'range keys from in-memory index tree' (duration: 205.41469ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:11:00.599954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.089831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-22T02:11:00.60003Z","caller":"traceutil/trace.go:171","msg":"trace[798046409] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5151; }","duration":"122.214052ms","start":"2026-09-22T02:11:00.477799Z","end":"2026-09-22T02:11:00.600013Z","steps":["trace[798046409] 'range keys from in-memory index tree' (duration: 121.932577ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:11:00.600427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.909483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T02:11:00.600919Z","caller":"traceutil/trace.go:171","msg":"trace[881042944] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5151; }","duration":"175.431982ms","start":"2026-09-22T02:11:00.425436Z","end":"2026-09-22T02:11:00.600868Z","steps":["trace[881042944] 'range keys from in-memory index tree' (duration: 174.75775ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:11:01.082583Z","caller":"traceutil/trace.go:171","msg":"trace[687872483] transaction","detail":"{read_only:false; response_revision:5156; number_of_response:1; }","duration":"324.919814ms","start":"2026-09-22T02:11:00.757635Z","end":"2026-09-22T02:11:01.082555Z","steps":["trace[687872483] 'process raft request' (duration: 324.599619ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:11:01.082735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:11:00.757625Z","time spent":"325.021877ms","remote":"127.0.0.1:60512","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1505,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:11:01.082864Z","caller":"traceutil/trace.go:171","msg":"trace[823882925] transaction","detail":"{read_only:false; response_revision:5157; number_of_response:1; }","duration":"324.941545ms","start":"2026-09-22T02:11:00.757901Z","end":"2026-09-22T02:11:01.082842Z","steps":["trace[823882925] 'process raft request' (duration: 324.572468ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:11:01.083011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:11:00.757892Z","time spent":"325.025476ms","remote":"127.0.0.1:60382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":892,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:11:01.083602Z","caller":"traceutil/trace.go:171","msg":"trace[231914705] transaction","detail":"{read_only:false; response_revision:5159; number_of_response:1; }","duration":"283.821444ms","start":"2026-09-22T02:11:00.799765Z","end":"2026-09-22T02:11:01.083586Z","steps":["trace[231914705] 'process raft request' (duration: 283.705071ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:11:01.084031Z","caller":"traceutil/trace.go:171","msg":"trace[1232907177] transaction","detail":"{read_only:false; response_revision:5158; number_of_response:1; }","duration":"325.2724ms","start":"2026-09-22T02:11:00.758743Z","end":"2026-09-22T02:11:01.084016Z","steps":["trace[1232907177] 'process raft request' (duration: 324.036279ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:11:01.084129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:11:00.758728Z","time spent":"325.348991ms","remote":"127.0.0.1:60512","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1428,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:11:01.088305Z","caller":"traceutil/trace.go:171","msg":"trace[804593973] linearizableReadLoop","detail":"{readStateIndex:5353; appliedIndex:5348; }","duration":"213.467838ms","start":"2026-09-22T02:11:00.874814Z","end":"2026-09-22T02:11:01.088282Z","steps":["trace[804593973] 'read index received' (duration: 207.497844ms)","trace[804593973] 'applied index is now lower than readState.Index' (duration: 5.968894ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:11:01.088642Z","caller":"traceutil/trace.go:171","msg":"trace[1409365131] transaction","detail":"{read_only:false; response_revision:5160; number_of_response:1; }","duration":"277.167099ms","start":"2026-09-22T02:11:00.811442Z","end":"2026-09-22T02:11:01.088609Z","steps":["trace[1409365131] 'process raft request' (duration: 276.65483ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:11:01.088734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.794633ms","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-09-22T02:11:01.088788Z","caller":"traceutil/trace.go:171","msg":"trace[1188117326] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5160; }","duration":"191.889595ms","start":"2026-09-22T02:11:00.896882Z","end":"2026-09-22T02:11:01.088772Z","steps":["trace[1188117326] 'agreement among raft nodes before linearized reading' (duration: 191.696262ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:11:01.088733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.888665ms","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-09-22T02:11:01.088902Z","caller":"traceutil/trace.go:171","msg":"trace[205194619] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5160; }","duration":"214.064298ms","start":"2026-09-22T02:11:00.87478Z","end":"2026-09-22T02:11:01.088844Z","steps":["trace[205194619] 'agreement among raft nodes before linearized reading' (duration: 213.696022ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:11:19.837036Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2830} {"level":"info","ts":"2026-09-22T02:11:19.93028Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2830,"took":"89.873742ms","hash":552472044,"current-db-size-bytes":52154368,"current-db-size":"52 MB","current-db-size-in-use-bytes":27668480,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-22T02:11:19.930346Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":552472044,"revision":2830,"compact-revision":-1} {"level":"info","ts":"2026-09-22T02:16:19.843326Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5311} {"level":"info","ts":"2026-09-22T02:16:19.951674Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5311,"took":"106.940964ms","hash":479375433,"current-db-size-bytes":52154368,"current-db-size":"52 MB","current-db-size-in-use-bytes":18599936,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-22T02:16:19.951762Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":479375433,"revision":5311,"compact-revision":2830}