{"level":"warn","ts":"2026-06-26T03:28:35.682521Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-26T03:28:35.682596Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.70:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.70:2380","--initial-cluster=instance=https://199.204.45.70:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.70:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.70: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-06-26T03:28:35.682663Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-26T03:28:35.682671Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.70:2380"]} {"level":"info","ts":"2026-06-26T03:28:35.682689Z","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-06-26T03:28:35.683305Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.70:2379"]} {"level":"info","ts":"2026-06-26T03:28:35.683394Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.70:2380"],"listen-peer-urls":["https://199.204.45.70:2380"],"advertise-client-urls":["https://199.204.45.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.70: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-06-26T03:28:35.688092Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.438256ms"} {"level":"info","ts":"2026-06-26T03:28:35.698677Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f8844a9a4263041d","cluster-id":"4749b7c221ae0648"} {"level":"info","ts":"2026-06-26T03:28:35.698874Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d switched to configuration voters=()"} {"level":"info","ts":"2026-06-26T03:28:35.698917Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became follower at term 0"} {"level":"info","ts":"2026-06-26T03:28:35.698943Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f8844a9a4263041d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-26T03:28:35.698966Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became follower at term 1"} {"level":"info","ts":"2026-06-26T03:28:35.69903Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d switched to configuration voters=(17907520044731139101)"} {"level":"warn","ts":"2026-06-26T03:28:35.705087Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-26T03:28:35.708261Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-26T03:28:35.710053Z","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-06-26T03:28:35.71287Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f8844a9a4263041d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-26T03:28:35.713013Z","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-06-26T03:28:35.713097Z","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-06-26T03:28:35.713118Z","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-06-26T03:28:35.713122Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f8844a9a4263041d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-26T03:28:35.714576Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d switched to configuration voters=(17907520044731139101)"} {"level":"info","ts":"2026-06-26T03:28:35.715063Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4749b7c221ae0648","local-member-id":"f8844a9a4263041d","added-peer-id":"f8844a9a4263041d","added-peer-peer-urls":["https://199.204.45.70:2380"]} {"level":"info","ts":"2026-06-26T03:28:35.716776Z","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-06-26T03:28:35.716944Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.70:2380"} {"level":"info","ts":"2026-06-26T03:28:35.716999Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.70:2380"} {"level":"info","ts":"2026-06-26T03:28:35.718168Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-26T03:28:35.718504Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f8844a9a4263041d","initial-advertise-peer-urls":["https://199.204.45.70:2380"],"listen-peer-urls":["https://199.204.45.70:2380"],"advertise-client-urls":["https://199.204.45.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-26T03:28:35.799753Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d is starting a new election at term 1"} {"level":"info","ts":"2026-06-26T03:28:35.799817Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became pre-candidate at term 1"} {"level":"info","ts":"2026-06-26T03:28:35.799848Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d received MsgPreVoteResp from f8844a9a4263041d at term 1"} {"level":"info","ts":"2026-06-26T03:28:35.799867Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became candidate at term 2"} {"level":"info","ts":"2026-06-26T03:28:35.799914Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d received MsgVoteResp from f8844a9a4263041d at term 2"} {"level":"info","ts":"2026-06-26T03:28:35.799932Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became leader at term 2"} {"level":"info","ts":"2026-06-26T03:28:35.79995Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f8844a9a4263041d elected leader f8844a9a4263041d at term 2"} {"level":"info","ts":"2026-06-26T03:28:35.802493Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f8844a9a4263041d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.70:2379]}","request-path":"/0/members/f8844a9a4263041d/attributes","cluster-id":"4749b7c221ae0648","publish-timeout":"7s"} {"level":"info","ts":"2026-06-26T03:28:35.80256Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-26T03:28:35.802679Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-26T03:28:35.802704Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-26T03:28:35.804524Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.70:2379"} {"level":"info","ts":"2026-06-26T03:28:35.805115Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4749b7c221ae0648","local-member-id":"f8844a9a4263041d","cluster-version":"3.5"} {"level":"info","ts":"2026-06-26T03:28:35.805224Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-26T03:28:35.805279Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-26T03:28:35.805363Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-26T03:28:35.805395Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-26T03:28:35.809697Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-06-26T03:28:37.935392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.6617ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-06-26T03:28:37.9357Z","caller":"traceutil/trace.go:171","msg":"trace[2084151997] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"626.972641ms","start":"2026-06-26T03:28:37.30867Z","end":"2026-06-26T03:28:37.935643Z","steps":["trace[2084151997] 'process raft request' (duration: 404.597453ms)","trace[2084151997] 'compare' (duration: 221.543802ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T03:28:37.93579Z","caller":"traceutil/trace.go:171","msg":"trace[1179455572] linearizableReadLoop","detail":"{readStateIndex:24; appliedIndex:14; }","duration":"585.500661ms","start":"2026-06-26T03:28:37.350273Z","end":"2026-06-26T03:28:37.935773Z","steps":["trace[1179455572] 'read index received' (duration: 363.050227ms)","trace[1179455572] 'applied index is now lower than readState.Index' (duration: 222.449584ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T03:28:37.93578Z","caller":"traceutil/trace.go:171","msg":"trace[1933449127] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"624.1165ms","start":"2026-06-26T03:28:37.311619Z","end":"2026-06-26T03:28:37.935736Z","steps":["trace[1933449127] 'process raft request' (duration: 623.979871ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:28:37.935863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:28:37.308657Z","time spent":"627.130852ms","remote":"127.0.0.1:51452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-26T03:28:37.935895Z","caller":"traceutil/trace.go:171","msg":"trace[646750458] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"621.462062ms","start":"2026-06-26T03:28:37.314388Z","end":"2026-06-26T03:28:37.93585Z","steps":["trace[646750458] 'process raft request' (duration: 621.290071ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:28:37.935974Z","caller":"traceutil/trace.go:171","msg":"trace[80662293] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"627.199277ms","start":"2026-06-26T03:28:37.308768Z","end":"2026-06-26T03:28:37.935967Z","steps":["trace[80662293] 'process raft request' (duration: 626.732706ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:28:37.936006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:28:37.308763Z","time spent":"627.227348ms","remote":"127.0.0.1:51452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":965,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-26T03:28:37.936049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:28:37.314371Z","time spent":"621.612812ms","remote":"127.0.0.1:51452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":965,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-26T03:28:37.936109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.625898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-26T03:28:37.936159Z","caller":"traceutil/trace.go:171","msg":"trace[23853380] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:20; }","duration":"572.699554ms","start":"2026-06-26T03:28:37.363437Z","end":"2026-06-26T03:28:37.936136Z","steps":["trace[23853380] 'agreement among raft nodes before linearized reading' (duration: 572.617459ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:28:37.936176Z","caller":"traceutil/trace.go:171","msg":"trace[854875250] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"627.279112ms","start":"2026-06-26T03:28:37.308878Z","end":"2026-06-26T03:28:37.936157Z","steps":["trace[854875250] 'process raft request' (duration: 626.693492ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:28:37.936258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.027587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-06-26T03:28:37.936227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.94015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"warn","ts":"2026-06-26T03:28:37.936264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:28:37.308861Z","time spent":"627.375169ms","remote":"127.0.0.1:51452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":923,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-26T03:28:37.936285Z","caller":"traceutil/trace.go:171","msg":"trace[137342538] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:20; }","duration":"380.077651ms","start":"2026-06-26T03:28:37.556199Z","end":"2026-06-26T03:28:37.936276Z","steps":["trace[137342538] 'agreement among raft nodes before linearized reading' (duration: 380.038298ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:28:37.936302Z","caller":"traceutil/trace.go:171","msg":"trace[1127984918] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"620.296535ms","start":"2026-06-26T03:28:37.315989Z","end":"2026-06-26T03:28:37.936285Z","steps":["trace[1127984918] 'process raft request' (duration: 619.726997ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:28:37.936308Z","caller":"traceutil/trace.go:171","msg":"trace[1408871540] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:20; }","duration":"586.061748ms","start":"2026-06-26T03:28:37.350227Z","end":"2026-06-26T03:28:37.936289Z","steps":["trace[1408871540] 'agreement among raft nodes before linearized reading' (duration: 585.7885ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:28:37.936338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:28:37.315978Z","time spent":"620.343678ms","remote":"127.0.0.1:51104","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-06-26T03:28:37.936349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.665913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-06-26T03:28:37.936351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:28:37.350215Z","time spent":"586.127003ms","remote":"127.0.0.1:51040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-06-26T03:28:37.936376Z","caller":"traceutil/trace.go:171","msg":"trace[628668062] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:20; }","duration":"460.722187ms","start":"2026-06-26T03:28:37.475646Z","end":"2026-06-26T03:28:37.936368Z","steps":["trace[628668062] 'agreement among raft nodes before linearized reading' (duration: 460.687124ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:28:37.936396Z","caller":"traceutil/trace.go:171","msg":"trace[167037737] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"627.54473ms","start":"2026-06-26T03:28:37.308842Z","end":"2026-06-26T03:28:37.936386Z","steps":["trace[167037737] 'process raft request' (duration: 626.701754ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:28:37.936402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:28:37.47563Z","time spent":"460.76564ms","remote":"127.0.0.1:51184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" "} {"level":"warn","ts":"2026-06-26T03:28:37.936453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:28:37.30883Z","time spent":"627.591033ms","remote":"127.0.0.1:51452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-26T03:28:37.936485Z","caller":"traceutil/trace.go:171","msg":"trace[2025579589] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"618.024642ms","start":"2026-06-26T03:28:37.318454Z","end":"2026-06-26T03:28:37.936479Z","steps":["trace[2025579589] 'process raft request' (duration: 617.291253ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:28:37.936315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:28:37.556179Z","time spent":"380.128904ms","remote":"127.0.0.1:51310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-06-26T03:28:37.936511Z","caller":"traceutil/trace.go:171","msg":"trace[12729418] transaction","detail":"{read_only:false; number_of_response:0; response_revision:17; }","duration":"622.403635ms","start":"2026-06-26T03:28:37.314101Z","end":"2026-06-26T03:28:37.936505Z","steps":["trace[12729418] 'process raft request' (duration: 621.555718ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:28:37.936521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:28:37.318444Z","time spent":"618.059985ms","remote":"127.0.0.1:51182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-26T03:28:37.936559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:28:37.314093Z","time spent":"622.440648ms","remote":"127.0.0.1:51040","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-26T03:28:37.936479Z","caller":"traceutil/trace.go:171","msg":"trace[92641400] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"624.029194ms","start":"2026-06-26T03:28:37.312425Z","end":"2026-06-26T03:28:37.936454Z","steps":["trace[92641400] 'process raft request' (duration: 623.200479ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:28:37.936611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:28:37.312413Z","time spent":"624.184505ms","remote":"127.0.0.1:51370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-26T03:28:37.935904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:28:37.311611Z","time spent":"624.231758ms","remote":"127.0.0.1:51040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":338,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-26T03:28:37.936201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:28:37.363415Z","time spent":"572.776649ms","remote":"127.0.0.1:51040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/default\" "} {"level":"info","ts":"2026-06-26T03:28:38.188246Z","caller":"traceutil/trace.go:171","msg":"trace[229486864] transaction","detail":"{read_only:false; response_revision:83; number_of_response:1; }","duration":"104.421954ms","start":"2026-06-26T03:28:38.08378Z","end":"2026-06-26T03:28:38.188202Z","steps":["trace[229486864] 'process raft request' (duration: 66.505027ms)","trace[229486864] 'compare' (duration: 37.744276ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T03:28:39.219103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"884.78461ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-06-26T03:28:39.219213Z","caller":"traceutil/trace.go:171","msg":"trace[341881696] linearizableReadLoop","detail":"{readStateIndex:89; appliedIndex:88; }","duration":"1.021643862s","start":"2026-06-26T03:28:38.197553Z","end":"2026-06-26T03:28:39.219197Z","steps":["trace[341881696] 'read index received' (duration: 136.447136ms)","trace[341881696] 'applied index is now lower than readState.Index' (duration: 885.195416ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T03:28:39.219342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.021796812s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-26T03:28:39.219401Z","caller":"traceutil/trace.go:171","msg":"trace[483493364] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:84; }","duration":"1.021888539s","start":"2026-06-26T03:28:38.197498Z","end":"2026-06-26T03:28:39.219386Z","steps":["trace[483493364] 'agreement among raft nodes before linearized reading' (duration: 1.021768551s)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:28:39.219442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:28:38.197438Z","time spent":"1.021994335s","remote":"127.0.0.1:51274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":27,"request content":"key:\"/registry/priorityclasses/system-node-critical\" "} {"level":"warn","ts":"2026-06-26T03:28:39.219474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"917.645957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-26T03:28:39.219615Z","caller":"traceutil/trace.go:171","msg":"trace[304814906] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:84; }","duration":"917.803647ms","start":"2026-06-26T03:28:38.301767Z","end":"2026-06-26T03:28:39.219571Z","steps":["trace[304814906] 'agreement among raft nodes before linearized reading' (duration: 917.614165ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:28:39.219668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.020837667s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-06-26T03:28:39.219691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:28:38.301748Z","time spent":"917.908824ms","remote":"127.0.0.1:50924","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-06-26T03:28:39.219707Z","caller":"traceutil/trace.go:171","msg":"trace[2113955202] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:84; }","duration":"1.020896182s","start":"2026-06-26T03:28:38.198801Z","end":"2026-06-26T03:28:39.219698Z","steps":["trace[2113955202] 'agreement among raft nodes before linearized reading' (duration: 1.020481174s)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:28:39.219743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:28:38.198789Z","time spent":"1.020946905s","remote":"127.0.0.1:51256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" "} {"level":"info","ts":"2026-06-26T03:28:39.219281Z","caller":"traceutil/trace.go:171","msg":"trace[1558965085] transaction","detail":"{read_only:false; response_revision:84; number_of_response:1; }","duration":"1.025473369s","start":"2026-06-26T03:28:38.19377Z","end":"2026-06-26T03:28:39.219243Z","steps":["trace[1558965085] 'process raft request' (duration: 140.308085ms)","trace[1558965085] 'compare' (duration: 884.664481ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T03:28:39.220085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:28:38.193744Z","time spent":"1.02622799s","remote":"127.0.0.1:50992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":642,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-26T03:28:39.7247Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296567981295629295,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-26T03:28:40.691941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"865.968933ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-06-26T03:28:40.692057Z","caller":"traceutil/trace.go:171","msg":"trace[1151519851] linearizableReadLoop","detail":"{readStateIndex:90; appliedIndex:89; }","duration":"1.468068424s","start":"2026-06-26T03:28:39.223968Z","end":"2026-06-26T03:28:40.692037Z","steps":["trace[1151519851] 'read index received' (duration: 601.920937ms)","trace[1151519851] 'applied index is now lower than readState.Index' (duration: 866.145957ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T03:28:40.692116Z","caller":"traceutil/trace.go:171","msg":"trace[523437275] transaction","detail":"{read_only:false; response_revision:85; number_of_response:1; }","duration":"1.468934653s","start":"2026-06-26T03:28:39.223159Z","end":"2026-06-26T03:28:40.692094Z","steps":["trace[523437275] 'process raft request' (duration: 602.731272ms)","trace[523437275] 'compare' (duration: 865.805893ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T03:28:40.692203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:28:39.223142Z","time spent":"1.469020898s","remote":"127.0.0.1:51274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":429,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-26T03:28:40.692377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.271605462s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-06-26T03:28:40.692424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.820362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-26T03:28:40.692443Z","caller":"traceutil/trace.go:171","msg":"trace[1474017571] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:85; }","duration":"1.27171676s","start":"2026-06-26T03:28:39.420709Z","end":"2026-06-26T03:28:40.692426Z","steps":["trace[1474017571] 'agreement among raft nodes before linearized reading' (duration: 1.271624024s)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:28:40.692485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:28:39.420679Z","time spent":"1.271794515s","remote":"127.0.0.1:51184","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-06-26T03:28:40.692542Z","caller":"traceutil/trace.go:171","msg":"trace[811884589] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:85; }","duration":"141.889447ms","start":"2026-06-26T03:28:40.550563Z","end":"2026-06-26T03:28:40.692452Z","steps":["trace[811884589] 'agreement among raft nodes before linearized reading' (duration: 141.826332ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:28:40.692388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.468361544s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-06-26T03:28:40.692592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.390600451s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-26T03:28:40.692658Z","caller":"traceutil/trace.go:171","msg":"trace[386616390] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:85; }","duration":"1.390665175s","start":"2026-06-26T03:28:39.301976Z","end":"2026-06-26T03:28:40.692641Z","steps":["trace[386616390] 'agreement among raft nodes before linearized reading' (duration: 1.390561208s)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:28:40.692631Z","caller":"traceutil/trace.go:171","msg":"trace[1342751923] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:85; }","duration":"1.468694396s","start":"2026-06-26T03:28:39.223919Z","end":"2026-06-26T03:28:40.692613Z","steps":["trace[1342751923] 'agreement among raft nodes before linearized reading' (duration: 1.468416498s)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:28:40.692777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.468781952s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18bc83d7aad38d34\" ","response":"range_response_count:1 size:650"} {"level":"warn","ts":"2026-06-26T03:28:40.692785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:28:39.22377Z","time spent":"1.468997248s","remote":"127.0.0.1:51256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/system:aggregate-to-admin\" "} {"level":"info","ts":"2026-06-26T03:28:40.692841Z","caller":"traceutil/trace.go:171","msg":"trace[1719102443] range","detail":"{range_begin:/registry/events/default/instance.18bc83d7aad38d34; range_end:; response_count:1; response_revision:85; }","duration":"1.468872578s","start":"2026-06-26T03:28:39.223952Z","end":"2026-06-26T03:28:40.692824Z","steps":["trace[1719102443] 'agreement among raft nodes before linearized reading' (duration: 1.468184621s)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:28:40.692783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:28:39.301933Z","time spent":"1.390836427s","remote":"127.0.0.1:50924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-26T03:28:40.692893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:28:39.223945Z","time spent":"1.468933952s","remote":"127.0.0.1:50992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":673,"request content":"key:\"/registry/events/default/instance.18bc83d7aad38d34\" "} {"level":"info","ts":"2026-06-26T03:29:11.280962Z","caller":"traceutil/trace.go:171","msg":"trace[582412915] linearizableReadLoop","detail":"{readStateIndex:503; appliedIndex:502; }","duration":"129.004158ms","start":"2026-06-26T03:29:11.151926Z","end":"2026-06-26T03:29:11.28093Z","steps":["trace[582412915] 'read index received' (duration: 128.766262ms)","trace[582412915] 'applied index is now lower than readState.Index' (duration: 236.476µs)"],"step_count":2} {"level":"info","ts":"2026-06-26T03:29:11.281012Z","caller":"traceutil/trace.go:171","msg":"trace[583262953] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"130.101713ms","start":"2026-06-26T03:29:11.150883Z","end":"2026-06-26T03:29:11.280985Z","steps":["trace[583262953] 'process raft request' (duration: 129.858057ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:29:11.357117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.15516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-06-26T03:29:11.357223Z","caller":"traceutil/trace.go:171","msg":"trace[649678074] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:484; }","duration":"205.308258ms","start":"2026-06-26T03:29:11.151889Z","end":"2026-06-26T03:29:11.357197Z","steps":["trace[649678074] 'agreement among raft nodes before linearized reading' (duration: 129.170776ms)","trace[649678074] 'range keys from in-memory index tree' (duration: 75.937734ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T03:29:14.396762Z","caller":"traceutil/trace.go:171","msg":"trace[1050006346] linearizableReadLoop","detail":"{readStateIndex:512; appliedIndex:511; }","duration":"268.697244ms","start":"2026-06-26T03:29:14.128038Z","end":"2026-06-26T03:29:14.396736Z","steps":["trace[1050006346] 'read index received' (duration: 268.489316ms)","trace[1050006346] 'applied index is now lower than readState.Index' (duration: 206.758µs)"],"step_count":2} {"level":"info","ts":"2026-06-26T03:29:14.396837Z","caller":"traceutil/trace.go:171","msg":"trace[1053467928] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"402.538399ms","start":"2026-06-26T03:29:13.99426Z","end":"2026-06-26T03:29:14.396798Z","steps":["trace[1053467928] 'process raft request' (duration: 402.324652ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:29:14.396902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.844552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-26T03:29:14.396947Z","caller":"traceutil/trace.go:171","msg":"trace[502182741] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:0; response_revision:492; }","duration":"268.943182ms","start":"2026-06-26T03:29:14.12799Z","end":"2026-06-26T03:29:14.396934Z","steps":["trace[502182741] 'agreement among raft nodes before linearized reading' (duration: 268.853223ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:29:14.397013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:29:13.994242Z","time spent":"402.677248ms","remote":"127.0.0.1:51184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-26T03:29:16.880039Z","caller":"traceutil/trace.go:171","msg":"trace[22180135] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"563.071039ms","start":"2026-06-26T03:29:16.316936Z","end":"2026-06-26T03:29:16.880007Z","steps":["trace[22180135] 'process raft request' (duration: 562.87743ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:29:16.880214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:29:16.316902Z","time spent":"563.227999ms","remote":"127.0.0.1:50960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-26T03:29:16.888648Z","caller":"traceutil/trace.go:171","msg":"trace[456921117] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"552.226939ms","start":"2026-06-26T03:29:16.336393Z","end":"2026-06-26T03:29:16.888619Z","steps":["trace[456921117] 'process raft request' (duration: 551.140188ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:29:16.888744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:29:16.336387Z","time spent":"552.312168ms","remote":"127.0.0.1:50960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-26T03:29:16.888708Z","caller":"traceutil/trace.go:171","msg":"trace[1661860448] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"554.829625ms","start":"2026-06-26T03:29:16.333816Z","end":"2026-06-26T03:29:16.888646Z","steps":["trace[1661860448] 'process raft request' (duration: 553.632915ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:29:16.888841Z","caller":"traceutil/trace.go:171","msg":"trace[105921037] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"554.803735ms","start":"2026-06-26T03:29:16.334029Z","end":"2026-06-26T03:29:16.888833Z","steps":["trace[105921037] 'process raft request' (duration: 553.461757ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:29:16.888787Z","caller":"traceutil/trace.go:171","msg":"trace[834244218] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"556.661288ms","start":"2026-06-26T03:29:16.332056Z","end":"2026-06-26T03:29:16.888717Z","steps":["trace[834244218] 'process raft request' (duration: 555.26389ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:29:16.888891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:29:16.334024Z","time spent":"554.848015ms","remote":"127.0.0.1:50960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-26T03:29:16.888905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:29:16.333793Z","time spent":"555.020213ms","remote":"127.0.0.1:50960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-26T03:29:16.888954Z","caller":"traceutil/trace.go:171","msg":"trace[2069118877] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"504.852914ms","start":"2026-06-26T03:29:16.384094Z","end":"2026-06-26T03:29:16.888947Z","steps":["trace[2069118877] 'process raft request' (duration: 503.543315ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:29:16.888971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:29:16.332034Z","time spent":"556.863636ms","remote":"127.0.0.1:50960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-26T03:29:16.889018Z","caller":"traceutil/trace.go:171","msg":"trace[137242810] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"525.85561ms","start":"2026-06-26T03:29:16.363155Z","end":"2026-06-26T03:29:16.88901Z","steps":["trace[137242810] 'process raft request' (duration: 524.430073ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:29:16.889057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:29:16.363133Z","time spent":"525.90849ms","remote":"127.0.0.1:50960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-26T03:29:16.888989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:29:16.384074Z","time spent":"504.902064ms","remote":"127.0.0.1:50960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-26T03:29:16.889147Z","caller":"traceutil/trace.go:171","msg":"trace[846501116] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"571.561901ms","start":"2026-06-26T03:29:16.317572Z","end":"2026-06-26T03:29:16.889134Z","steps":["trace[846501116] 'process raft request' (duration: 569.592099ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:29:16.889187Z","caller":"traceutil/trace.go:171","msg":"trace[1531551210] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"383.270198ms","start":"2026-06-26T03:29:16.505907Z","end":"2026-06-26T03:29:16.889177Z","steps":["trace[1531551210] 'process raft request' (duration: 381.803291ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:29:16.889198Z","caller":"traceutil/trace.go:171","msg":"trace[497316275] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"556.874617ms","start":"2026-06-26T03:29:16.332308Z","end":"2026-06-26T03:29:16.889183Z","steps":["trace[497316275] 'process raft request' (duration: 555.061233ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:29:16.889225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:29:16.317533Z","time spent":"571.664941ms","remote":"127.0.0.1:50960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-26T03:29:16.889249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:29:16.505863Z","time spent":"383.343547ms","remote":"127.0.0.1:50960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-26T03:29:16.889266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:29:16.332304Z","time spent":"556.929566ms","remote":"127.0.0.1:50960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-26T03:29:16.889421Z","caller":"traceutil/trace.go:171","msg":"trace[310658196] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"556.253252ms","start":"2026-06-26T03:29:16.332342Z","end":"2026-06-26T03:29:16.888595Z","steps":["trace[310658196] 'process raft request' (duration: 555.065502ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:29:16.889822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:29:16.332339Z","time spent":"557.424711ms","remote":"127.0.0.1:50960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-26T03:29:16.889436Z","caller":"traceutil/trace.go:171","msg":"trace[1161694731] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"567.939025ms","start":"2026-06-26T03:29:16.320741Z","end":"2026-06-26T03:29:16.88868Z","steps":["trace[1161694731] 'process raft request' (duration: 566.491478ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:29:16.890132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:29:16.320718Z","time spent":"569.297762ms","remote":"127.0.0.1:50960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-26T03:29:20.518032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.187013218s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-06-26T03:29:20.518073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.066180059s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-06-26T03:29:20.518091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.8056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-26T03:29:20.518132Z","caller":"traceutil/trace.go:171","msg":"trace[327141548] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:611; }","duration":"1.066310429s","start":"2026-06-26T03:29:19.451805Z","end":"2026-06-26T03:29:20.518115Z","steps":["trace[327141548] 'range keys from in-memory index tree' (duration: 1.066029211s)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:29:20.518131Z","caller":"traceutil/trace.go:171","msg":"trace[1858518171] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:611; }","duration":"1.187185677s","start":"2026-06-26T03:29:19.330923Z","end":"2026-06-26T03:29:20.518109Z","steps":["trace[1858518171] 'range keys from in-memory index tree' (duration: 1.186779108s)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:29:20.51817Z","caller":"traceutil/trace.go:171","msg":"trace[1032624189] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:611; }","duration":"624.9459ms","start":"2026-06-26T03:29:19.893204Z","end":"2026-06-26T03:29:20.51815Z","steps":["trace[1032624189] 'range keys from in-memory index tree' (duration: 624.638361ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:29:20.518177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:29:19.45178Z","time spent":"1.066387709s","remote":"127.0.0.1:51184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-26T03:29:20.518178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:29:19.330886Z","time spent":"1.187281846s","remote":"127.0.0.1:51184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-26T03:29:20.518173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"974.637308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-06-26T03:29:20.518213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:29:19.893165Z","time spent":"625.039689ms","remote":"127.0.0.1:50924","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-06-26T03:29:20.518227Z","caller":"traceutil/trace.go:171","msg":"trace[1465454035] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:611; }","duration":"974.719918ms","start":"2026-06-26T03:29:19.543491Z","end":"2026-06-26T03:29:20.518211Z","steps":["trace[1465454035] 'range keys from in-memory index tree' (duration: 974.449029ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:29:20.518262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:29:19.543468Z","time spent":"974.787097ms","remote":"127.0.0.1:51184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-26T03:29:20.518669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.349077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:29570"} {"level":"info","ts":"2026-06-26T03:29:20.518718Z","caller":"traceutil/trace.go:171","msg":"trace[1752075869] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:611; }","duration":"392.434306ms","start":"2026-06-26T03:29:20.126273Z","end":"2026-06-26T03:29:20.518707Z","steps":["trace[1752075869] 'range keys from in-memory index tree' (duration: 391.444052ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:29:20.518756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:29:20.126249Z","time spent":"392.499586ms","remote":"127.0.0.1:50960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":103,"response count":1,"response size":29593,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" "} {"level":"warn","ts":"2026-06-26T03:29:25.041393Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296567981295630460,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-26T03:29:25.234264Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.030972718s","expected-duration":"1s"} {"level":"info","ts":"2026-06-26T03:29:25.235137Z","caller":"traceutil/trace.go:171","msg":"trace[1886050947] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"1.031963258s","start":"2026-06-26T03:29:24.203143Z","end":"2026-06-26T03:29:25.235106Z","steps":["trace[1886050947] 'process raft request' (duration: 1.031710738s)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:29:25.235431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:29:24.203115Z","time spent":"1.032113508s","remote":"127.0.0.1:51120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-26T03:29:25.972798Z","caller":"traceutil/trace.go:171","msg":"trace[931408987] linearizableReadLoop","detail":"{readStateIndex:662; appliedIndex:660; }","duration":"1.43167669s","start":"2026-06-26T03:29:24.541097Z","end":"2026-06-26T03:29:25.972774Z","steps":["trace[931408987] 'read index received' (duration: 693.979346ms)","trace[931408987] 'applied index is now lower than readState.Index' (duration: 737.696144ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T03:29:25.973002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.43189182s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-26T03:29:25.972969Z","caller":"traceutil/trace.go:171","msg":"trace[522017964] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"1.53164942s","start":"2026-06-26T03:29:24.441268Z","end":"2026-06-26T03:29:25.972917Z","steps":["trace[522017964] 'process raft request' (duration: 1.53144484s)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:29:25.973004Z","caller":"traceutil/trace.go:171","msg":"trace[1436514871] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"1.641913341s","start":"2026-06-26T03:29:24.331051Z","end":"2026-06-26T03:29:25.972964Z","steps":["trace[1436514871] 'process raft request' (duration: 1.641106192s)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:29:25.973044Z","caller":"traceutil/trace.go:171","msg":"trace[1934716912] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:636; }","duration":"1.431977901s","start":"2026-06-26T03:29:24.541054Z","end":"2026-06-26T03:29:25.973032Z","steps":["trace[1934716912] 'agreement among raft nodes before linearized reading' (duration: 1.4318396s)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:29:25.973143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.080575122s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-26T03:29:25.973176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:29:24.441236Z","time spent":"1.531827471s","remote":"127.0.0.1:51184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-26T03:29:25.973201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:29:24.541035Z","time spent":"1.432152861s","remote":"127.0.0.1:51184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-26T03:29:25.973202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:29:24.331025Z","time spent":"1.642065512s","remote":"127.0.0.1:51020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036221,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-26T03:29:25.973238Z","caller":"traceutil/trace.go:171","msg":"trace[811394138] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:636; }","duration":"1.080712412s","start":"2026-06-26T03:29:24.892498Z","end":"2026-06-26T03:29:25.97321Z","steps":["trace[811394138] 'agreement among raft nodes before linearized reading' (duration: 1.080555782s)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:29:25.97326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.431369741s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-06-26T03:29:25.973288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:29:24.892445Z","time spent":"1.080833551s","remote":"127.0.0.1:50924","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-06-26T03:29:25.973364Z","caller":"traceutil/trace.go:171","msg":"trace[1014872722] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:636; }","duration":"1.431497311s","start":"2026-06-26T03:29:24.541823Z","end":"2026-06-26T03:29:25.97332Z","steps":["trace[1014872722] 'agreement among raft nodes before linearized reading' (duration: 1.431241011s)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:29:25.973411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:29:24.541799Z","time spent":"1.431602501s","remote":"127.0.0.1:51184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-26T03:29:25.973585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.431690141s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-26T03:29:25.97366Z","caller":"traceutil/trace.go:171","msg":"trace[1307304590] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:636; }","duration":"1.431798331s","start":"2026-06-26T03:29:24.541841Z","end":"2026-06-26T03:29:25.973639Z","steps":["trace[1307304590] 'agreement among raft nodes before linearized reading' (duration: 1.431517781s)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:29:25.973816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:29:24.541817Z","time spent":"1.431985761s","remote":"127.0.0.1:51184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-26T03:29:25.974275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"879.358049ms","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-06-26T03:29:25.974326Z","caller":"traceutil/trace.go:171","msg":"trace[378024784] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:636; }","duration":"879.451099ms","start":"2026-06-26T03:29:25.094863Z","end":"2026-06-26T03:29:25.974314Z","steps":["trace[378024784] 'agreement among raft nodes before linearized reading' (duration: 879.330519ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:29:25.974357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:29:25.094844Z","time spent":"879.506268ms","remote":"127.0.0.1:51184","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-06-26T03:29:26.488738Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296567981295630466,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-26T03:29:27.384155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.810093ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-26T03:29:27.384504Z","caller":"traceutil/trace.go:171","msg":"trace[792195013] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"1.407257432s","start":"2026-06-26T03:29:25.97722Z","end":"2026-06-26T03:29:27.384477Z","steps":["trace[792195013] 'process raft request' (duration: 692.037868ms)","trace[792195013] 'compare' (duration: 714.543573ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T03:29:27.384665Z","caller":"traceutil/trace.go:171","msg":"trace[1224928849] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"1.406561203s","start":"2026-06-26T03:29:25.978069Z","end":"2026-06-26T03:29:27.38463Z","steps":["trace[1224928849] 'process raft request' (duration: 1.406204552s)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:29:27.38469Z","caller":"traceutil/trace.go:171","msg":"trace[99291183] linearizableReadLoop","detail":"{readStateIndex:667; appliedIndex:663; }","duration":"1.396292303s","start":"2026-06-26T03:29:25.988379Z","end":"2026-06-26T03:29:27.384672Z","steps":["trace[99291183] 'read index received' (duration: 680.674339ms)","trace[99291183] 'applied index is now lower than readState.Index' (duration: 715.616864ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T03:29:27.384703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:29:25.977194Z","time spent":"1.407362162s","remote":"127.0.0.1:51184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-26T03:29:27.384745Z","caller":"traceutil/trace.go:171","msg":"trace[549878757] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"1.403695273s","start":"2026-06-26T03:29:25.981025Z","end":"2026-06-26T03:29:27.384721Z","steps":["trace[549878757] 'process raft request' (duration: 1.403493163s)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:29:27.384812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.396424433s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-26T03:29:27.384832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:29:25.981004Z","time spent":"1.403778933s","remote":"127.0.0.1:51184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-26T03:29:27.384831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:29:25.97805Z","time spent":"1.406688153s","remote":"127.0.0.1:51184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-26T03:29:27.384847Z","caller":"traceutil/trace.go:171","msg":"trace[703159262] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:641; }","duration":"1.396490673s","start":"2026-06-26T03:29:25.988344Z","end":"2026-06-26T03:29:27.384834Z","steps":["trace[703159262] 'agreement among raft nodes before linearized reading' (duration: 1.396393343s)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:29:27.384885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:29:25.988329Z","time spent":"1.396549114s","remote":"127.0.0.1:50924","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-06-26T03:29:27.385058Z","caller":"traceutil/trace.go:171","msg":"trace[91935139] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"1.347644998s","start":"2026-06-26T03:29:26.037401Z","end":"2026-06-26T03:29:27.385046Z","steps":["trace[91935139] 'process raft request' (duration: 1.347193426s)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:29:27.385111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:29:26.037381Z","time spent":"1.347699878s","remote":"127.0.0.1:51256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1092,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-26T03:29:28.538742Z","caller":"traceutil/trace.go:171","msg":"trace[64816556] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"131.368976ms","start":"2026-06-26T03:29:28.407341Z","end":"2026-06-26T03:29:28.53871Z","steps":["trace[64816556] 'process raft request' (duration: 129.187661ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:29:43.950975Z","caller":"traceutil/trace.go:171","msg":"trace[368538110] linearizableReadLoop","detail":"{readStateIndex:843; appliedIndex:842; }","duration":"457.752492ms","start":"2026-06-26T03:29:43.493199Z","end":"2026-06-26T03:29:43.950951Z","steps":["trace[368538110] 'read index received' (duration: 457.57946ms)","trace[368538110] 'applied index is now lower than readState.Index' (duration: 172.042µs)"],"step_count":2} {"level":"info","ts":"2026-06-26T03:29:43.951124Z","caller":"traceutil/trace.go:171","msg":"trace[422194663] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"462.869318ms","start":"2026-06-26T03:29:43.488231Z","end":"2026-06-26T03:29:43.951101Z","steps":["trace[422194663] 'process raft request' (duration: 462.601724ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:29:43.951238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.031107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-06-26T03:29:43.951258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:29:43.488214Z","time spent":"462.964369ms","remote":"127.0.0.1:51184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-26T03:29:43.951289Z","caller":"traceutil/trace.go:171","msg":"trace[1680653300] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:811; }","duration":"458.132218ms","start":"2026-06-26T03:29:43.493142Z","end":"2026-06-26T03:29:43.951274Z","steps":["trace[1680653300] 'agreement among raft nodes before linearized reading' (duration: 457.964955ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:29:43.951336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:29:43.493115Z","time spent":"458.202749ms","remote":"127.0.0.1:51184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-26T03:29:43.951332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.879446ms","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-06-26T03:29:43.951417Z","caller":"traceutil/trace.go:171","msg":"trace[311673392] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:811; }","duration":"451.999217ms","start":"2026-06-26T03:29:43.499398Z","end":"2026-06-26T03:29:43.951397Z","steps":["trace[311673392] 'agreement among raft nodes before linearized reading' (duration: 451.755844ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:29:43.951461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:29:43.499389Z","time spent":"452.063239ms","remote":"127.0.0.1:51184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-26T03:29:44.587634Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296567981295630808,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-26T03:29:44.705017Z","caller":"traceutil/trace.go:171","msg":"trace[1609572769] transaction","detail":"{read_only:false; response_revision:812; number_of_response:1; }","duration":"749.027467ms","start":"2026-06-26T03:29:43.955954Z","end":"2026-06-26T03:29:44.704982Z","steps":["trace[1609572769] 'process raft request' (duration: 705.152581ms)","trace[1609572769] 'compare' (duration: 43.609262ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T03:29:44.705174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:29:43.955933Z","time spent":"749.157229ms","remote":"127.0.0.1:51184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-26T03:29:44.709175Z","caller":"traceutil/trace.go:171","msg":"trace[1568717599] linearizableReadLoop","detail":"{readStateIndex:847; appliedIndex:843; }","duration":"621.81776ms","start":"2026-06-26T03:29:44.087342Z","end":"2026-06-26T03:29:44.709159Z","steps":["trace[1568717599] 'read index received' (duration: 573.816579ms)","trace[1568717599] 'applied index is now lower than readState.Index' (duration: 48.000121ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T03:29:44.709363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.990762ms","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-06-26T03:29:44.709394Z","caller":"traceutil/trace.go:171","msg":"trace[1532213006] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:815; }","duration":"622.084313ms","start":"2026-06-26T03:29:44.087303Z","end":"2026-06-26T03:29:44.709387Z","steps":["trace[1532213006] 'agreement among raft nodes before linearized reading' (duration: 621.91742ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:29:44.709422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:29:44.087279Z","time spent":"622.135214ms","remote":"127.0.0.1:51184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-26T03:29:44.709655Z","caller":"traceutil/trace.go:171","msg":"trace[1085292190] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"753.68285ms","start":"2026-06-26T03:29:43.95596Z","end":"2026-06-26T03:29:44.709643Z","steps":["trace[1085292190] 'process raft request' (duration: 753.02515ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:29:44.709716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:29:43.955945Z","time spent":"753.73416ms","remote":"127.0.0.1:51184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-26T03:29:44.710097Z","caller":"traceutil/trace.go:171","msg":"trace[1068074348] transaction","detail":"{read_only:false; response_revision:814; number_of_response:1; }","duration":"475.913007ms","start":"2026-06-26T03:29:44.234176Z","end":"2026-06-26T03:29:44.710089Z","steps":["trace[1068074348] 'process raft request' (duration: 474.912381ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:29:44.71016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:29:44.234153Z","time spent":"475.969578ms","remote":"127.0.0.1:51104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5051,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-26T03:29:44.710388Z","caller":"traceutil/trace.go:171","msg":"trace[545377822] transaction","detail":"{read_only:false; response_revision:815; number_of_response:1; }","duration":"387.984169ms","start":"2026-06-26T03:29:44.322397Z","end":"2026-06-26T03:29:44.710381Z","steps":["trace[545377822] 'process raft request' (duration: 386.72619ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:29:44.710437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:29:44.322359Z","time spent":"388.047329ms","remote":"127.0.0.1:51164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-26T03:31:31.218656Z","caller":"traceutil/trace.go:171","msg":"trace[43382136] linearizableReadLoop","detail":"{readStateIndex:1382; appliedIndex:1381; }","duration":"327.06312ms","start":"2026-06-26T03:31:30.891564Z","end":"2026-06-26T03:31:31.218627Z","steps":["trace[43382136] 'read index received' (duration: 326.704532ms)","trace[43382136] 'applied index is now lower than readState.Index' (duration: 356.938µs)"],"step_count":2} {"level":"warn","ts":"2026-06-26T03:31:31.218811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.236339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-26T03:31:31.218851Z","caller":"traceutil/trace.go:171","msg":"trace[732024548] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1325; }","duration":"327.351665ms","start":"2026-06-26T03:31:30.891488Z","end":"2026-06-26T03:31:31.21884Z","steps":["trace[732024548] 'agreement among raft nodes before linearized reading' (duration: 327.247669ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:31:31.218891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:31:30.891448Z","time spent":"327.435089ms","remote":"127.0.0.1:50924","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-06-26T03:31:31.218897Z","caller":"traceutil/trace.go:171","msg":"trace[829736880] transaction","detail":"{read_only:false; response_revision:1325; number_of_response:1; }","duration":"506.99588ms","start":"2026-06-26T03:31:30.711804Z","end":"2026-06-26T03:31:31.218799Z","steps":["trace[829736880] 'process raft request' (duration: 506.578518ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:31:31.219398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:31:30.71178Z","time spent":"507.382979ms","remote":"127.0.0.1:51184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-26T03:31:37.415339Z","caller":"traceutil/trace.go:171","msg":"trace[1140641177] transaction","detail":"{read_only:false; response_revision:1352; number_of_response:1; }","duration":"122.225118ms","start":"2026-06-26T03:31:37.293086Z","end":"2026-06-26T03:31:37.415312Z","steps":["trace[1140641177] 'process raft request' (duration: 57.816507ms)","trace[1140641177] 'compare' (duration: 64.238112ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T03:31:37.415575Z","caller":"traceutil/trace.go:171","msg":"trace[1745966746] transaction","detail":"{read_only:false; response_revision:1353; number_of_response:1; }","duration":"122.319382ms","start":"2026-06-26T03:31:37.293245Z","end":"2026-06-26T03:31:37.415565Z","steps":["trace[1745966746] 'process raft request' (duration: 122.027727ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:31:37.415604Z","caller":"traceutil/trace.go:171","msg":"trace[2101042092] transaction","detail":"{read_only:false; response_revision:1354; number_of_response:1; }","duration":"122.290201ms","start":"2026-06-26T03:31:37.293289Z","end":"2026-06-26T03:31:37.415579Z","steps":["trace[2101042092] 'process raft request' (duration: 122.170504ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:32:01.860511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.646853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/default\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-06-26T03:32:01.860631Z","caller":"traceutil/trace.go:171","msg":"trace[1471477916] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/default; range_end:; response_count:1; response_revision:1440; }","duration":"100.809643ms","start":"2026-06-26T03:32:01.759802Z","end":"2026-06-26T03:32:01.860611Z","steps":["trace[1471477916] 'range keys from in-memory index tree' (duration: 100.449552ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:32:02.39144Z","caller":"traceutil/trace.go:171","msg":"trace[941894875] linearizableReadLoop","detail":"{readStateIndex:1507; appliedIndex:1506; }","duration":"125.865754ms","start":"2026-06-26T03:32:02.265549Z","end":"2026-06-26T03:32:02.391415Z","steps":["trace[941894875] 'read index received' (duration: 125.644852ms)","trace[941894875] 'applied index is now lower than readState.Index' (duration: 219.872µs)"],"step_count":2} {"level":"info","ts":"2026-06-26T03:32:02.391512Z","caller":"traceutil/trace.go:171","msg":"trace[1349412436] transaction","detail":"{read_only:false; response_revision:1442; number_of_response:1; }","duration":"151.340029ms","start":"2026-06-26T03:32:02.240133Z","end":"2026-06-26T03:32:02.391473Z","steps":["trace[1349412436] 'process raft request' (duration: 151.150129ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:32:02.391616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.052674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/kubeadm:kubelet-config\" ","response":"range_response_count:1 size:538"} {"level":"info","ts":"2026-06-26T03:32:02.391667Z","caller":"traceutil/trace.go:171","msg":"trace[1306383758] range","detail":"{range_begin:/registry/rolebindings/kube-system/kubeadm:kubelet-config; range_end:; response_count:1; response_revision:1442; }","duration":"126.16417ms","start":"2026-06-26T03:32:02.265488Z","end":"2026-06-26T03:32:02.391652Z","steps":["trace[1306383758] 'agreement among raft nodes before linearized reading' (duration: 126.060674ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:32:02.391714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.225139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/kubeadm:nodes-kubeadm-config\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-06-26T03:32:02.392058Z","caller":"traceutil/trace.go:171","msg":"trace[851274896] range","detail":"{range_begin:/registry/rolebindings/kube-system/kubeadm:nodes-kubeadm-config; range_end:; response_count:1; response_revision:1442; }","duration":"100.606249ms","start":"2026-06-26T03:32:02.291426Z","end":"2026-06-26T03:32:02.392033Z","steps":["trace[851274896] 'agreement among raft nodes before linearized reading' (duration: 100.183596ms)"],"step_count":1}