{"level":"warn","ts":"2026-08-14T09:08:34.379966Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-14T09:08:34.380042Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.117: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.19.213.117:2380","--initial-cluster=instance=https://199.19.213.117:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.117:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.117: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-08-14T09:08:34.380107Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-14T09:08:34.380115Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.117:2380"]} {"level":"info","ts":"2026-08-14T09:08:34.380132Z","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-08-14T09:08:34.380744Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.117:2379"]} {"level":"info","ts":"2026-08-14T09:08:34.380815Z","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.19.213.117:2380"],"listen-peer-urls":["https://199.19.213.117:2380"],"advertise-client-urls":["https://199.19.213.117:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.117:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.117: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-08-14T09:08:34.383301Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.279503ms"} {"level":"info","ts":"2026-08-14T09:08:34.390375Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5f9f905057b5b268","cluster-id":"293172f52aeb3f03"} {"level":"info","ts":"2026-08-14T09:08:34.390442Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f9f905057b5b268 switched to configuration voters=()"} {"level":"info","ts":"2026-08-14T09:08:34.39046Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f9f905057b5b268 became follower at term 0"} {"level":"info","ts":"2026-08-14T09:08:34.390473Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5f9f905057b5b268 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-14T09:08:34.390483Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f9f905057b5b268 became follower at term 1"} {"level":"info","ts":"2026-08-14T09:08:34.390533Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f9f905057b5b268 switched to configuration voters=(6890384629643457128)"} {"level":"warn","ts":"2026-08-14T09:08:34.400278Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-14T09:08:34.401864Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-14T09:08:34.40334Z","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-08-14T09:08:34.405785Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5f9f905057b5b268","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-14T09:08:34.40606Z","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-08-14T09:08:34.406125Z","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-08-14T09:08:34.406132Z","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-08-14T09:08:34.406158Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5f9f905057b5b268","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-14T09:08:34.410912Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f9f905057b5b268 switched to configuration voters=(6890384629643457128)"} {"level":"info","ts":"2026-08-14T09:08:34.411156Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"293172f52aeb3f03","local-member-id":"5f9f905057b5b268","added-peer-id":"5f9f905057b5b268","added-peer-peer-urls":["https://199.19.213.117:2380"]} {"level":"info","ts":"2026-08-14T09:08:34.414668Z","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-08-14T09:08:34.414774Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.117:2380"} {"level":"info","ts":"2026-08-14T09:08:34.414812Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.117:2380"} {"level":"info","ts":"2026-08-14T09:08:34.415109Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5f9f905057b5b268","initial-advertise-peer-urls":["https://199.19.213.117:2380"],"listen-peer-urls":["https://199.19.213.117:2380"],"advertise-client-urls":["https://199.19.213.117:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.117:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-14T09:08:34.415161Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-14T09:08:34.692483Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f9f905057b5b268 is starting a new election at term 1"} {"level":"info","ts":"2026-08-14T09:08:34.692529Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f9f905057b5b268 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-14T09:08:34.69255Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f9f905057b5b268 received MsgPreVoteResp from 5f9f905057b5b268 at term 1"} {"level":"info","ts":"2026-08-14T09:08:34.692562Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f9f905057b5b268 became candidate at term 2"} {"level":"info","ts":"2026-08-14T09:08:34.692566Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f9f905057b5b268 received MsgVoteResp from 5f9f905057b5b268 at term 2"} {"level":"info","ts":"2026-08-14T09:08:34.692577Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f9f905057b5b268 became leader at term 2"} {"level":"info","ts":"2026-08-14T09:08:34.692584Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5f9f905057b5b268 elected leader 5f9f905057b5b268 at term 2"} {"level":"info","ts":"2026-08-14T09:08:34.69341Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-14T09:08:34.6934Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-14T09:08:34.693397Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-14T09:08:34.693676Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-14T09:08:34.693689Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-14T09:08:34.694013Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"293172f52aeb3f03","local-member-id":"5f9f905057b5b268","cluster-version":"3.5"} {"level":"info","ts":"2026-08-14T09:08:34.694076Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-14T09:08:34.694089Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-14T09:08:34.695542Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.117:2379"} {"level":"info","ts":"2026-08-14T09:08:34.699831Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-14T09:08:34.693391Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5f9f905057b5b268","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.117:2379]}","request-path":"/0/members/5f9f905057b5b268/attributes","cluster-id":"293172f52aeb3f03","publish-timeout":"7s"} {"level":"info","ts":"2026-08-14T09:08:36.170566Z","caller":"traceutil/trace.go:171","msg":"trace[7525106] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"127.976576ms","start":"2026-08-14T09:08:36.042556Z","end":"2026-08-14T09:08:36.170533Z","steps":["trace[7525106] 'process raft request' (duration: 79.499006ms)","trace[7525106] 'compare' (duration: 48.391048ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:08:36.171033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.192691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-14T09:08:36.171173Z","caller":"traceutil/trace.go:171","msg":"trace[1987507614] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:0; response_revision:5; }","duration":"128.378975ms","start":"2026-08-14T09:08:36.042769Z","end":"2026-08-14T09:08:36.171148Z","steps":["trace[1987507614] 'agreement among raft nodes before linearized reading' (duration: 128.094029ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:08:36.170715Z","caller":"traceutil/trace.go:171","msg":"trace[1772647479] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:4; }","duration":"127.890485ms","start":"2026-08-14T09:08:36.042798Z","end":"2026-08-14T09:08:36.170689Z","steps":["trace[1772647479] 'read index received' (duration: 37.878956ms)","trace[1772647479] 'applied index is now lower than readState.Index' (duration: 90.010689ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:08:36.194153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.283375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-14T09:08:36.194216Z","caller":"traceutil/trace.go:171","msg":"trace[431628265] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:11; }","duration":"148.362937ms","start":"2026-08-14T09:08:36.045837Z","end":"2026-08-14T09:08:36.1942Z","steps":["trace[431628265] 'agreement among raft nodes before linearized reading' (duration: 148.251885ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:08:36.19452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.646734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-14T09:08:36.195292Z","caller":"traceutil/trace.go:171","msg":"trace[1146588172] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:11; }","duration":"148.781697ms","start":"2026-08-14T09:08:36.04582Z","end":"2026-08-14T09:08:36.194602Z","steps":["trace[1146588172] 'agreement among raft nodes before linearized reading' (duration: 148.634173ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:08:38.661772Z","caller":"traceutil/trace.go:171","msg":"trace[890465609] transaction","detail":"{read_only:false; response_revision:203; number_of_response:1; }","duration":"129.797918ms","start":"2026-08-14T09:08:38.531934Z","end":"2026-08-14T09:08:38.661732Z","steps":["trace[890465609] 'process raft request' (duration: 75.561785ms)","trace[890465609] 'compare' (duration: 54.09304ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:08:40.692597Z","caller":"traceutil/trace.go:171","msg":"trace[651012486] transaction","detail":"{read_only:false; response_revision:257; number_of_response:1; }","duration":"117.552856ms","start":"2026-08-14T09:08:40.575018Z","end":"2026-08-14T09:08:40.692571Z","steps":["trace[651012486] 'process raft request' (duration: 75.673109ms)","trace[651012486] 'compare' (duration: 41.719863ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:08:40.69303Z","caller":"traceutil/trace.go:171","msg":"trace[1557210381] transaction","detail":"{read_only:false; response_revision:258; number_of_response:1; }","duration":"115.868195ms","start":"2026-08-14T09:08:40.577129Z","end":"2026-08-14T09:08:40.692997Z","steps":["trace[1557210381] 'process raft request' (duration: 115.495477ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:08:40.693072Z","caller":"traceutil/trace.go:171","msg":"trace[507379403] transaction","detail":"{read_only:false; response_revision:259; number_of_response:1; }","duration":"108.530136ms","start":"2026-08-14T09:08:40.584526Z","end":"2026-08-14T09:08:40.693056Z","steps":["trace[507379403] 'process raft request' (duration: 108.202148ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:08:43.774046Z","caller":"traceutil/trace.go:171","msg":"trace[1654187521] transaction","detail":"{read_only:false; response_revision:301; number_of_response:1; }","duration":"138.869197ms","start":"2026-08-14T09:08:43.635147Z","end":"2026-08-14T09:08:43.774016Z","steps":["trace[1654187521] 'process raft request' (duration: 138.687643ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:08:45.617216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.015217ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-08-14T09:08:45.75881Z","caller":"traceutil/trace.go:171","msg":"trace[2025085568] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"136.046423ms","start":"2026-08-14T09:08:45.622743Z","end":"2026-08-14T09:08:45.75879Z","steps":["trace[2025085568] 'process raft request' (duration: 135.861189ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:08:45.759077Z","caller":"traceutil/trace.go:171","msg":"trace[1718417630] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"131.964928ms","start":"2026-08-14T09:08:45.627062Z","end":"2026-08-14T09:08:45.759027Z","steps":["trace[1718417630] 'process raft request' (duration: 131.677362ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:08:45.952874Z","caller":"traceutil/trace.go:171","msg":"trace[1123471490] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"131.863495ms","start":"2026-08-14T09:08:45.820981Z","end":"2026-08-14T09:08:45.952844Z","steps":["trace[1123471490] 'process raft request' (duration: 126.478531ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:08:50.140317Z","caller":"traceutil/trace.go:171","msg":"trace[544690385] linearizableReadLoop","detail":"{readStateIndex:382; appliedIndex:381; }","duration":"117.936574ms","start":"2026-08-14T09:08:50.022358Z","end":"2026-08-14T09:08:50.140294Z","steps":["trace[544690385] 'read index received' (duration: 117.747999ms)","trace[544690385] 'applied index is now lower than readState.Index' (duration: 187.675µs)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:08:50.140462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.087738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:08:50.140505Z","caller":"traceutil/trace.go:171","msg":"trace[944858904] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:369; }","duration":"118.19093ms","start":"2026-08-14T09:08:50.022302Z","end":"2026-08-14T09:08:50.140493Z","steps":["trace[944858904] 'agreement among raft nodes before linearized reading' (duration: 118.083428ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:08:50.14058Z","caller":"traceutil/trace.go:171","msg":"trace[991381837] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"365.324358ms","start":"2026-08-14T09:08:49.775224Z","end":"2026-08-14T09:08:50.140548Z","steps":["trace[991381837] 'process raft request' (duration: 364.932799ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:08:50.141323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:08:49.775177Z","time spent":"365.560934ms","remote":"127.0.0.1:38666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:08:50.871666Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12855701056172054856,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-14T09:08:51.372716Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12855701056172054856,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-14T09:08:51.873254Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12855701056172054856,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-14T09:08:52.002118Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.79541529s","expected-duration":"1s"} {"level":"info","ts":"2026-08-14T09:08:52.002659Z","caller":"traceutil/trace.go:171","msg":"trace[1437167438] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"1.849111251s","start":"2026-08-14T09:08:50.153513Z","end":"2026-08-14T09:08:52.002624Z","steps":["trace[1437167438] 'process raft request' (duration: 1.848895145s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:08:52.002868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:08:50.153495Z","time spent":"1.849263824s","remote":"127.0.0.1:38608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7288,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:08:52.100191Z","caller":"traceutil/trace.go:171","msg":"trace[1313533515] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"1.447670777s","start":"2026-08-14T09:08:50.65239Z","end":"2026-08-14T09:08:52.100061Z","steps":["trace[1313533515] 'process raft request' (duration: 1.445954378s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:08:52.100387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:08:50.652365Z","time spent":"1.447909092s","remote":"127.0.0.1:38666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:08:52.100395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.728706559s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-14T09:08:52.100533Z","caller":"traceutil/trace.go:171","msg":"trace[1834487881] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:371; }","duration":"1.728918934s","start":"2026-08-14T09:08:50.37157Z","end":"2026-08-14T09:08:52.100489Z","steps":["trace[1834487881] 'agreement among raft nodes before linearized reading' (duration: 1.728627397s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:08:52.100601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:08:50.371548Z","time spent":"1.729038397s","remote":"127.0.0.1:38666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-14T09:08:52.100685Z","caller":"traceutil/trace.go:171","msg":"trace[160860959] linearizableReadLoop","detail":"{readStateIndex:384; appliedIndex:382; }","duration":"1.728385902s","start":"2026-08-14T09:08:50.371621Z","end":"2026-08-14T09:08:52.100007Z","steps":["trace[160860959] 'read index received' (duration: 1.630731886s)","trace[160860959] 'applied index is now lower than readState.Index' (duration: 97.652586ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:08:52.10084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.710649793s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-14T09:08:52.100876Z","caller":"traceutil/trace.go:171","msg":"trace[1429847465] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:371; }","duration":"1.710709964s","start":"2026-08-14T09:08:50.390153Z","end":"2026-08-14T09:08:52.100863Z","steps":["trace[1429847465] 'agreement among raft nodes before linearized reading' (duration: 1.710634242s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:08:52.100906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:08:50.390142Z","time spent":"1.710757465s","remote":"127.0.0.1:38666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-14T09:08:52.101051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.52737915s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-14T09:08:52.10105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.281130462s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-14T09:08:52.101102Z","caller":"traceutil/trace.go:171","msg":"trace[1370194030] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:371; }","duration":"1.527454322s","start":"2026-08-14T09:08:50.573628Z","end":"2026-08-14T09:08:52.101082Z","steps":["trace[1370194030] 'agreement among raft nodes before linearized reading' (duration: 1.52737647s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:08:52.101142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:08:50.573604Z","time spent":"1.527530844s","remote":"127.0.0.1:38452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-14T09:08:52.101141Z","caller":"traceutil/trace.go:171","msg":"trace[1738491123] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:371; }","duration":"1.281279725s","start":"2026-08-14T09:08:50.819835Z","end":"2026-08-14T09:08:52.101115Z","steps":["trace[1738491123] 'agreement among raft nodes before linearized reading' (duration: 1.281066931s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:08:52.10119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:08:50.819812Z","time spent":"1.281369208s","remote":"127.0.0.1:38666","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-08-14T09:08:52.627963Z","caller":"traceutil/trace.go:171","msg":"trace[1941019526] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"521.22668ms","start":"2026-08-14T09:08:52.10671Z","end":"2026-08-14T09:08:52.627937Z","steps":["trace[1941019526] 'process raft request' (duration: 492.980817ms)","trace[1941019526] 'compare' (duration: 28.13636ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:08:52.628119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:08:52.106682Z","time spent":"521.358552ms","remote":"127.0.0.1:38666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:08:52.633503Z","caller":"traceutil/trace.go:171","msg":"trace[9619033] linearizableReadLoop","detail":"{readStateIndex:388; appliedIndex:384; }","duration":"522.98834ms","start":"2026-08-14T09:08:52.110503Z","end":"2026-08-14T09:08:52.633491Z","steps":["trace[9619033] 'read index received' (duration: 489.302422ms)","trace[9619033] 'applied index is now lower than readState.Index' (duration: 33.685138ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:08:52.633703Z","caller":"traceutil/trace.go:171","msg":"trace[1933643469] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"526.612213ms","start":"2026-08-14T09:08:52.107072Z","end":"2026-08-14T09:08:52.633685Z","steps":["trace[1933643469] 'process raft request' (duration: 526.244805ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:08:52.633795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:08:52.107049Z","time spent":"526.688216ms","remote":"127.0.0.1:38666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:08:52.633915Z","caller":"traceutil/trace.go:171","msg":"trace[1081096379] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"526.670235ms","start":"2026-08-14T09:08:52.107236Z","end":"2026-08-14T09:08:52.633906Z","steps":["trace[1081096379] 'process raft request' (duration: 526.180734ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:08:52.633961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:08:52.107193Z","time spent":"526.743857ms","remote":"127.0.0.1:38666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:08:52.634098Z","caller":"traceutil/trace.go:171","msg":"trace[687441687] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"523.671916ms","start":"2026-08-14T09:08:52.110419Z","end":"2026-08-14T09:08:52.634091Z","steps":["trace[687441687] 'process raft request' (duration: 523.033271ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:08:52.634135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:08:52.110382Z","time spent":"523.730557ms","remote":"127.0.0.1:38608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4119,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:08:52.634229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.726058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:08:52.634257Z","caller":"traceutil/trace.go:171","msg":"trace[745334642] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:375; }","duration":"523.769508ms","start":"2026-08-14T09:08:52.110477Z","end":"2026-08-14T09:08:52.634247Z","steps":["trace[745334642] 'agreement among raft nodes before linearized reading' (duration: 523.728767ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:08:52.63428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:08:52.110465Z","time spent":"523.809559ms","remote":"127.0.0.1:38464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-14T09:08:52.63446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.835624ms","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-08-14T09:08:52.634528Z","caller":"traceutil/trace.go:171","msg":"trace[290510847] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:375; }","duration":"489.955587ms","start":"2026-08-14T09:08:52.144556Z","end":"2026-08-14T09:08:52.634512Z","steps":["trace[290510847] 'agreement among raft nodes before linearized reading' (duration: 489.823674ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:08:52.634569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:08:52.144538Z","time spent":"490.023048ms","remote":"127.0.0.1:38666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-14T09:09:15.69236Z","caller":"traceutil/trace.go:171","msg":"trace[1448779514] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"566.181197ms","start":"2026-08-14T09:09:15.126154Z","end":"2026-08-14T09:09:15.692335Z","steps":["trace[1448779514] 'process raft request' (duration: 565.947902ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:09:15.692645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:09:15.126132Z","time spent":"566.359511ms","remote":"127.0.0.1:38492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-14T09:09:15.703789Z","caller":"traceutil/trace.go:171","msg":"trace[901974182] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"399.9923ms","start":"2026-08-14T09:09:15.303776Z","end":"2026-08-14T09:09:15.703768Z","steps":["trace[901974182] 'process raft request' (duration: 399.949639ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:09:15.704005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:09:15.30375Z","time spent":"400.202034ms","remote":"127.0.0.1:38492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-14T09:09:15.704301Z","caller":"traceutil/trace.go:171","msg":"trace[1240243389] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"576.65031ms","start":"2026-08-14T09:09:15.127643Z","end":"2026-08-14T09:09:15.704293Z","steps":["trace[1240243389] 'process raft request' (duration: 575.619276ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:09:15.704409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:09:15.127636Z","time spent":"576.752922ms","remote":"127.0.0.1:38492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-14T09:09:15.704591Z","caller":"traceutil/trace.go:171","msg":"trace[954366671] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"575.474542ms","start":"2026-08-14T09:09:15.129109Z","end":"2026-08-14T09:09:15.704583Z","steps":["trace[954366671] 'process raft request' (duration: 574.244664ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:09:15.704681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:09:15.12909Z","time spent":"575.571654ms","remote":"127.0.0.1:38492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-14T09:09:15.704825Z","caller":"traceutil/trace.go:171","msg":"trace[852693522] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"569.822002ms","start":"2026-08-14T09:09:15.134996Z","end":"2026-08-14T09:09:15.704818Z","steps":["trace[852693522] 'process raft request' (duration: 568.387689ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:09:15.704912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:09:15.134969Z","time spent":"569.923563ms","remote":"127.0.0.1:38492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-14T09:09:15.705042Z","caller":"traceutil/trace.go:171","msg":"trace[385704133] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"565.390608ms","start":"2026-08-14T09:09:15.139643Z","end":"2026-08-14T09:09:15.705033Z","steps":["trace[385704133] 'process raft request' (duration: 563.767881ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:09:15.705125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:09:15.139628Z","time spent":"565.480071ms","remote":"127.0.0.1:38492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-14T09:09:15.70531Z","caller":"traceutil/trace.go:171","msg":"trace[522118151] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"563.372784ms","start":"2026-08-14T09:09:15.14193Z","end":"2026-08-14T09:09:15.705302Z","steps":["trace[522118151] 'process raft request' (duration: 561.537131ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:09:15.705402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:09:15.141889Z","time spent":"563.495876ms","remote":"127.0.0.1:38492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-14T09:09:15.705543Z","caller":"traceutil/trace.go:171","msg":"trace[921695481] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"562.293148ms","start":"2026-08-14T09:09:15.143239Z","end":"2026-08-14T09:09:15.705532Z","steps":["trace[921695481] 'process raft request' (duration: 560.295841ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:09:15.705631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:09:15.143215Z","time spent":"562.399341ms","remote":"127.0.0.1:38492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-14T09:09:15.705719Z","caller":"traceutil/trace.go:171","msg":"trace[183392750] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"561.982981ms","start":"2026-08-14T09:09:15.143728Z","end":"2026-08-14T09:09:15.705711Z","steps":["trace[183392750] 'process raft request' (duration: 559.834741ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:09:15.705781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:09:15.143719Z","time spent":"562.025903ms","remote":"127.0.0.1:38492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-14T09:09:15.705903Z","caller":"traceutil/trace.go:171","msg":"trace[33713555] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"555.218014ms","start":"2026-08-14T09:09:15.150677Z","end":"2026-08-14T09:09:15.705895Z","steps":["trace[33713555] 'process raft request' (duration: 552.914291ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:09:15.705687Z","caller":"traceutil/trace.go:171","msg":"trace[1899520392] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"549.996034ms","start":"2026-08-14T09:09:15.155678Z","end":"2026-08-14T09:09:15.705674Z","steps":["trace[1899520392] 'process raft request' (duration: 547.955987ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:09:15.705942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:09:15.150657Z","time spent":"555.267866ms","remote":"127.0.0.1:38492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-14T09:09:15.706016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:09:15.155642Z","time spent":"550.323462ms","remote":"127.0.0.1:38492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-14T09:09:15.705788Z","caller":"traceutil/trace.go:171","msg":"trace[1218852649] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"533.304539ms","start":"2026-08-14T09:09:15.172425Z","end":"2026-08-14T09:09:15.70573Z","steps":["trace[1218852649] 'process raft request' (duration: 531.248972ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:09:15.706352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:09:15.172395Z","time spent":"533.891622ms","remote":"127.0.0.1:38492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-14T09:09:21.881807Z","caller":"traceutil/trace.go:171","msg":"trace[2094258713] linearizableReadLoop","detail":"{readStateIndex:624; appliedIndex:623; }","duration":"238.456997ms","start":"2026-08-14T09:09:21.643328Z","end":"2026-08-14T09:09:21.881785Z","steps":["trace[2094258713] 'read index received' (duration: 237.762712ms)","trace[2094258713] 'applied index is now lower than readState.Index' (duration: 693.385µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:09:21.881871Z","caller":"traceutil/trace.go:171","msg":"trace[1835502741] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"364.455188ms","start":"2026-08-14T09:09:21.51737Z","end":"2026-08-14T09:09:21.881825Z","steps":["trace[1835502741] 'process raft request' (duration: 363.881955ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:09:21.881916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.578441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-ml94w\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-08-14T09:09:21.881951Z","caller":"traceutil/trace.go:171","msg":"trace[1554863410] range","detail":"{range_begin:/registry/pods/kube-system/cilium-ml94w; range_end:; response_count:1; response_revision:602; }","duration":"238.663142ms","start":"2026-08-14T09:09:21.643279Z","end":"2026-08-14T09:09:21.881942Z","steps":["trace[1554863410] 'agreement among raft nodes before linearized reading' (duration: 238.58447ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:09:21.88205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:09:21.517341Z","time spent":"364.609711ms","remote":"127.0.0.1:38522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036293,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:09:22.463621Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12855701056172055290,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-14T09:09:22.498334Z","caller":"traceutil/trace.go:171","msg":"trace[678162869] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"593.784751ms","start":"2026-08-14T09:09:21.903845Z","end":"2026-08-14T09:09:22.497629Z","steps":["trace[678162869] 'process raft request' (duration: 593.527195ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:09:22.498475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:09:21.903813Z","time spent":"594.586073ms","remote":"127.0.0.1:38608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:09:22.855303Z","caller":"traceutil/trace.go:171","msg":"trace[1366725226] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"920.435709ms","start":"2026-08-14T09:09:21.93483Z","end":"2026-08-14T09:09:22.855266Z","steps":["trace[1366725226] 'process raft request' (duration: 826.558759ms)","trace[1366725226] 'compare' (duration: 93.628014ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:09:22.855342Z","caller":"traceutil/trace.go:171","msg":"trace[1570561193] linearizableReadLoop","detail":"{readStateIndex:626; appliedIndex:624; }","duration":"892.069704ms","start":"2026-08-14T09:09:21.96323Z","end":"2026-08-14T09:09:22.855299Z","steps":["trace[1570561193] 'read index received' (duration: 534.169814ms)","trace[1570561193] 'applied index is now lower than readState.Index' (duration: 357.89521ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:09:22.85533Z","caller":"traceutil/trace.go:171","msg":"trace[797408038] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"210.495674ms","start":"2026-08-14T09:09:22.64481Z","end":"2026-08-14T09:09:22.855305Z","steps":["trace[797408038] 'process raft request' (duration: 210.416012ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:09:22.855454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:09:21.934808Z","time spent":"920.574213ms","remote":"127.0.0.1:38908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-14T09:09:22.855532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"892.28402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:09:22.85558Z","caller":"traceutil/trace.go:171","msg":"trace[1378688735] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:605; }","duration":"892.384061ms","start":"2026-08-14T09:09:21.963184Z","end":"2026-08-14T09:09:22.855568Z","steps":["trace[1378688735] 'agreement among raft nodes before linearized reading' (duration: 892.267718ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:09:22.855619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:09:21.963164Z","time spent":"892.447203ms","remote":"127.0.0.1:38464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-14T09:09:25.033254Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.73467803s","expected-duration":"1s"} {"level":"warn","ts":"2026-08-14T09:09:25.044423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.079737424s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:09:25.044491Z","caller":"traceutil/trace.go:171","msg":"trace[746395059] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:609; }","duration":"2.079873098s","start":"2026-08-14T09:09:22.964599Z","end":"2026-08-14T09:09:25.044472Z","steps":["trace[746395059] 'range keys from in-memory index tree' (duration: 2.079592591s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:09:25.044532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:09:22.964566Z","time spent":"2.079957499s","remote":"127.0.0.1:38464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-14T09:09:25.044582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.245645ms","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-08-14T09:09:25.044769Z","caller":"traceutil/trace.go:171","msg":"trace[1305778111] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:609; }","duration":"556.454581ms","start":"2026-08-14T09:09:24.488283Z","end":"2026-08-14T09:09:25.044738Z","steps":["trace[1305778111] 'range keys from in-memory index tree' (duration: 556.217535ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:09:25.04544Z","caller":"traceutil/trace.go:171","msg":"trace[1829023669] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"1.748362027s","start":"2026-08-14T09:09:23.29704Z","end":"2026-08-14T09:09:25.045402Z","steps":["trace[1829023669] 'process raft request' (duration: 1.736704052s)","trace[1829023669] 'compare' (duration: 10.70472ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:09:25.045604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:09:23.297006Z","time spent":"1.748508791s","remote":"127.0.0.1:38522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036317,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:09:25.059825Z","caller":"traceutil/trace.go:171","msg":"trace[1316709102] linearizableReadLoop","detail":"{readStateIndex:633; appliedIndex:631; }","duration":"189.928833ms","start":"2026-08-14T09:09:24.869877Z","end":"2026-08-14T09:09:25.059805Z","steps":["trace[1316709102] 'read index received' (duration: 163.672637ms)","trace[1316709102] 'applied index is now lower than readState.Index' (duration: 26.255076ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:09:25.060255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.319563ms","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-08-14T09:09:25.060344Z","caller":"traceutil/trace.go:171","msg":"trace[364692226] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:610; }","duration":"190.500328ms","start":"2026-08-14T09:09:24.869824Z","end":"2026-08-14T09:09:25.060324Z","steps":["trace[364692226] 'agreement among raft nodes before linearized reading' (duration: 190.158949ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:09:25.06155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.536629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-14T09:09:25.061627Z","caller":"traceutil/trace.go:171","msg":"trace[1725606148] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:610; }","duration":"166.661443ms","start":"2026-08-14T09:09:24.894949Z","end":"2026-08-14T09:09:25.061611Z","steps":["trace[1725606148] 'agreement among raft nodes before linearized reading' (duration: 166.419707ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:09:25.061906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.668579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-08-14T09:09:25.061912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.760966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-14T09:09:25.061956Z","caller":"traceutil/trace.go:171","msg":"trace[760137358] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:610; }","duration":"165.792512ms","start":"2026-08-14T09:09:24.896149Z","end":"2026-08-14T09:09:25.061942Z","steps":["trace[760137358] 'agreement among raft nodes before linearized reading' (duration: 165.592697ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:09:25.061962Z","caller":"traceutil/trace.go:171","msg":"trace[1680812737] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:610; }","duration":"166.858728ms","start":"2026-08-14T09:09:24.895092Z","end":"2026-08-14T09:09:25.061951Z","steps":["trace[1680812737] 'agreement among raft nodes before linearized reading' (duration: 166.740015ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:09:27.086236Z","caller":"traceutil/trace.go:171","msg":"trace[102510981] linearizableReadLoop","detail":"{readStateIndex:677; appliedIndex:676; }","duration":"121.293863ms","start":"2026-08-14T09:09:26.964922Z","end":"2026-08-14T09:09:27.086215Z","steps":["trace[102510981] 'read index received' (duration: 121.260083ms)","trace[102510981] 'applied index is now lower than readState.Index' (duration: 32.74µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:09:27.086294Z","caller":"traceutil/trace.go:171","msg":"trace[2130700835] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"182.594887ms","start":"2026-08-14T09:09:26.903674Z","end":"2026-08-14T09:09:27.086269Z","steps":["trace[2130700835] 'process raft request' (duration: 182.431163ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:09:27.086779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.740404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:09:27.086971Z","caller":"traceutil/trace.go:171","msg":"trace[1065703233] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:653; }","duration":"122.021092ms","start":"2026-08-14T09:09:26.96488Z","end":"2026-08-14T09:09:27.086901Z","steps":["trace[1065703233] 'agreement among raft nodes before linearized reading' (duration: 121.468778ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:09:30.049874Z","caller":"traceutil/trace.go:171","msg":"trace[271537890] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"119.506429ms","start":"2026-08-14T09:09:29.930324Z","end":"2026-08-14T09:09:30.049831Z","steps":["trace[271537890] 'process raft request' (duration: 118.920785ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:09:37.100581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.198446ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-14T09:09:37.100701Z","caller":"traceutil/trace.go:171","msg":"trace[804804393] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"348.421929ms","start":"2026-08-14T09:09:36.752259Z","end":"2026-08-14T09:09:37.100681Z","steps":["trace[804804393] 'process raft request' (duration: 113.974118ms)","trace[804804393] 'compare' (duration: 233.853286ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:09:37.100765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:09:36.752233Z","time spent":"348.501652ms","remote":"127.0.0.1:38602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":780,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:09:37.10182Z","caller":"traceutil/trace.go:171","msg":"trace[66906973] linearizableReadLoop","detail":"{readStateIndex:780; appliedIndex:778; }","duration":"136.897338ms","start":"2026-08-14T09:09:36.964899Z","end":"2026-08-14T09:09:37.101797Z","steps":["trace[66906973] 'read index received' (duration: 136.651962ms)","trace[66906973] 'applied index is now lower than readState.Index' (duration: 242.606µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:09:37.101851Z","caller":"traceutil/trace.go:171","msg":"trace[1246417203] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"349.150928ms","start":"2026-08-14T09:09:36.752666Z","end":"2026-08-14T09:09:37.101817Z","steps":["trace[1246417203] 'process raft request' (duration: 348.941232ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:09:37.101961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.041713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:09:37.102003Z","caller":"traceutil/trace.go:171","msg":"trace[51166720] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:751; }","duration":"137.140476ms","start":"2026-08-14T09:09:36.964847Z","end":"2026-08-14T09:09:37.101987Z","steps":["trace[51166720] 'agreement among raft nodes before linearized reading' (duration: 137.045873ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:09:37.102007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:09:36.752649Z","time spent":"349.280881ms","remote":"127.0.0.1:38680","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1289,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:09:37.10202Z","caller":"traceutil/trace.go:171","msg":"trace[712601299] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"345.378383ms","start":"2026-08-14T09:09:36.756606Z","end":"2026-08-14T09:09:37.101985Z","steps":["trace[712601299] 'process raft request' (duration: 345.111985ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:09:37.102173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:09:36.756599Z","time spent":"345.495377ms","remote":"127.0.0.1:38608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4854,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:09:40.529928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"779.025052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-k9lvc\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-08-14T09:09:40.530004Z","caller":"traceutil/trace.go:171","msg":"trace[544212773] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-k9lvc; range_end:; response_count:1; response_revision:774; }","duration":"779.148825ms","start":"2026-08-14T09:09:39.750838Z","end":"2026-08-14T09:09:40.529987Z","steps":["trace[544212773] 'range keys from in-memory index tree' (duration: 778.695214ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:09:40.530046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:09:39.750815Z","time spent":"779.221207ms","remote":"127.0.0.1:38608","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-k9lvc\" "} {"level":"warn","ts":"2026-08-14T09:09:40.530135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.773928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:09:40.530203Z","caller":"traceutil/trace.go:171","msg":"trace[1039766824] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:774; }","duration":"565.889181ms","start":"2026-08-14T09:09:39.964299Z","end":"2026-08-14T09:09:40.530189Z","steps":["trace[1039766824] 'range keys from in-memory index tree' (duration: 565.671186ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:09:40.530231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:09:39.964271Z","time spent":"565.954413ms","remote":"127.0.0.1:38464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-14T09:09:40.896628Z","caller":"traceutil/trace.go:171","msg":"trace[1634800220] linearizableReadLoop","detail":"{readStateIndex:805; appliedIndex:804; }","duration":"321.003346ms","start":"2026-08-14T09:09:40.575603Z","end":"2026-08-14T09:09:40.896606Z","steps":["trace[1634800220] 'read index received' (duration: 320.837722ms)","trace[1634800220] 'applied index is now lower than readState.Index' (duration: 164.664µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:09:40.896745Z","caller":"traceutil/trace.go:171","msg":"trace[472662386] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"358.524516ms","start":"2026-08-14T09:09:40.538212Z","end":"2026-08-14T09:09:40.896736Z","steps":["trace[472662386] 'process raft request' (duration: 358.256589ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:09:40.89684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:09:40.538196Z","time spent":"358.575517ms","remote":"127.0.0.1:38608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:09:40.897062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.982289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-14T09:09:40.897154Z","caller":"traceutil/trace.go:171","msg":"trace[1301315985] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:775; }","duration":"320.110352ms","start":"2026-08-14T09:09:40.577021Z","end":"2026-08-14T09:09:40.897132Z","steps":["trace[1301315985] 'agreement among raft nodes before linearized reading' (duration: 319.967168ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:09:40.89719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.535285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-14T09:09:40.897205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:09:40.577011Z","time spent":"320.180663ms","remote":"127.0.0.1:38796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":29,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"info","ts":"2026-08-14T09:09:40.897235Z","caller":"traceutil/trace.go:171","msg":"trace[1996138479] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:775; }","duration":"315.654298ms","start":"2026-08-14T09:09:40.581569Z","end":"2026-08-14T09:09:40.897224Z","steps":["trace[1996138479] 'agreement among raft nodes before linearized reading' (duration: 315.550466ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:09:40.897272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.633701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-08-14T09:09:40.897303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:09:40.581527Z","time spent":"315.761201ms","remote":"127.0.0.1:38730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":27,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"info","ts":"2026-08-14T09:09:40.897323Z","caller":"traceutil/trace.go:171","msg":"trace[1370058020] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:775; }","duration":"321.742873ms","start":"2026-08-14T09:09:40.575565Z","end":"2026-08-14T09:09:40.897308Z","steps":["trace[1370058020] 'agreement among raft nodes before linearized reading' (duration: 321.494407ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:09:40.897365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:09:40.575553Z","time spent":"321.800265ms","remote":"127.0.0.1:38666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-14T09:09:47.361774Z","caller":"traceutil/trace.go:171","msg":"trace[1188674279] transaction","detail":"{read_only:false; response_revision:841; number_of_response:1; }","duration":"151.097809ms","start":"2026-08-14T09:09:47.210619Z","end":"2026-08-14T09:09:47.361717Z","steps":["trace[1188674279] 'process raft request' (duration: 147.694993ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:09:47.361871Z","caller":"traceutil/trace.go:171","msg":"trace[127439559] transaction","detail":"{read_only:false; response_revision:842; number_of_response:1; }","duration":"150.33585ms","start":"2026-08-14T09:09:47.211489Z","end":"2026-08-14T09:09:47.361825Z","steps":["trace[127439559] 'process raft request' (duration: 150.070683ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:09:47.36202Z","caller":"traceutil/trace.go:171","msg":"trace[1636036851] transaction","detail":"{read_only:false; response_revision:843; number_of_response:1; }","duration":"117.107078ms","start":"2026-08-14T09:09:47.244884Z","end":"2026-08-14T09:09:47.361991Z","steps":["trace[1636036851] 'process raft request' (duration: 116.857892ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:09:48.242177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.496962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5066"} {"level":"info","ts":"2026-08-14T09:09:48.242255Z","caller":"traceutil/trace.go:171","msg":"trace[129086745] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:845; }","duration":"159.636505ms","start":"2026-08-14T09:09:48.0826Z","end":"2026-08-14T09:09:48.242236Z","steps":["trace[129086745] 'range keys from in-memory index tree' (duration: 159.255726ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:09:48.402589Z","caller":"traceutil/trace.go:171","msg":"trace[87967693] transaction","detail":"{read_only:false; response_revision:848; number_of_response:1; }","duration":"108.034258ms","start":"2026-08-14T09:09:48.294491Z","end":"2026-08-14T09:09:48.402525Z","steps":["trace[87967693] 'process raft request' (duration: 48.12134ms)","trace[87967693] 'compare' (duration: 59.714843ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:09:48.403002Z","caller":"traceutil/trace.go:171","msg":"trace[49542133] transaction","detail":"{read_only:false; response_revision:849; number_of_response:1; }","duration":"104.578422ms","start":"2026-08-14T09:09:48.298407Z","end":"2026-08-14T09:09:48.402985Z","steps":["trace[49542133] 'process raft request' (duration: 104.040737ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:09:48.515682Z","caller":"traceutil/trace.go:171","msg":"trace[31054366] transaction","detail":"{read_only:false; response_revision:852; number_of_response:1; }","duration":"103.407821ms","start":"2026-08-14T09:09:48.412258Z","end":"2026-08-14T09:09:48.515666Z","steps":["trace[31054366] 'process raft request' (duration: 102.777235ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:09:49.529704Z","caller":"traceutil/trace.go:171","msg":"trace[1946657034] linearizableReadLoop","detail":"{readStateIndex:891; appliedIndex:890; }","duration":"164.433088ms","start":"2026-08-14T09:09:49.365245Z","end":"2026-08-14T09:09:49.529678Z","steps":["trace[1946657034] 'read index received' (duration: 85.014684ms)","trace[1946657034] 'applied index is now lower than readState.Index' (duration: 79.417414ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:09:49.52982Z","caller":"traceutil/trace.go:171","msg":"trace[234341319] transaction","detail":"{read_only:false; response_revision:858; number_of_response:1; }","duration":"164.95772ms","start":"2026-08-14T09:09:49.364823Z","end":"2026-08-14T09:09:49.529781Z","steps":["trace[234341319] 'process raft request' (duration: 85.494896ms)","trace[234341319] 'compare' (duration: 79.201838ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:09:49.53001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.12009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-14T09:09:49.53005Z","caller":"traceutil/trace.go:171","msg":"trace[1070967120] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:858; }","duration":"164.189632ms","start":"2026-08-14T09:09:49.365849Z","end":"2026-08-14T09:09:49.530039Z","steps":["trace[1070967120] 'agreement among raft nodes before linearized reading' (duration: 164.004177ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:09:49.530035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.766756ms","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-08-14T09:09:49.530107Z","caller":"traceutil/trace.go:171","msg":"trace[1271450391] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:858; }","duration":"164.845918ms","start":"2026-08-14T09:09:49.365241Z","end":"2026-08-14T09:09:49.530086Z","steps":["trace[1271450391] 'agreement among raft nodes before linearized reading' (duration: 164.602622ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:09:49.530159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.904909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-08-14T09:09:49.530234Z","caller":"traceutil/trace.go:171","msg":"trace[2014961064] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:858; }","duration":"162.996131ms","start":"2026-08-14T09:09:49.36722Z","end":"2026-08-14T09:09:49.530216Z","steps":["trace[2014961064] 'agreement among raft nodes before linearized reading' (duration: 162.748455ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:09:51.212188Z","caller":"traceutil/trace.go:171","msg":"trace[1007134705] transaction","detail":"{read_only:false; response_revision:865; number_of_response:1; }","duration":"165.074533ms","start":"2026-08-14T09:09:51.04709Z","end":"2026-08-14T09:09:51.212165Z","steps":["trace[1007134705] 'process raft request' (duration: 164.92957ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:09:54.301629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.708278ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-08-14T09:09:54.466352Z","caller":"traceutil/trace.go:171","msg":"trace[1082978984] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"392.755413ms","start":"2026-08-14T09:09:54.073574Z","end":"2026-08-14T09:09:54.46633Z","steps":["trace[1082978984] 'process raft request' (duration: 392.609369ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:09:54.46661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:09:54.073549Z","time spent":"392.872305ms","remote":"127.0.0.1:38666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:09:55.420388Z","caller":"traceutil/trace.go:171","msg":"trace[741762386] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"146.670768ms","start":"2026-08-14T09:09:55.273697Z","end":"2026-08-14T09:09:55.420368Z","steps":["trace[741762386] 'process raft request' (duration: 62.36136ms)","trace[741762386] 'compare' (duration: 83.975738ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:09:55.492889Z","caller":"traceutil/trace.go:171","msg":"trace[1274650632] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"219.143133ms","start":"2026-08-14T09:09:55.273691Z","end":"2026-08-14T09:09:55.492834Z","steps":["trace[1274650632] 'process raft request' (duration: 218.839605ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:09:55.493093Z","caller":"traceutil/trace.go:171","msg":"trace[1472349012] transaction","detail":"{read_only:false; response_revision:908; number_of_response:1; }","duration":"217.867611ms","start":"2026-08-14T09:09:55.275204Z","end":"2026-08-14T09:09:55.493072Z","steps":["trace[1472349012] 'process raft request' (duration: 217.537252ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:09:55.493394Z","caller":"traceutil/trace.go:171","msg":"trace[854404984] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"217.803729ms","start":"2026-08-14T09:09:55.275565Z","end":"2026-08-14T09:09:55.493369Z","steps":["trace[854404984] 'process raft request' (duration: 217.436489ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:09:55.80309Z","caller":"traceutil/trace.go:171","msg":"trace[1545775865] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"304.940148ms","start":"2026-08-14T09:09:55.498132Z","end":"2026-08-14T09:09:55.803073Z","steps":["trace[1545775865] 'process raft request' (duration: 304.844336ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:09:55.803199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:09:55.49811Z","time spent":"305.03216ms","remote":"127.0.0.1:38666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:09:55.854Z","caller":"traceutil/trace.go:171","msg":"trace[1115966664] linearizableReadLoop","detail":"{readStateIndex:945; appliedIndex:944; }","duration":"264.487952ms","start":"2026-08-14T09:09:55.589494Z","end":"2026-08-14T09:09:55.853982Z","steps":["trace[1115966664] 'read index received' (duration: 213.614453ms)","trace[1115966664] 'applied index is now lower than readState.Index' (duration: 50.872529ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:09:55.854118Z","caller":"traceutil/trace.go:171","msg":"trace[85271261] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"354.128004ms","start":"2026-08-14T09:09:55.499935Z","end":"2026-08-14T09:09:55.854063Z","steps":["trace[85271261] 'process raft request' (duration: 353.93429ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:09:55.854191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.670007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-14T09:09:55.854227Z","caller":"traceutil/trace.go:171","msg":"trace[298157103] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:911; }","duration":"264.751428ms","start":"2026-08-14T09:09:55.589466Z","end":"2026-08-14T09:09:55.854217Z","steps":["trace[298157103] 'agreement among raft nodes before linearized reading' (duration: 264.670336ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:09:55.854281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:09:55.499925Z","time spent":"354.271508ms","remote":"127.0.0.1:38868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5626,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:09:55.854451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.091894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-08-14T09:09:55.85445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.625965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-14T09:09:55.854514Z","caller":"traceutil/trace.go:171","msg":"trace[818181144] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:911; }","duration":"163.181876ms","start":"2026-08-14T09:09:55.691315Z","end":"2026-08-14T09:09:55.854497Z","steps":["trace[818181144] 'agreement among raft nodes before linearized reading' (duration: 162.99262ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:09:55.85452Z","caller":"traceutil/trace.go:171","msg":"trace[919939343] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:911; }","duration":"230.764748ms","start":"2026-08-14T09:09:55.62374Z","end":"2026-08-14T09:09:55.854505Z","steps":["trace[919939343] 'agreement among raft nodes before linearized reading' (duration: 230.568302ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:09:55.854587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.505549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-14T09:09:55.854664Z","caller":"traceutil/trace.go:171","msg":"trace[1023975240] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:911; }","duration":"177.632812ms","start":"2026-08-14T09:09:55.677012Z","end":"2026-08-14T09:09:55.854645Z","steps":["trace[1023975240] 'agreement among raft nodes before linearized reading' (duration: 177.334633ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:09:58.257634Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12855701056172055958,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-14T09:09:58.601459Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.174697368s","expected-duration":"1s"} {"level":"info","ts":"2026-08-14T09:09:58.601799Z","caller":"traceutil/trace.go:171","msg":"trace[1758865578] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"1.175125379s","start":"2026-08-14T09:09:57.426655Z","end":"2026-08-14T09:09:58.60178Z","steps":["trace[1758865578] 'process raft request' (duration: 1.174986256s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:09:58.601927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:09:57.426637Z","time spent":"1.175219811s","remote":"127.0.0.1:38666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:09:59.034562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.427287ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-14T09:09:59.034683Z","caller":"traceutil/trace.go:171","msg":"trace[1411249565] linearizableReadLoop","detail":"{readStateIndex:951; appliedIndex:949; }","duration":"1.278069188s","start":"2026-08-14T09:09:57.756605Z","end":"2026-08-14T09:09:59.034674Z","steps":["trace[1411249565] 'read index received' (duration: 845.086745ms)","trace[1411249565] 'applied index is now lower than readState.Index' (duration: 432.981773ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:09:59.034755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.27814591s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:09:59.034736Z","caller":"traceutil/trace.go:171","msg":"trace[619042620] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"545.656258ms","start":"2026-08-14T09:09:58.489054Z","end":"2026-08-14T09:09:59.03471Z","steps":["trace[619042620] 'process raft request' (duration: 545.584536ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:09:59.03475Z","caller":"traceutil/trace.go:171","msg":"trace[1767247447] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"1.253935627s","start":"2026-08-14T09:09:57.780791Z","end":"2026-08-14T09:09:59.034726Z","steps":["trace[1767247447] 'process raft request' (duration: 943.289865ms)","trace[1767247447] 'compare' (duration: 310.337795ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:09:59.034843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.227704752s","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-08-14T09:09:59.034846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:09:58.489033Z","time spent":"545.75294ms","remote":"127.0.0.1:38666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:09:59.034857Z","caller":"traceutil/trace.go:171","msg":"trace[628051] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:918; }","duration":"1.227748533s","start":"2026-08-14T09:09:57.807105Z","end":"2026-08-14T09:09:59.034854Z","steps":["trace[628051] 'agreement among raft nodes before linearized reading' (duration: 1.227682441s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:09:59.03485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:09:57.780779Z","time spent":"1.254016439s","remote":"127.0.0.1:38666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:09:59.034878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:09:57.807088Z","time spent":"1.227785924s","remote":"127.0.0.1:38666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-14T09:09:59.03477Z","caller":"traceutil/trace.go:171","msg":"trace[1506755347] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:918; }","duration":"1.278188451s","start":"2026-08-14T09:09:57.756576Z","end":"2026-08-14T09:09:59.034765Z","steps":["trace[1506755347] 'agreement among raft nodes before linearized reading' (duration: 1.278154581s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:09:59.034907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.070550599s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-14T09:09:59.03497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.110208875s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-14T09:09:59.034984Z","caller":"traceutil/trace.go:171","msg":"trace[1011942777] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:918; }","duration":"1.110235125s","start":"2026-08-14T09:09:57.924744Z","end":"2026-08-14T09:09:59.034979Z","steps":["trace[1011942777] 'agreement among raft nodes before linearized reading' (duration: 1.110194214s)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:09:59.034981Z","caller":"traceutil/trace.go:171","msg":"trace[929553811] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:918; }","duration":"1.070655722s","start":"2026-08-14T09:09:57.964308Z","end":"2026-08-14T09:09:59.034963Z","steps":["trace[929553811] 'agreement among raft nodes before linearized reading' (duration: 1.070533969s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:09:59.034976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:09:57.756564Z","time spent":"1.278362936s","remote":"127.0.0.1:38632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":27,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-08-14T09:09:59.035029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.111318713s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-08-14T09:09:59.035021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:09:57.964294Z","time spent":"1.070719074s","remote":"127.0.0.1:38464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-14T09:09:59.035043Z","caller":"traceutil/trace.go:171","msg":"trace[728595963] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:918; }","duration":"1.111368534s","start":"2026-08-14T09:09:57.923671Z","end":"2026-08-14T09:09:59.035039Z","steps":["trace[728595963] 'agreement among raft nodes before linearized reading' (duration: 1.111334143s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:09:59.035055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:09:57.923648Z","time spent":"1.111404226s","remote":"127.0.0.1:38666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-14T09:09:59.035054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.666336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.117\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-14T09:09:59.035087Z","caller":"traceutil/trace.go:171","msg":"trace[1757848078] range","detail":"{range_begin:/registry/masterleases/199.19.213.117; range_end:; response_count:1; response_revision:918; }","duration":"522.714697ms","start":"2026-08-14T09:09:58.512362Z","end":"2026-08-14T09:09:59.035077Z","steps":["trace[1757848078] 'agreement among raft nodes before linearized reading' (duration: 522.581994ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:09:59.035114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:09:58.512356Z","time spent":"522.752018ms","remote":"127.0.0.1:38480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.117\" "} {"level":"warn","ts":"2026-08-14T09:09:59.035165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.111534368s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-14T09:09:59.035183Z","caller":"traceutil/trace.go:171","msg":"trace[1763815429] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:918; }","duration":"1.11158072s","start":"2026-08-14T09:09:57.923597Z","end":"2026-08-14T09:09:59.035177Z","steps":["trace[1763815429] 'agreement among raft nodes before linearized reading' (duration: 1.111529729s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:09:59.035196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:09:57.923579Z","time spent":"1.11161391s","remote":"127.0.0.1:38666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-14T09:09:59.034997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:09:57.924732Z","time spent":"1.110261495s","remote":"127.0.0.1:38666","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-08-14T09:10:04.759871Z","caller":"traceutil/trace.go:171","msg":"trace[527498229] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"123.292125ms","start":"2026-08-14T09:10:04.636556Z","end":"2026-08-14T09:10:04.759849Z","steps":["trace[527498229] 'process raft request' (duration: 123.138861ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:31.30167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.186165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:10:31.301773Z","caller":"traceutil/trace.go:171","msg":"trace[983328411] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1032; }","duration":"337.303437ms","start":"2026-08-14T09:10:30.964431Z","end":"2026-08-14T09:10:31.301734Z","steps":["trace[983328411] 'range keys from in-memory index tree' (duration: 337.081662ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:31.301815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:10:30.964417Z","time spent":"337.387049ms","remote":"127.0.0.1:38452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-14T09:10:31.731279Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12855701056172056257,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-14T09:10:31.756897Z","caller":"traceutil/trace.go:171","msg":"trace[1381009327] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"616.645546ms","start":"2026-08-14T09:10:31.140219Z","end":"2026-08-14T09:10:31.756865Z","steps":["trace[1381009327] 'process raft request' (duration: 616.384419ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:31.757046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:10:31.14019Z","time spent":"616.77562ms","remote":"127.0.0.1:38666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:10:31.75705Z","caller":"traceutil/trace.go:171","msg":"trace[1664129027] linearizableReadLoop","detail":"{readStateIndex:1074; appliedIndex:1073; }","duration":"526.017068ms","start":"2026-08-14T09:10:31.230757Z","end":"2026-08-14T09:10:31.756774Z","steps":["trace[1664129027] 'read index received' (duration: 525.721421ms)","trace[1664129027] 'applied index is now lower than readState.Index' (duration: 294.827µs)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:10:31.757153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.392858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-08-14T09:10:31.757187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.76295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-14T09:10:31.757208Z","caller":"traceutil/trace.go:171","msg":"trace[1878861954] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1033; }","duration":"526.4496ms","start":"2026-08-14T09:10:31.230743Z","end":"2026-08-14T09:10:31.757192Z","steps":["trace[1878861954] 'agreement among raft nodes before linearized reading' (duration: 526.244105ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:10:31.757222Z","caller":"traceutil/trace.go:171","msg":"trace[1741048388] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1033; }","duration":"517.846572ms","start":"2026-08-14T09:10:31.239366Z","end":"2026-08-14T09:10:31.757212Z","steps":["trace[1741048388] 'agreement among raft nodes before linearized reading' (duration: 517.7643ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:31.757244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:10:31.23072Z","time spent":"526.516712ms","remote":"127.0.0.1:38666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-14T09:10:31.75726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:10:31.239356Z","time spent":"517.894773ms","remote":"127.0.0.1:38602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":3,"response size":29,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-08-14T09:10:31.757399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.873791ms","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-08-14T09:10:31.75743Z","caller":"traceutil/trace.go:171","msg":"trace[620407855] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1033; }","duration":"496.917583ms","start":"2026-08-14T09:10:31.260504Z","end":"2026-08-14T09:10:31.757421Z","steps":["trace[620407855] 'agreement among raft nodes before linearized reading' (duration: 496.837231ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:31.757455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:10:31.260486Z","time spent":"496.963933ms","remote":"127.0.0.1:38666","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-08-14T09:10:31.757539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.400064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-08-14T09:10:31.757574Z","caller":"traceutil/trace.go:171","msg":"trace[295219354] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1033; }","duration":"497.445665ms","start":"2026-08-14T09:10:31.260117Z","end":"2026-08-14T09:10:31.757562Z","steps":["trace[295219354] 'agreement among raft nodes before linearized reading' (duration: 497.318902ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:31.7576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:10:31.259935Z","time spent":"497.659151ms","remote":"127.0.0.1:38666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":453,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-14T09:10:31.757595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.495167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-14T09:10:31.757635Z","caller":"traceutil/trace.go:171","msg":"trace[1036151064] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1033; }","duration":"497.553599ms","start":"2026-08-14T09:10:31.260071Z","end":"2026-08-14T09:10:31.757624Z","steps":["trace[1036151064] 'agreement among raft nodes before linearized reading' (duration: 497.422155ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:31.757644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.703282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-14T09:10:31.757672Z","caller":"traceutil/trace.go:171","msg":"trace[1030213274] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1033; }","duration":"235.769814ms","start":"2026-08-14T09:10:31.521894Z","end":"2026-08-14T09:10:31.757664Z","steps":["trace[1030213274] 'agreement among raft nodes before linearized reading' (duration: 235.715682ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:31.757685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:10:31.260064Z","time spent":"497.61391ms","remote":"127.0.0.1:38666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-14T09:10:31.944019Z","caller":"traceutil/trace.go:171","msg":"trace[503255506] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"181.87965ms","start":"2026-08-14T09:10:31.762105Z","end":"2026-08-14T09:10:31.943985Z","steps":["trace[503255506] 'process raft request' (duration: 180.798393ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:10:31.985228Z","caller":"traceutil/trace.go:171","msg":"trace[1466227717] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"223.059524ms","start":"2026-08-14T09:10:31.762148Z","end":"2026-08-14T09:10:31.985208Z","steps":["trace[1466227717] 'process raft request' (duration: 222.746136ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:10:31.985232Z","caller":"traceutil/trace.go:171","msg":"trace[1009511370] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"222.738816ms","start":"2026-08-14T09:10:31.762461Z","end":"2026-08-14T09:10:31.9852Z","steps":["trace[1009511370] 'process raft request' (duration: 222.633463ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:10:31.985539Z","caller":"traceutil/trace.go:171","msg":"trace[920566514] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"222.693314ms","start":"2026-08-14T09:10:31.762828Z","end":"2026-08-14T09:10:31.985522Z","steps":["trace[920566514] 'process raft request' (duration: 222.308324ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:32.408452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.399869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:10:32.408535Z","caller":"traceutil/trace.go:171","msg":"trace[656641026] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1037; }","duration":"363.495512ms","start":"2026-08-14T09:10:32.045019Z","end":"2026-08-14T09:10:32.408515Z","steps":["trace[656641026] 'count revisions from in-memory index tree' (duration: 363.316578ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:32.408589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:10:32.044964Z","time spent":"363.610485ms","remote":"127.0.0.1:38708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"info","ts":"2026-08-14T09:10:33.160485Z","caller":"traceutil/trace.go:171","msg":"trace[757074717] transaction","detail":"{read_only:false; response_revision:1038; number_of_response:1; }","duration":"250.059057ms","start":"2026-08-14T09:10:32.910401Z","end":"2026-08-14T09:10:33.16046Z","steps":["trace[757074717] 'process raft request' (duration: 249.918713ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:34.376509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.786379ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-08-14T09:10:34.376765Z","caller":"traceutil/trace.go:171","msg":"trace[1084466578] linearizableReadLoop","detail":"{readStateIndex:1080; appliedIndex:1079; }","duration":"815.369403ms","start":"2026-08-14T09:10:33.561375Z","end":"2026-08-14T09:10:34.376745Z","steps":["trace[1084466578] 'read index received' (duration: 350.39703ms)","trace[1084466578] 'applied index is now lower than readState.Index' (duration: 464.970863ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:10:34.376953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"815.557437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:10:34.377011Z","caller":"traceutil/trace.go:171","msg":"trace[35939474] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1038; }","duration":"815.67113ms","start":"2026-08-14T09:10:33.561324Z","end":"2026-08-14T09:10:34.376995Z","steps":["trace[35939474] 'agreement among raft nodes before linearized reading' (duration: 815.542797ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:34.377062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:10:33.561303Z","time spent":"815.745203ms","remote":"127.0.0.1:38684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":27,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"info","ts":"2026-08-14T09:10:34.379384Z","caller":"traceutil/trace.go:171","msg":"trace[311588450] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"616.745551ms","start":"2026-08-14T09:10:33.762619Z","end":"2026-08-14T09:10:34.379364Z","steps":["trace[311588450] 'process raft request' (duration: 616.567426ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:34.37951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:10:33.762601Z","time spent":"616.842723ms","remote":"127.0.0.1:38666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:10:34.37973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.919577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"warn","ts":"2026-08-14T09:10:34.379683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.944204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-14T09:10:34.37974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.27652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:10:34.379816Z","caller":"traceutil/trace.go:171","msg":"trace[570015348] transaction","detail":"{read_only:false; response_revision:1040; number_of_response:1; }","duration":"484.074128ms","start":"2026-08-14T09:10:33.895733Z","end":"2026-08-14T09:10:34.379808Z","steps":["trace[570015348] 'process raft request' (duration: 483.582355ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:10:34.379788Z","caller":"traceutil/trace.go:171","msg":"trace[1610835314] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1040; }","duration":"499.130819ms","start":"2026-08-14T09:10:33.880637Z","end":"2026-08-14T09:10:34.379768Z","steps":["trace[1610835314] 'agreement among raft nodes before linearized reading' (duration: 498.872232ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:34.379863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:10:33.895703Z","time spent":"484.138349ms","remote":"127.0.0.1:38666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:10:34.379883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:10:33.880617Z","time spent":"499.251761ms","remote":"127.0.0.1:38606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":29,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"info","ts":"2026-08-14T09:10:34.379794Z","caller":"traceutil/trace.go:171","msg":"trace[70954445] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1040; }","duration":"416.406773ms","start":"2026-08-14T09:10:33.963373Z","end":"2026-08-14T09:10:34.37978Z","steps":["trace[70954445] 'agreement among raft nodes before linearized reading' (duration: 416.28461ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:34.379955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.756353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-14T09:10:34.379977Z","caller":"traceutil/trace.go:171","msg":"trace[1138981452] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1040; }","duration":"390.810294ms","start":"2026-08-14T09:10:33.989161Z","end":"2026-08-14T09:10:34.379971Z","steps":["trace[1138981452] 'agreement among raft nodes before linearized reading' (duration: 390.752512ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:34.379996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:10:33.989144Z","time spent":"390.847805ms","remote":"127.0.0.1:38666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-14T09:10:34.380076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.619373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-08-14T09:10:34.379988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:10:33.963352Z","time spent":"416.623278ms","remote":"127.0.0.1:38464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-14T09:10:34.380097Z","caller":"traceutil/trace.go:171","msg":"trace[645297044] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1040; }","duration":"430.678515ms","start":"2026-08-14T09:10:33.949413Z","end":"2026-08-14T09:10:34.380091Z","steps":["trace[645297044] 'agreement among raft nodes before linearized reading' (duration: 430.628124ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:34.380114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:10:33.94939Z","time spent":"430.719625ms","remote":"127.0.0.1:38666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-14T09:10:34.379763Z","caller":"traceutil/trace.go:171","msg":"trace[629062511] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1040; }","duration":"390.951827ms","start":"2026-08-14T09:10:33.988803Z","end":"2026-08-14T09:10:34.379755Z","steps":["trace[629062511] 'agreement among raft nodes before linearized reading' (duration: 390.817904ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:34.380171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:10:33.988772Z","time spent":"391.395169ms","remote":"127.0.0.1:38666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":453,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-14T09:10:34.380361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.877911ms","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-08-14T09:10:34.380467Z","caller":"traceutil/trace.go:171","msg":"trace[106482453] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1040; }","duration":"392.004604ms","start":"2026-08-14T09:10:33.988408Z","end":"2026-08-14T09:10:34.380412Z","steps":["trace[106482453] 'agreement among raft nodes before linearized reading' (duration: 391.729657ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:34.380559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:10:33.9884Z","time spent":"392.111096ms","remote":"127.0.0.1:38666","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-08-14T09:10:34.61372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.680895ms","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-08-14T09:10:34.613883Z","caller":"traceutil/trace.go:171","msg":"trace[1960798871] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1040; }","duration":"125.870989ms","start":"2026-08-14T09:10:34.487992Z","end":"2026-08-14T09:10:34.613862Z","steps":["trace[1960798871] 'range keys from in-memory index tree' (duration: 125.661524ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:34.613945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.238276ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-14T09:10:34.61417Z","caller":"traceutil/trace.go:171","msg":"trace[1944585695] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"227.538456ms","start":"2026-08-14T09:10:34.386615Z","end":"2026-08-14T09:10:34.614153Z","steps":["trace[1944585695] 'process raft request' (duration: 227.504805ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:10:34.614211Z","caller":"traceutil/trace.go:171","msg":"trace[1116059472] transaction","detail":"{read_only:false; response_revision:1041; number_of_response:1; }","duration":"229.358002ms","start":"2026-08-14T09:10:34.384832Z","end":"2026-08-14T09:10:34.61419Z","steps":["trace[1116059472] 'process raft request' (duration: 108.798857ms)","trace[1116059472] 'compare' (duration: 120.103983ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:10:34.614438Z","caller":"traceutil/trace.go:171","msg":"trace[2141065416] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"229.505596ms","start":"2026-08-14T09:10:34.384921Z","end":"2026-08-14T09:10:34.614426Z","steps":["trace[2141065416] 'process raft request' (duration: 229.167616ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:10:34.614338Z","caller":"traceutil/trace.go:171","msg":"trace[381995402] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"229.434794ms","start":"2026-08-14T09:10:34.384892Z","end":"2026-08-14T09:10:34.614327Z","steps":["trace[381995402] 'process raft request' (duration: 229.153036ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:51.179572Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12855701056172056505,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-14T09:10:51.180279Z","caller":"traceutil/trace.go:171","msg":"trace[1520287988] transaction","detail":"{read_only:false; response_revision:1143; number_of_response:1; }","duration":"740.790522ms","start":"2026-08-14T09:10:50.439469Z","end":"2026-08-14T09:10:51.180259Z","steps":["trace[1520287988] 'process raft request' (duration: 740.671779ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:51.180371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:10:50.439451Z","time spent":"740.862535ms","remote":"127.0.0.1:38666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:10:52.00349Z","caller":"traceutil/trace.go:171","msg":"trace[1262153734] linearizableReadLoop","detail":"{readStateIndex:1191; appliedIndex:1189; }","duration":"1.324464674s","start":"2026-08-14T09:10:50.67901Z","end":"2026-08-14T09:10:52.003474Z","steps":["trace[1262153734] 'read index received' (duration: 501.14864ms)","trace[1262153734] 'applied index is now lower than readState.Index' (duration: 823.315464ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:10:52.003713Z","caller":"traceutil/trace.go:171","msg":"trace[1552727024] transaction","detail":"{read_only:false; response_revision:1144; number_of_response:1; }","duration":"1.51609724s","start":"2026-08-14T09:10:50.487584Z","end":"2026-08-14T09:10:52.003681Z","steps":["trace[1552727024] 'process raft request' (duration: 1.515790573s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:52.003773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.324748442s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-14T09:10:52.003796Z","caller":"traceutil/trace.go:171","msg":"trace[1442298344] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1144; }","duration":"1.324817963s","start":"2026-08-14T09:10:50.678971Z","end":"2026-08-14T09:10:52.003789Z","steps":["trace[1442298344] 'agreement among raft nodes before linearized reading' (duration: 1.324718461s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:52.003813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:10:50.678956Z","time spent":"1.324852725s","remote":"127.0.0.1:38666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-14T09:10:52.003833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:10:50.487565Z","time spent":"1.516200574s","remote":"127.0.0.1:38666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:10:52.003869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.316235816s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-08-14T09:10:52.003889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"766.410893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-14T09:10:52.003937Z","caller":"traceutil/trace.go:171","msg":"trace[1339163301] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1144; }","duration":"1.316350328s","start":"2026-08-14T09:10:50.687571Z","end":"2026-08-14T09:10:52.003922Z","steps":["trace[1339163301] 'agreement among raft nodes before linearized reading' (duration: 1.316115553s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:52.003971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:10:50.687563Z","time spent":"1.316402561s","remote":"127.0.0.1:38666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-14T09:10:52.003968Z","caller":"traceutil/trace.go:171","msg":"trace[1623294327] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1144; }","duration":"766.538216ms","start":"2026-08-14T09:10:51.237407Z","end":"2026-08-14T09:10:52.003945Z","steps":["trace[1623294327] 'agreement among raft nodes before linearized reading' (duration: 766.29693ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:52.004016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:10:51.237387Z","time spent":"766.619428ms","remote":"127.0.0.1:38666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-14T09:10:52.004073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.315165348s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-14T09:10:52.004091Z","caller":"traceutil/trace.go:171","msg":"trace[152215174] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1144; }","duration":"1.31521962s","start":"2026-08-14T09:10:50.688867Z","end":"2026-08-14T09:10:52.004086Z","steps":["trace[152215174] 'agreement among raft nodes before linearized reading' (duration: 1.315174128s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:52.004109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:10:50.688848Z","time spent":"1.315258101s","remote":"127.0.0.1:38666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-14T09:10:52.004128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.315307132s","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-08-14T09:10:52.004157Z","caller":"traceutil/trace.go:171","msg":"trace[696708475] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1144; }","duration":"1.315364843s","start":"2026-08-14T09:10:50.688787Z","end":"2026-08-14T09:10:52.004152Z","steps":["trace[696708475] 'agreement among raft nodes before linearized reading' (duration: 1.31525579s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:10:52.004174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:10:50.68877Z","time spent":"1.315400795s","remote":"127.0.0.1:38666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-14T09:11:06.46137Z","caller":"traceutil/trace.go:171","msg":"trace[140030183] transaction","detail":"{read_only:false; response_revision:1199; number_of_response:1; }","duration":"398.465198ms","start":"2026-08-14T09:11:06.062881Z","end":"2026-08-14T09:11:06.461346Z","steps":["trace[140030183] 'process raft request' (duration: 398.306684ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:11:06.461513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:11:06.062869Z","time spent":"398.57384ms","remote":"127.0.0.1:38666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:11:07.430118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.04961ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-14T09:11:07.430326Z","caller":"traceutil/trace.go:171","msg":"trace[307714959] transaction","detail":"{read_only:false; response_revision:1201; number_of_response:1; }","duration":"186.406994ms","start":"2026-08-14T09:11:07.243899Z","end":"2026-08-14T09:11:07.430306Z","steps":["trace[307714959] 'process raft request' (duration: 186.319652ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:11:07.430673Z","caller":"traceutil/trace.go:171","msg":"trace[1842750022] linearizableReadLoop","detail":"{readStateIndex:1250; appliedIndex:1248; }","duration":"1.349663953s","start":"2026-08-14T09:11:06.080982Z","end":"2026-08-14T09:11:07.430646Z","steps":["trace[1842750022] 'read index received' (duration: 380.262497ms)","trace[1842750022] 'applied index is now lower than readState.Index' (duration: 969.399386ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:11:07.430783Z","caller":"traceutil/trace.go:171","msg":"trace[2035423469] transaction","detail":"{read_only:false; response_revision:1200; number_of_response:1; }","duration":"1.367440673s","start":"2026-08-14T09:11:06.063317Z","end":"2026-08-14T09:11:07.430758Z","steps":["trace[2035423469] 'process raft request' (duration: 829.672645ms)","trace[2035423469] 'compare' (duration: 536.816794ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:11:07.43088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.615806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-14T09:11:07.430909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:11:06.063306Z","time spent":"1.367530165s","remote":"127.0.0.1:38666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:11:07.430903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.34991844s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-08-14T09:11:07.43093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.346677737s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-14T09:11:07.430961Z","caller":"traceutil/trace.go:171","msg":"trace[1652717427] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1201; }","duration":"1.350006812s","start":"2026-08-14T09:11:06.080941Z","end":"2026-08-14T09:11:07.430948Z","steps":["trace[1652717427] 'agreement among raft nodes before linearized reading' (duration: 1.349855828s)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:11:07.430968Z","caller":"traceutil/trace.go:171","msg":"trace[1860797829] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1201; }","duration":"1.346731669s","start":"2026-08-14T09:11:06.084224Z","end":"2026-08-14T09:11:07.430956Z","steps":["trace[1860797829] 'agreement among raft nodes before linearized reading' (duration: 1.346591486s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:11:07.431007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:11:06.080922Z","time spent":"1.350076753s","remote":"127.0.0.1:38666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-14T09:11:07.430958Z","caller":"traceutil/trace.go:171","msg":"trace[634358697] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1201; }","duration":"466.739699ms","start":"2026-08-14T09:11:06.964199Z","end":"2026-08-14T09:11:07.430938Z","steps":["trace[634358697] 'agreement among raft nodes before linearized reading' (duration: 466.625996ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:11:07.431041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:11:06.964164Z","time spent":"466.871642ms","remote":"127.0.0.1:38464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-14T09:11:07.431013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:11:06.084215Z","time spent":"1.34679071s","remote":"127.0.0.1:38666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-14T09:11:07.431078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.343162938s","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-08-14T09:11:07.431143Z","caller":"traceutil/trace.go:171","msg":"trace[530801875] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1201; }","duration":"1.34326486s","start":"2026-08-14T09:11:06.087861Z","end":"2026-08-14T09:11:07.431126Z","steps":["trace[530801875] 'agreement among raft nodes before linearized reading' (duration: 1.343043405s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:11:07.431198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:11:06.08785Z","time spent":"1.343339372s","remote":"127.0.0.1:38666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-14T09:11:07.631083Z","caller":"traceutil/trace.go:171","msg":"trace[49730057] transaction","detail":"{read_only:false; response_revision:1202; number_of_response:1; }","duration":"195.808382ms","start":"2026-08-14T09:11:07.435249Z","end":"2026-08-14T09:11:07.631057Z","steps":["trace[49730057] 'process raft request' (duration: 188.682062ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:11:07.692983Z","caller":"traceutil/trace.go:171","msg":"trace[1075243411] transaction","detail":"{read_only:false; response_revision:1204; number_of_response:1; }","duration":"256.961351ms","start":"2026-08-14T09:11:07.435998Z","end":"2026-08-14T09:11:07.69296Z","steps":["trace[1075243411] 'process raft request' (duration: 256.9125ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:11:07.693088Z","caller":"traceutil/trace.go:171","msg":"trace[941385733] transaction","detail":"{read_only:false; response_revision:1203; number_of_response:1; }","duration":"257.486486ms","start":"2026-08-14T09:11:07.435568Z","end":"2026-08-14T09:11:07.693054Z","steps":["trace[941385733] 'process raft request' (duration: 257.175137ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:11:09.197034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.156522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:11:09.197123Z","caller":"traceutil/trace.go:171","msg":"trace[1665747143] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1213; }","duration":"232.285057ms","start":"2026-08-14T09:11:08.964819Z","end":"2026-08-14T09:11:09.197104Z","steps":["trace[1665747143] 'range keys from in-memory index tree' (duration: 232.08231ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:11:10.135825Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12855701056172056665,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-14T09:11:10.288582Z","caller":"traceutil/trace.go:171","msg":"trace[393095953] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"852.273978ms","start":"2026-08-14T09:11:09.436285Z","end":"2026-08-14T09:11:10.288559Z","steps":["trace[393095953] 'process raft request' (duration: 852.127924ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:11:10.288718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:11:09.436267Z","time spent":"852.381151ms","remote":"127.0.0.1:38666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:11:10.292777Z","caller":"traceutil/trace.go:171","msg":"trace[1665164373] linearizableReadLoop","detail":"{readStateIndex:1266; appliedIndex:1264; }","duration":"657.299288ms","start":"2026-08-14T09:11:09.635462Z","end":"2026-08-14T09:11:10.292761Z","steps":["trace[1665164373] 'read index received' (duration: 653.024689ms)","trace[1665164373] 'applied index is now lower than readState.Index' (duration: 4.273639ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:11:10.292873Z","caller":"traceutil/trace.go:171","msg":"trace[218240489] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"853.858718ms","start":"2026-08-14T09:11:09.439005Z","end":"2026-08-14T09:11:10.292864Z","steps":["trace[218240489] 'process raft request' (duration: 853.618271ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:11:10.29294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:11:09.438987Z","time spent":"853.91496ms","remote":"127.0.0.1:38666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:11:10.293103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"657.646746ms","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-08-14T09:11:10.293137Z","caller":"traceutil/trace.go:171","msg":"trace[1651836466] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1215; }","duration":"657.682067ms","start":"2026-08-14T09:11:09.635446Z","end":"2026-08-14T09:11:10.293128Z","steps":["trace[1651836466] 'agreement among raft nodes before linearized reading' (duration: 657.570954ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:11:10.293169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:11:09.63538Z","time spent":"657.78322ms","remote":"127.0.0.1:38666","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-08-14T09:11:10.293296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.589979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-14T09:11:10.293327Z","caller":"traceutil/trace.go:171","msg":"trace[466623519] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1215; }","duration":"596.656881ms","start":"2026-08-14T09:11:09.696662Z","end":"2026-08-14T09:11:10.293319Z","steps":["trace[466623519] 'agreement among raft nodes before linearized reading' (duration: 596.583079ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:11:10.293351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:11:09.696641Z","time spent":"596.703741ms","remote":"127.0.0.1:38666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-14T09:11:10.293486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.270076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-14T09:11:10.293545Z","caller":"traceutil/trace.go:171","msg":"trace[1509702838] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1215; }","duration":"595.375019ms","start":"2026-08-14T09:11:09.698156Z","end":"2026-08-14T09:11:10.293531Z","steps":["trace[1509702838] 'agreement among raft nodes before linearized reading' (duration: 595.232935ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:11:10.293583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:11:09.698136Z","time spent":"595.44068ms","remote":"127.0.0.1:38666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-14T09:11:12.614539Z","caller":"traceutil/trace.go:171","msg":"trace[75702621] linearizableReadLoop","detail":"{readStateIndex:1271; appliedIndex:1270; }","duration":"316.814558ms","start":"2026-08-14T09:11:12.2977Z","end":"2026-08-14T09:11:12.614514Z","steps":["trace[75702621] 'read index received' (duration: 316.563722ms)","trace[75702621] 'applied index is now lower than readState.Index' (duration: 249.746µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:11:12.614874Z","caller":"traceutil/trace.go:171","msg":"trace[1358496951] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"319.713393ms","start":"2026-08-14T09:11:12.294972Z","end":"2026-08-14T09:11:12.614685Z","steps":["trace[1358496951] 'process raft request' (duration: 319.360214ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:11:12.615019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.875279ms","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-08-14T09:11:12.615064Z","caller":"traceutil/trace.go:171","msg":"trace[1883378842] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1220; }","duration":"226.930981ms","start":"2026-08-14T09:11:12.388121Z","end":"2026-08-14T09:11:12.615052Z","steps":["trace[1883378842] 'agreement among raft nodes before linearized reading' (duration: 226.792296ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:11:12.61488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.103851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-08-14T09:11:12.615152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.806385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-14T09:11:12.615178Z","caller":"traceutil/trace.go:171","msg":"trace[1255723121] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1220; }","duration":"262.437681ms","start":"2026-08-14T09:11:12.35271Z","end":"2026-08-14T09:11:12.615148Z","steps":["trace[1255723121] 'agreement among raft nodes before linearized reading' (duration: 262.04758ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:11:12.615202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:11:12.294948Z","time spent":"319.983559ms","remote":"127.0.0.1:38666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:11:12.615203Z","caller":"traceutil/trace.go:171","msg":"trace[326219893] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1220; }","duration":"261.871077ms","start":"2026-08-14T09:11:12.353316Z","end":"2026-08-14T09:11:12.615187Z","steps":["trace[326219893] 'agreement among raft nodes before linearized reading' (duration: 261.736684ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:11:12.615281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.554048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-14T09:11:12.615336Z","caller":"traceutil/trace.go:171","msg":"trace[1298000669] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1220; }","duration":"317.65823ms","start":"2026-08-14T09:11:12.297664Z","end":"2026-08-14T09:11:12.615322Z","steps":["trace[1298000669] 'agreement among raft nodes before linearized reading' (duration: 317.26585ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:11:12.615371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:11:12.297645Z","time spent":"317.717902ms","remote":"127.0.0.1:38666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-14T09:11:12.728942Z","caller":"traceutil/trace.go:171","msg":"trace[666203763] transaction","detail":"{read_only:false; response_revision:1221; number_of_response:1; }","duration":"109.28982ms","start":"2026-08-14T09:11:12.619628Z","end":"2026-08-14T09:11:12.728918Z","steps":["trace[666203763] 'process raft request' (duration: 10.035485ms)","trace[666203763] 'compare' (duration: 99.028159ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:11:12.729032Z","caller":"traceutil/trace.go:171","msg":"trace[99147756] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"108.51478ms","start":"2026-08-14T09:11:12.620499Z","end":"2026-08-14T09:11:12.729014Z","steps":["trace[99147756] 'process raft request' (duration: 108.468919ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:11:12.729216Z","caller":"traceutil/trace.go:171","msg":"trace[430381007] transaction","detail":"{read_only:false; response_revision:1223; number_of_response:1; }","duration":"109.552796ms","start":"2026-08-14T09:11:12.619653Z","end":"2026-08-14T09:11:12.729206Z","steps":["trace[430381007] 'process raft request' (duration: 109.238308ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:11:12.729362Z","caller":"traceutil/trace.go:171","msg":"trace[1016114634] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"109.675899ms","start":"2026-08-14T09:11:12.619653Z","end":"2026-08-14T09:11:12.729329Z","steps":["trace[1016114634] 'process raft request' (duration: 109.185287ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:11:12.729428Z","caller":"traceutil/trace.go:171","msg":"trace[1968897149] transaction","detail":"{read_only:false; response_revision:1224; number_of_response:1; }","duration":"109.471114ms","start":"2026-08-14T09:11:12.619932Z","end":"2026-08-14T09:11:12.729403Z","steps":["trace[1968897149] 'process raft request' (duration: 109.000662ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:11:13.465215Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12855701056172056681,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-14T09:11:13.965526Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12855701056172056681,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-14T09:11:14.11511Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.399387644s","expected-duration":"1s"} {"level":"warn","ts":"2026-08-14T09:11:14.708741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.744215ms","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-08-14T09:11:14.708837Z","caller":"traceutil/trace.go:171","msg":"trace[476024435] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1225; }","duration":"220.849127ms","start":"2026-08-14T09:11:14.487971Z","end":"2026-08-14T09:11:14.70882Z","steps":["trace[476024435] 'range keys from in-memory index tree' (duration: 220.730704ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:11:14.709069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.609363ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-14T09:11:14.709168Z","caller":"traceutil/trace.go:171","msg":"trace[1296271857] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"1.99356414s","start":"2026-08-14T09:11:12.715585Z","end":"2026-08-14T09:11:14.709149Z","steps":["trace[1296271857] 'process raft request' (duration: 1.399801403s)","trace[1296271857] 'compare' (duration: 593.487071ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:11:14.709229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:11:12.715564Z","time spent":"1.993636023s","remote":"127.0.0.1:38666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:11:14.726847Z","caller":"traceutil/trace.go:171","msg":"trace[1421489702] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"697.951527ms","start":"2026-08-14T09:11:14.028872Z","end":"2026-08-14T09:11:14.726824Z","steps":["trace[1421489702] 'process raft request' (duration: 697.738052ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:11:14.726985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:11:14.028849Z","time spent":"698.0709ms","remote":"127.0.0.1:38606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5722,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:11:14.727059Z","caller":"traceutil/trace.go:171","msg":"trace[116508960] transaction","detail":"{read_only:false; response_revision:1228; number_of_response:1; }","duration":"107.268038ms","start":"2026-08-14T09:11:14.619771Z","end":"2026-08-14T09:11:14.727039Z","steps":["trace[116508960] 'process raft request' (duration: 106.989921ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:11:14.72723Z","caller":"traceutil/trace.go:171","msg":"trace[590211688] linearizableReadLoop","detail":"{readStateIndex:1278; appliedIndex:1276; }","duration":"1.762215698s","start":"2026-08-14T09:11:12.964997Z","end":"2026-08-14T09:11:14.727213Z","steps":["trace[590211688] 'read index received' (duration: 1.150396313s)","trace[590211688] 'applied index is now lower than readState.Index' (duration: 611.818255ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:11:14.727344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.762344022s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:11:14.727372Z","caller":"traceutil/trace.go:171","msg":"trace[501026239] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1228; }","duration":"1.762418263s","start":"2026-08-14T09:11:12.964945Z","end":"2026-08-14T09:11:14.727363Z","steps":["trace[501026239] 'agreement among raft nodes before linearized reading' (duration: 1.762365832s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:11:14.727402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:11:12.96492Z","time spent":"1.762472995s","remote":"127.0.0.1:38464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-14T09:11:14.727558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.757421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-14T09:11:14.727612Z","caller":"traceutil/trace.go:171","msg":"trace[1037063020] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1228; }","duration":"517.858773ms","start":"2026-08-14T09:11:14.20974Z","end":"2026-08-14T09:11:14.727598Z","steps":["trace[1037063020] 'agreement among raft nodes before linearized reading' (duration: 517.764981ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:11:14.727649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:11:14.209724Z","time spent":"517.916545ms","remote":"127.0.0.1:38544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":5,"response size":29,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-08-14T09:11:14.727678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.262240898s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-08-14T09:11:14.727772Z","caller":"traceutil/trace.go:171","msg":"trace[1912735848] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1228; }","duration":"1.262382161s","start":"2026-08-14T09:11:13.465369Z","end":"2026-08-14T09:11:14.727751Z","steps":["trace[1912735848] 'agreement among raft nodes before linearized reading' (duration: 1.262093344s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:11:14.727816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:11:13.465344Z","time spent":"1.262464504s","remote":"127.0.0.1:38666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":496,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-08-14T09:11:15.714271Z","caller":"traceutil/trace.go:171","msg":"trace[741335561] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"121.790987ms","start":"2026-08-14T09:11:15.592455Z","end":"2026-08-14T09:11:15.714246Z","steps":["trace[741335561] 'process raft request' (duration: 121.644153ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:11:16.645713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.989314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:11:16.645818Z","caller":"traceutil/trace.go:171","msg":"trace[2086565260] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1249; }","duration":"233.126088ms","start":"2026-08-14T09:11:16.412668Z","end":"2026-08-14T09:11:16.645794Z","steps":["trace[2086565260] 'range keys from in-memory index tree' (duration: 232.925313ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:11:18.50752Z","caller":"traceutil/trace.go:171","msg":"trace[1043896747] linearizableReadLoop","detail":"{readStateIndex:1315; appliedIndex:1314; }","duration":"305.874922ms","start":"2026-08-14T09:11:18.201629Z","end":"2026-08-14T09:11:18.507504Z","steps":["trace[1043896747] 'read index received' (duration: 305.779979ms)","trace[1043896747] 'applied index is now lower than readState.Index' (duration: 94.292µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:11:18.507593Z","caller":"traceutil/trace.go:171","msg":"trace[978756828] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"307.645417ms","start":"2026-08-14T09:11:18.199908Z","end":"2026-08-14T09:11:18.507553Z","steps":["trace[978756828] 'process raft request' (duration: 307.499373ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:11:18.507639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.993314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:11:18.507764Z","caller":"traceutil/trace.go:171","msg":"trace[374840543] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1263; }","duration":"306.160678ms","start":"2026-08-14T09:11:18.201596Z","end":"2026-08-14T09:11:18.507757Z","steps":["trace[374840543] 'agreement among raft nodes before linearized reading' (duration: 305.970393ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:11:18.507759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:11:18.199887Z","time spent":"307.80105ms","remote":"127.0.0.1:38508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-14T09:11:18.507788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:11:18.20158Z","time spent":"306.202099ms","remote":"127.0.0.1:38666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-08-14T09:11:32.885693Z","caller":"traceutil/trace.go:171","msg":"trace[427589549] transaction","detail":"{read_only:false; response_revision:1349; number_of_response:1; }","duration":"109.524343ms","start":"2026-08-14T09:11:32.776134Z","end":"2026-08-14T09:11:32.885658Z","steps":["trace[427589549] 'process raft request' (duration: 109.153414ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:11:33.040648Z","caller":"traceutil/trace.go:171","msg":"trace[724662907] transaction","detail":"{read_only:false; response_revision:1351; number_of_response:1; }","duration":"150.692933ms","start":"2026-08-14T09:11:32.889916Z","end":"2026-08-14T09:11:33.040609Z","steps":["trace[724662907] 'process raft request' (duration: 125.187479ms)","trace[724662907] 'compare' (duration: 25.175646ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:11:33.040835Z","caller":"traceutil/trace.go:171","msg":"trace[1361686607] transaction","detail":"{read_only:false; response_revision:1353; number_of_response:1; }","duration":"150.648142ms","start":"2026-08-14T09:11:32.890166Z","end":"2026-08-14T09:11:33.040814Z","steps":["trace[1361686607] 'process raft request' (duration: 150.336064ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:11:33.040878Z","caller":"traceutil/trace.go:171","msg":"trace[1113332954] transaction","detail":"{read_only:false; response_revision:1352; number_of_response:1; }","duration":"150.716403ms","start":"2026-08-14T09:11:32.890151Z","end":"2026-08-14T09:11:33.040868Z","steps":["trace[1113332954] 'process raft request' (duration: 150.307193ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:11:33.040938Z","caller":"traceutil/trace.go:171","msg":"trace[911673556] transaction","detail":"{read_only:false; response_revision:1354; number_of_response:1; }","duration":"149.306648ms","start":"2026-08-14T09:11:32.891606Z","end":"2026-08-14T09:11:33.040913Z","steps":["trace[911673556] 'process raft request' (duration: 148.936968ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:12:48.681524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.279865ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-14T09:12:48.68202Z","caller":"traceutil/trace.go:171","msg":"trace[1550243460] transaction","detail":"{read_only:false; response_revision:1698; number_of_response:1; }","duration":"144.471503ms","start":"2026-08-14T09:12:48.537496Z","end":"2026-08-14T09:12:48.681968Z","steps":["trace[1550243460] 'process raft request' (duration: 40.596394ms)","trace[1550243460] 'compare' (duration: 103.09526ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:12:50.185894Z","caller":"traceutil/trace.go:171","msg":"trace[753220814] transaction","detail":"{read_only:false; response_revision:1707; number_of_response:1; }","duration":"117.416001ms","start":"2026-08-14T09:12:50.068453Z","end":"2026-08-14T09:12:50.185869Z","steps":["trace[753220814] 'process raft request' (duration: 117.238557ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:12:50.942024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.899807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:12:50.942149Z","caller":"traceutil/trace.go:171","msg":"trace[1070873294] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:1709; }","duration":"111.119613ms","start":"2026-08-14T09:12:50.831Z","end":"2026-08-14T09:12:50.94212Z","steps":["trace[1070873294] 'count revisions from in-memory index tree' (duration: 110.726514ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:12:53.821104Z","caller":"traceutil/trace.go:171","msg":"trace[2140303020] transaction","detail":"{read_only:false; response_revision:1723; number_of_response:1; }","duration":"118.88685ms","start":"2026-08-14T09:12:53.702171Z","end":"2026-08-14T09:12:53.821058Z","steps":["trace[2140303020] 'process raft request' (duration: 35.785913ms)","trace[2140303020] 'compare' (duration: 82.877691ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:12:53.947959Z","caller":"traceutil/trace.go:171","msg":"trace[1162797365] transaction","detail":"{read_only:false; response_revision:1724; number_of_response:1; }","duration":"121.750511ms","start":"2026-08-14T09:12:53.826177Z","end":"2026-08-14T09:12:53.947927Z","steps":["trace[1162797365] 'process raft request' (duration: 102.414613ms)","trace[1162797365] 'compare' (duration: 19.221165ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:13:45.229376Z","caller":"traceutil/trace.go:171","msg":"trace[1587658732] transaction","detail":"{read_only:false; response_revision:1956; number_of_response:1; }","duration":"150.094481ms","start":"2026-08-14T09:13:45.079246Z","end":"2026-08-14T09:13:45.229341Z","steps":["trace[1587658732] 'process raft request' (duration: 149.842364ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:13:45.708637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.829783ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-14T09:13:45.709062Z","caller":"traceutil/trace.go:171","msg":"trace[1258193517] transaction","detail":"{read_only:false; response_revision:1958; number_of_response:1; }","duration":"255.121085ms","start":"2026-08-14T09:13:45.453879Z","end":"2026-08-14T09:13:45.709Z","steps":["trace[1258193517] 'process raft request' (duration: 83.834041ms)","trace[1258193517] 'compare' (duration: 170.584357ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:13:45.709061Z","caller":"traceutil/trace.go:171","msg":"trace[2073115119] transaction","detail":"{read_only:false; response_revision:1959; number_of_response:1; }","duration":"179.373938ms","start":"2026-08-14T09:13:45.529637Z","end":"2026-08-14T09:13:45.70901Z","steps":["trace[2073115119] 'process raft request' (duration: 179.270265ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:13:46.114869Z","caller":"traceutil/trace.go:171","msg":"trace[1488937646] transaction","detail":"{read_only:false; response_revision:1961; number_of_response:1; }","duration":"172.868224ms","start":"2026-08-14T09:13:45.941964Z","end":"2026-08-14T09:13:46.114832Z","steps":["trace[1488937646] 'process raft request' (duration: 172.666389ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:13:46.347306Z","caller":"traceutil/trace.go:171","msg":"trace[663381090] linearizableReadLoop","detail":"{readStateIndex:2045; appliedIndex:2042; }","duration":"401.76966ms","start":"2026-08-14T09:13:45.94551Z","end":"2026-08-14T09:13:46.347279Z","steps":["trace[663381090] 'read index received' (duration: 169.095579ms)","trace[663381090] 'applied index is now lower than readState.Index' (duration: 232.67049ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:13:46.347367Z","caller":"traceutil/trace.go:171","msg":"trace[337187070] transaction","detail":"{read_only:false; response_revision:1962; number_of_response:1; }","duration":"405.152354ms","start":"2026-08-14T09:13:45.942153Z","end":"2026-08-14T09:13:46.347305Z","steps":["trace[337187070] 'process raft request' (duration: 369.346952ms)","trace[337187070] 'compare' (duration: 35.516365ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:13:46.347438Z","caller":"traceutil/trace.go:171","msg":"trace[591318533] transaction","detail":"{read_only:false; response_revision:1963; number_of_response:1; }","duration":"405.157695ms","start":"2026-08-14T09:13:45.942261Z","end":"2026-08-14T09:13:46.347419Z","steps":["trace[591318533] 'process raft request' (duration: 404.924429ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:13:46.347561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:13:45.942101Z","time spent":"405.36142ms","remote":"127.0.0.1:38666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:13:46.347573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:13:45.942251Z","time spent":"405.231987ms","remote":"127.0.0.1:38666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:13:46.347587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.065077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:6772"} {"level":"info","ts":"2026-08-14T09:13:46.347639Z","caller":"traceutil/trace.go:171","msg":"trace[1690051244] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1963; }","duration":"402.137279ms","start":"2026-08-14T09:13:45.945486Z","end":"2026-08-14T09:13:46.347623Z","steps":["trace[1690051244] 'agreement among raft nodes before linearized reading' (duration: 401.947454ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:13:46.347683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:13:45.945476Z","time spent":"402.19485ms","remote":"127.0.0.1:38606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":6794,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-08-14T09:13:46.347767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.71043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-14T09:13:46.347802Z","caller":"traceutil/trace.go:171","msg":"trace[816054657] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1963; }","duration":"253.796462ms","start":"2026-08-14T09:13:46.093997Z","end":"2026-08-14T09:13:46.347794Z","steps":["trace[816054657] 'agreement among raft nodes before linearized reading' (duration: 253.730221ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:13:46.347871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.216326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-14T09:13:46.347925Z","caller":"traceutil/trace.go:171","msg":"trace[1925460841] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1963; }","duration":"272.322589ms","start":"2026-08-14T09:13:46.075589Z","end":"2026-08-14T09:13:46.347911Z","steps":["trace[1925460841] 'agreement among raft nodes before linearized reading' (duration: 272.119654ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:13:48.548745Z","caller":"traceutil/trace.go:171","msg":"trace[2077228743] transaction","detail":"{read_only:false; response_revision:1973; number_of_response:1; }","duration":"142.95861ms","start":"2026-08-14T09:13:48.405745Z","end":"2026-08-14T09:13:48.548703Z","steps":["trace[2077228743] 'process raft request' (duration: 75.881471ms)","trace[2077228743] 'compare' (duration: 66.847274ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:14:11.097154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.759492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-14T09:14:11.097263Z","caller":"traceutil/trace.go:171","msg":"trace[1258877273] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:2094; }","duration":"577.965038ms","start":"2026-08-14T09:14:10.519276Z","end":"2026-08-14T09:14:11.097241Z","steps":["trace[1258877273] 'count revisions from in-memory index tree' (duration: 577.551917ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:14:11.097311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:14:10.519232Z","time spent":"578.06878ms","remote":"127.0.0.1:38888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":3,"response size":29,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-08-14T09:14:11.097323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.586727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-14T09:14:11.097386Z","caller":"traceutil/trace.go:171","msg":"trace[339458813] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2094; }","duration":"573.7121ms","start":"2026-08-14T09:14:10.52366Z","end":"2026-08-14T09:14:11.097372Z","steps":["trace[339458813] 'range keys from in-memory index tree' (duration: 573.453064ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:14:11.097414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:14:10.523649Z","time spent":"573.758701ms","remote":"127.0.0.1:38666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-14T09:14:11.097467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.754057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:14:11.097553Z","caller":"traceutil/trace.go:171","msg":"trace[595107180] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2094; }","duration":"304.859059ms","start":"2026-08-14T09:14:10.792672Z","end":"2026-08-14T09:14:11.097531Z","steps":["trace[595107180] 'range keys from in-memory index tree' (duration: 304.666034ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:14:11.097604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:14:10.792666Z","time spent":"304.925991ms","remote":"127.0.0.1:38464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-14T09:14:11.097645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.854523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-08-14T09:14:11.097584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.831605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-08-14T09:14:11.097663Z","caller":"traceutil/trace.go:171","msg":"trace[1686261620] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2094; }","duration":"328.893214ms","start":"2026-08-14T09:14:10.768764Z","end":"2026-08-14T09:14:11.097658Z","steps":["trace[1686261620] 'range keys from in-memory index tree' (duration: 328.77989ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:14:11.097683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:14:10.768755Z","time spent":"328.925086ms","remote":"127.0.0.1:38666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-14T09:14:11.097496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.965756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-08-14T09:14:11.097803Z","caller":"traceutil/trace.go:171","msg":"trace[1831485252] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2094; }","duration":"603.318655ms","start":"2026-08-14T09:14:10.494463Z","end":"2026-08-14T09:14:11.097781Z","steps":["trace[1831485252] 'range keys from in-memory index tree' (duration: 602.612518ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:14:11.097874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:14:10.494437Z","time spent":"603.423878ms","remote":"127.0.0.1:38666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":496,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-08-14T09:14:11.098264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.151207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-14T09:14:11.099483Z","caller":"traceutil/trace.go:171","msg":"trace[645835276] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2094; }","duration":"339.011219ms","start":"2026-08-14T09:14:10.758668Z","end":"2026-08-14T09:14:11.097679Z","steps":["trace[645835276] 'range keys from in-memory index tree' (duration: 338.577688ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:14:11.099622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:14:10.758642Z","time spent":"340.957688ms","remote":"127.0.0.1:38666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":547,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-08-14T09:14:11.100171Z","caller":"traceutil/trace.go:171","msg":"trace[857376969] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2094; }","duration":"525.377342ms","start":"2026-08-14T09:14:10.573002Z","end":"2026-08-14T09:14:11.09838Z","steps":["trace[857376969] 'range keys from in-memory index tree' (duration: 524.003108ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:14:11.100336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:14:10.572979Z","time spent":"527.26892ms","remote":"127.0.0.1:38666","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-08-14T09:14:11.100288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.063858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:14:11.101345Z","caller":"traceutil/trace.go:171","msg":"trace[1319484815] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:2094; }","duration":"567.146984ms","start":"2026-08-14T09:14:10.53417Z","end":"2026-08-14T09:14:11.101317Z","steps":["trace[1319484815] 'count revisions from in-memory index tree' (duration: 562.804826ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:14:11.101388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:14:10.534161Z","time spent":"567.210526ms","remote":"127.0.0.1:47522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"info","ts":"2026-08-14T09:14:11.502318Z","caller":"traceutil/trace.go:171","msg":"trace[389875206] transaction","detail":"{read_only:false; response_revision:2096; number_of_response:1; }","duration":"397.355439ms","start":"2026-08-14T09:14:11.104928Z","end":"2026-08-14T09:14:11.502283Z","steps":["trace[389875206] 'process raft request' (duration: 397.038341ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:14:11.502328Z","caller":"traceutil/trace.go:171","msg":"trace[280177885] linearizableReadLoop","detail":"{readStateIndex:2187; appliedIndex:2183; }","duration":"136.804935ms","start":"2026-08-14T09:14:11.365492Z","end":"2026-08-14T09:14:11.502297Z","steps":["trace[280177885] 'read index received' (duration: 136.400075ms)","trace[280177885] 'applied index is now lower than readState.Index' (duration: 403.94µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:14:11.502531Z","caller":"traceutil/trace.go:171","msg":"trace[968447136] transaction","detail":"{read_only:false; response_revision:2098; number_of_response:1; }","duration":"396.698181ms","start":"2026-08-14T09:14:11.105815Z","end":"2026-08-14T09:14:11.502513Z","steps":["trace[968447136] 'process raft request' (duration: 396.389464ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:14:11.502592Z","caller":"traceutil/trace.go:171","msg":"trace[32664689] transaction","detail":"{read_only:false; response_revision:2099; number_of_response:1; }","duration":"396.190119ms","start":"2026-08-14T09:14:11.106371Z","end":"2026-08-14T09:14:11.502562Z","steps":["trace[32664689] 'process raft request' (duration: 395.884311ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:14:11.502614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:14:11.105804Z","time spent":"396.766713ms","remote":"127.0.0.1:38666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:14:11.502634Z","caller":"traceutil/trace.go:171","msg":"trace[1715656627] transaction","detail":"{read_only:false; response_revision:2097; number_of_response:1; }","duration":"397.594863ms","start":"2026-08-14T09:14:11.105013Z","end":"2026-08-14T09:14:11.502608Z","steps":["trace[1715656627] 'process raft request' (duration: 397.146212ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:14:11.50264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.109932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-08-14T09:14:11.502704Z","caller":"traceutil/trace.go:171","msg":"trace[875698538] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2099; }","duration":"137.237987ms","start":"2026-08-14T09:14:11.36545Z","end":"2026-08-14T09:14:11.502688Z","steps":["trace[875698538] 'agreement among raft nodes before linearized reading' (duration: 136.96591ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:14:11.502731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:14:11.105005Z","time spent":"397.678626ms","remote":"127.0.0.1:38666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:14:11.502755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:14:11.106346Z","time spent":"396.319951ms","remote":"127.0.0.1:38666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:14:11.502887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:14:11.104906Z","time spent":"397.494872ms","remote":"127.0.0.1:38666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:14:12.148161Z","caller":"traceutil/trace.go:171","msg":"trace[1110283020] linearizableReadLoop","detail":"{readStateIndex:2190; appliedIndex:2189; }","duration":"185.323628ms","start":"2026-08-14T09:14:11.962809Z","end":"2026-08-14T09:14:12.148132Z","steps":["trace[1110283020] 'read index received' (duration: 185.02102ms)","trace[1110283020] 'applied index is now lower than readState.Index' (duration: 301.008µs)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:14:12.14839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.544003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:14:12.14844Z","caller":"traceutil/trace.go:171","msg":"trace[2103583835] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2102; }","duration":"185.635375ms","start":"2026-08-14T09:14:11.962789Z","end":"2026-08-14T09:14:12.148425Z","steps":["trace[2103583835] 'agreement among raft nodes before linearized reading' (duration: 185.528362ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:14:12.148216Z","caller":"traceutil/trace.go:171","msg":"trace[1759505586] transaction","detail":"{read_only:false; response_revision:2102; number_of_response:1; }","duration":"192.657373ms","start":"2026-08-14T09:14:11.955517Z","end":"2026-08-14T09:14:12.148174Z","steps":["trace[1759505586] 'process raft request' (duration: 192.397256ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:14:12.302828Z","caller":"traceutil/trace.go:171","msg":"trace[804842306] transaction","detail":"{read_only:false; response_revision:2103; number_of_response:1; }","duration":"146.621122ms","start":"2026-08-14T09:14:12.156181Z","end":"2026-08-14T09:14:12.302802Z","steps":["trace[804842306] 'process raft request' (duration: 91.205976ms)","trace[804842306] 'compare' (duration: 55.272943ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:14:47.949113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.114268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/cilium-operator\" ","response":"range_response_count:1 size:4709"} {"level":"info","ts":"2026-08-14T09:14:47.949213Z","caller":"traceutil/trace.go:171","msg":"trace[254866103] range","detail":"{range_begin:/registry/deployments/kube-system/cilium-operator; range_end:; response_count:1; response_revision:2277; }","duration":"105.261831ms","start":"2026-08-14T09:14:47.843934Z","end":"2026-08-14T09:14:47.949195Z","steps":["trace[254866103] 'range keys from in-memory index tree' (duration: 104.963704ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:14:48.995396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.467466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-67659f764b-6mxf2\" ","response":"range_response_count:1 size:4908"} {"level":"info","ts":"2026-08-14T09:14:48.995481Z","caller":"traceutil/trace.go:171","msg":"trace[1681357866] range","detail":"{range_begin:/registry/pods/kube-system/coredns-67659f764b-6mxf2; range_end:; response_count:1; response_revision:2282; }","duration":"157.566899ms","start":"2026-08-14T09:14:48.837895Z","end":"2026-08-14T09:14:48.995462Z","steps":["trace[1681357866] 'range keys from in-memory index tree' (duration: 157.123747ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:14:48.99563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.134423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-hcnqz\" ","response":"range_response_count:1 size:4694"} {"level":"info","ts":"2026-08-14T09:14:48.995735Z","caller":"traceutil/trace.go:171","msg":"trace[29618586] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-hcnqz; range_end:; response_count:1; response_revision:2282; }","duration":"160.287317ms","start":"2026-08-14T09:14:48.835427Z","end":"2026-08-14T09:14:48.995714Z","steps":["trace[29618586] 'range keys from in-memory index tree' (duration: 159.960079ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:14:49.425047Z","caller":"traceutil/trace.go:171","msg":"trace[222431670] linearizableReadLoop","detail":"{readStateIndex:2380; appliedIndex:2379; }","duration":"126.69159ms","start":"2026-08-14T09:14:49.298332Z","end":"2026-08-14T09:14:49.425023Z","steps":["trace[222431670] 'read index received' (duration: 126.454555ms)","trace[222431670] 'applied index is now lower than readState.Index' (duration: 236.115µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:14:49.425143Z","caller":"traceutil/trace.go:171","msg":"trace[2079281869] transaction","detail":"{read_only:false; response_revision:2284; number_of_response:1; }","duration":"127.083932ms","start":"2026-08-14T09:14:49.298031Z","end":"2026-08-14T09:14:49.425115Z","steps":["trace[2079281869] 'process raft request' (duration: 126.843525ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:14:49.425272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.916137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-67659f764b-pwmmm\" ","response":"range_response_count:1 size:4908"} {"level":"info","ts":"2026-08-14T09:14:49.42531Z","caller":"traceutil/trace.go:171","msg":"trace[729462768] range","detail":"{range_begin:/registry/pods/kube-system/coredns-67659f764b-pwmmm; range_end:; response_count:1; response_revision:2284; }","duration":"126.986909ms","start":"2026-08-14T09:14:49.298311Z","end":"2026-08-14T09:14:49.425298Z","steps":["trace[729462768] 'agreement among raft nodes before linearized reading' (duration: 126.839356ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:14:49.42566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.705986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:116 size:89536"} {"level":"info","ts":"2026-08-14T09:14:49.42571Z","caller":"traceutil/trace.go:171","msg":"trace[1377984765] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:116; response_revision:2284; }","duration":"121.778007ms","start":"2026-08-14T09:14:49.30392Z","end":"2026-08-14T09:14:49.425698Z","steps":["trace[1377984765] 'agreement among raft nodes before linearized reading' (duration: 121.318056ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:14:51.8003Z","caller":"traceutil/trace.go:171","msg":"trace[923133377] transaction","detail":"{read_only:false; response_revision:2295; number_of_response:1; }","duration":"163.694923ms","start":"2026-08-14T09:14:51.636575Z","end":"2026-08-14T09:14:51.80027Z","steps":["trace[923133377] 'process raft request' (duration: 144.47597ms)","trace[923133377] 'compare' (duration: 18.944166ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:14:54.405479Z","caller":"traceutil/trace.go:171","msg":"trace[752743247] linearizableReadLoop","detail":"{readStateIndex:2404; appliedIndex:2403; }","duration":"235.014449ms","start":"2026-08-14T09:14:54.17044Z","end":"2026-08-14T09:14:54.405454Z","steps":["trace[752743247] 'read index received' (duration: 234.772683ms)","trace[752743247] 'applied index is now lower than readState.Index' (duration: 240.666µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:14:54.405602Z","caller":"traceutil/trace.go:171","msg":"trace[109111058] transaction","detail":"{read_only:false; response_revision:2307; number_of_response:1; }","duration":"237.04836ms","start":"2026-08-14T09:14:54.168518Z","end":"2026-08-14T09:14:54.405566Z","steps":["trace[109111058] 'process raft request' (duration: 236.786953ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:14:54.405732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.258245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/default\" ","response":"range_response_count:1 size:179"} {"level":"info","ts":"2026-08-14T09:14:54.405864Z","caller":"traceutil/trace.go:171","msg":"trace[1814950727] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/default; range_end:; response_count:1; response_revision:2307; }","duration":"235.41767ms","start":"2026-08-14T09:14:54.170427Z","end":"2026-08-14T09:14:54.405844Z","steps":["trace[1814950727] 'agreement among raft nodes before linearized reading' (duration: 235.174004ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:14:54.601316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.595456ms","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-08-14T09:14:54.60148Z","caller":"traceutil/trace.go:171","msg":"trace[474754018] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2307; }","duration":"112.78041ms","start":"2026-08-14T09:14:54.488672Z","end":"2026-08-14T09:14:54.601453Z","steps":["trace[474754018] 'range keys from in-memory index tree' (duration: 112.566905ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:14:54.601556Z","caller":"traceutil/trace.go:171","msg":"trace[843187225] linearizableReadLoop","detail":"{readStateIndex:2405; appliedIndex:2404; }","duration":"169.404596ms","start":"2026-08-14T09:14:54.43212Z","end":"2026-08-14T09:14:54.601525Z","steps":["trace[843187225] 'read index received' (duration: 105.353223ms)","trace[843187225] 'applied index is now lower than readState.Index' (duration: 64.049913ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:14:54.601702Z","caller":"traceutil/trace.go:171","msg":"trace[1182661301] transaction","detail":"{read_only:false; response_revision:2308; number_of_response:1; }","duration":"190.353165ms","start":"2026-08-14T09:14:54.411266Z","end":"2026-08-14T09:14:54.601619Z","steps":["trace[1182661301] 'process raft request' (duration: 126.22851ms)","trace[1182661301] 'compare' (duration: 63.872118ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:14:54.602009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.859267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-08-14T09:14:54.602123Z","caller":"traceutil/trace.go:171","msg":"trace[769789940] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2308; }","duration":"170.017082ms","start":"2026-08-14T09:14:54.432078Z","end":"2026-08-14T09:14:54.602095Z","steps":["trace[769789940] 'agreement among raft nodes before linearized reading' (duration: 169.657782ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:14:54.761074Z","caller":"traceutil/trace.go:171","msg":"trace[1805702192] linearizableReadLoop","detail":"{readStateIndex:2407; appliedIndex:2406; }","duration":"120.149896ms","start":"2026-08-14T09:14:54.640899Z","end":"2026-08-14T09:14:54.761049Z","steps":["trace[1805702192] 'read index received' (duration: 83.75892ms)","trace[1805702192] 'applied index is now lower than readState.Index' (duration: 36.389956ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:14:54.761251Z","caller":"traceutil/trace.go:171","msg":"trace[89830272] transaction","detail":"{read_only:false; response_revision:2310; number_of_response:1; }","duration":"153.813964ms","start":"2026-08-14T09:14:54.607412Z","end":"2026-08-14T09:14:54.761226Z","steps":["trace[89830272] 'process raft request' (duration: 117.205032ms)","trace[89830272] 'compare' (duration: 36.295034ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:14:54.761731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.433628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" limit:500 ","response":"range_response_count:6 size:363682"} {"level":"info","ts":"2026-08-14T09:14:54.761803Z","caller":"traceutil/trace.go:171","msg":"trace[369304667] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:6; response_revision:2310; }","duration":"117.578732ms","start":"2026-08-14T09:14:54.644212Z","end":"2026-08-14T09:14:54.761791Z","steps":["trace[369304667] 'agreement among raft nodes before linearized reading' (duration: 117.000286ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:14:54.762177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.578348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" limit:500 ","response":"range_response_count:6 size:363682"} {"level":"info","ts":"2026-08-14T09:14:54.762302Z","caller":"traceutil/trace.go:171","msg":"trace[138437176] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:6; response_revision:2310; }","duration":"153.762493ms","start":"2026-08-14T09:14:54.60851Z","end":"2026-08-14T09:14:54.762273Z","steps":["trace[138437176] 'agreement among raft nodes before linearized reading' (duration: 152.692675ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:14:56.017941Z","caller":"traceutil/trace.go:171","msg":"trace[1448094828] transaction","detail":"{read_only:false; response_revision:2313; number_of_response:1; }","duration":"103.141868ms","start":"2026-08-14T09:14:55.914772Z","end":"2026-08-14T09:14:56.017914Z","steps":["trace[1448094828] 'process raft request' (duration: 102.327338ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:14:56.237142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.93528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:116 size:89536"} {"level":"info","ts":"2026-08-14T09:14:56.237233Z","caller":"traceutil/trace.go:171","msg":"trace[296818780] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:116; response_revision:2314; }","duration":"116.053083ms","start":"2026-08-14T09:14:56.12116Z","end":"2026-08-14T09:14:56.237213Z","steps":["trace[296818780] 'range keys from in-memory index tree' (duration: 115.329925ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:14:56.570742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.535868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/persistent-volume-binder\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-08-14T09:14:56.570825Z","caller":"traceutil/trace.go:171","msg":"trace[1822454600] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/persistent-volume-binder; range_end:; response_count:1; response_revision:2315; }","duration":"132.645931ms","start":"2026-08-14T09:14:56.438161Z","end":"2026-08-14T09:14:56.570807Z","steps":["trace[1822454600] 'range keys from in-memory index tree' (duration: 132.473736ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:14:56.571066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.664316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/kube-system/ceph-csi-rbd-provisioner-http-metrics\" ","response":"range_response_count:1 size:1195"} {"level":"info","ts":"2026-08-14T09:14:56.571098Z","caller":"traceutil/trace.go:171","msg":"trace[539662263] range","detail":"{range_begin:/registry/services/specs/kube-system/ceph-csi-rbd-provisioner-http-metrics; range_end:; response_count:1; response_revision:2315; }","duration":"131.714388ms","start":"2026-08-14T09:14:56.439374Z","end":"2026-08-14T09:14:56.571088Z","steps":["trace[539662263] 'range keys from in-memory index tree' (duration: 131.563584ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:14:56.89143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.564686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/kube-system/ceph-csi-rbd-provisioner-http-metrics\" ","response":"range_response_count:1 size:1195"} {"level":"info","ts":"2026-08-14T09:14:56.891519Z","caller":"traceutil/trace.go:171","msg":"trace[705548303] range","detail":"{range_begin:/registry/services/specs/kube-system/ceph-csi-rbd-provisioner-http-metrics; range_end:; response_count:1; response_revision:2317; }","duration":"220.688179ms","start":"2026-08-14T09:14:56.670811Z","end":"2026-08-14T09:14:56.891499Z","steps":["trace[705548303] 'range keys from in-memory index tree' (duration: 220.388461ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:14:56.891746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.380518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-08-14T09:14:56.8918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.679764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/pod-garbage-collector\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-08-14T09:14:56.891858Z","caller":"traceutil/trace.go:171","msg":"trace[1800098021] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2317; }","duration":"125.566613ms","start":"2026-08-14T09:14:56.76627Z","end":"2026-08-14T09:14:56.891837Z","steps":["trace[1800098021] 'range keys from in-memory index tree' (duration: 125.168303ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:14:56.891918Z","caller":"traceutil/trace.go:171","msg":"trace[1672084500] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/pod-garbage-collector; range_end:; response_count:1; response_revision:2317; }","duration":"196.841197ms","start":"2026-08-14T09:14:56.695046Z","end":"2026-08-14T09:14:56.891888Z","steps":["trace[1672084500] 'range keys from in-memory index tree' (duration: 196.590411ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:14:58.112605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.033945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/statefulset-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-08-14T09:14:58.112716Z","caller":"traceutil/trace.go:171","msg":"trace[678605360] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/statefulset-controller; range_end:; response_count:1; response_revision:2322; }","duration":"103.214279ms","start":"2026-08-14T09:14:58.00948Z","end":"2026-08-14T09:14:58.112695Z","steps":["trace[678605360] 'range keys from in-memory index tree' (duration: 102.932222ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:14:58.112621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.333017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-controller\" ","response":"range_response_count:1 size:201"} {"level":"info","ts":"2026-08-14T09:14:58.112915Z","caller":"traceutil/trace.go:171","msg":"trace[664447281] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-controller; range_end:; response_count:1; response_revision:2322; }","duration":"100.720787ms","start":"2026-08-14T09:14:58.01215Z","end":"2026-08-14T09:14:58.112871Z","steps":["trace[664447281] 'range keys from in-memory index tree' (duration: 100.244485ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:14:58.29442Z","caller":"traceutil/trace.go:171","msg":"trace[1840392095] linearizableReadLoop","detail":"{readStateIndex:2421; appliedIndex:2419; }","duration":"173.644403ms","start":"2026-08-14T09:14:58.120752Z","end":"2026-08-14T09:14:58.294396Z","steps":["trace[1840392095] 'read index received' (duration: 43.827203ms)","trace[1840392095] 'applied index is now lower than readState.Index' (duration: 129.81607ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:14:58.294535Z","caller":"traceutil/trace.go:171","msg":"trace[1373683616] transaction","detail":"{read_only:false; response_revision:2324; number_of_response:1; }","duration":"176.236179ms","start":"2026-08-14T09:14:58.118265Z","end":"2026-08-14T09:14:58.294501Z","steps":["trace[1373683616] 'process raft request' (duration: 168.276908ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:14:58.294782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.004243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" limit:500 ","response":"range_response_count:6 size:363682"} {"level":"info","ts":"2026-08-14T09:14:58.294853Z","caller":"traceutil/trace.go:171","msg":"trace[1925363767] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:6; response_revision:2324; }","duration":"174.107995ms","start":"2026-08-14T09:14:58.120729Z","end":"2026-08-14T09:14:58.294837Z","steps":["trace[1925363767] 'agreement among raft nodes before linearized reading' (duration: 173.783937ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:14:58.294929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.857857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/statefulset-controller\" ","response":"range_response_count:1 size:209"} {"level":"warn","ts":"2026-08-14T09:14:58.294925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.627504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-14T09:14:58.295041Z","caller":"traceutil/trace.go:171","msg":"trace[588604427] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:2324; }","duration":"146.819408ms","start":"2026-08-14T09:14:58.148198Z","end":"2026-08-14T09:14:58.295018Z","steps":["trace[588604427] 'agreement among raft nodes before linearized reading' (duration: 146.589483ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:14:58.294981Z","caller":"traceutil/trace.go:171","msg":"trace[2023939786] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/statefulset-controller; range_end:; response_count:1; response_revision:2324; }","duration":"107.952249ms","start":"2026-08-14T09:14:58.187011Z","end":"2026-08-14T09:14:58.294964Z","steps":["trace[2023939786] 'agreement among raft nodes before linearized reading' (duration: 107.837976ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:14:59.18798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.654342ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-14T09:14:59.188116Z","caller":"traceutil/trace.go:171","msg":"trace[374147185] transaction","detail":"{read_only:false; response_revision:2329; number_of_response:1; }","duration":"198.10803ms","start":"2026-08-14T09:14:58.989987Z","end":"2026-08-14T09:14:59.188095Z","steps":["trace[374147185] 'process raft request' (duration: 61.226783ms)","trace[374147185] 'compare' (duration: 136.535078ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:14:59.821035Z","caller":"traceutil/trace.go:171","msg":"trace[1768758634] linearizableReadLoop","detail":"{readStateIndex:2428; appliedIndex:2427; }","duration":"150.316386ms","start":"2026-08-14T09:14:59.670687Z","end":"2026-08-14T09:14:59.821003Z","steps":["trace[1768758634] 'read index received' (duration: 150.124831ms)","trace[1768758634] 'applied index is now lower than readState.Index' (duration: 189.705µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:14:59.821144Z","caller":"traceutil/trace.go:171","msg":"trace[578612375] transaction","detail":"{read_only:false; response_revision:2330; number_of_response:1; }","duration":"170.614467ms","start":"2026-08-14T09:14:59.650501Z","end":"2026-08-14T09:14:59.821115Z","steps":["trace[578612375] 'process raft request' (duration: 170.300998ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:14:59.821357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.637244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-14T09:14:59.821416Z","caller":"traceutil/trace.go:171","msg":"trace[661189267] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2330; }","duration":"150.743457ms","start":"2026-08-14T09:14:59.670661Z","end":"2026-08-14T09:14:59.821404Z","steps":["trace[661189267] 'agreement among raft nodes before linearized reading' (duration: 150.46636ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:15:00.062814Z","caller":"traceutil/trace.go:171","msg":"trace[18419690] linearizableReadLoop","detail":"{readStateIndex:2429; appliedIndex:2428; }","duration":"137.611936ms","start":"2026-08-14T09:14:59.925173Z","end":"2026-08-14T09:15:00.062785Z","steps":["trace[18419690] 'read index received' (duration: 55.070636ms)","trace[18419690] 'applied index is now lower than readState.Index' (duration: 82.54022ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:15:00.062892Z","caller":"traceutil/trace.go:171","msg":"trace[795000419] transaction","detail":"{read_only:false; response_revision:2331; number_of_response:1; }","duration":"237.085151ms","start":"2026-08-14T09:14:59.825771Z","end":"2026-08-14T09:15:00.062856Z","steps":["trace[795000419] 'process raft request' (duration: 154.527311ms)","trace[795000419] 'compare' (duration: 82.353265ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:15:00.06301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.809321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-14T09:15:00.063053Z","caller":"traceutil/trace.go:171","msg":"trace[642469118] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2331; }","duration":"137.908853ms","start":"2026-08-14T09:14:59.925132Z","end":"2026-08-14T09:15:00.063041Z","steps":["trace[642469118] 'agreement among raft nodes before linearized reading' (duration: 137.749849ms)"],"step_count":1}