{"level":"warn","ts":"2026-05-29T02:00:47.466824Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-29T02:00:47.466918Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.74: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.74:2380","--initial-cluster=instance=https://199.204.45.74:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.74:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.74: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-05-29T02:00:47.466989Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-29T02:00:47.467018Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.74:2380"]} {"level":"info","ts":"2026-05-29T02:00:47.467036Z","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-05-29T02:00:47.467815Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"]} {"level":"info","ts":"2026-05-29T02:00:47.467887Z","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.74:2380"],"listen-peer-urls":["https://199.204.45.74:2380"],"advertise-client-urls":["https://199.204.45.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.74: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-05-29T02:00:47.473682Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.932497ms"} {"level":"info","ts":"2026-05-29T02:00:47.483966Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"89ba38f964b95aa0","cluster-id":"54492f71bf65c400"} {"level":"info","ts":"2026-05-29T02:00:47.484018Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=()"} {"level":"info","ts":"2026-05-29T02:00:47.484035Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became follower at term 0"} {"level":"info","ts":"2026-05-29T02:00:47.484076Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 89ba38f964b95aa0 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-29T02:00:47.484084Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became follower at term 1"} {"level":"info","ts":"2026-05-29T02:00:47.484153Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=(9924307372652190368)"} {"level":"warn","ts":"2026-05-29T02:00:47.489555Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-29T02:00:47.490727Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-29T02:00:47.492962Z","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-05-29T02:00:47.494942Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"89ba38f964b95aa0","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-29T02:00:47.495853Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"89ba38f964b95aa0","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-29T02:00:47.495868Z","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-05-29T02:00:47.496539Z","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-05-29T02:00:47.496635Z","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-05-29T02:00:47.496626Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=(9924307372652190368)"} {"level":"info","ts":"2026-05-29T02:00:47.497243Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"54492f71bf65c400","local-member-id":"89ba38f964b95aa0","added-peer-id":"89ba38f964b95aa0","added-peer-peer-urls":["https://199.204.45.74:2380"]} {"level":"info","ts":"2026-05-29T02:00:47.499629Z","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-05-29T02:00:47.499753Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.74:2380"} {"level":"info","ts":"2026-05-29T02:00:47.499785Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.74:2380"} {"level":"info","ts":"2026-05-29T02:00:47.500623Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"89ba38f964b95aa0","initial-advertise-peer-urls":["https://199.204.45.74:2380"],"listen-peer-urls":["https://199.204.45.74:2380"],"advertise-client-urls":["https://199.204.45.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-29T02:00:47.500659Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-29T02:00:48.184808Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 is starting a new election at term 1"} {"level":"info","ts":"2026-05-29T02:00:48.184874Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-29T02:00:48.184961Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 received MsgPreVoteResp from 89ba38f964b95aa0 at term 1"} {"level":"info","ts":"2026-05-29T02:00:48.184987Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became candidate at term 2"} {"level":"info","ts":"2026-05-29T02:00:48.185033Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 received MsgVoteResp from 89ba38f964b95aa0 at term 2"} {"level":"info","ts":"2026-05-29T02:00:48.185079Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became leader at term 2"} {"level":"info","ts":"2026-05-29T02:00:48.185099Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 89ba38f964b95aa0 elected leader 89ba38f964b95aa0 at term 2"} {"level":"info","ts":"2026-05-29T02:00:48.197578Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T02:00:48.199681Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"89ba38f964b95aa0","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.74:2379]}","request-path":"/0/members/89ba38f964b95aa0/attributes","cluster-id":"54492f71bf65c400","publish-timeout":"7s"} {"level":"info","ts":"2026-05-29T02:00:48.199705Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-29T02:00:48.199834Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-29T02:00:48.20141Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.74:2379"} {"level":"info","ts":"2026-05-29T02:00:48.201845Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-29T02:00:48.201915Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-29T02:00:48.202849Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"54492f71bf65c400","local-member-id":"89ba38f964b95aa0","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T02:00:48.203018Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T02:00:48.203146Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T02:00:48.203756Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-29T02:00:49.808523Z","caller":"traceutil/trace.go:171","msg":"trace[1367769408] transaction","detail":"{read_only:false; response_revision:41; number_of_response:1; }","duration":"121.452127ms","start":"2026-05-29T02:00:49.687031Z","end":"2026-05-29T02:00:49.808483Z","steps":["trace[1367769408] 'process raft request' (duration: 96.591515ms)","trace[1367769408] 'compare' (duration: 24.670641ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:00:49.808611Z","caller":"traceutil/trace.go:171","msg":"trace[926608495] linearizableReadLoop","detail":"{readStateIndex:45; appliedIndex:44; }","duration":"119.406887ms","start":"2026-05-29T02:00:49.689184Z","end":"2026-05-29T02:00:49.808591Z","steps":["trace[926608495] 'read index received' (duration: 94.452104ms)","trace[926608495] 'applied index is now lower than readState.Index' (duration: 24.953423ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:00:49.808715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.519947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-29T02:00:49.808764Z","caller":"traceutil/trace.go:171","msg":"trace[1019883793] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:41; }","duration":"119.603028ms","start":"2026-05-29T02:00:49.689152Z","end":"2026-05-29T02:00:49.808756Z","steps":["trace[1019883793] 'agreement among raft nodes before linearized reading' (duration: 119.503487ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:00:49.939677Z","caller":"traceutil/trace.go:171","msg":"trace[264064186] transaction","detail":"{read_only:false; response_revision:50; number_of_response:1; }","duration":"109.014311ms","start":"2026-05-29T02:00:49.830644Z","end":"2026-05-29T02:00:49.939658Z","steps":["trace[264064186] 'process raft request' (duration: 105.867285ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:00:50.253869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.867363ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-05-29T02:00:50.254091Z","caller":"traceutil/trace.go:171","msg":"trace[1087431638] transaction","detail":"{read_only:false; response_revision:57; number_of_response:1; }","duration":"261.564565ms","start":"2026-05-29T02:00:49.992499Z","end":"2026-05-29T02:00:50.254064Z","steps":["trace[1087431638] 'process raft request' (duration: 61.671537ms)","trace[1087431638] 'compare' (duration: 198.768102ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:00:50.255024Z","caller":"traceutil/trace.go:171","msg":"trace[147939791] transaction","detail":"{read_only:false; response_revision:59; number_of_response:1; }","duration":"237.95553ms","start":"2026-05-29T02:00:50.017045Z","end":"2026-05-29T02:00:50.255001Z","steps":["trace[147939791] 'process raft request' (duration: 237.88722ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:00:50.255243Z","caller":"traceutil/trace.go:171","msg":"trace[930550036] transaction","detail":"{read_only:false; response_revision:58; number_of_response:1; }","duration":"238.812344ms","start":"2026-05-29T02:00:50.016408Z","end":"2026-05-29T02:00:50.255221Z","steps":["trace[930550036] 'process raft request' (duration: 238.437022ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:00:51.515263Z","caller":"traceutil/trace.go:171","msg":"trace[23157754] transaction","detail":"{read_only:false; response_revision:207; number_of_response:1; }","duration":"116.632333ms","start":"2026-05-29T02:00:51.398602Z","end":"2026-05-29T02:00:51.515234Z","steps":["trace[23157754] 'process raft request' (duration: 75.931859ms)","trace[23157754] 'compare' (duration: 40.461993ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:00:53.595098Z","caller":"traceutil/trace.go:171","msg":"trace[503267010] transaction","detail":"{read_only:false; response_revision:262; number_of_response:1; }","duration":"224.578146ms","start":"2026-05-29T02:00:53.370488Z","end":"2026-05-29T02:00:53.595066Z","steps":["trace[503267010] 'process raft request' (duration: 224.305605ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:00:54.352121Z","caller":"traceutil/trace.go:171","msg":"trace[982069347] linearizableReadLoop","detail":"{readStateIndex:287; appliedIndex:285; }","duration":"174.916482ms","start":"2026-05-29T02:00:54.177184Z","end":"2026-05-29T02:00:54.352101Z","steps":["trace[982069347] 'read index received' (duration: 15.55116ms)","trace[982069347] 'applied index is now lower than readState.Index' (duration: 159.364302ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:00:54.35215Z","caller":"traceutil/trace.go:171","msg":"trace[1632584353] transaction","detail":"{read_only:false; response_revision:281; number_of_response:1; }","duration":"179.567947ms","start":"2026-05-29T02:00:54.172557Z","end":"2026-05-29T02:00:54.352125Z","steps":["trace[1632584353] 'process raft request' (duration: 175.907657ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:00:54.352234Z","caller":"traceutil/trace.go:171","msg":"trace[405183916] transaction","detail":"{read_only:false; response_revision:282; number_of_response:1; }","duration":"173.627846ms","start":"2026-05-29T02:00:54.178594Z","end":"2026-05-29T02:00:54.352222Z","steps":["trace[405183916] 'process raft request' (duration: 173.465195ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:00:54.352489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.273384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T02:00:54.352587Z","caller":"traceutil/trace.go:171","msg":"trace[1077387240] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:282; }","duration":"175.400165ms","start":"2026-05-29T02:00:54.177162Z","end":"2026-05-29T02:00:54.352562Z","steps":["trace[1077387240] 'agreement among raft nodes before linearized reading' (duration: 175.208674ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:00:54.407976Z","caller":"traceutil/trace.go:171","msg":"trace[259394816] transaction","detail":"{read_only:false; number_of_response:0; response_revision:282; }","duration":"129.593069ms","start":"2026-05-29T02:00:54.278361Z","end":"2026-05-29T02:00:54.407954Z","steps":["trace[259394816] 'process raft request' (duration: 129.484428ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:00:54.408117Z","caller":"traceutil/trace.go:171","msg":"trace[1890753282] transaction","detail":"{read_only:false; number_of_response:0; response_revision:282; }","duration":"112.48608ms","start":"2026-05-29T02:00:54.295593Z","end":"2026-05-29T02:00:54.408079Z","steps":["trace[1890753282] 'process raft request' (duration: 112.31973ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:00:54.408198Z","caller":"traceutil/trace.go:171","msg":"trace[272479908] transaction","detail":"{read_only:false; number_of_response:0; response_revision:285; }","duration":"111.217614ms","start":"2026-05-29T02:00:54.296961Z","end":"2026-05-29T02:00:54.408179Z","steps":["trace[272479908] 'process raft request' (duration: 111.188664ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:00:54.408261Z","caller":"traceutil/trace.go:171","msg":"trace[374875467] transaction","detail":"{read_only:false; response_revision:284; number_of_response:1; }","duration":"112.139579ms","start":"2026-05-29T02:00:54.296086Z","end":"2026-05-29T02:00:54.408225Z","steps":["trace[374875467] 'process raft request' (duration: 111.998118ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:00:54.408323Z","caller":"traceutil/trace.go:171","msg":"trace[1323686859] transaction","detail":"{read_only:false; response_revision:283; number_of_response:1; }","duration":"112.27628ms","start":"2026-05-29T02:00:54.29603Z","end":"2026-05-29T02:00:54.408306Z","steps":["trace[1323686859] 'process raft request' (duration: 111.912538ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:00:54.408332Z","caller":"traceutil/trace.go:171","msg":"trace[1188635474] transaction","detail":"{read_only:false; response_revision:285; number_of_response:1; }","duration":"111.838498ms","start":"2026-05-29T02:00:54.296474Z","end":"2026-05-29T02:00:54.408313Z","steps":["trace[1188635474] 'process raft request' (duration: 111.648927ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:00:54.408819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.308567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/pv-protection-controller\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T02:00:54.408835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.555655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-05-29T02:00:54.408878Z","caller":"traceutil/trace.go:171","msg":"trace[657881270] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/pv-protection-controller; range_end:; response_count:0; response_revision:285; }","duration":"189.409827ms","start":"2026-05-29T02:00:54.219452Z","end":"2026-05-29T02:00:54.408862Z","steps":["trace[657881270] 'agreement among raft nodes before linearized reading' (duration: 189.216856ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:00:54.408881Z","caller":"traceutil/trace.go:171","msg":"trace[202504812] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:285; }","duration":"231.644045ms","start":"2026-05-29T02:00:54.177224Z","end":"2026-05-29T02:00:54.408868Z","steps":["trace[202504812] 'agreement among raft nodes before linearized reading' (duration: 231.472855ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:00:54.561334Z","caller":"traceutil/trace.go:171","msg":"trace[1270594175] transaction","detail":"{read_only:false; response_revision:288; number_of_response:1; }","duration":"126.03312ms","start":"2026-05-29T02:00:54.435275Z","end":"2026-05-29T02:00:54.561308Z","steps":["trace[1270594175] 'process raft request' (duration: 75.957412ms)","trace[1270594175] 'compare' (duration: 49.805197ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:00:54.561399Z","caller":"traceutil/trace.go:171","msg":"trace[375141071] linearizableReadLoop","detail":"{readStateIndex:298; appliedIndex:296; }","duration":"125.8156ms","start":"2026-05-29T02:00:54.435562Z","end":"2026-05-29T02:00:54.561377Z","steps":["trace[375141071] 'read index received' (duration: 75.841831ms)","trace[375141071] 'applied index is now lower than readState.Index' (duration: 49.972579ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:00:54.561554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.95455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4016"} {"level":"info","ts":"2026-05-29T02:00:54.561584Z","caller":"traceutil/trace.go:171","msg":"trace[847149194] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:291; }","duration":"126.067131ms","start":"2026-05-29T02:00:54.435509Z","end":"2026-05-29T02:00:54.561576Z","steps":["trace[847149194] 'agreement among raft nodes before linearized reading' (duration: 125.93961ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:00:54.561668Z","caller":"traceutil/trace.go:171","msg":"trace[1526466912] transaction","detail":"{read_only:false; response_revision:290; number_of_response:1; }","duration":"124.768944ms","start":"2026-05-29T02:00:54.436883Z","end":"2026-05-29T02:00:54.561652Z","steps":["trace[1526466912] 'process raft request' (duration: 124.344352ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:00:54.561815Z","caller":"traceutil/trace.go:171","msg":"trace[850374405] transaction","detail":"{read_only:false; response_revision:289; number_of_response:1; }","duration":"126.518553ms","start":"2026-05-29T02:00:54.435277Z","end":"2026-05-29T02:00:54.561796Z","steps":["trace[850374405] 'process raft request' (duration: 125.90843ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:00:54.561923Z","caller":"traceutil/trace.go:171","msg":"trace[205858810] transaction","detail":"{read_only:false; response_revision:291; number_of_response:1; }","duration":"124.959925ms","start":"2026-05-29T02:00:54.436951Z","end":"2026-05-29T02:00:54.561911Z","steps":["trace[205858810] 'process raft request' (duration: 124.377952ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:00:55.004822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.663864ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-29T02:00:55.004941Z","caller":"traceutil/trace.go:171","msg":"trace[2075613601] transaction","detail":"{read_only:false; response_revision:295; number_of_response:1; }","duration":"405.661943ms","start":"2026-05-29T02:00:54.599258Z","end":"2026-05-29T02:00:55.00492Z","steps":["trace[2075613601] 'process raft request' (duration: 86.830058ms)","trace[2075613601] 'compare' (duration: 318.519624ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:00:55.005001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:00:54.59924Z","time spent":"405.739904ms","remote":"127.0.0.1:40180","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":636,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T02:00:55.032935Z","caller":"traceutil/trace.go:171","msg":"trace[1267798128] linearizableReadLoop","detail":"{readStateIndex:306; appliedIndex:305; }","duration":"103.103331ms","start":"2026-05-29T02:00:54.929814Z","end":"2026-05-29T02:00:55.032917Z","steps":["trace[1267798128] 'read index received' (duration: 103.051041ms)","trace[1267798128] 'applied index is now lower than readState.Index' (duration: 50.67µs)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:00:55.033073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.267232ms","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-05-29T02:00:55.033103Z","caller":"traceutil/trace.go:171","msg":"trace[629940101] transaction","detail":"{read_only:false; response_revision:296; number_of_response:1; }","duration":"367.845068ms","start":"2026-05-29T02:00:54.665164Z","end":"2026-05-29T02:00:55.033009Z","steps":["trace[629940101] 'process raft request' (duration: 367.619827ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:00:55.033112Z","caller":"traceutil/trace.go:171","msg":"trace[1308220586] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:297; }","duration":"103.376633ms","start":"2026-05-29T02:00:54.929725Z","end":"2026-05-29T02:00:55.033101Z","steps":["trace[1308220586] 'agreement among raft nodes before linearized reading' (duration: 103.275963ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:00:55.033331Z","caller":"traceutil/trace.go:171","msg":"trace[1327627322] transaction","detail":"{read_only:false; response_revision:297; number_of_response:1; }","duration":"364.172618ms","start":"2026-05-29T02:00:54.669117Z","end":"2026-05-29T02:00:55.033289Z","steps":["trace[1327627322] 'process raft request' (duration: 363.747756ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:00:55.033394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:00:54.665138Z","time spent":"368.113949ms","remote":"127.0.0.1:40290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":201,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T02:00:55.033635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:00:54.669088Z","time spent":"364.42137ms","remote":"127.0.0.1:40548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3200,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T02:00:55.501955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.711335ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-29T02:00:55.502144Z","caller":"traceutil/trace.go:171","msg":"trace[308872364] transaction","detail":"{read_only:false; response_revision:298; number_of_response:1; }","duration":"493.332521ms","start":"2026-05-29T02:00:55.008783Z","end":"2026-05-29T02:00:55.502115Z","steps":["trace[308872364] 'process raft request' (duration: 100.388055ms)","trace[308872364] 'compare' (duration: 392.552554ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:00:55.502291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:00:55.008739Z","time spent":"493.487522ms","remote":"127.0.0.1:40180","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":740,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T02:00:55.502245Z","caller":"traceutil/trace.go:171","msg":"trace[1229516944] linearizableReadLoop","detail":"{readStateIndex:307; appliedIndex:306; }","duration":"465.438538ms","start":"2026-05-29T02:00:55.036742Z","end":"2026-05-29T02:00:55.50218Z","steps":["trace[1229516944] 'read index received' (duration: 72.431801ms)","trace[1229516944] 'applied index is now lower than readState.Index' (duration: 392.999616ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:00:55.502172Z","caller":"traceutil/trace.go:171","msg":"trace[2130600267] transaction","detail":"{read_only:false; response_revision:299; number_of_response:1; }","duration":"464.536863ms","start":"2026-05-29T02:00:55.037619Z","end":"2026-05-29T02:00:55.502156Z","steps":["trace[2130600267] 'process raft request' (duration: 464.452973ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:00:55.502443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:00:55.037602Z","time spent":"464.795325ms","remote":"127.0.0.1:40366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T02:00:55.502583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.740079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-05-29T02:00:55.502699Z","caller":"traceutil/trace.go:171","msg":"trace[273431204] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:299; }","duration":"465.97691ms","start":"2026-05-29T02:00:55.0367Z","end":"2026-05-29T02:00:55.502677Z","steps":["trace[273431204] 'agreement among raft nodes before linearized reading' (duration: 465.610848ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:00:55.502894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:00:55.03668Z","time spent":"466.198651ms","remote":"127.0.0.1:40290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":239,"request content":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" "} {"level":"info","ts":"2026-05-29T02:00:55.546729Z","caller":"traceutil/trace.go:171","msg":"trace[183087413] transaction","detail":"{read_only:false; number_of_response:0; response_revision:299; }","duration":"338.794408ms","start":"2026-05-29T02:00:55.207903Z","end":"2026-05-29T02:00:55.546698Z","steps":["trace[183087413] 'process raft request' (duration: 338.648357ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:00:55.547089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:00:55.207874Z","time spent":"339.0819ms","remote":"127.0.0.1:40276","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T02:00:55.781142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.604439ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:5"} {"level":"info","ts":"2026-05-29T02:00:55.781445Z","caller":"traceutil/trace.go:171","msg":"trace[775074050] linearizableReadLoop","detail":"{readStateIndex:311; appliedIndex:308; }","duration":"279.066141ms","start":"2026-05-29T02:00:55.502358Z","end":"2026-05-29T02:00:55.781425Z","steps":["trace[775074050] 'read index received' (duration: 44.116367ms)","trace[775074050] 'applied index is now lower than readState.Index' (duration: 234.948524ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:00:55.781493Z","caller":"traceutil/trace.go:171","msg":"trace[2051703523] transaction","detail":"{read_only:false; number_of_response:0; response_revision:299; }","duration":"573.446701ms","start":"2026-05-29T02:00:55.208002Z","end":"2026-05-29T02:00:55.781448Z","steps":["trace[2051703523] 'process raft request' (duration: 448.43174ms)","trace[2051703523] 'compare' (duration: 124.527519ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:00:55.781601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.711593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:5513"} {"level":"warn","ts":"2026-05-29T02:00:55.781628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"705.290187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T02:00:55.781661Z","caller":"traceutil/trace.go:171","msg":"trace[307131040] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:299; }","duration":"575.819383ms","start":"2026-05-29T02:00:55.205826Z","end":"2026-05-29T02:00:55.781646Z","steps":["trace[307131040] 'agreement among raft nodes before linearized reading' (duration: 575.640662ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:00:55.781689Z","caller":"traceutil/trace.go:171","msg":"trace[2052397294] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:299; }","duration":"705.387987ms","start":"2026-05-29T02:00:55.076284Z","end":"2026-05-29T02:00:55.781672Z","steps":["trace[2052397294] 'agreement among raft nodes before linearized reading' (duration: 705.282457ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:00:55.781714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:00:55.205807Z","time spent":"575.895813ms","remote":"127.0.0.1:40276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":5536,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"warn","ts":"2026-05-29T02:00:55.781733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:00:55.076266Z","time spent":"705.459638ms","remote":"127.0.0.1:40082","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-05-29T02:00:55.781774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"742.221356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:115"} {"level":"info","ts":"2026-05-29T02:00:55.781826Z","caller":"traceutil/trace.go:171","msg":"trace[22951497] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:299; }","duration":"742.325337ms","start":"2026-05-29T02:00:55.039492Z","end":"2026-05-29T02:00:55.781817Z","steps":["trace[22951497] 'agreement among raft nodes before linearized reading' (duration: 742.091496ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:00:55.781876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:00:55.039464Z","time spent":"742.404517ms","remote":"127.0.0.1:40158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":138,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"info","ts":"2026-05-29T02:00:55.781945Z","caller":"traceutil/trace.go:171","msg":"trace[894961598] transaction","detail":"{read_only:false; number_of_response:0; response_revision:299; }","duration":"573.171109ms","start":"2026-05-29T02:00:55.208749Z","end":"2026-05-29T02:00:55.78192Z","steps":["trace[894961598] 'process raft request' (duration: 572.592736ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:00:55.781965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.995405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-05-29T02:00:55.782027Z","caller":"traceutil/trace.go:171","msg":"trace[771153381] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:299; }","duration":"276.085955ms","start":"2026-05-29T02:00:55.505924Z","end":"2026-05-29T02:00:55.78201Z","steps":["trace[771153381] 'agreement among raft nodes before linearized reading' (duration: 275.912344ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:00:55.782065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:00:55.208714Z","time spent":"573.29691ms","remote":"127.0.0.1:40276","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T02:00:55.781762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:00:55.207957Z","time spent":"573.676672ms","remote":"127.0.0.1:40276","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T02:00:55.782576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.748503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:5513"} {"level":"info","ts":"2026-05-29T02:00:55.782663Z","caller":"traceutil/trace.go:171","msg":"trace[109675397] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:299; }","duration":"232.891444ms","start":"2026-05-29T02:00:55.549762Z","end":"2026-05-29T02:00:55.782653Z","steps":["trace[109675397] 'agreement among raft nodes before linearized reading' (duration: 232.198751ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:00:56.274957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.781452ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T02:00:56.27505Z","caller":"traceutil/trace.go:171","msg":"trace[345189607] linearizableReadLoop","detail":"{readStateIndex:328; appliedIndex:327; }","duration":"306.120462ms","start":"2026-05-29T02:00:55.968915Z","end":"2026-05-29T02:00:56.275035Z","steps":["trace[345189607] 'read index received' (duration: 182.106219ms)","trace[345189607] 'applied index is now lower than readState.Index' (duration: 124.013293ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:00:56.275073Z","caller":"traceutil/trace.go:171","msg":"trace[306077884] transaction","detail":"{read_only:false; response_revision:316; number_of_response:1; }","duration":"316.176924ms","start":"2026-05-29T02:00:55.958873Z","end":"2026-05-29T02:00:56.27505Z","steps":["trace[306077884] 'process raft request' (duration: 192.225351ms)","trace[306077884] 'compare' (duration: 123.660221ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:00:56.275139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.231682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-05-29T02:00:56.275163Z","caller":"traceutil/trace.go:171","msg":"trace[1245173646] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler; range_end:; response_count:1; response_revision:316; }","duration":"306.280242ms","start":"2026-05-29T02:00:55.968876Z","end":"2026-05-29T02:00:56.275156Z","steps":["trace[1245173646] 'agreement among raft nodes before linearized reading' (duration: 306.230992ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:00:56.275184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:00:55.968863Z","time spent":"306.316972ms","remote":"127.0.0.1:40290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":239,"request content":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" "} {"level":"warn","ts":"2026-05-29T02:00:56.275172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:00:55.958863Z","time spent":"316.269074ms","remote":"127.0.0.1:40276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7279,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T02:00:56.275369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.90927ms","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-05-29T02:00:56.275454Z","caller":"traceutil/trace.go:171","msg":"trace[1034874217] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:316; }","duration":"102.094661ms","start":"2026-05-29T02:00:56.17334Z","end":"2026-05-29T02:00:56.275434Z","steps":["trace[1034874217] 'agreement among raft nodes before linearized reading' (duration: 101.89307ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:00:56.969335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.984753ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-29T02:00:56.969493Z","caller":"traceutil/trace.go:171","msg":"trace[340495125] transaction","detail":"{read_only:false; response_revision:319; number_of_response:1; }","duration":"613.362268ms","start":"2026-05-29T02:00:56.356104Z","end":"2026-05-29T02:00:56.969467Z","steps":["trace[340495125] 'process raft request' (duration: 79.138094ms)","trace[340495125] 'compare' (duration: 533.761552ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:00:56.969656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:00:56.35609Z","time spent":"613.521829ms","remote":"127.0.0.1:40290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":176,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T02:00:56.98966Z","caller":"traceutil/trace.go:171","msg":"trace[1969649643] transaction","detail":"{read_only:false; response_revision:320; number_of_response:1; }","duration":"629.059518ms","start":"2026-05-29T02:00:56.360573Z","end":"2026-05-29T02:00:56.989632Z","steps":["trace[1969649643] 'process raft request' (duration: 628.605156ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:00:56.98983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:00:56.360565Z","time spent":"629.166539ms","remote":"127.0.0.1:40276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5311,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T02:00:57.250082Z","caller":"traceutil/trace.go:171","msg":"trace[2022942394] transaction","detail":"{read_only:false; response_revision:325; number_of_response:1; }","duration":"134.648903ms","start":"2026-05-29T02:00:57.115394Z","end":"2026-05-29T02:00:57.250043Z","steps":["trace[2022942394] 'process raft request' (duration: 93.317004ms)","trace[2022942394] 'compare' (duration: 41.153378ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:00:57.612041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.374189ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-29T02:00:57.612173Z","caller":"traceutil/trace.go:171","msg":"trace[1614615502] linearizableReadLoop","detail":"{readStateIndex:342; appliedIndex:341; }","duration":"104.275388ms","start":"2026-05-29T02:00:57.507873Z","end":"2026-05-29T02:00:57.612149Z","steps":["trace[1614615502] 'read index received' (duration: 30.92µs)","trace[1614615502] 'applied index is now lower than readState.Index' (duration: 104.242648ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:00:57.612263Z","caller":"traceutil/trace.go:171","msg":"trace[1742461358] transaction","detail":"{read_only:false; response_revision:330; number_of_response:1; }","duration":"268.839063ms","start":"2026-05-29T02:00:57.343358Z","end":"2026-05-29T02:00:57.612197Z","steps":["trace[1742461358] 'process raft request' (duration: 26.225532ms)","trace[1742461358] 'compare' (duration: 242.181599ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:00:57.612329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.416959ms","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-05-29T02:00:57.612499Z","caller":"traceutil/trace.go:171","msg":"trace[1916884691] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:330; }","duration":"104.64158ms","start":"2026-05-29T02:00:57.507842Z","end":"2026-05-29T02:00:57.612483Z","steps":["trace[1916884691] 'agreement among raft nodes before linearized reading' (duration: 104.365679ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:00:58.141747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.004218ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T02:00:58.141915Z","caller":"traceutil/trace.go:171","msg":"trace[663620408] linearizableReadLoop","detail":"{readStateIndex:351; appliedIndex:348; }","duration":"322.141959ms","start":"2026-05-29T02:00:57.81976Z","end":"2026-05-29T02:00:58.141902Z","steps":["trace[663620408] 'read index received' (duration: 151.86571ms)","trace[663620408] 'applied index is now lower than readState.Index' (duration: 170.275599ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:00:58.142Z","caller":"traceutil/trace.go:171","msg":"trace[1681657905] transaction","detail":"{read_only:false; response_revision:337; number_of_response:1; }","duration":"361.043957ms","start":"2026-05-29T02:00:57.780947Z","end":"2026-05-29T02:00:58.141991Z","steps":["trace[1681657905] 'process raft request' (duration: 190.723277ms)","trace[1681657905] 'compare' (duration: 169.832987ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:00:58.142045Z","caller":"traceutil/trace.go:171","msg":"trace[903701740] transaction","detail":"{read_only:false; response_revision:339; number_of_response:1; }","duration":"357.437189ms","start":"2026-05-29T02:00:57.784595Z","end":"2026-05-29T02:00:58.142033Z","steps":["trace[903701740] 'process raft request' (duration: 357.277028ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:00:58.142097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:00:57.784579Z","time spent":"357.494249ms","remote":"127.0.0.1:40376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1050,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T02:00:58.142049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:00:57.780913Z","time spent":"361.111178ms","remote":"127.0.0.1:40548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3691,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T02:00:58.142328Z","caller":"traceutil/trace.go:171","msg":"trace[1183872259] transaction","detail":"{read_only:false; response_revision:338; number_of_response:1; }","duration":"358.418153ms","start":"2026-05-29T02:00:57.783902Z","end":"2026-05-29T02:00:58.142321Z","steps":["trace[1183872259] 'process raft request' (duration: 357.928371ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:00:58.142334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.579962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-05-29T02:00:58.142399Z","caller":"traceutil/trace.go:171","msg":"trace[934621726] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:339; }","duration":"322.693203ms","start":"2026-05-29T02:00:57.819697Z","end":"2026-05-29T02:00:58.142391Z","steps":["trace[934621726] 'agreement among raft nodes before linearized reading' (duration: 322.597722ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:00:58.14243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:00:57.81967Z","time spent":"322.752723ms","remote":"127.0.0.1:40290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":1,"response size":241,"request content":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" "} {"level":"warn","ts":"2026-05-29T02:00:58.142526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.697549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-05-29T02:00:58.142558Z","caller":"traceutil/trace.go:171","msg":"trace[121436350] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:339; }","duration":"272.72855ms","start":"2026-05-29T02:00:57.869822Z","end":"2026-05-29T02:00:58.14255Z","steps":["trace[121436350] 'agreement among raft nodes before linearized reading' (duration: 272.681199ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:00:58.142374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:00:57.783894Z","time spent":"358.457444ms","remote":"127.0.0.1:40352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2267,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T02:00:58.142779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.334432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-29T02:00:58.142811Z","caller":"traceutil/trace.go:171","msg":"trace[820815124] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:339; }","duration":"123.397873ms","start":"2026-05-29T02:00:58.019405Z","end":"2026-05-29T02:00:58.142803Z","steps":["trace[820815124] 'agreement among raft nodes before linearized reading' (duration: 123.353303ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:00:58.142964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.718621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-29T02:00:58.142997Z","caller":"traceutil/trace.go:171","msg":"trace[1636288643] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:339; }","duration":"172.837883ms","start":"2026-05-29T02:00:57.970152Z","end":"2026-05-29T02:00:58.142989Z","steps":["trace[1636288643] 'agreement among raft nodes before linearized reading' (duration: 172.754772ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:00:58.143096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.324173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-05-29T02:00:58.143121Z","caller":"traceutil/trace.go:171","msg":"trace[661154771] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:339; }","duration":"222.382514ms","start":"2026-05-29T02:00:57.920732Z","end":"2026-05-29T02:00:58.143114Z","steps":["trace[661154771] 'agreement among raft nodes before linearized reading' (duration: 222.335964ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:00:58.432212Z","caller":"traceutil/trace.go:171","msg":"trace[2119663149] linearizableReadLoop","detail":"{readStateIndex:356; appliedIndex:355; }","duration":"226.641343ms","start":"2026-05-29T02:00:58.205548Z","end":"2026-05-29T02:00:58.432189Z","steps":["trace[2119663149] 'read index received' (duration: 153.167183ms)","trace[2119663149] 'applied index is now lower than readState.Index' (duration: 73.47317ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:00:58.432324Z","caller":"traceutil/trace.go:171","msg":"trace[1031438818] transaction","detail":"{read_only:false; response_revision:344; number_of_response:1; }","duration":"251.564629ms","start":"2026-05-29T02:00:58.180749Z","end":"2026-05-29T02:00:58.432314Z","steps":["trace[1031438818] 'process raft request' (duration: 177.935928ms)","trace[1031438818] 'compare' (duration: 73.37633ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:00:58.432533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.7736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-05-29T02:00:58.432604Z","caller":"traceutil/trace.go:171","msg":"trace[1491230580] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:344; }","duration":"253.90105ms","start":"2026-05-29T02:00:58.178687Z","end":"2026-05-29T02:00:58.432588Z","steps":["trace[1491230580] 'agreement among raft nodes before linearized reading' (duration: 253.737229ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:00:58.484164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.995756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-29T02:00:58.484248Z","caller":"traceutil/trace.go:171","msg":"trace[2055220550] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:347; }","duration":"265.110587ms","start":"2026-05-29T02:00:58.219119Z","end":"2026-05-29T02:00:58.484229Z","steps":["trace[2055220550] 'agreement among raft nodes before linearized reading' (duration: 264.947826ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:00:58.484223Z","caller":"traceutil/trace.go:171","msg":"trace[612583905] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"268.546515ms","start":"2026-05-29T02:00:58.215655Z","end":"2026-05-29T02:00:58.484201Z","steps":["trace[612583905] 'process raft request' (duration: 268.314013ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:00:58.484655Z","caller":"traceutil/trace.go:171","msg":"trace[2017119305] transaction","detail":"{read_only:false; response_revision:345; number_of_response:1; }","duration":"273.65453ms","start":"2026-05-29T02:00:58.210987Z","end":"2026-05-29T02:00:58.484642Z","steps":["trace[2017119305] 'process raft request' (duration: 272.801915ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:00:58.484728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.159809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4169"} {"level":"info","ts":"2026-05-29T02:00:58.484771Z","caller":"traceutil/trace.go:171","msg":"trace[1026409234] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"273.350518ms","start":"2026-05-29T02:00:58.211364Z","end":"2026-05-29T02:00:58.484715Z","steps":["trace[1026409234] 'process raft request' (duration: 272.541804ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:00:58.484828Z","caller":"traceutil/trace.go:171","msg":"trace[291867790] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:347; }","duration":"196.29256ms","start":"2026-05-29T02:00:58.288512Z","end":"2026-05-29T02:00:58.484804Z","steps":["trace[291867790] 'agreement among raft nodes before linearized reading' (duration: 196.095969ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:00:58.484905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.128104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T02:00:58.484966Z","caller":"traceutil/trace.go:171","msg":"trace[669776228] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:347; }","duration":"197.224934ms","start":"2026-05-29T02:00:58.287724Z","end":"2026-05-29T02:00:58.484949Z","steps":["trace[669776228] 'agreement among raft nodes before linearized reading' (duration: 197.135654ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:00:58.484974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.689198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-05-29T02:00:58.485095Z","caller":"traceutil/trace.go:171","msg":"trace[1935449646] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:347; }","duration":"213.931419ms","start":"2026-05-29T02:00:58.271136Z","end":"2026-05-29T02:00:58.485068Z","steps":["trace[1935449646] 'agreement among raft nodes before linearized reading' (duration: 213.615337ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:00:58.485616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.758386ms","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-05-29T02:00:58.487885Z","caller":"traceutil/trace.go:171","msg":"trace[1033803686] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:347; }","duration":"166.051467ms","start":"2026-05-29T02:00:58.321817Z","end":"2026-05-29T02:00:58.487869Z","steps":["trace[1033803686] 'agreement among raft nodes before linearized reading' (duration: 162.62362ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:00:59.128232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.337922ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-29T02:00:59.128485Z","caller":"traceutil/trace.go:171","msg":"trace[807922268] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"636.417947ms","start":"2026-05-29T02:00:58.492025Z","end":"2026-05-29T02:00:59.128442Z","steps":["trace[807922268] 'process raft request' (duration: 99.218ms)","trace[807922268] 'compare' (duration: 536.213792ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:00:59.128857Z","caller":"traceutil/trace.go:171","msg":"trace[2000109934] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"636.736408ms","start":"2026-05-29T02:00:58.492096Z","end":"2026-05-29T02:00:59.128832Z","steps":["trace[2000109934] 'process raft request' (duration: 636.247905ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:00:59.128893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:00:58.492002Z","time spent":"636.568577ms","remote":"127.0.0.1:40290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":164,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T02:00:59.128964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:00:58.492063Z","time spent":"636.847359ms","remote":"127.0.0.1:40194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1731,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T02:00:59.12939Z","caller":"traceutil/trace.go:171","msg":"trace[1960628328] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"633.26428ms","start":"2026-05-29T02:00:58.496109Z","end":"2026-05-29T02:00:59.129373Z","steps":["trace[1960628328] 'process raft request' (duration: 632.335336ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:00:59.129481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:00:58.496097Z","time spent":"633.335511ms","remote":"127.0.0.1:40366","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-05-29T02:00:59.129624Z","caller":"traceutil/trace.go:171","msg":"trace[163850257] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"630.393536ms","start":"2026-05-29T02:00:58.499194Z","end":"2026-05-29T02:00:59.129588Z","steps":["trace[163850257] 'process raft request' (duration: 630.150445ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:00:59.129688Z","caller":"traceutil/trace.go:171","msg":"trace[829263024] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"630.410486ms","start":"2026-05-29T02:00:58.499267Z","end":"2026-05-29T02:00:59.129677Z","steps":["trace[829263024] 'process raft request' (duration: 630.151965ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:00:59.129736Z","caller":"traceutil/trace.go:171","msg":"trace[445273270] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"595.991663ms","start":"2026-05-29T02:00:58.533732Z","end":"2026-05-29T02:00:59.129724Z","steps":["trace[445273270] 'process raft request' (duration: 595.780092ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:00:59.129772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:00:58.499257Z","time spent":"630.481266ms","remote":"127.0.0.1:40440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2191,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T02:00:59.129871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:00:58.53371Z","time spent":"596.071553ms","remote":"127.0.0.1:40180","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":687,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T02:00:59.129672Z","caller":"traceutil/trace.go:171","msg":"trace[52730075] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"633.04184ms","start":"2026-05-29T02:00:58.496609Z","end":"2026-05-29T02:00:59.129651Z","steps":["trace[52730075] 'process raft request' (duration: 632.655898ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:00:59.129888Z","caller":"traceutil/trace.go:171","msg":"trace[1150115615] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"594.302474ms","start":"2026-05-29T02:00:58.535412Z","end":"2026-05-29T02:00:59.129714Z","steps":["trace[1150115615] 'process raft request' (duration: 594.185923ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:00:59.129942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:00:58.496602Z","time spent":"633.319131ms","remote":"127.0.0.1:40276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3527,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T02:00:59.129894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:00:58.499183Z","time spent":"630.638258ms","remote":"127.0.0.1:40440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":899,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T02:00:59.130132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:00:58.535395Z","time spent":"594.535535ms","remote":"127.0.0.1:40276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7279,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T02:00:59.129695Z","caller":"traceutil/trace.go:171","msg":"trace[985303377] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"630.306586ms","start":"2026-05-29T02:00:58.499319Z","end":"2026-05-29T02:00:59.129625Z","steps":["trace[985303377] 'process raft request' (duration: 630.144445ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:00:59.130602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:00:58.499262Z","time spent":"631.29134ms","remote":"127.0.0.1:40440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2094,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T02:00:59.13127Z","caller":"traceutil/trace.go:171","msg":"trace[687177140] linearizableReadLoop","detail":"{readStateIndex:370; appliedIndex:363; }","duration":"597.513971ms","start":"2026-05-29T02:00:58.53373Z","end":"2026-05-29T02:00:59.131244Z","steps":["trace[687177140] 'read index received' (duration: 57.616661ms)","trace[687177140] 'applied index is now lower than readState.Index' (duration: 539.89464ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:00:59.131654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.913013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/kube-proxy\" ","response":"range_response_count:1 size:2901"} {"level":"info","ts":"2026-05-29T02:00:59.131915Z","caller":"traceutil/trace.go:171","msg":"trace[768754079] range","detail":"{range_begin:/registry/daemonsets/kube-system/kube-proxy; range_end:; response_count:1; response_revision:360; }","duration":"598.206814ms","start":"2026-05-29T02:00:58.533694Z","end":"2026-05-29T02:00:59.131901Z","steps":["trace[768754079] 'agreement among raft nodes before linearized reading' (duration: 597.891362ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:00:59.132081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:00:58.533678Z","time spent":"598.391655ms","remote":"127.0.0.1:40552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":2924,"request content":"key:\"/registry/daemonsets/kube-system/kube-proxy\" "} {"level":"warn","ts":"2026-05-29T02:00:59.131991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.520197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"info","ts":"2026-05-29T02:00:59.132847Z","caller":"traceutil/trace.go:171","msg":"trace[428230664] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:360; }","duration":"421.415062ms","start":"2026-05-29T02:00:58.711415Z","end":"2026-05-29T02:00:59.13283Z","steps":["trace[428230664] 'agreement among raft nodes before linearized reading' (duration: 420.418427ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:00:59.132907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:00:58.711388Z","time spent":"421.505232ms","remote":"127.0.0.1:40290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":208,"request content":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" "} {"level":"info","ts":"2026-05-29T02:00:59.299459Z","caller":"traceutil/trace.go:171","msg":"trace[263194668] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"161.585109ms","start":"2026-05-29T02:00:59.13783Z","end":"2026-05-29T02:00:59.299415Z","steps":["trace[263194668] 'process raft request' (duration: 144.359193ms)","trace[263194668] 'compare' (duration: 16.763664ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:00:59.29951Z","caller":"traceutil/trace.go:171","msg":"trace[2111058871] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"160.794055ms","start":"2026-05-29T02:00:59.138667Z","end":"2026-05-29T02:00:59.299461Z","steps":["trace[2111058871] 'process raft request' (duration: 160.641645ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:00:59.299875Z","caller":"traceutil/trace.go:171","msg":"trace[668214774] linearizableReadLoop","detail":"{readStateIndex:380; appliedIndex:372; }","duration":"151.955132ms","start":"2026-05-29T02:00:59.147774Z","end":"2026-05-29T02:00:59.299729Z","steps":["trace[668214774] 'read index received' (duration: 17.960991ms)","trace[668214774] 'applied index is now lower than readState.Index' (duration: 133.993111ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:00:59.300252Z","caller":"traceutil/trace.go:171","msg":"trace[1232822110] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"156.778675ms","start":"2026-05-29T02:00:59.143457Z","end":"2026-05-29T02:00:59.300235Z","steps":["trace[1232822110] 'process raft request' (duration: 156.037662ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:00:59.300316Z","caller":"traceutil/trace.go:171","msg":"trace[1686652349] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"143.64009ms","start":"2026-05-29T02:00:59.156651Z","end":"2026-05-29T02:00:59.300291Z","steps":["trace[1686652349] 'process raft request' (duration: 143.032827ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:00:59.300327Z","caller":"traceutil/trace.go:171","msg":"trace[1238705772] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"157.409949ms","start":"2026-05-29T02:00:59.142893Z","end":"2026-05-29T02:00:59.300303Z","steps":["trace[1238705772] 'process raft request' (duration: 156.528024ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:00:59.300536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.738776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/kube-proxy\" ","response":"range_response_count:1 size:2901"} {"level":"info","ts":"2026-05-29T02:00:59.300565Z","caller":"traceutil/trace.go:171","msg":"trace[10778778] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"152.841146ms","start":"2026-05-29T02:00:59.147704Z","end":"2026-05-29T02:00:59.300545Z","steps":["trace[10778778] 'process raft request' (duration: 151.826241ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:00:59.300605Z","caller":"traceutil/trace.go:171","msg":"trace[248020323] range","detail":"{range_begin:/registry/daemonsets/kube-system/kube-proxy; range_end:; response_count:1; response_revision:370; }","duration":"152.846826ms","start":"2026-05-29T02:00:59.147739Z","end":"2026-05-29T02:00:59.300586Z","steps":["trace[248020323] 'agreement among raft nodes before linearized reading' (duration: 152.681715ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:00:59.300995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.082071ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T02:00:59.301068Z","caller":"traceutil/trace.go:171","msg":"trace[1435166477] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"153.273647ms","start":"2026-05-29T02:00:59.147776Z","end":"2026-05-29T02:00:59.30105Z","steps":["trace[1435166477] 'process raft request' (duration: 151.79064ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:00:59.301129Z","caller":"traceutil/trace.go:171","msg":"trace[1938935484] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:370; }","duration":"118.243692ms","start":"2026-05-29T02:00:59.18287Z","end":"2026-05-29T02:00:59.301114Z","steps":["trace[1938935484] 'agreement among raft nodes before linearized reading' (duration: 118.048451ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:00:59.301089Z","caller":"traceutil/trace.go:171","msg":"trace[520323229] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"147.412968ms","start":"2026-05-29T02:00:59.153625Z","end":"2026-05-29T02:00:59.301038Z","steps":["trace[520323229] 'process raft request' (duration: 145.980191ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:00:59.299925Z","caller":"traceutil/trace.go:171","msg":"trace[1607255696] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"159.019467ms","start":"2026-05-29T02:00:59.14088Z","end":"2026-05-29T02:00:59.299899Z","steps":["trace[1607255696] 'process raft request' (duration: 158.491554ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:00:59.929834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.743058ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-29T02:00:59.930173Z","caller":"traceutil/trace.go:171","msg":"trace[315589807] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"621.823169ms","start":"2026-05-29T02:00:59.308299Z","end":"2026-05-29T02:00:59.930122Z","steps":["trace[315589807] 'process raft request' (duration: 402.355178ms)","trace[315589807] 'compare' (duration: 217.964414ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:00:59.930366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:00:59.308272Z","time spent":"621.99981ms","remote":"127.0.0.1:40180","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T02:00:59.930427Z","caller":"traceutil/trace.go:171","msg":"trace[1280013153] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"619.654638ms","start":"2026-05-29T02:00:59.310711Z","end":"2026-05-29T02:00:59.930365Z","steps":["trace[1280013153] 'process raft request' (duration: 619.336807ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:00:59.930792Z","caller":"traceutil/trace.go:171","msg":"trace[2045524395] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"618.216121ms","start":"2026-05-29T02:00:59.312562Z","end":"2026-05-29T02:00:59.930779Z","steps":["trace[2045524395] 'process raft request' (duration: 617.96967ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:00:59.930903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:00:59.312553Z","time spent":"618.282732ms","remote":"127.0.0.1:40440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T02:00:59.930714Z","caller":"traceutil/trace.go:171","msg":"trace[428539080] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"619.774259ms","start":"2026-05-29T02:00:59.310873Z","end":"2026-05-29T02:00:59.930647Z","steps":["trace[428539080] 'process raft request' (duration: 619.339147ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:00:59.931306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:00:59.310848Z","time spent":"620.341103ms","remote":"127.0.0.1:40276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4001,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T02:00:59.932342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:00:59.310677Z","time spent":"620.05568ms","remote":"127.0.0.1:40556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3759,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T02:01:00.021202Z","caller":"traceutil/trace.go:171","msg":"trace[1825242904] linearizableReadLoop","detail":"{readStateIndex:388; appliedIndex:383; }","duration":"355.417842ms","start":"2026-05-29T02:00:59.665758Z","end":"2026-05-29T02:01:00.021175Z","steps":["trace[1825242904] 'read index received' (duration: 44.988005ms)","trace[1825242904] 'applied index is now lower than readState.Index' (duration: 310.428457ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:01:00.021413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.641483ms","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-05-29T02:01:00.02146Z","caller":"traceutil/trace.go:171","msg":"trace[77229702] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:376; }","duration":"355.759053ms","start":"2026-05-29T02:00:59.665689Z","end":"2026-05-29T02:01:00.021448Z","steps":["trace[77229702] 'agreement among raft nodes before linearized reading' (duration: 355.603463ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:01:00.021443Z","caller":"traceutil/trace.go:171","msg":"trace[613976429] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"664.091841ms","start":"2026-05-29T02:00:59.357232Z","end":"2026-05-29T02:01:00.021324Z","steps":["trace[613976429] 'process raft request' (duration: 663.771849ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:01:00.021501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:00:59.665665Z","time spent":"355.825834ms","remote":"127.0.0.1:40366","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-05-29T02:01:00.021632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:00:59.357199Z","time spent":"664.367042ms","remote":"127.0.0.1:40276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5139,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T02:01:00.577948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.779582ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T02:01:00.578268Z","caller":"traceutil/trace.go:171","msg":"trace[1881500612] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"551.616842ms","start":"2026-05-29T02:01:00.02663Z","end":"2026-05-29T02:01:00.578247Z","steps":["trace[1881500612] 'process raft request' (duration: 551.564492ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:01:00.57836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:01:00.026602Z","time spent":"551.720993ms","remote":"127.0.0.1:40366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T02:01:00.578468Z","caller":"traceutil/trace.go:171","msg":"trace[930157828] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"638.237776ms","start":"2026-05-29T02:00:59.940118Z","end":"2026-05-29T02:01:00.578356Z","steps":["trace[930157828] 'process raft request' (duration: 637.965585ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:01:00.578738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:00:59.940096Z","time spent":"638.494067ms","remote":"127.0.0.1:40556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3804,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T02:01:00.578966Z","caller":"traceutil/trace.go:171","msg":"trace[14278455] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"702.384137ms","start":"2026-05-29T02:00:59.876558Z","end":"2026-05-29T02:01:00.578942Z","steps":["trace[14278455] 'process raft request' (duration: 552.492759ms)","trace[14278455] 'compare' (duration: 148.41038ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:01:00.579152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:00:59.876514Z","time spent":"702.529998ms","remote":"127.0.0.1:40366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T02:01:00.57954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.029979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7294"} {"level":"info","ts":"2026-05-29T02:01:00.579617Z","caller":"traceutil/trace.go:171","msg":"trace[1774752104] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:379; }","duration":"553.17029ms","start":"2026-05-29T02:01:00.02643Z","end":"2026-05-29T02:01:00.579601Z","steps":["trace[1774752104] 'agreement among raft nodes before linearized reading' (duration: 553.005439ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:01:00.57976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:01:00.026402Z","time spent":"553.24726ms","remote":"127.0.0.1:40276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7317,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"info","ts":"2026-05-29T02:01:00.580054Z","caller":"traceutil/trace.go:171","msg":"trace[221800530] linearizableReadLoop","detail":"{readStateIndex:390; appliedIndex:388; }","duration":"552.038484ms","start":"2026-05-29T02:01:00.026496Z","end":"2026-05-29T02:01:00.578534Z","steps":["trace[221800530] 'read index received' (duration: 402.502868ms)","trace[221800530] 'applied index is now lower than readState.Index' (duration: 149.529476ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:01:00.580435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.913289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T02:01:00.580503Z","caller":"traceutil/trace.go:171","msg":"trace[483247251] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:379; }","duration":"503.02956ms","start":"2026-05-29T02:01:00.077455Z","end":"2026-05-29T02:01:00.580485Z","steps":["trace[483247251] 'agreement among raft nodes before linearized reading' (duration: 502.898569ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:01:00.580662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:01:00.077429Z","time spent":"503.17762ms","remote":"127.0.0.1:40082","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-05-29T02:01:01.060644Z","caller":"traceutil/trace.go:171","msg":"trace[1971710896] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"105.204854ms","start":"2026-05-29T02:01:00.955359Z","end":"2026-05-29T02:01:01.060564Z","steps":["trace[1971710896] 'process raft request' (duration: 61.143735ms)","trace[1971710896] 'compare' (duration: 43.717687ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:01:01.296557Z","caller":"traceutil/trace.go:171","msg":"trace[1171253639] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"152.439926ms","start":"2026-05-29T02:01:01.144092Z","end":"2026-05-29T02:01:01.296532Z","steps":["trace[1171253639] 'process raft request' (duration: 145.0986ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:01:01.296577Z","caller":"traceutil/trace.go:171","msg":"trace[203536477] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"150.057565ms","start":"2026-05-29T02:01:01.146496Z","end":"2026-05-29T02:01:01.296554Z","steps":["trace[203536477] 'process raft request' (duration: 149.691764ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:01:02.571988Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530393669842665302,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-29T02:01:02.729034Z","caller":"traceutil/trace.go:171","msg":"trace[173983658] linearizableReadLoop","detail":"{readStateIndex:403; appliedIndex:402; }","duration":"657.493018ms","start":"2026-05-29T02:01:02.071513Z","end":"2026-05-29T02:01:02.729007Z","steps":["trace[173983658] 'read index received' (duration: 657.183666ms)","trace[173983658] 'applied index is now lower than readState.Index' (duration: 308.122µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:01:02.729137Z","caller":"traceutil/trace.go:171","msg":"trace[1049927381] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"727.328242ms","start":"2026-05-29T02:01:02.001758Z","end":"2026-05-29T02:01:02.729086Z","steps":["trace[1049927381] 'process raft request' (duration: 727.034841ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:01:02.729199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"657.678119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.74\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-29T02:01:02.729234Z","caller":"traceutil/trace.go:171","msg":"trace[1645009104] range","detail":"{range_begin:/registry/masterleases/199.204.45.74; range_end:; response_count:1; response_revision:390; }","duration":"657.798399ms","start":"2026-05-29T02:01:02.071424Z","end":"2026-05-29T02:01:02.729222Z","steps":["trace[1645009104] 'agreement among raft nodes before linearized reading' (duration: 657.711928ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:01:02.729277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:01:02.071387Z","time spent":"657.880899ms","remote":"127.0.0.1:40138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.74\" "} {"level":"warn","ts":"2026-05-29T02:01:02.729321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:01:02.001719Z","time spent":"727.503494ms","remote":"127.0.0.1:40276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4307,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T02:01:02.729356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.400808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-29T02:01:02.729363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.579674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T02:01:02.729406Z","caller":"traceutil/trace.go:171","msg":"trace[1719978483] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:390; }","duration":"143.482819ms","start":"2026-05-29T02:01:02.58591Z","end":"2026-05-29T02:01:02.729393Z","steps":["trace[1719978483] 'agreement among raft nodes before linearized reading' (duration: 143.367838ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:01:02.729428Z","caller":"traceutil/trace.go:171","msg":"trace[179083021] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:390; }","duration":"652.666064ms","start":"2026-05-29T02:01:02.076751Z","end":"2026-05-29T02:01:02.729417Z","steps":["trace[179083021] 'agreement among raft nodes before linearized reading' (duration: 652.521633ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:01:02.729509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:01:02.076739Z","time spent":"652.759864ms","remote":"127.0.0.1:40082","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-05-29T02:01:03.233923Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530393669842665307,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T02:01:05.87289Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.112290933s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-29T02:01:06.076537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000099671s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-29T02:01:06.07664Z","caller":"traceutil/trace.go:171","msg":"trace[1828196959] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000248751s","start":"2026-05-29T02:01:04.07637Z","end":"2026-05-29T02:01:06.076619Z","steps":["trace[1828196959] 'agreement among raft nodes before linearized reading' (duration: 2.000125531s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:01:06.076702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:01:04.076352Z","time spent":"2.000338432s","remote":"127.0.0.1:40082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/29 02:01:06 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-29T02:01:06.288857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.447399ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T02:01:06.288991Z","caller":"traceutil/trace.go:171","msg":"trace[143220089] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:390; }","duration":"218.59175ms","start":"2026-05-29T02:01:06.070381Z","end":"2026-05-29T02:01:06.288973Z","steps":["trace[143220089] 'range keys from in-memory index tree' (duration: 218.431369ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:01:06.289383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.572313538s","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-05-29T02:01:06.289576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:01:02.730663Z","time spent":"3.558907544s","remote":"127.0.0.1:40138","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-29T02:01:06.289662Z","caller":"traceutil/trace.go:171","msg":"trace[2088270389] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"1.683744933s","start":"2026-05-29T02:01:04.605903Z","end":"2026-05-29T02:01:06.289648Z","steps":["trace[2088270389] 'process raft request' (duration: 1.683688413s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:01:06.289757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:01:04.605864Z","time spent":"1.683835353s","remote":"127.0.0.1:40366","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-05-29T02:01:06.29003Z","caller":"traceutil/trace.go:171","msg":"trace[1537054827] linearizableReadLoop","detail":"{readStateIndex:405; appliedIndex:403; }","duration":"3.556756493s","start":"2026-05-29T02:01:02.73323Z","end":"2026-05-29T02:01:06.289987Z","steps":["trace[1537054827] 'read index received' (duration: 983.880622ms)","trace[1537054827] 'applied index is now lower than readState.Index' (duration: 2.572873841s)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:01:06.29011Z","caller":"traceutil/trace.go:171","msg":"trace[1820753730] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"3.555616547s","start":"2026-05-29T02:01:02.734482Z","end":"2026-05-29T02:01:06.290098Z","steps":["trace[1820753730] 'process raft request' (duration: 3.554974464s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:01:06.29018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:01:02.734466Z","time spent":"3.555681278s","remote":"127.0.0.1:40366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T02:01:06.290274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.557057604s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4322"} {"level":"info","ts":"2026-05-29T02:01:06.290318Z","caller":"traceutil/trace.go:171","msg":"trace[1017315943] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:392; }","duration":"3.557102244s","start":"2026-05-29T02:01:02.733202Z","end":"2026-05-29T02:01:06.290304Z","steps":["trace[1017315943] 'agreement among raft nodes before linearized reading' (duration: 3.556936884s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:01:06.290377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:01:02.733153Z","time spent":"3.557189435s","remote":"127.0.0.1:40276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4345,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-05-29T02:01:06.290397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.98773339s","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-05-29T02:01:06.290429Z","caller":"traceutil/trace.go:171","msg":"trace[812327403] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:392; }","duration":"2.9878059s","start":"2026-05-29T02:01:03.302613Z","end":"2026-05-29T02:01:06.290419Z","steps":["trace[812327403] 'agreement among raft nodes before linearized reading' (duration: 2.98774902s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:01:06.290456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:01:03.302592Z","time spent":"2.98785869s","remote":"127.0.0.1:40366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-29T02:01:06.290575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.682289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T02:01:06.290607Z","caller":"traceutil/trace.go:171","msg":"trace[1141689306] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:392; }","duration":"100.729019ms","start":"2026-05-29T02:01:06.18987Z","end":"2026-05-29T02:01:06.290599Z","steps":["trace[1141689306] 'agreement among raft nodes before linearized reading' (duration: 100.685659ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:01:06.290728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.863687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T02:01:06.290758Z","caller":"traceutil/trace.go:171","msg":"trace[1950208908] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:392; }","duration":"213.920908ms","start":"2026-05-29T02:01:06.07683Z","end":"2026-05-29T02:01:06.290751Z","steps":["trace[1950208908] 'agreement among raft nodes before linearized reading' (duration: 213.879667ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:01:06.670268Z","caller":"traceutil/trace.go:171","msg":"trace[555149161] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"372.726987ms","start":"2026-05-29T02:01:06.297515Z","end":"2026-05-29T02:01:06.670242Z","steps":["trace[555149161] 'process raft request' (duration: 372.657967ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:01:06.670458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:01:06.297503Z","time spent":"372.856307ms","remote":"127.0.0.1:40276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4115,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T02:01:06.67044Z","caller":"traceutil/trace.go:171","msg":"trace[452830436] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"375.891182ms","start":"2026-05-29T02:01:06.294492Z","end":"2026-05-29T02:01:06.670383Z","steps":["trace[452830436] 'process raft request' (duration: 375.56167ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:01:06.670713Z","caller":"traceutil/trace.go:171","msg":"trace[812677203] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"378.929706ms","start":"2026-05-29T02:01:06.291758Z","end":"2026-05-29T02:01:06.670688Z","steps":["trace[812677203] 'process raft request' (duration: 337.903197ms)","trace[812677203] 'compare' (duration: 40.241915ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:01:06.670847Z","caller":"traceutil/trace.go:171","msg":"trace[1123248542] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"375.313209ms","start":"2026-05-29T02:01:06.295517Z","end":"2026-05-29T02:01:06.67083Z","steps":["trace[1123248542] 'process raft request' (duration: 374.602685ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:01:06.670882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:01:06.291735Z","time spent":"379.034477ms","remote":"127.0.0.1:40138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T02:01:06.670942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:01:06.295492Z","time spent":"375.406379ms","remote":"127.0.0.1:40180","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T02:01:06.671087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:01:06.294474Z","time spent":"376.122953ms","remote":"127.0.0.1:40366","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-05-29T02:01:20.562543Z","caller":"traceutil/trace.go:171","msg":"trace[1850110301] linearizableReadLoop","detail":"{readStateIndex:451; appliedIndex:450; }","duration":"129.051488ms","start":"2026-05-29T02:01:20.433453Z","end":"2026-05-29T02:01:20.562504Z","steps":["trace[1850110301] 'read index received' (duration: 128.675381ms)","trace[1850110301] 'applied index is now lower than readState.Index' (duration: 374.127µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:01:20.565242Z","caller":"traceutil/trace.go:171","msg":"trace[507834571] transaction","detail":"{read_only:false; response_revision:434; number_of_response:1; }","duration":"130.945453ms","start":"2026-05-29T02:01:20.431689Z","end":"2026-05-29T02:01:20.562634Z","steps":["trace[507834571] 'process raft request' (duration: 130.496212ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:01:20.566123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.643029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4056"} {"level":"info","ts":"2026-05-29T02:01:20.566216Z","caller":"traceutil/trace.go:171","msg":"trace[395360133] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:434; }","duration":"132.774215ms","start":"2026-05-29T02:01:20.433422Z","end":"2026-05-29T02:01:20.566196Z","steps":["trace[395360133] 'agreement among raft nodes before linearized reading' (duration: 129.251526ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:01:34.574983Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530393669842665631,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-29T02:01:34.656734Z","caller":"traceutil/trace.go:171","msg":"trace[2005043985] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"788.502489ms","start":"2026-05-29T02:01:33.868201Z","end":"2026-05-29T02:01:34.656704Z","steps":["trace[2005043985] 'process raft request' (duration: 782.73525ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:01:34.656912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:01:33.868169Z","time spent":"788.662555ms","remote":"127.0.0.1:40148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T02:01:34.662517Z","caller":"traceutil/trace.go:171","msg":"trace[405705785] linearizableReadLoop","detail":"{readStateIndex:598; appliedIndex:595; }","duration":"588.366704ms","start":"2026-05-29T02:01:34.07413Z","end":"2026-05-29T02:01:34.662497Z","steps":["trace[405705785] 'read index received' (duration: 576.65855ms)","trace[405705785] 'applied index is now lower than readState.Index' (duration: 11.707534ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:01:34.662834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.674935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T02:01:34.66296Z","caller":"traceutil/trace.go:171","msg":"trace[139084900] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:574; }","duration":"588.849692ms","start":"2026-05-29T02:01:34.074088Z","end":"2026-05-29T02:01:34.662938Z","steps":["trace[139084900] 'agreement among raft nodes before linearized reading' (duration: 588.608752ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:01:34.66307Z","caller":"traceutil/trace.go:171","msg":"trace[839449758] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"791.863985ms","start":"2026-05-29T02:01:33.871196Z","end":"2026-05-29T02:01:34.66306Z","steps":["trace[839449758] 'process raft request' (duration: 791.102476ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:01:34.663089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:01:34.074071Z","time spent":"589.005248ms","remote":"127.0.0.1:40082","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-05-29T02:01:34.663182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:01:33.871176Z","time spent":"791.925658ms","remote":"127.0.0.1:40148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T02:01:34.663268Z","caller":"traceutil/trace.go:171","msg":"trace[442617545] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"786.315812ms","start":"2026-05-29T02:01:33.876933Z","end":"2026-05-29T02:01:34.663248Z","steps":["trace[442617545] 'process raft request' (duration: 785.499761ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:01:34.663399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:01:33.87692Z","time spent":"786.416027ms","remote":"127.0.0.1:40148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":59607,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T02:01:37.375827Z","caller":"traceutil/trace.go:171","msg":"trace[996013560] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"248.730758ms","start":"2026-05-29T02:01:37.12706Z","end":"2026-05-29T02:01:37.375791Z","steps":["trace[996013560] 'process raft request' (duration: 248.548542ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:01:41.178768Z","caller":"traceutil/trace.go:171","msg":"trace[2060182393] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"222.969734ms","start":"2026-05-29T02:01:40.955775Z","end":"2026-05-29T02:01:41.178745Z","steps":["trace[2060182393] 'process raft request' (duration: 222.846069ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:01:41.178833Z","caller":"traceutil/trace.go:171","msg":"trace[2063083180] linearizableReadLoop","detail":"{readStateIndex:665; appliedIndex:665; }","duration":"105.00453ms","start":"2026-05-29T02:01:41.073791Z","end":"2026-05-29T02:01:41.178795Z","steps":["trace[2063083180] 'read index received' (duration: 104.98225ms)","trace[2063083180] 'applied index is now lower than readState.Index' (duration: 18.58µs)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:01:41.17898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.170066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T02:01:41.179022Z","caller":"traceutil/trace.go:171","msg":"trace[1630076385] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:639; }","duration":"105.257909ms","start":"2026-05-29T02:01:41.073753Z","end":"2026-05-29T02:01:41.179011Z","steps":["trace[1630076385] 'agreement among raft nodes before linearized reading' (duration: 105.157575ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:01:41.406548Z","caller":"traceutil/trace.go:171","msg":"trace[220004441] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"269.158467ms","start":"2026-05-29T02:01:41.137364Z","end":"2026-05-29T02:01:41.406522Z","steps":["trace[220004441] 'process raft request' (duration: 267.938045ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:01:41.424142Z","caller":"traceutil/trace.go:171","msg":"trace[1184531526] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"111.404733ms","start":"2026-05-29T02:01:41.312685Z","end":"2026-05-29T02:01:41.424089Z","steps":["trace[1184531526] 'process raft request' (duration: 111.223487ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:01:42.077063Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530393669842665797,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-29T02:01:42.288222Z","caller":"traceutil/trace.go:171","msg":"trace[289306252] linearizableReadLoop","detail":"{readStateIndex:674; appliedIndex:671; }","duration":"711.820665ms","start":"2026-05-29T02:01:41.576379Z","end":"2026-05-29T02:01:42.288199Z","steps":["trace[289306252] 'read index received' (duration: 671.307935ms)","trace[289306252] 'applied index is now lower than readState.Index' (duration: 40.51189ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:01:42.288572Z","caller":"traceutil/trace.go:171","msg":"trace[131130369] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"751.530683ms","start":"2026-05-29T02:01:41.537004Z","end":"2026-05-29T02:01:42.288534Z","steps":["trace[131130369] 'process raft request' (duration: 751.079587ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:01:42.28858Z","caller":"traceutil/trace.go:171","msg":"trace[1217506115] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"751.721239ms","start":"2026-05-29T02:01:41.53683Z","end":"2026-05-29T02:01:42.288551Z","steps":["trace[1217506115] 'process raft request' (duration: 710.849177ms)","trace[1217506115] 'compare' (duration: 40.290762ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:01:42.288577Z","caller":"traceutil/trace.go:171","msg":"trace[303802301] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"751.441039ms","start":"2026-05-29T02:01:41.537084Z","end":"2026-05-29T02:01:42.288525Z","steps":["trace[303802301] 'process raft request' (duration: 751.069606ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:01:42.288637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.617995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T02:01:42.288724Z","caller":"traceutil/trace.go:171","msg":"trace[338587301] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:648; }","duration":"212.770021ms","start":"2026-05-29T02:01:42.075937Z","end":"2026-05-29T02:01:42.288707Z","steps":["trace[338587301] 'agreement among raft nodes before linearized reading' (duration: 212.615745ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:01:42.288722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.833512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-29T02:01:42.288739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:01:41.536982Z","time spent":"751.669358ms","remote":"127.0.0.1:40338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T02:01:42.288772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:01:41.537062Z","time spent":"751.612585ms","remote":"127.0.0.1:40276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T02:01:42.288798Z","caller":"traceutil/trace.go:171","msg":"trace[1531745907] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:648; }","duration":"660.961317ms","start":"2026-05-29T02:01:41.627814Z","end":"2026-05-29T02:01:42.288776Z","steps":["trace[1531745907] 'agreement among raft nodes before linearized reading' (duration: 660.735219ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:01:42.288804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.870064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.74\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-05-29T02:01:42.288842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:01:41.627786Z","time spent":"661.04763ms","remote":"127.0.0.1:40366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-29T02:01:42.288857Z","caller":"traceutil/trace.go:171","msg":"trace[1344589243] range","detail":"{range_begin:/registry/masterleases/199.204.45.74; range_end:; response_count:1; response_revision:648; }","duration":"212.957207ms","start":"2026-05-29T02:01:42.075881Z","end":"2026-05-29T02:01:42.288838Z","steps":["trace[1344589243] 'agreement among raft nodes before linearized reading' (duration: 212.830953ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:01:42.288741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"712.360573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-29T02:01:42.288979Z","caller":"traceutil/trace.go:171","msg":"trace[1596421689] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:648; }","duration":"712.634062ms","start":"2026-05-29T02:01:41.576331Z","end":"2026-05-29T02:01:42.288965Z","steps":["trace[1596421689] 'agreement among raft nodes before linearized reading' (duration: 712.187437ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:01:42.289078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:01:41.576313Z","time spent":"712.751387ms","remote":"127.0.0.1:40366","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-05-29T02:01:42.288791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:01:41.536813Z","time spent":"751.841134ms","remote":"127.0.0.1:40180","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":775,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T02:01:42.43395Z","caller":"traceutil/trace.go:171","msg":"trace[1714777404] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"139.090976ms","start":"2026-05-29T02:01:42.294837Z","end":"2026-05-29T02:01:42.433928Z","steps":["trace[1714777404] 'process raft request' (duration: 139.051745ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:01:42.43401Z","caller":"traceutil/trace.go:171","msg":"trace[677630412] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"139.250361ms","start":"2026-05-29T02:01:42.294725Z","end":"2026-05-29T02:01:42.433975Z","steps":["trace[677630412] 'process raft request' (duration: 139.065595ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:01:43.330834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.40392ms","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-05-29T02:01:43.330916Z","caller":"traceutil/trace.go:171","msg":"trace[381068534] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:651; }","duration":"627.535995ms","start":"2026-05-29T02:01:42.703361Z","end":"2026-05-29T02:01:43.330897Z","steps":["trace[381068534] 'range keys from in-memory index tree' (duration: 627.232574ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:01:43.330961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:01:42.70334Z","time spent":"627.611277ms","remote":"127.0.0.1:40366","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-05-29T02:01:43.330943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.524149ms","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-05-29T02:01:43.331198Z","caller":"traceutil/trace.go:171","msg":"trace[261063970] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:651; }","duration":"147.786818ms","start":"2026-05-29T02:01:43.183386Z","end":"2026-05-29T02:01:43.331173Z","steps":["trace[261063970] 'range keys from in-memory index tree' (duration: 147.327602ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:01:45.237087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"799.105216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-29T02:01:45.237184Z","caller":"traceutil/trace.go:171","msg":"trace[1807346078] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:653; }","duration":"799.255382ms","start":"2026-05-29T02:01:44.437907Z","end":"2026-05-29T02:01:45.237162Z","steps":["trace[1807346078] 'range keys from in-memory index tree' (duration: 798.90387ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:01:45.237241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:01:44.437887Z","time spent":"799.333744ms","remote":"127.0.0.1:40366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-29T02:01:45.237409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"798.866699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-29T02:01:45.237486Z","caller":"traceutil/trace.go:171","msg":"trace[182038386] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:653; }","duration":"798.968902ms","start":"2026-05-29T02:01:44.438498Z","end":"2026-05-29T02:01:45.237467Z","steps":["trace[182038386] 'range keys from in-memory index tree' (duration: 798.724404ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:01:45.237551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:01:44.43849Z","time spent":"799.030324ms","remote":"127.0.0.1:40366","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-05-29T02:01:45.237734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.161311018s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T02:01:45.237764Z","caller":"traceutil/trace.go:171","msg":"trace[1328213087] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:653; }","duration":"1.16136587s","start":"2026-05-29T02:01:44.076387Z","end":"2026-05-29T02:01:45.237753Z","steps":["trace[1328213087] 'range keys from in-memory index tree' (duration: 1.161252296s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:01:45.23779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:01:44.07637Z","time spent":"1.161414541s","remote":"127.0.0.1:40082","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-05-29T02:01:46.703536Z","caller":"traceutil/trace.go:171","msg":"trace[957643102] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"220.891962ms","start":"2026-05-29T02:01:46.482619Z","end":"2026-05-29T02:01:46.703511Z","steps":["trace[957643102] 'process raft request' (duration: 220.768417ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:01:50.790077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.621783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-05-29T02:01:50.790176Z","caller":"traceutil/trace.go:171","msg":"trace[1597846049] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:726; }","duration":"475.767977ms","start":"2026-05-29T02:01:50.314379Z","end":"2026-05-29T02:01:50.790147Z","steps":["trace[1597846049] 'range keys from in-memory index tree' (duration: 475.427776ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:01:50.790227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:01:50.314359Z","time spent":"475.85695ms","remote":"127.0.0.1:40148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":1,"response size":5609,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" "} {"level":"info","ts":"2026-05-29T02:01:50.790439Z","caller":"traceutil/trace.go:171","msg":"trace[1656757842] linearizableReadLoop","detail":"{readStateIndex:755; appliedIndex:754; }","duration":"394.73715ms","start":"2026-05-29T02:01:50.395677Z","end":"2026-05-29T02:01:50.790415Z","steps":["trace[1656757842] 'read index received' (duration: 394.571275ms)","trace[1656757842] 'applied index is now lower than readState.Index' (duration: 164.595µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:01:50.790456Z","caller":"traceutil/trace.go:171","msg":"trace[692555671] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"474.59897ms","start":"2026-05-29T02:01:50.315834Z","end":"2026-05-29T02:01:50.790433Z","steps":["trace[692555671] 'process raft request' (duration: 474.373153ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:01:50.790606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:01:50.315819Z","time spent":"474.705733ms","remote":"127.0.0.1:40148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188237,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T02:01:50.790807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.107901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-4wpg6\" ","response":"range_response_count:1 size:18333"} {"level":"info","ts":"2026-05-29T02:01:50.790851Z","caller":"traceutil/trace.go:171","msg":"trace[752898350] range","detail":"{range_begin:/registry/pods/kube-system/cilium-4wpg6; range_end:; response_count:1; response_revision:727; }","duration":"395.203194ms","start":"2026-05-29T02:01:50.395636Z","end":"2026-05-29T02:01:50.790839Z","steps":["trace[752898350] 'agreement among raft nodes before linearized reading' (duration: 394.913255ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:01:50.790892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:01:50.395616Z","time spent":"395.264606ms","remote":"127.0.0.1:40276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18356,"request content":"key:\"/registry/pods/kube-system/cilium-4wpg6\" "} {"level":"warn","ts":"2026-05-29T02:01:50.799148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.89727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T02:01:50.799219Z","caller":"traceutil/trace.go:171","msg":"trace[806540995] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:728; }","duration":"252.022945ms","start":"2026-05-29T02:01:50.547181Z","end":"2026-05-29T02:01:50.799204Z","steps":["trace[806540995] 'agreement among raft nodes before linearized reading' (duration: 251.895321ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:01:50.799396Z","caller":"traceutil/trace.go:171","msg":"trace[23111409] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"258.85969ms","start":"2026-05-29T02:01:50.540512Z","end":"2026-05-29T02:01:50.799371Z","steps":["trace[23111409] 'process raft request' (duration: 258.455028ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:01:50.799626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:01:50.397341Z","time spent":"402.282097ms","remote":"127.0.0.1:40180","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-29T02:01:51.39497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.590423ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T02:01:51.395228Z","caller":"traceutil/trace.go:171","msg":"trace[1868325957] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"395.841558ms","start":"2026-05-29T02:01:50.999353Z","end":"2026-05-29T02:01:51.395195Z","steps":["trace[1868325957] 'process raft request' (duration: 267.947786ms)","trace[1868325957] 'compare' (duration: 127.226422ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:01:51.39519Z","caller":"traceutil/trace.go:171","msg":"trace[1443898217] linearizableReadLoop","detail":"{readStateIndex:764; appliedIndex:763; }","duration":"281.354833ms","start":"2026-05-29T02:01:51.113764Z","end":"2026-05-29T02:01:51.395119Z","steps":["trace[1443898217] 'read index received' (duration: 153.365418ms)","trace[1443898217] 'applied index is now lower than readState.Index' (duration: 127.988265ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:01:51.395389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:01:50.999327Z","time spent":"395.979873ms","remote":"127.0.0.1:40148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188480,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T02:01:51.395868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.095416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188252"} {"level":"info","ts":"2026-05-29T02:01:51.395918Z","caller":"traceutil/trace.go:171","msg":"trace[1553939164] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:735; }","duration":"282.182809ms","start":"2026-05-29T02:01:51.113721Z","end":"2026-05-29T02:01:51.395904Z","steps":["trace[1553939164] 'agreement among raft nodes before linearized reading' (duration: 281.936961ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:02:01.755406Z","caller":"traceutil/trace.go:171","msg":"trace[211836686] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"188.984357ms","start":"2026-05-29T02:02:01.566372Z","end":"2026-05-29T02:02:01.755357Z","steps":["trace[211836686] 'process raft request' (duration: 128.240414ms)","trace[211836686] 'compare' (duration: 60.404513ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:02:01.755596Z","caller":"traceutil/trace.go:171","msg":"trace[540008792] linearizableReadLoop","detail":"{readStateIndex:840; appliedIndex:837; }","duration":"183.951566ms","start":"2026-05-29T02:02:01.571619Z","end":"2026-05-29T02:02:01.75557Z","steps":["trace[540008792] 'read index received' (duration: 123.09318ms)","trace[540008792] 'applied index is now lower than readState.Index' (duration: 60.857516ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:02:01.756041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.394998ms","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-05-29T02:02:01.756124Z","caller":"traceutil/trace.go:171","msg":"trace[1710279525] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:808; }","duration":"184.504761ms","start":"2026-05-29T02:02:01.571602Z","end":"2026-05-29T02:02:01.756107Z","steps":["trace[1710279525] 'agreement among raft nodes before linearized reading' (duration: 184.266254ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:02:01.756215Z","caller":"traceutil/trace.go:171","msg":"trace[1819335644] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"189.602864ms","start":"2026-05-29T02:02:01.566589Z","end":"2026-05-29T02:02:01.756191Z","steps":["trace[1819335644] 'process raft request' (duration: 188.837643ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:02:01.756051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.828141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T02:02:01.756398Z","caller":"traceutil/trace.go:171","msg":"trace[1837233572] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"189.730797ms","start":"2026-05-29T02:02:01.56665Z","end":"2026-05-29T02:02:01.756381Z","steps":["trace[1837233572] 'process raft request' (duration: 188.880224ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:02:01.756481Z","caller":"traceutil/trace.go:171","msg":"trace[1288812550] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/envoy-gateway; range_end:; response_count:0; response_revision:808; }","duration":"149.287354ms","start":"2026-05-29T02:02:01.607155Z","end":"2026-05-29T02:02:01.756442Z","steps":["trace[1288812550] 'agreement among raft nodes before linearized reading' (duration: 148.820011ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:02:02.577057Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530393669842666210,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T02:02:03.078103Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530393669842666210,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T02:02:03.371243Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.308890234s","expected-duration":"1s"} {"level":"info","ts":"2026-05-29T02:02:03.371745Z","caller":"traceutil/trace.go:171","msg":"trace[1150232749] linearizableReadLoop","detail":"{readStateIndex:847; appliedIndex:846; }","duration":"1.295502663s","start":"2026-05-29T02:02:02.07622Z","end":"2026-05-29T02:02:03.371722Z","steps":["trace[1150232749] 'read index received' (duration: 1.295253206s)","trace[1150232749] 'applied index is now lower than readState.Index' (duration: 248.637µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:02:03.371832Z","caller":"traceutil/trace.go:171","msg":"trace[359873728] transaction","detail":"{read_only:false; response_revision:815; number_of_response:1; }","duration":"1.309602495s","start":"2026-05-29T02:02:02.062219Z","end":"2026-05-29T02:02:03.371822Z","steps":["trace[359873728] 'process raft request' (duration: 1.309328327s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:02:03.372062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"924.305599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-29T02:02:03.372207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.822351ms","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-05-29T02:02:03.372241Z","caller":"traceutil/trace.go:171","msg":"trace[588173037] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:815; }","duration":"924.514405ms","start":"2026-05-29T02:02:02.447708Z","end":"2026-05-29T02:02:03.372223Z","steps":["trace[588173037] 'agreement among raft nodes before linearized reading' (duration: 924.305099ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:02:03.372187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.697863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-29T02:02:03.372046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.721231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T02:02:03.372327Z","caller":"traceutil/trace.go:171","msg":"trace[1791306589] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:815; }","duration":"267.0789ms","start":"2026-05-29T02:02:03.10524Z","end":"2026-05-29T02:02:03.372319Z","steps":["trace[1791306589] 'agreement among raft nodes before linearized reading' (duration: 266.68866ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:02:03.372326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:02:02.447682Z","time spent":"924.610858ms","remote":"127.0.0.1:40444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":30,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"info","ts":"2026-05-29T02:02:03.372347Z","caller":"traceutil/trace.go:171","msg":"trace[1217214890] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:815; }","duration":"237.848918ms","start":"2026-05-29T02:02:03.134449Z","end":"2026-05-29T02:02:03.372297Z","steps":["trace[1217214890] 'agreement among raft nodes before linearized reading' (duration: 237.675523ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:02:03.37212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.295898864s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.74\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-29T02:02:03.372576Z","caller":"traceutil/trace.go:171","msg":"trace[1914813650] range","detail":"{range_begin:/registry/masterleases/199.204.45.74; range_end:; response_count:1; response_revision:815; }","duration":"1.296240793s","start":"2026-05-29T02:02:02.076211Z","end":"2026-05-29T02:02:03.372452Z","steps":["trace[1914813650] 'agreement among raft nodes before linearized reading' (duration: 1.295811331s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:02:03.372706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.295600255s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T02:02:03.372717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:02:02.076139Z","time spent":"1.296528861s","remote":"127.0.0.1:40138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.74\" "} {"level":"info","ts":"2026-05-29T02:02:03.372776Z","caller":"traceutil/trace.go:171","msg":"trace[2141674890] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:815; }","duration":"1.296454599s","start":"2026-05-29T02:02:02.076284Z","end":"2026-05-29T02:02:03.372738Z","steps":["trace[2141674890] 'agreement among raft nodes before linearized reading' (duration: 1.295586825s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:02:03.372836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:02:02.076263Z","time spent":"1.296542272s","remote":"127.0.0.1:40082","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-05-29T02:02:03.372785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"847.92207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-dlvk4\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-05-29T02:02:03.373152Z","caller":"traceutil/trace.go:171","msg":"trace[2113997481] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-dlvk4; range_end:; response_count:1; response_revision:815; }","duration":"848.684911ms","start":"2026-05-29T02:02:02.524399Z","end":"2026-05-29T02:02:03.373084Z","steps":["trace[2113997481] 'agreement among raft nodes before linearized reading' (duration: 847.55346ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:02:03.37194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:02:02.062187Z","time spent":"1.309681506s","remote":"127.0.0.1:40366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T02:02:03.37323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:02:02.524378Z","time spent":"848.836375ms","remote":"127.0.0.1:40276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3987,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-dlvk4\" "} {"level":"info","ts":"2026-05-29T02:02:03.372281Z","caller":"traceutil/trace.go:171","msg":"trace[728787161] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:815; }","duration":"353.913214ms","start":"2026-05-29T02:02:03.018349Z","end":"2026-05-29T02:02:03.372262Z","steps":["trace[728787161] 'agreement among raft nodes before linearized reading' (duration: 353.630096ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:02:03.373373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:02:03.01833Z","time spent":"355.031364ms","remote":"127.0.0.1:40366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-29T02:02:07.521182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.714243ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-29T02:02:07.521386Z","caller":"traceutil/trace.go:171","msg":"trace[888540] linearizableReadLoop","detail":"{readStateIndex:899; appliedIndex:898; }","duration":"147.368921ms","start":"2026-05-29T02:02:07.373998Z","end":"2026-05-29T02:02:07.521367Z","steps":["trace[888540] 'read index received' (duration: 5.396262ms)","trace[888540] 'applied index is now lower than readState.Index' (duration: 141.970829ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:02:07.521814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.632286ms","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-05-29T02:02:07.521956Z","caller":"traceutil/trace.go:171","msg":"trace[1904702867] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:865; }","duration":"123.821651ms","start":"2026-05-29T02:02:07.398116Z","end":"2026-05-29T02:02:07.521937Z","steps":["trace[1904702867] 'agreement among raft nodes before linearized reading' (duration: 123.490732ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:02:07.52351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.502338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:5 size:1050260"} {"level":"info","ts":"2026-05-29T02:02:07.523571Z","caller":"traceutil/trace.go:171","msg":"trace[1890421893] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:5; response_revision:865; }","duration":"149.612311ms","start":"2026-05-29T02:02:07.373942Z","end":"2026-05-29T02:02:07.523555Z","steps":["trace[1890421893] 'agreement among raft nodes before linearized reading' (duration: 147.556236ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:02:32.373629Z","caller":"traceutil/trace.go:171","msg":"trace[1101981060] linearizableReadLoop","detail":"{readStateIndex:1076; appliedIndex:1075; }","duration":"413.756383ms","start":"2026-05-29T02:02:31.959857Z","end":"2026-05-29T02:02:32.373614Z","steps":["trace[1101981060] 'read index received' (duration: 413.592599ms)","trace[1101981060] 'applied index is now lower than readState.Index' (duration: 163.034µs)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:02:32.373795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.595449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-29T02:02:32.37382Z","caller":"traceutil/trace.go:171","msg":"trace[935134963] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1037; }","duration":"413.65412ms","start":"2026-05-29T02:02:31.960159Z","end":"2026-05-29T02:02:32.373813Z","steps":["trace[935134963] 'agreement among raft nodes before linearized reading' (duration: 413.550868ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:02:32.373842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:02:31.960151Z","time spent":"413.686001ms","remote":"127.0.0.1:40366","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-05-29T02:02:32.373873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.998918ms","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-05-29T02:02:32.373942Z","caller":"traceutil/trace.go:171","msg":"trace[1520197752] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1037; }","duration":"414.11995ms","start":"2026-05-29T02:02:31.959807Z","end":"2026-05-29T02:02:32.373927Z","steps":["trace[1520197752] 'agreement among raft nodes before linearized reading' (duration: 413.900076ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:02:32.373718Z","caller":"traceutil/trace.go:171","msg":"trace[1057305913] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"420.566625ms","start":"2026-05-29T02:02:31.95312Z","end":"2026-05-29T02:02:32.373687Z","steps":["trace[1057305913] 'process raft request' (duration: 420.351521ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:02:32.373965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.536528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.74\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-05-29T02:02:32.373982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:02:31.959786Z","time spent":"414.183051ms","remote":"127.0.0.1:40366","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":"info","ts":"2026-05-29T02:02:32.374016Z","caller":"traceutil/trace.go:171","msg":"trace[635452234] range","detail":"{range_begin:/registry/masterleases/199.204.45.74; range_end:; response_count:1; response_revision:1037; }","duration":"297.622959ms","start":"2026-05-29T02:02:32.07638Z","end":"2026-05-29T02:02:32.374003Z","steps":["trace[635452234] 'agreement among raft nodes before linearized reading' (duration: 297.421265ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:02:32.373957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.609258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-29T02:02:32.374084Z","caller":"traceutil/trace.go:171","msg":"trace[947040107] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1037; }","duration":"411.76206ms","start":"2026-05-29T02:02:31.962308Z","end":"2026-05-29T02:02:32.37407Z","steps":["trace[947040107] 'agreement among raft nodes before linearized reading' (duration: 411.548156ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:02:32.37408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:02:31.953103Z","time spent":"420.892973ms","remote":"127.0.0.1:40366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T02:02:32.37411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:02:31.962298Z","time spent":"411.806052ms","remote":"127.0.0.1:40366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-29T02:02:32.563447Z","caller":"traceutil/trace.go:171","msg":"trace[1563427607] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"182.887463ms","start":"2026-05-29T02:02:32.380535Z","end":"2026-05-29T02:02:32.563422Z","steps":["trace[1563427607] 'process raft request' (duration: 182.811462ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:02:32.563469Z","caller":"traceutil/trace.go:171","msg":"trace[2129056185] transaction","detail":"{read_only:false; response_revision:1038; number_of_response:1; }","duration":"185.405566ms","start":"2026-05-29T02:02:32.378028Z","end":"2026-05-29T02:02:32.563433Z","steps":["trace[2129056185] 'process raft request' (duration: 128.098414ms)","trace[2129056185] 'compare' (duration: 57.056766ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:02:32.56362Z","caller":"traceutil/trace.go:171","msg":"trace[746145414] transaction","detail":"{read_only:false; response_revision:1041; number_of_response:1; }","duration":"110.928287ms","start":"2026-05-29T02:02:32.452674Z","end":"2026-05-29T02:02:32.563603Z","steps":["trace[746145414] 'process raft request' (duration: 110.868166ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:02:32.563698Z","caller":"traceutil/trace.go:171","msg":"trace[1998903915] transaction","detail":"{read_only:false; response_revision:1040; number_of_response:1; }","duration":"183.095138ms","start":"2026-05-29T02:02:32.380577Z","end":"2026-05-29T02:02:32.563672Z","steps":["trace[1998903915] 'process raft request' (duration: 182.809552ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:02:42.537321Z","caller":"traceutil/trace.go:171","msg":"trace[1844640714] transaction","detail":"{read_only:false; response_revision:1075; number_of_response:1; }","duration":"112.833438ms","start":"2026-05-29T02:02:42.424458Z","end":"2026-05-29T02:02:42.537291Z","steps":["trace[1844640714] 'process raft request' (duration: 112.669965ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:03:04.135401Z","caller":"traceutil/trace.go:171","msg":"trace[1139049759] transaction","detail":"{read_only:false; response_revision:1145; number_of_response:1; }","duration":"114.106834ms","start":"2026-05-29T02:03:04.021216Z","end":"2026-05-29T02:03:04.135323Z","steps":["trace[1139049759] 'process raft request' (duration: 113.934561ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:03:06.30858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.98564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T02:03:06.308641Z","caller":"traceutil/trace.go:171","msg":"trace[432442671] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1152; }","duration":"121.092702ms","start":"2026-05-29T02:03:06.187533Z","end":"2026-05-29T02:03:06.308625Z","steps":["trace[432442671] 'range keys from in-memory index tree' (duration: 120.908139ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:03:07.204879Z","caller":"traceutil/trace.go:171","msg":"trace[1314020209] linearizableReadLoop","detail":"{readStateIndex:1202; appliedIndex:1201; }","duration":"212.639859ms","start":"2026-05-29T02:03:06.992216Z","end":"2026-05-29T02:03:07.204855Z","steps":["trace[1314020209] 'read index received' (duration: 212.465766ms)","trace[1314020209] 'applied index is now lower than readState.Index' (duration: 173.093µs)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:03:07.205046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.684434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T02:03:07.205101Z","caller":"traceutil/trace.go:171","msg":"trace[1977984082] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1156; }","duration":"127.784036ms","start":"2026-05-29T02:03:07.077304Z","end":"2026-05-29T02:03:07.205088Z","steps":["trace[1977984082] 'agreement among raft nodes before linearized reading' (duration: 127.694445ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:03:07.205041Z","caller":"traceutil/trace.go:171","msg":"trace[523230080] transaction","detail":"{read_only:false; response_revision:1156; number_of_response:1; }","duration":"278.642827ms","start":"2026-05-29T02:03:06.926345Z","end":"2026-05-29T02:03:07.204988Z","steps":["trace[523230080] 'process raft request' (duration: 278.364542ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:03:07.205114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.872402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-29T02:03:07.205178Z","caller":"traceutil/trace.go:171","msg":"trace[622007990] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1156; }","duration":"212.972474ms","start":"2026-05-29T02:03:06.992191Z","end":"2026-05-29T02:03:07.205164Z","steps":["trace[622007990] 'agreement among raft nodes before linearized reading' (duration: 212.785591ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:03:07.381329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.467001ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T02:03:07.381403Z","caller":"traceutil/trace.go:171","msg":"trace[474033623] transaction","detail":"{read_only:false; response_revision:1157; number_of_response:1; }","duration":"171.836207ms","start":"2026-05-29T02:03:07.209553Z","end":"2026-05-29T02:03:07.381389Z","steps":["trace[474033623] 'process raft request' (duration: 54.253534ms)","trace[474033623] 'compare' (duration: 117.37323ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:03:55.694469Z","caller":"traceutil/trace.go:171","msg":"trace[1498736388] transaction","detail":"{read_only:false; response_revision:1353; number_of_response:1; }","duration":"250.758035ms","start":"2026-05-29T02:03:55.44368Z","end":"2026-05-29T02:03:55.694438Z","steps":["trace[1498736388] 'process raft request' (duration: 250.485221ms)"],"step_count":1}