{"level":"warn","ts":"2026-09-08T22:50:54.563463Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-08T22:50:54.563669Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.44:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.44:2380","--initial-cluster=instance=https://162.253.55.44:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.44:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.44:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-09-08T22:50:54.563816Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-08T22:50:54.563861Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.44:2380"]} {"level":"info","ts":"2026-09-08T22:50:54.563913Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-08T22:50:54.565489Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.44:2379"]} {"level":"info","ts":"2026-09-08T22:50:54.565689Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.44:2380"],"listen-peer-urls":["https://162.253.55.44:2380"],"advertise-client-urls":["https://162.253.55.44:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.44:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.44:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-09-08T22:50:54.635847Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"69.790947ms"} {"level":"info","ts":"2026-09-08T22:50:54.748895Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b4cb6cb294c6743","cluster-id":"77f9414eebec887e"} {"level":"info","ts":"2026-09-08T22:50:54.749035Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4cb6cb294c6743 switched to configuration voters=()"} {"level":"info","ts":"2026-09-08T22:50:54.749074Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4cb6cb294c6743 became follower at term 0"} {"level":"info","ts":"2026-09-08T22:50:54.749089Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b4cb6cb294c6743 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-08T22:50:54.749126Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4cb6cb294c6743 became follower at term 1"} {"level":"info","ts":"2026-09-08T22:50:54.74918Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4cb6cb294c6743 switched to configuration voters=(814226616334706499)"} {"level":"warn","ts":"2026-09-08T22:50:54.765869Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-08T22:50:54.769309Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-08T22:50:54.772987Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-09-08T22:50:54.778029Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b4cb6cb294c6743","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-08T22:50:54.778477Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b4cb6cb294c6743","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-08T22:50:54.778735Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-08T22:50:54.780218Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-08T22:50:54.780346Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-08T22:50:54.782213Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4cb6cb294c6743 switched to configuration voters=(814226616334706499)"} {"level":"info","ts":"2026-09-08T22:50:54.782426Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"77f9414eebec887e","local-member-id":"b4cb6cb294c6743","added-peer-id":"b4cb6cb294c6743","added-peer-peer-urls":["https://162.253.55.44:2380"]} {"level":"info","ts":"2026-09-08T22:50:54.786237Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-08T22:50:54.786454Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.44:2380"} {"level":"info","ts":"2026-09-08T22:50:54.786532Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.44:2380"} {"level":"info","ts":"2026-09-08T22:50:54.786904Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b4cb6cb294c6743","initial-advertise-peer-urls":["https://162.253.55.44:2380"],"listen-peer-urls":["https://162.253.55.44:2380"],"advertise-client-urls":["https://162.253.55.44:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.44:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-08T22:50:54.787007Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-08T22:50:55.650173Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4cb6cb294c6743 is starting a new election at term 1"} {"level":"info","ts":"2026-09-08T22:50:55.650251Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4cb6cb294c6743 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-08T22:50:55.650294Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4cb6cb294c6743 received MsgPreVoteResp from b4cb6cb294c6743 at term 1"} {"level":"info","ts":"2026-09-08T22:50:55.65032Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4cb6cb294c6743 became candidate at term 2"} {"level":"info","ts":"2026-09-08T22:50:55.650335Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4cb6cb294c6743 received MsgVoteResp from b4cb6cb294c6743 at term 2"} {"level":"info","ts":"2026-09-08T22:50:55.650355Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4cb6cb294c6743 became leader at term 2"} {"level":"info","ts":"2026-09-08T22:50:55.650379Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b4cb6cb294c6743 elected leader b4cb6cb294c6743 at term 2"} {"level":"info","ts":"2026-09-08T22:50:55.651592Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-08T22:50:55.652792Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b4cb6cb294c6743","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.44:2379]}","request-path":"/0/members/b4cb6cb294c6743/attributes","cluster-id":"77f9414eebec887e","publish-timeout":"7s"} {"level":"info","ts":"2026-09-08T22:50:55.652799Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-08T22:50:55.652961Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-08T22:50:55.653571Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"77f9414eebec887e","local-member-id":"b4cb6cb294c6743","cluster-version":"3.5"} {"level":"info","ts":"2026-09-08T22:50:55.653694Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-08T22:50:55.653727Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-08T22:50:55.655463Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-08T22:50:55.655515Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-08T22:50:55.655715Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-08T22:50:55.65652Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.44:2379"} {"level":"info","ts":"2026-09-08T22:51:37.02093Z","caller":"traceutil/trace.go:171","msg":"trace[992878871] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"146.950533ms","start":"2026-09-08T22:51:36.873952Z","end":"2026-09-08T22:51:37.020903Z","steps":["trace[992878871] 'process raft request' (duration: 146.80218ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:51:37.041968Z","caller":"traceutil/trace.go:171","msg":"trace[595123502] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"140.632425ms","start":"2026-09-08T22:51:36.901203Z","end":"2026-09-08T22:51:37.041836Z","steps":["trace[595123502] 'process raft request' (duration: 140.231816ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:51:37.042104Z","caller":"traceutil/trace.go:171","msg":"trace[1196095334] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"102.845272ms","start":"2026-09-08T22:51:36.939225Z","end":"2026-09-08T22:51:37.04207Z","steps":["trace[1196095334] 'process raft request' (duration: 102.741829ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:51:37.042337Z","caller":"traceutil/trace.go:171","msg":"trace[238565518] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"141.27707ms","start":"2026-09-08T22:51:36.901046Z","end":"2026-09-08T22:51:37.042323Z","steps":["trace[238565518] 'process raft request' (duration: 140.303498ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:51:37.042467Z","caller":"traceutil/trace.go:171","msg":"trace[1535583756] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"136.53882ms","start":"2026-09-08T22:51:36.905913Z","end":"2026-09-08T22:51:37.042452Z","steps":["trace[1535583756] 'process raft request' (duration: 135.591558ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:51:37.042487Z","caller":"traceutil/trace.go:171","msg":"trace[1848006865] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"141.343632ms","start":"2026-09-08T22:51:36.901128Z","end":"2026-09-08T22:51:37.042472Z","steps":["trace[1848006865] 'process raft request' (duration: 140.261557ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:51:37.042535Z","caller":"traceutil/trace.go:171","msg":"trace[1272988647] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"168.324866ms","start":"2026-09-08T22:51:36.874192Z","end":"2026-09-08T22:51:37.042517Z","steps":["trace[1272988647] 'process raft request' (duration: 166.994765ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:51:37.042606Z","caller":"traceutil/trace.go:171","msg":"trace[2116068580] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"121.097435ms","start":"2026-09-08T22:51:36.921494Z","end":"2026-09-08T22:51:37.042591Z","steps":["trace[2116068580] 'process raft request' (duration: 120.299395ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:51:37.042619Z","caller":"traceutil/trace.go:171","msg":"trace[1050498251] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"133.981002ms","start":"2026-09-08T22:51:36.908626Z","end":"2026-09-08T22:51:37.042607Z","steps":["trace[1050498251] 'process raft request' (duration: 132.975298ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:51:37.042644Z","caller":"traceutil/trace.go:171","msg":"trace[472943131] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"161.308665ms","start":"2026-09-08T22:51:36.881328Z","end":"2026-09-08T22:51:37.042636Z","steps":["trace[472943131] 'process raft request' (duration: 159.980163ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:51:37.042718Z","caller":"traceutil/trace.go:171","msg":"trace[2060315141] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"132.702053ms","start":"2026-09-08T22:51:36.910006Z","end":"2026-09-08T22:51:37.042708Z","steps":["trace[2060315141] 'process raft request' (duration: 131.668878ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:51:43.394335Z","caller":"traceutil/trace.go:171","msg":"trace[717016939] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:648; }","duration":"164.662959ms","start":"2026-09-08T22:51:43.229608Z","end":"2026-09-08T22:51:43.394271Z","steps":["trace[717016939] 'read index received' (duration: 157.320839ms)","trace[717016939] 'applied index is now lower than readState.Index' (duration: 7.34117ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:51:43.394914Z","caller":"traceutil/trace.go:171","msg":"trace[1540383538] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"276.219657ms","start":"2026-09-08T22:51:43.11868Z","end":"2026-09-08T22:51:43.394899Z","steps":["trace[1540383538] 'process raft request' (duration: 274.001119ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:51:43.395538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.88817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-gdh46\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-09-08T22:51:43.395718Z","caller":"traceutil/trace.go:171","msg":"trace[494531483] range","detail":"{range_begin:/registry/pods/kube-system/cilium-gdh46; range_end:; response_count:1; response_revision:627; }","duration":"166.134206ms","start":"2026-09-08T22:51:43.229548Z","end":"2026-09-08T22:51:43.395683Z","steps":["trace[494531483] 'agreement among raft nodes before linearized reading' (duration: 165.804997ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:51:43.672747Z","caller":"traceutil/trace.go:171","msg":"trace[385082751] linearizableReadLoop","detail":"{readStateIndex:650; appliedIndex:649; }","duration":"182.081777ms","start":"2026-09-08T22:51:43.490599Z","end":"2026-09-08T22:51:43.672681Z","steps":["trace[385082751] 'read index received' (duration: 177.840968ms)","trace[385082751] 'applied index is now lower than readState.Index' (duration: 4.238719ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:51:43.672955Z","caller":"traceutil/trace.go:171","msg":"trace[1624017270] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"218.884788ms","start":"2026-09-08T22:51:43.454041Z","end":"2026-09-08T22:51:43.672925Z","steps":["trace[1624017270] 'process raft request' (duration: 214.457594ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:51:43.673031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.379486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T22:51:43.67323Z","caller":"traceutil/trace.go:171","msg":"trace[1544310312] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:628; }","duration":"182.655433ms","start":"2026-09-08T22:51:43.490551Z","end":"2026-09-08T22:51:43.673206Z","steps":["trace[1544310312] 'agreement among raft nodes before linearized reading' (duration: 182.305374ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:51:44.35287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.391525ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T22:51:44.352992Z","caller":"traceutil/trace.go:171","msg":"trace[273960950] linearizableReadLoop","detail":"{readStateIndex:651; appliedIndex:650; }","duration":"543.878558ms","start":"2026-09-08T22:51:43.80909Z","end":"2026-09-08T22:51:44.352969Z","steps":["trace[273960950] 'read index received' (duration: 51.5916ms)","trace[273960950] 'applied index is now lower than readState.Index' (duration: 492.284158ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:51:44.353048Z","caller":"traceutil/trace.go:171","msg":"trace[923818855] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"631.95105ms","start":"2026-09-08T22:51:43.721067Z","end":"2026-09-08T22:51:44.353018Z","steps":["trace[923818855] 'process raft request' (duration: 139.576831ms)","trace[923818855] 'compare' (duration: 491.173448ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T22:51:44.353218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:51:43.721032Z","time spent":"632.084674ms","remote":"127.0.0.1:45130","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-09-08T22:51:44.353422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.325299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-08T22:51:44.353937Z","caller":"traceutil/trace.go:171","msg":"trace[848125899] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:629; }","duration":"544.655788ms","start":"2026-09-08T22:51:43.809037Z","end":"2026-09-08T22:51:44.353693Z","steps":["trace[848125899] 'agreement among raft nodes before linearized reading' (duration: 544.016161ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:51:44.354259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:51:43.809014Z","time spent":"545.051858ms","remote":"127.0.0.1:45130","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-09-08T22:51:44.355705Z","caller":"traceutil/trace.go:171","msg":"trace[598071093] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"264.53216ms","start":"2026-09-08T22:51:44.091154Z","end":"2026-09-08T22:51:44.355687Z","steps":["trace[598071093] 'process raft request' (duration: 263.423651ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:51:44.383827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.642648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-08T22:51:44.383908Z","caller":"traceutil/trace.go:171","msg":"trace[113432084] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:631; }","duration":"461.794692ms","start":"2026-09-08T22:51:43.922096Z","end":"2026-09-08T22:51:44.38389Z","steps":["trace[113432084] 'agreement among raft nodes before linearized reading' (duration: 461.572036ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:51:44.383958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:51:43.922071Z","time spent":"461.878174ms","remote":"127.0.0.1:45130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-08T22:51:51.073123Z","caller":"traceutil/trace.go:171","msg":"trace[1301875405] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"119.194076ms","start":"2026-09-08T22:51:50.953872Z","end":"2026-09-08T22:51:51.073066Z","steps":["trace[1301875405] 'process raft request' (duration: 118.547998ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:51:51.434054Z","caller":"traceutil/trace.go:171","msg":"trace[1167863678] linearizableReadLoop","detail":"{readStateIndex:761; appliedIndex:760; }","duration":"109.235507ms","start":"2026-09-08T22:51:51.324793Z","end":"2026-09-08T22:51:51.434029Z","steps":["trace[1167863678] 'read index received' (duration: 109.083952ms)","trace[1167863678] 'applied index is now lower than readState.Index' (duration: 150.215µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:51:51.434135Z","caller":"traceutil/trace.go:171","msg":"trace[1134402707] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"114.024563ms","start":"2026-09-08T22:51:51.320069Z","end":"2026-09-08T22:51:51.434094Z","steps":["trace[1134402707] 'process raft request' (duration: 113.746516ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:51:51.434202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.390491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T22:51:51.434251Z","caller":"traceutil/trace.go:171","msg":"trace[342566203] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:737; }","duration":"109.474573ms","start":"2026-09-08T22:51:51.324752Z","end":"2026-09-08T22:51:51.434227Z","steps":["trace[342566203] 'agreement among raft nodes before linearized reading' (duration: 109.404531ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:51:51.460316Z","caller":"traceutil/trace.go:171","msg":"trace[2108119816] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"114.810596ms","start":"2026-09-08T22:51:51.345435Z","end":"2026-09-08T22:51:51.460245Z","steps":["trace[2108119816] 'process raft request' (duration: 114.640261ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:51:51.460473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.380388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-09-08T22:51:51.46056Z","caller":"traceutil/trace.go:171","msg":"trace[1548099287] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:738; }","duration":"124.488851ms","start":"2026-09-08T22:51:51.33605Z","end":"2026-09-08T22:51:51.460538Z","steps":["trace[1548099287] 'agreement among raft nodes before linearized reading' (duration: 124.192093ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:51:51.854659Z","caller":"traceutil/trace.go:171","msg":"trace[337058310] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"156.772293ms","start":"2026-09-08T22:51:51.69786Z","end":"2026-09-08T22:51:51.854633Z","steps":["trace[337058310] 'process raft request' (duration: 156.619839ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:51:51.968264Z","caller":"traceutil/trace.go:171","msg":"trace[602179713] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"268.776168ms","start":"2026-09-08T22:51:51.699462Z","end":"2026-09-08T22:51:51.968239Z","steps":["trace[602179713] 'process raft request' (duration: 224.480705ms)","trace[602179713] 'compare' (duration: 44.090267ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:51:51.968507Z","caller":"traceutil/trace.go:171","msg":"trace[938146220] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"265.122105ms","start":"2026-09-08T22:51:51.703374Z","end":"2026-09-08T22:51:51.968497Z","steps":["trace[938146220] 'process raft request' (duration: 264.813536ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:51:52.123635Z","caller":"traceutil/trace.go:171","msg":"trace[2066169995] linearizableReadLoop","detail":"{readStateIndex:766; appliedIndex:765; }","duration":"136.426372ms","start":"2026-09-08T22:51:51.987182Z","end":"2026-09-08T22:51:52.123609Z","steps":["trace[2066169995] 'read index received' (duration: 116.992321ms)","trace[2066169995] 'applied index is now lower than readState.Index' (duration: 19.433121ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:51:52.123833Z","caller":"traceutil/trace.go:171","msg":"trace[1656455520] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"144.237364ms","start":"2026-09-08T22:51:51.979561Z","end":"2026-09-08T22:51:52.123799Z","steps":["trace[1656455520] 'process raft request' (duration: 124.69531ms)","trace[1656455520] 'compare' (duration: 19.197254ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T22:51:52.12392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.623078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-08T22:51:52.125598Z","caller":"traceutil/trace.go:171","msg":"trace[2056400000] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:742; }","duration":"138.330177ms","start":"2026-09-08T22:51:51.987145Z","end":"2026-09-08T22:51:52.125475Z","steps":["trace[2056400000] 'agreement among raft nodes before linearized reading' (duration: 136.529755ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:51:52.595866Z","caller":"traceutil/trace.go:171","msg":"trace[1802693555] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"119.996221ms","start":"2026-09-08T22:51:52.475814Z","end":"2026-09-08T22:51:52.595811Z","steps":["trace[1802693555] 'process raft request' (duration: 44.897916ms)","trace[1802693555] 'compare' (duration: 74.780986ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:51:52.65655Z","caller":"traceutil/trace.go:171","msg":"trace[297936694] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"180.429125ms","start":"2026-09-08T22:51:52.476087Z","end":"2026-09-08T22:51:52.656516Z","steps":["trace[297936694] 'process raft request' (duration: 180.183678ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:51:52.656807Z","caller":"traceutil/trace.go:171","msg":"trace[769141759] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"180.61003ms","start":"2026-09-08T22:51:52.476132Z","end":"2026-09-08T22:51:52.656742Z","steps":["trace[769141759] 'process raft request' (duration: 180.333762ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:51:53.484312Z","caller":"traceutil/trace.go:171","msg":"trace[825734306] transaction","detail":"{read_only:false; number_of_response:0; response_revision:748; }","duration":"209.719616ms","start":"2026-09-08T22:51:53.274535Z","end":"2026-09-08T22:51:53.484255Z","steps":["trace[825734306] 'process raft request' (duration: 207.457999ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:51:53.508201Z","caller":"traceutil/trace.go:171","msg":"trace[1128479620] linearizableReadLoop","detail":"{readStateIndex:774; appliedIndex:772; }","duration":"185.267563ms","start":"2026-09-08T22:51:53.322909Z","end":"2026-09-08T22:51:53.508177Z","steps":["trace[1128479620] 'read index received' (duration: 159.09747ms)","trace[1128479620] 'applied index is now lower than readState.Index' (duration: 26.166543ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T22:51:53.508394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.466408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T22:51:53.5084Z","caller":"traceutil/trace.go:171","msg":"trace[622042621] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"205.891464ms","start":"2026-09-08T22:51:53.302431Z","end":"2026-09-08T22:51:53.508322Z","steps":["trace[622042621] 'process raft request' (duration: 205.607496ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:51:53.50847Z","caller":"traceutil/trace.go:171","msg":"trace[897551305] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:749; }","duration":"185.626503ms","start":"2026-09-08T22:51:53.322827Z","end":"2026-09-08T22:51:53.508454Z","steps":["trace[897551305] 'agreement among raft nodes before linearized reading' (duration: 185.484669ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:51:53.508586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.824067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/cilium-gdh46.18d37a446f766fdd\" ","response":"range_response_count:1 size:795"} {"level":"info","ts":"2026-09-08T22:51:53.508658Z","caller":"traceutil/trace.go:171","msg":"trace[25364279] range","detail":"{range_begin:/registry/events/kube-system/cilium-gdh46.18d37a446f766fdd; range_end:; response_count:1; response_revision:749; }","duration":"163.954331ms","start":"2026-09-08T22:51:53.344692Z","end":"2026-09-08T22:51:53.508647Z","steps":["trace[25364279] 'agreement among raft nodes before linearized reading' (duration: 163.692714ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:51:53.697392Z","caller":"traceutil/trace.go:171","msg":"trace[286876881] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"180.644208ms","start":"2026-09-08T22:51:53.516713Z","end":"2026-09-08T22:51:53.697357Z","steps":["trace[286876881] 'process raft request' (duration: 162.000614ms)","trace[286876881] 'compare' (duration: 18.432818ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:51:54.783365Z","caller":"traceutil/trace.go:171","msg":"trace[1219642785] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"118.036498ms","start":"2026-09-08T22:51:54.665298Z","end":"2026-09-08T22:51:54.783334Z","steps":["trace[1219642785] 'process raft request' (duration: 36.089434ms)","trace[1219642785] 'compare' (duration: 81.748819ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:51:55.4971Z","caller":"traceutil/trace.go:171","msg":"trace[1407688690] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"131.640559ms","start":"2026-09-08T22:51:55.365431Z","end":"2026-09-08T22:51:55.497072Z","steps":["trace[1407688690] 'process raft request' (duration: 131.445504ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:51:55.6555Z","caller":"traceutil/trace.go:171","msg":"trace[1839720516] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"135.890727ms","start":"2026-09-08T22:51:55.51958Z","end":"2026-09-08T22:51:55.65547Z","steps":["trace[1839720516] 'process raft request' (duration: 134.434113ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:51:55.792121Z","caller":"traceutil/trace.go:171","msg":"trace[1216621761] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"120.088895ms","start":"2026-09-08T22:51:55.672004Z","end":"2026-09-08T22:51:55.792093Z","steps":["trace[1216621761] 'process raft request' (duration: 94.416911ms)","trace[1216621761] 'compare' (duration: 25.53739ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:51:55.851464Z","caller":"traceutil/trace.go:171","msg":"trace[851678966] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"165.260491ms","start":"2026-09-08T22:51:55.686177Z","end":"2026-09-08T22:51:55.851437Z","steps":["trace[851678966] 'process raft request' (duration: 165.021354ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:51:55.851712Z","caller":"traceutil/trace.go:171","msg":"trace[147737326] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"163.571361ms","start":"2026-09-08T22:51:55.688113Z","end":"2026-09-08T22:51:55.851684Z","steps":["trace[147737326] 'process raft request' (duration: 163.271442ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:51:55.852008Z","caller":"traceutil/trace.go:171","msg":"trace[269038840] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"162.442986ms","start":"2026-09-08T22:51:55.689555Z","end":"2026-09-08T22:51:55.851998Z","steps":["trace[269038840] 'process raft request' (duration: 162.105436ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:51:55.981387Z","caller":"traceutil/trace.go:171","msg":"trace[258292483] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"115.799817ms","start":"2026-09-08T22:51:55.865548Z","end":"2026-09-08T22:51:55.981348Z","steps":["trace[258292483] 'process raft request' (duration: 76.441776ms)","trace[258292483] 'compare' (duration: 39.183666ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:51:55.993469Z","caller":"traceutil/trace.go:171","msg":"trace[966113504] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"124.181597ms","start":"2026-09-08T22:51:55.869264Z","end":"2026-09-08T22:51:55.993446Z","steps":["trace[966113504] 'process raft request' (duration: 124.025463ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:51:58.946014Z","caller":"traceutil/trace.go:171","msg":"trace[767174563] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"181.327738ms","start":"2026-09-08T22:51:58.76466Z","end":"2026-09-08T22:51:58.945988Z","steps":["trace[767174563] 'process raft request' (duration: 109.196978ms)","trace[767174563] 'compare' (duration: 71.881873ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:51:58.94622Z","caller":"traceutil/trace.go:171","msg":"trace[1238487490] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"181.317548ms","start":"2026-09-08T22:51:58.764874Z","end":"2026-09-08T22:51:58.946191Z","steps":["trace[1238487490] 'process raft request' (duration: 181.06987ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:51:58.946486Z","caller":"traceutil/trace.go:171","msg":"trace[345793993] linearizableReadLoop","detail":"{readStateIndex:809; appliedIndex:806; }","duration":"123.936379ms","start":"2026-09-08T22:51:58.822532Z","end":"2026-09-08T22:51:58.946469Z","steps":["trace[345793993] 'read index received' (duration: 51.334354ms)","trace[345793993] 'applied index is now lower than readState.Index' (duration: 72.600525ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T22:51:58.946663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.117636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-08T22:51:58.946704Z","caller":"traceutil/trace.go:171","msg":"trace[623918101] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:782; }","duration":"124.198918ms","start":"2026-09-08T22:51:58.822493Z","end":"2026-09-08T22:51:58.946692Z","steps":["trace[623918101] 'agreement among raft nodes before linearized reading' (duration: 124.037962ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:51:59.307189Z","caller":"traceutil/trace.go:171","msg":"trace[1070463598] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"118.861028ms","start":"2026-09-08T22:51:59.188303Z","end":"2026-09-08T22:51:59.307164Z","steps":["trace[1070463598] 'process raft request' (duration: 118.680712ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:52:00.502089Z","caller":"traceutil/trace.go:171","msg":"trace[269554737] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"169.569735ms","start":"2026-09-08T22:52:00.332497Z","end":"2026-09-08T22:52:00.502067Z","steps":["trace[269554737] 'process raft request' (duration: 169.397519ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:01.330811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.52345ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T22:52:01.330996Z","caller":"traceutil/trace.go:171","msg":"trace[337969959] linearizableReadLoop","detail":"{readStateIndex:818; appliedIndex:816; }","duration":"320.904468ms","start":"2026-09-08T22:52:01.009995Z","end":"2026-09-08T22:52:01.330899Z","steps":["trace[337969959] 'read index received' (duration: 70.054046ms)","trace[337969959] 'applied index is now lower than readState.Index' (duration: 250.848532ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:52:01.331141Z","caller":"traceutil/trace.go:171","msg":"trace[1623238578] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"326.557916ms","start":"2026-09-08T22:52:01.004551Z","end":"2026-09-08T22:52:01.331109Z","steps":["trace[1623238578] 'process raft request' (duration: 129.647814ms)","trace[1623238578] 'compare' (duration: 196.430257ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T22:52:01.331261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:52:01.004512Z","time spent":"326.67578ms","remote":"127.0.0.1:45130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T22:52:01.331342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.338636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-08T22:52:01.331505Z","caller":"traceutil/trace.go:171","msg":"trace[1812525973] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:790; }","duration":"257.597493ms","start":"2026-09-08T22:52:01.073892Z","end":"2026-09-08T22:52:01.331489Z","steps":["trace[1812525973] 'agreement among raft nodes before linearized reading' (duration: 257.342535ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:01.331506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.507107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-09-08T22:52:01.33138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.688527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-08T22:52:01.33158Z","caller":"traceutil/trace.go:171","msg":"trace[1351563492] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:790; }","duration":"321.648192ms","start":"2026-09-08T22:52:01.009913Z","end":"2026-09-08T22:52:01.331562Z","steps":["trace[1351563492] 'agreement among raft nodes before linearized reading' (duration: 321.477016ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:01.331617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:52:01.009886Z","time spent":"321.723433ms","remote":"127.0.0.1:45130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-08T22:52:01.331616Z","caller":"traceutil/trace.go:171","msg":"trace[229732702] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:790; }","duration":"244.948886ms","start":"2026-09-08T22:52:01.086654Z","end":"2026-09-08T22:52:01.331603Z","steps":["trace[229732702] 'agreement among raft nodes before linearized reading' (duration: 244.636726ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:52:01.518371Z","caller":"traceutil/trace.go:171","msg":"trace[264894277] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"183.073227ms","start":"2026-09-08T22:52:01.335273Z","end":"2026-09-08T22:52:01.518346Z","steps":["trace[264894277] 'process raft request' (duration: 156.976804ms)","trace[264894277] 'compare' (duration: 25.980918ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T22:52:01.873289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.158754ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-08T22:52:01.873414Z","caller":"traceutil/trace.go:171","msg":"trace[933641518] linearizableReadLoop","detail":"{readStateIndex:820; appliedIndex:819; }","duration":"196.014944ms","start":"2026-09-08T22:52:01.677377Z","end":"2026-09-08T22:52:01.873392Z","steps":["trace[933641518] 'read index received' (duration: 89.519ms)","trace[933641518] 'applied index is now lower than readState.Index' (duration: 106.494004ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:52:01.873499Z","caller":"traceutil/trace.go:171","msg":"trace[1747898003] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"397.701126ms","start":"2026-09-08T22:52:01.475729Z","end":"2026-09-08T22:52:01.87343Z","steps":["trace[1747898003] 'process raft request' (duration: 291.327255ms)","trace[1747898003] 'compare' (duration: 105.908296ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T22:52:01.873569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.178469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-08T22:52:01.87361Z","caller":"traceutil/trace.go:171","msg":"trace[1973778900] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:792; }","duration":"196.263791ms","start":"2026-09-08T22:52:01.677334Z","end":"2026-09-08T22:52:01.873597Z","steps":["trace[1973778900] 'agreement among raft nodes before linearized reading' (duration: 196.113146ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:01.873816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:52:01.475702Z","time spent":"397.901402ms","remote":"127.0.0.1:44934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":722,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-08T22:52:02.220157Z","caller":"traceutil/trace.go:171","msg":"trace[1137526407] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"135.562937ms","start":"2026-09-08T22:52:02.08457Z","end":"2026-09-08T22:52:02.220133Z","steps":["trace[1137526407] 'process raft request' (duration: 52.667443ms)","trace[1137526407] 'compare' (duration: 82.819291ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T22:52:02.519889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.298834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T22:52:02.520009Z","caller":"traceutil/trace.go:171","msg":"trace[679765943] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:794; }","duration":"195.479279ms","start":"2026-09-08T22:52:02.324514Z","end":"2026-09-08T22:52:02.519993Z","steps":["trace[679765943] 'range keys from in-memory index tree' (duration: 195.175171ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:02.520106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.93987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-rnmpz\" ","response":"range_response_count:1 size:4740"} {"level":"warn","ts":"2026-09-08T22:52:02.520145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.466079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-08T22:52:02.520236Z","caller":"traceutil/trace.go:171","msg":"trace[1903981062] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-rnmpz; range_end:; response_count:1; response_revision:794; }","duration":"176.104495ms","start":"2026-09-08T22:52:02.344117Z","end":"2026-09-08T22:52:02.520221Z","steps":["trace[1903981062] 'range keys from in-memory index tree' (duration: 175.680451ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:52:02.520361Z","caller":"traceutil/trace.go:171","msg":"trace[738484463] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:794; }","duration":"116.748368ms","start":"2026-09-08T22:52:02.403586Z","end":"2026-09-08T22:52:02.520334Z","steps":["trace[738484463] 'count revisions from in-memory index tree' (duration: 116.102617ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:52:02.691234Z","caller":"traceutil/trace.go:171","msg":"trace[1766547601] linearizableReadLoop","detail":"{readStateIndex:823; appliedIndex:822; }","duration":"184.366767ms","start":"2026-09-08T22:52:02.506849Z","end":"2026-09-08T22:52:02.691216Z","steps":["trace[1766547601] 'read index received' (duration: 184.273824ms)","trace[1766547601] 'applied index is now lower than readState.Index' (duration: 91.853µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:52:02.691326Z","caller":"traceutil/trace.go:171","msg":"trace[2062607149] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"269.048647ms","start":"2026-09-08T22:52:02.422264Z","end":"2026-09-08T22:52:02.691313Z","steps":["trace[2062607149] 'process raft request' (duration: 268.81137ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:02.691543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.677957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-08T22:52:02.691626Z","caller":"traceutil/trace.go:171","msg":"trace[1235806228] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:795; }","duration":"184.76924ms","start":"2026-09-08T22:52:02.506821Z","end":"2026-09-08T22:52:02.69159Z","steps":["trace[1235806228] 'agreement among raft nodes before linearized reading' (duration: 184.505351ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:52:02.84632Z","caller":"traceutil/trace.go:171","msg":"trace[572332960] linearizableReadLoop","detail":"{readStateIndex:824; appliedIndex:823; }","duration":"155.018635ms","start":"2026-09-08T22:52:02.691282Z","end":"2026-09-08T22:52:02.8463Z","steps":["trace[572332960] 'read index received' (duration: 154.911172ms)","trace[572332960] 'applied index is now lower than readState.Index' (duration: 106.333µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:52:02.846419Z","caller":"traceutil/trace.go:171","msg":"trace[872180432] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"317.332752ms","start":"2026-09-08T22:52:02.529076Z","end":"2026-09-08T22:52:02.846409Z","steps":["trace[872180432] 'process raft request' (duration: 317.092084ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:02.84651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.777447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T22:52:02.846576Z","caller":"traceutil/trace.go:171","msg":"trace[428009838] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:796; }","duration":"324.887012ms","start":"2026-09-08T22:52:02.521677Z","end":"2026-09-08T22:52:02.846564Z","steps":["trace[428009838] 'agreement among raft nodes before linearized reading' (duration: 324.787519ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:02.846613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:52:02.521641Z","time spent":"324.965134ms","remote":"127.0.0.1:44836","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-09-08T22:52:02.846626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:52:02.529057Z","time spent":"317.384254ms","remote":"127.0.0.1:45060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5031,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T22:52:02.912802Z","caller":"traceutil/trace.go:171","msg":"trace[721471989] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"215.798385ms","start":"2026-09-08T22:52:02.696977Z","end":"2026-09-08T22:52:02.912776Z","steps":["trace[721471989] 'process raft request' (duration: 215.068143ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:52:03.039863Z","caller":"traceutil/trace.go:171","msg":"trace[1598078640] linearizableReadLoop","detail":"{readStateIndex:827; appliedIndex:825; }","duration":"127.670256ms","start":"2026-09-08T22:52:02.912168Z","end":"2026-09-08T22:52:03.039838Z","steps":["trace[1598078640] 'read index received' (duration: 47.083956ms)","trace[1598078640] 'applied index is now lower than readState.Index' (duration: 80.58505ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:52:03.040238Z","caller":"traceutil/trace.go:171","msg":"trace[1887800000] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"186.120653ms","start":"2026-09-08T22:52:02.854096Z","end":"2026-09-08T22:52:03.040216Z","steps":["trace[1887800000] 'process raft request' (duration: 185.658218ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:52:03.040327Z","caller":"traceutil/trace.go:171","msg":"trace[412345585] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"187.712033ms","start":"2026-09-08T22:52:02.852552Z","end":"2026-09-08T22:52:03.040264Z","steps":["trace[412345585] 'process raft request' (duration: 106.783692ms)","trace[412345585] 'compare' (duration: 80.217518ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T22:52:03.040839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.766668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-rnmpz\" ","response":"range_response_count:1 size:5046"} {"level":"info","ts":"2026-09-08T22:52:03.0409Z","caller":"traceutil/trace.go:171","msg":"trace[13532034] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-rnmpz; range_end:; response_count:1; response_revision:799; }","duration":"189.858751ms","start":"2026-09-08T22:52:02.851024Z","end":"2026-09-08T22:52:03.040883Z","steps":["trace[13532034] 'agreement among raft nodes before linearized reading' (duration: 188.946312ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:03.041245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.691147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T22:52:03.04137Z","caller":"traceutil/trace.go:171","msg":"trace[1776940709] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:799; }","duration":"134.697959ms","start":"2026-09-08T22:52:02.90665Z","end":"2026-09-08T22:52:03.041348Z","steps":["trace[1776940709] 'agreement among raft nodes before linearized reading' (duration: 133.586584ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:52:03.298332Z","caller":"traceutil/trace.go:171","msg":"trace[1039032758] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"382.453118ms","start":"2026-09-08T22:52:02.915839Z","end":"2026-09-08T22:52:03.298292Z","steps":["trace[1039032758] 'process raft request' (duration: 284.947605ms)","trace[1039032758] 'compare' (duration: 97.380569ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T22:52:03.29861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:52:02.915825Z","time spent":"382.687126ms","remote":"127.0.0.1:45130","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-09-08T22:52:03.475027Z","caller":"traceutil/trace.go:171","msg":"trace[1893498169] linearizableReadLoop","detail":"{readStateIndex:829; appliedIndex:827; }","duration":"391.170071ms","start":"2026-09-08T22:52:03.083832Z","end":"2026-09-08T22:52:03.475002Z","steps":["trace[1893498169] 'read index received' (duration: 116.973587ms)","trace[1893498169] 'applied index is now lower than readState.Index' (duration: 274.195444ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:52:03.475145Z","caller":"traceutil/trace.go:171","msg":"trace[2018218336] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"423.307401ms","start":"2026-09-08T22:52:03.051811Z","end":"2026-09-08T22:52:03.475118Z","steps":["trace[2018218336] 'process raft request' (duration: 383.111954ms)","trace[2018218336] 'compare' (duration: 39.954549ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T22:52:03.47534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:52:03.051727Z","time spent":"423.472856ms","remote":"127.0.0.1:45060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4853,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T22:52:03.475403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.17761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-08T22:52:03.475504Z","caller":"traceutil/trace.go:171","msg":"trace[725832524] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:801; }","duration":"140.319095ms","start":"2026-09-08T22:52:03.335169Z","end":"2026-09-08T22:52:03.475488Z","steps":["trace[725832524] 'agreement among raft nodes before linearized reading' (duration: 140.058526ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:03.475584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.676498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-08T22:52:03.476038Z","caller":"traceutil/trace.go:171","msg":"trace[1152174339] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:801; }","duration":"392.285847ms","start":"2026-09-08T22:52:03.083733Z","end":"2026-09-08T22:52:03.476019Z","steps":["trace[1152174339] 'agreement among raft nodes before linearized reading' (duration: 391.41398ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:03.476201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:52:03.08372Z","time spent":"392.465933ms","remote":"127.0.0.1:45130","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-09-08T22:52:03.696224Z","caller":"traceutil/trace.go:171","msg":"trace[360500260] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"213.459197ms","start":"2026-09-08T22:52:03.482744Z","end":"2026-09-08T22:52:03.696203Z","steps":["trace[360500260] 'process raft request' (duration: 213.22811ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:52:03.696684Z","caller":"traceutil/trace.go:171","msg":"trace[945678067] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"213.323554ms","start":"2026-09-08T22:52:03.48333Z","end":"2026-09-08T22:52:03.696653Z","steps":["trace[945678067] 'process raft request' (duration: 212.822188ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:52:03.696744Z","caller":"traceutil/trace.go:171","msg":"trace[1889293306] linearizableReadLoop","detail":"{readStateIndex:834; appliedIndex:829; }","duration":"173.88103ms","start":"2026-09-08T22:52:03.522848Z","end":"2026-09-08T22:52:03.696729Z","steps":["trace[1889293306] 'read index received' (duration: 5.467335ms)","trace[1889293306] 'applied index is now lower than readState.Index' (duration: 168.412975ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:52:03.697024Z","caller":"traceutil/trace.go:171","msg":"trace[1648179503] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"210.233184ms","start":"2026-09-08T22:52:03.48675Z","end":"2026-09-08T22:52:03.696983Z","steps":["trace[1648179503] 'process raft request' (duration: 209.903344ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:03.697085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.224281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-08T22:52:03.698041Z","caller":"traceutil/trace.go:171","msg":"trace[498928285] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:806; }","duration":"175.216163ms","start":"2026-09-08T22:52:03.522802Z","end":"2026-09-08T22:52:03.698018Z","steps":["trace[498928285] 'agreement among raft nodes before linearized reading' (duration: 174.041475ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:52:03.697097Z","caller":"traceutil/trace.go:171","msg":"trace[616841731] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"213.574941ms","start":"2026-09-08T22:52:03.483504Z","end":"2026-09-08T22:52:03.697079Z","steps":["trace[616841731] 'process raft request' (duration: 213.060155ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:52:03.87336Z","caller":"traceutil/trace.go:171","msg":"trace[1159127071] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"170.622086ms","start":"2026-09-08T22:52:03.702698Z","end":"2026-09-08T22:52:03.87332Z","steps":["trace[1159127071] 'process raft request' (duration: 169.710577ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:52:04.130352Z","caller":"traceutil/trace.go:171","msg":"trace[852207300] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"423.688346ms","start":"2026-09-08T22:52:03.70663Z","end":"2026-09-08T22:52:04.130319Z","steps":["trace[852207300] 'process raft request' (duration: 383.801528ms)","trace[852207300] 'compare' (duration: 39.769204ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T22:52:04.130691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:52:03.706617Z","time spent":"423.939733ms","remote":"127.0.0.1:45312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4213,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T22:52:04.660111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.622689ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-09-08T22:52:04.660352Z","caller":"traceutil/trace.go:171","msg":"trace[2008379071] linearizableReadLoop","detail":"{readStateIndex:837; appliedIndex:836; }","duration":"277.609456ms","start":"2026-09-08T22:52:04.382717Z","end":"2026-09-08T22:52:04.660326Z","steps":["trace[2008379071] 'read index received' (duration: 85.675857ms)","trace[2008379071] 'applied index is now lower than readState.Index' (duration: 191.931249ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T22:52:04.660476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.749691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T22:52:04.660555Z","caller":"traceutil/trace.go:171","msg":"trace[1535512253] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:808; }","duration":"277.887546ms","start":"2026-09-08T22:52:04.382649Z","end":"2026-09-08T22:52:04.660536Z","steps":["trace[1535512253] 'agreement among raft nodes before linearized reading' (duration: 277.765662ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:52:05.425607Z","caller":"traceutil/trace.go:171","msg":"trace[29256567] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"118.007112ms","start":"2026-09-08T22:52:05.307577Z","end":"2026-09-08T22:52:05.425584Z","steps":["trace[29256567] 'process raft request' (duration: 117.457405ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:52:05.997678Z","caller":"traceutil/trace.go:171","msg":"trace[1176918285] linearizableReadLoop","detail":"{readStateIndex:841; appliedIndex:840; }","duration":"119.638714ms","start":"2026-09-08T22:52:05.878015Z","end":"2026-09-08T22:52:05.997654Z","steps":["trace[1176918285] 'read index received' (duration: 119.445328ms)","trace[1176918285] 'applied index is now lower than readState.Index' (duration: 192.486µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:52:05.997804Z","caller":"traceutil/trace.go:171","msg":"trace[1884510986] transaction","detail":"{read_only:false; response_revision:812; number_of_response:1; }","duration":"209.822431ms","start":"2026-09-08T22:52:05.787958Z","end":"2026-09-08T22:52:05.99778Z","steps":["trace[1884510986] 'process raft request' (duration: 209.553472ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:05.997941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.948096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-08T22:52:05.998023Z","caller":"traceutil/trace.go:171","msg":"trace[214497591] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:812; }","duration":"120.08539ms","start":"2026-09-08T22:52:05.877924Z","end":"2026-09-08T22:52:05.998009Z","steps":["trace[214497591] 'agreement among raft nodes before linearized reading' (duration: 119.853652ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:06.267027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.588771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T22:52:06.267119Z","caller":"traceutil/trace.go:171","msg":"trace[2027903903] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:813; }","duration":"107.754627ms","start":"2026-09-08T22:52:06.159344Z","end":"2026-09-08T22:52:06.267098Z","steps":["trace[2027903903] 'range keys from in-memory index tree' (duration: 107.505839ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:06.26715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.686834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T22:52:06.267245Z","caller":"traceutil/trace.go:171","msg":"trace[224036699] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:813; }","duration":"107.86507ms","start":"2026-09-08T22:52:06.159362Z","end":"2026-09-08T22:52:06.267227Z","steps":["trace[224036699] 'count revisions from in-memory index tree' (duration: 107.592111ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:06.267552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.514754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T22:52:06.267597Z","caller":"traceutil/trace.go:171","msg":"trace[1246589855] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:813; }","duration":"155.587506ms","start":"2026-09-08T22:52:06.111997Z","end":"2026-09-08T22:52:06.267584Z","steps":["trace[1246589855] 'count revisions from in-memory index tree' (duration: 155.438411ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:06.267666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.765589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T22:52:06.26771Z","caller":"traceutil/trace.go:171","msg":"trace[892222933] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:813; }","duration":"154.82851ms","start":"2026-09-08T22:52:06.11287Z","end":"2026-09-08T22:52:06.267698Z","steps":["trace[892222933] 'range keys from in-memory index tree' (duration: 154.716246ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:06.267805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.645327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T22:52:06.267836Z","caller":"traceutil/trace.go:171","msg":"trace[338930981] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:813; }","duration":"155.709309ms","start":"2026-09-08T22:52:06.112118Z","end":"2026-09-08T22:52:06.267827Z","steps":["trace[338930981] 'range keys from in-memory index tree' (duration: 155.549095ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:52:07.760974Z","caller":"traceutil/trace.go:171","msg":"trace[1235478222] transaction","detail":"{read_only:false; response_revision:816; number_of_response:1; }","duration":"113.674468ms","start":"2026-09-08T22:52:07.647276Z","end":"2026-09-08T22:52:07.760951Z","steps":["trace[1235478222] 'process raft request' (duration: 113.528204ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:52:08.898727Z","caller":"traceutil/trace.go:171","msg":"trace[1695866059] transaction","detail":"{read_only:false; response_revision:819; number_of_response:1; }","duration":"109.587151ms","start":"2026-09-08T22:52:08.789119Z","end":"2026-09-08T22:52:08.898706Z","steps":["trace[1695866059] 'process raft request' (duration: 109.436436ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:09.319031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.456432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.44\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-09-08T22:52:09.319106Z","caller":"traceutil/trace.go:171","msg":"trace[1901880002] range","detail":"{range_begin:/registry/masterleases/162.253.55.44; range_end:; response_count:1; response_revision:819; }","duration":"165.593397ms","start":"2026-09-08T22:52:09.153495Z","end":"2026-09-08T22:52:09.319089Z","steps":["trace[1901880002] 'range keys from in-memory index tree' (duration: 165.317817ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:09.319162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.346237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-08T22:52:09.319304Z","caller":"traceutil/trace.go:171","msg":"trace[653890841] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:819; }","duration":"297.543294ms","start":"2026-09-08T22:52:09.021732Z","end":"2026-09-08T22:52:09.319275Z","steps":["trace[653890841] 'range keys from in-memory index tree' (duration: 297.070127ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:52:09.634308Z","caller":"traceutil/trace.go:171","msg":"trace[2121198257] transaction","detail":"{read_only:false; response_revision:820; number_of_response:1; }","duration":"309.906609ms","start":"2026-09-08T22:52:09.324368Z","end":"2026-09-08T22:52:09.634274Z","steps":["trace[2121198257] 'process raft request' (duration: 252.111799ms)","trace[2121198257] 'compare' (duration: 57.51804ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T22:52:09.634499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:52:09.324346Z","time spent":"310.043403ms","remote":"127.0.0.1:45130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T22:52:09.649667Z","caller":"traceutil/trace.go:171","msg":"trace[281438686] linearizableReadLoop","detail":"{readStateIndex:851; appliedIndex:849; }","duration":"203.981084ms","start":"2026-09-08T22:52:09.445671Z","end":"2026-09-08T22:52:09.649652Z","steps":["trace[281438686] 'read index received' (duration: 130.849461ms)","trace[281438686] 'applied index is now lower than readState.Index' (duration: 73.130963ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:52:09.649728Z","caller":"traceutil/trace.go:171","msg":"trace[433486422] transaction","detail":"{read_only:false; response_revision:821; number_of_response:1; }","duration":"232.047686ms","start":"2026-09-08T22:52:09.417654Z","end":"2026-09-08T22:52:09.649702Z","steps":["trace[433486422] 'process raft request' (duration: 231.894231ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:09.649851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.176431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-08T22:52:09.649886Z","caller":"traceutil/trace.go:171","msg":"trace[240492692] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:821; }","duration":"204.248494ms","start":"2026-09-08T22:52:09.445628Z","end":"2026-09-08T22:52:09.649876Z","steps":["trace[240492692] 'agreement among raft nodes before linearized reading' (duration: 204.078668ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:52:09.789292Z","caller":"traceutil/trace.go:171","msg":"trace[664450710] linearizableReadLoop","detail":"{readStateIndex:852; appliedIndex:851; }","duration":"133.629624ms","start":"2026-09-08T22:52:09.655637Z","end":"2026-09-08T22:52:09.789267Z","steps":["trace[664450710] 'read index received' (duration: 87.468185ms)","trace[664450710] 'applied index is now lower than readState.Index' (duration: 46.159829ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T22:52:09.78953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.876212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-08T22:52:09.789821Z","caller":"traceutil/trace.go:171","msg":"trace[495400474] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:822; }","duration":"134.252505ms","start":"2026-09-08T22:52:09.65555Z","end":"2026-09-08T22:52:09.789803Z","steps":["trace[495400474] 'agreement among raft nodes before linearized reading' (duration: 133.867752ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:52:09.789967Z","caller":"traceutil/trace.go:171","msg":"trace[1411409534] transaction","detail":"{read_only:false; response_revision:822; number_of_response:1; }","duration":"135.531957ms","start":"2026-09-08T22:52:09.653843Z","end":"2026-09-08T22:52:09.789375Z","steps":["trace[1411409534] 'process raft request' (duration: 89.312906ms)","trace[1411409534] 'compare' (duration: 45.984764ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:52:10.497079Z","caller":"traceutil/trace.go:171","msg":"trace[1589568951] transaction","detail":"{read_only:false; response_revision:824; number_of_response:1; }","duration":"363.95832ms","start":"2026-09-08T22:52:10.133097Z","end":"2026-09-08T22:52:10.497056Z","steps":["trace[1589568951] 'process raft request' (duration: 363.772283ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:10.497236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:52:10.133079Z","time spent":"364.077503ms","remote":"127.0.0.1:45130","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-09-08T22:52:10.619718Z","caller":"traceutil/trace.go:171","msg":"trace[1021506778] linearizableReadLoop","detail":"{readStateIndex:855; appliedIndex:853; }","duration":"295.102182ms","start":"2026-09-08T22:52:10.32459Z","end":"2026-09-08T22:52:10.619693Z","steps":["trace[1021506778] 'read index received' (duration: 172.308423ms)","trace[1021506778] 'applied index is now lower than readState.Index' (duration: 122.792539ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:52:10.619768Z","caller":"traceutil/trace.go:171","msg":"trace[2041440395] transaction","detail":"{read_only:false; response_revision:825; number_of_response:1; }","duration":"486.409487ms","start":"2026-09-08T22:52:10.133337Z","end":"2026-09-08T22:52:10.619747Z","steps":["trace[2041440395] 'process raft request' (duration: 414.323002ms)","trace[2041440395] 'compare' (duration: 71.881848ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T22:52:10.619847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.249057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T22:52:10.61988Z","caller":"traceutil/trace.go:171","msg":"trace[582204567] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:825; }","duration":"295.33246ms","start":"2026-09-08T22:52:10.324535Z","end":"2026-09-08T22:52:10.619867Z","steps":["trace[582204567] 'agreement among raft nodes before linearized reading' (duration: 295.262668ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:10.619932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:52:10.133327Z","time spent":"486.496229ms","remote":"127.0.0.1:45130","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-09-08T22:52:11.914484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.630601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-08T22:52:11.914565Z","caller":"traceutil/trace.go:171","msg":"trace[159334681] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:826; }","duration":"119.761996ms","start":"2026-09-08T22:52:11.794785Z","end":"2026-09-08T22:52:11.914547Z","steps":["trace[159334681] 'range keys from in-memory index tree' (duration: 119.431575ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:52:12.131722Z","caller":"traceutil/trace.go:171","msg":"trace[1207343417] transaction","detail":"{read_only:false; response_revision:828; number_of_response:1; }","duration":"212.843933ms","start":"2026-09-08T22:52:11.918828Z","end":"2026-09-08T22:52:12.131672Z","steps":["trace[1207343417] 'process raft request' (duration: 198.815102ms)","trace[1207343417] 'compare' (duration: 13.72352ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T22:52:12.519587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.042811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T22:52:12.519684Z","caller":"traceutil/trace.go:171","msg":"trace[2135721636] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:828; }","duration":"197.199237ms","start":"2026-09-08T22:52:12.322465Z","end":"2026-09-08T22:52:12.519664Z","steps":["trace[2135721636] 'range keys from in-memory index tree' (duration: 196.874826ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:13.286648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.923075ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T22:52:13.28674Z","caller":"traceutil/trace.go:171","msg":"trace[1658726348] linearizableReadLoop","detail":"{readStateIndex:860; appliedIndex:859; }","duration":"662.048777ms","start":"2026-09-08T22:52:12.624676Z","end":"2026-09-08T22:52:13.286725Z","steps":["trace[1658726348] 'read index received' (duration: 124.03444ms)","trace[1658726348] 'applied index is now lower than readState.Index' (duration: 538.013297ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T22:52:13.286883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.213653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-08T22:52:13.286903Z","caller":"traceutil/trace.go:171","msg":"trace[224691878] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:830; }","duration":"662.476441ms","start":"2026-09-08T22:52:12.624418Z","end":"2026-09-08T22:52:13.286894Z","steps":["trace[224691878] 'agreement among raft nodes before linearized reading' (duration: 662.342817ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:13.286925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:52:12.624389Z","time spent":"662.531553ms","remote":"127.0.0.1:45130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-08T22:52:13.287144Z","caller":"traceutil/trace.go:171","msg":"trace[1059986864] transaction","detail":"{read_only:false; response_revision:830; number_of_response:1; }","duration":"761.986561ms","start":"2026-09-08T22:52:12.525148Z","end":"2026-09-08T22:52:13.287135Z","steps":["trace[1059986864] 'process raft request' (duration: 223.493996ms)","trace[1059986864] 'compare' (duration: 537.819861ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T22:52:13.287189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:52:12.525107Z","time spent":"762.059333ms","remote":"127.0.0.1:45130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T22:52:14.199418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:52:13.85946Z","time spent":"339.948539ms","remote":"127.0.0.1:59934","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-09-08T22:52:14.199689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"626.294874ms","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-09-08T22:52:14.199781Z","caller":"traceutil/trace.go:171","msg":"trace[1150099437] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:830; }","duration":"626.399398ms","start":"2026-09-08T22:52:13.573368Z","end":"2026-09-08T22:52:14.199768Z","steps":["trace[1150099437] 'range keys from in-memory index tree' (duration: 626.278313ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:14.200434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.198473ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T22:52:14.20066Z","caller":"traceutil/trace.go:171","msg":"trace[1053720291] linearizableReadLoop","detail":"{readStateIndex:861; appliedIndex:860; }","duration":"523.910734ms","start":"2026-09-08T22:52:13.676731Z","end":"2026-09-08T22:52:14.200641Z","steps":["trace[1053720291] 'read index received' (duration: 422.213953ms)","trace[1053720291] 'applied index is now lower than readState.Index' (duration: 101.691831ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T22:52:14.200958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.216174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-08T22:52:14.201018Z","caller":"traceutil/trace.go:171","msg":"trace[1475650532] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:831; }","duration":"524.313327ms","start":"2026-09-08T22:52:13.676689Z","end":"2026-09-08T22:52:14.201003Z","steps":["trace[1475650532] 'agreement among raft nodes before linearized reading' (duration: 524.136681ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:14.20106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:52:13.676668Z","time spent":"524.38094ms","remote":"127.0.0.1:45130","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-09-08T22:52:14.200746Z","caller":"traceutil/trace.go:171","msg":"trace[206529932] transaction","detail":"{read_only:false; response_revision:831; number_of_response:1; }","duration":"909.806714ms","start":"2026-09-08T22:52:13.29091Z","end":"2026-09-08T22:52:14.200717Z","steps":["trace[206529932] 'process raft request' (duration: 808.099082ms)","trace[206529932] 'compare' (duration: 100.955775ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T22:52:14.201297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.834617ms","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-09-08T22:52:14.20136Z","caller":"traceutil/trace.go:171","msg":"trace[1948266526] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:831; }","duration":"513.92569ms","start":"2026-09-08T22:52:13.687418Z","end":"2026-09-08T22:52:14.201344Z","steps":["trace[1948266526] 'agreement among raft nodes before linearized reading' (duration: 513.826127ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:14.201408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:52:13.687408Z","time spent":"513.986183ms","remote":"127.0.0.1:45174","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":"warn","ts":"2026-09-08T22:52:14.201285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:52:13.290887Z","time spent":"910.356773ms","remote":"127.0.0.1:45130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T22:52:14.201529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.246083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-08T22:52:14.201585Z","caller":"traceutil/trace.go:171","msg":"trace[2088474241] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:831; }","duration":"245.341197ms","start":"2026-09-08T22:52:13.95623Z","end":"2026-09-08T22:52:14.201571Z","steps":["trace[2088474241] 'agreement among raft nodes before linearized reading' (duration: 245.199612ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:14.559401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.116898ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T22:52:14.55971Z","caller":"traceutil/trace.go:171","msg":"trace[2007602218] transaction","detail":"{read_only:false; response_revision:832; number_of_response:1; }","duration":"352.401484ms","start":"2026-09-08T22:52:14.20728Z","end":"2026-09-08T22:52:14.559682Z","steps":["trace[2007602218] 'process raft request' (duration: 115.917544ms)","trace[2007602218] 'compare' (duration: 235.953321ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:52:14.559734Z","caller":"traceutil/trace.go:171","msg":"trace[1212736422] transaction","detail":"{read_only:false; response_revision:833; number_of_response:1; }","duration":"351.478522ms","start":"2026-09-08T22:52:14.208238Z","end":"2026-09-08T22:52:14.559716Z","steps":["trace[1212736422] 'process raft request' (duration: 351.384969ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:14.5599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:52:14.208214Z","time spent":"351.645618ms","remote":"127.0.0.1:45130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T22:52:14.559988Z","caller":"traceutil/trace.go:171","msg":"trace[1122928463] linearizableReadLoop","detail":"{readStateIndex:864; appliedIndex:861; }","duration":"236.817711ms","start":"2026-09-08T22:52:14.323147Z","end":"2026-09-08T22:52:14.559965Z","steps":["trace[1122928463] 'read index received' (duration: 230.959138ms)","trace[1122928463] 'applied index is now lower than readState.Index' (duration: 5.857123ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:52:14.560015Z","caller":"traceutil/trace.go:171","msg":"trace[957490027] transaction","detail":"{read_only:false; response_revision:834; number_of_response:1; }","duration":"349.729752ms","start":"2026-09-08T22:52:14.21027Z","end":"2026-09-08T22:52:14.559999Z","steps":["trace[957490027] 'process raft request' (duration: 349.411871ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:14.559925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:52:14.20725Z","time spent":"352.57652ms","remote":"127.0.0.1:45130","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-09-08T22:52:14.560117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:52:14.210247Z","time spent":"349.814224ms","remote":"127.0.0.1:45130","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-09-08T22:52:14.676152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.191763ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"warn","ts":"2026-09-08T22:52:14.676152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.982553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T22:52:14.676317Z","caller":"traceutil/trace.go:171","msg":"trace[1628003896] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:834; }","duration":"353.159609ms","start":"2026-09-08T22:52:14.323109Z","end":"2026-09-08T22:52:14.676269Z","steps":["trace[1628003896] 'agreement among raft nodes before linearized reading' (duration: 236.945435ms)","trace[1628003896] 'range keys from in-memory index tree' (duration: 115.999836ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T22:52:14.676376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:52:14.323058Z","time spent":"353.302944ms","remote":"127.0.0.1:44852","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-09-08T22:52:15.823557Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7440967494780298312,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-08T22:52:16.284775Z","caller":"traceutil/trace.go:171","msg":"trace[2128889449] linearizableReadLoop","detail":"{readStateIndex:866; appliedIndex:865; }","duration":"961.685049ms","start":"2026-09-08T22:52:15.323067Z","end":"2026-09-08T22:52:16.284752Z","steps":["trace[2128889449] 'read index received' (duration: 961.44283ms)","trace[2128889449] 'applied index is now lower than readState.Index' (duration: 241.089µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:52:16.284844Z","caller":"traceutil/trace.go:171","msg":"trace[617032540] transaction","detail":"{read_only:false; response_revision:835; number_of_response:1; }","duration":"989.4748ms","start":"2026-09-08T22:52:15.295334Z","end":"2026-09-08T22:52:16.284808Z","steps":["trace[617032540] 'process raft request' (duration: 989.231071ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:16.285063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"961.917837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-08T22:52:16.285019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"833.539781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T22:52:16.285126Z","caller":"traceutil/trace.go:171","msg":"trace[1377506957] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:835; }","duration":"962.074382ms","start":"2026-09-08T22:52:15.323018Z","end":"2026-09-08T22:52:16.285092Z","steps":["trace[1377506957] 'agreement among raft nodes before linearized reading' (duration: 961.864525ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:16.285164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:52:15.323004Z","time spent":"962.149525ms","remote":"127.0.0.1:44852","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-09-08T22:52:16.285196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:52:15.295318Z","time spent":"989.756349ms","remote":"127.0.0.1:45130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T22:52:16.285349Z","caller":"traceutil/trace.go:171","msg":"trace[1687103597] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:835; }","duration":"833.684477ms","start":"2026-09-08T22:52:15.451417Z","end":"2026-09-08T22:52:16.285101Z","steps":["trace[1687103597] 'agreement among raft nodes before linearized reading' (duration: 833.545222ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:16.285436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:52:15.451395Z","time spent":"834.026668ms","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":27,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-09-08T22:52:16.720066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.638124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T22:52:16.720178Z","caller":"traceutil/trace.go:171","msg":"trace[1932525644] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:835; }","duration":"433.830051ms","start":"2026-09-08T22:52:16.286327Z","end":"2026-09-08T22:52:16.720157Z","steps":["trace[1932525644] 'range keys from in-memory index tree' (duration: 433.450447ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:16.720221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:52:16.286298Z","time spent":"433.913454ms","remote":"127.0.0.1:44836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-08T22:52:16.720214Z","caller":"traceutil/trace.go:171","msg":"trace[1421614126] linearizableReadLoop","detail":"{readStateIndex:867; appliedIndex:866; }","duration":"156.240753ms","start":"2026-09-08T22:52:16.563949Z","end":"2026-09-08T22:52:16.72019Z","steps":["trace[1421614126] 'read index received' (duration: 72.071955ms)","trace[1421614126] 'applied index is now lower than readState.Index' (duration: 84.166818ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:52:16.72025Z","caller":"traceutil/trace.go:171","msg":"trace[858066435] transaction","detail":"{read_only:false; response_revision:836; number_of_response:1; }","duration":"429.977775ms","start":"2026-09-08T22:52:16.290246Z","end":"2026-09-08T22:52:16.720224Z","steps":["trace[858066435] 'process raft request' (duration: 345.735053ms)","trace[858066435] 'compare' (duration: 83.698683ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T22:52:16.720475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.517973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-08T22:52:16.720524Z","caller":"traceutil/trace.go:171","msg":"trace[267948399] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:836; }","duration":"156.613076ms","start":"2026-09-08T22:52:16.563901Z","end":"2026-09-08T22:52:16.720514Z","steps":["trace[267948399] 'agreement among raft nodes before linearized reading' (duration: 156.464311ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:16.72055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:52:16.290223Z","time spent":"430.13166ms","remote":"127.0.0.1:45130","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-09-08T22:52:16.720819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.462295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-08T22:52:16.720861Z","caller":"traceutil/trace.go:171","msg":"trace[1541965805] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:836; }","duration":"155.523427ms","start":"2026-09-08T22:52:16.565327Z","end":"2026-09-08T22:52:16.720851Z","steps":["trace[1541965805] 'agreement among raft nodes before linearized reading' (duration: 155.374152ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:16.720923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.6248ms","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-09-08T22:52:16.721049Z","caller":"traceutil/trace.go:171","msg":"trace[287844079] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:836; }","duration":"155.858799ms","start":"2026-09-08T22:52:16.565171Z","end":"2026-09-08T22:52:16.72103Z","steps":["trace[287844079] 'agreement among raft nodes before linearized reading' (duration: 155.482636ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:52:16.973522Z","caller":"traceutil/trace.go:171","msg":"trace[946530906] linearizableReadLoop","detail":"{readStateIndex:870; appliedIndex:867; }","duration":"242.38671ms","start":"2026-09-08T22:52:16.731107Z","end":"2026-09-08T22:52:16.973494Z","steps":["trace[946530906] 'read index received' (duration: 82.422777ms)","trace[946530906] 'applied index is now lower than readState.Index' (duration: 159.962233ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:52:16.973581Z","caller":"traceutil/trace.go:171","msg":"trace[217515573] transaction","detail":"{read_only:false; response_revision:838; number_of_response:1; }","duration":"246.714413ms","start":"2026-09-08T22:52:16.726837Z","end":"2026-09-08T22:52:16.973551Z","steps":["trace[217515573] 'process raft request' (duration: 245.314205ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:16.973753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.611409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T22:52:16.973841Z","caller":"traceutil/trace.go:171","msg":"trace[424712526] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:839; }","duration":"242.740884ms","start":"2026-09-08T22:52:16.73108Z","end":"2026-09-08T22:52:16.97382Z","steps":["trace[424712526] 'agreement among raft nodes before linearized reading' (duration: 242.562548ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:52:16.97365Z","caller":"traceutil/trace.go:171","msg":"trace[763617541] transaction","detail":"{read_only:false; response_revision:839; number_of_response:1; }","duration":"246.642041ms","start":"2026-09-08T22:52:16.726995Z","end":"2026-09-08T22:52:16.973637Z","steps":["trace[763617541] 'process raft request' (duration: 246.403962ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:52:19.227408Z","caller":"traceutil/trace.go:171","msg":"trace[1132537201] linearizableReadLoop","detail":"{readStateIndex:873; appliedIndex:872; }","duration":"247.279095ms","start":"2026-09-08T22:52:18.980107Z","end":"2026-09-08T22:52:19.227386Z","steps":["trace[1132537201] 'read index received' (duration: 247.15843ms)","trace[1132537201] 'applied index is now lower than readState.Index' (duration: 119.544µs)"],"step_count":2} {"level":"warn","ts":"2026-09-08T22:52:19.227616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.479391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-08T22:52:19.22766Z","caller":"traceutil/trace.go:171","msg":"trace[1946191508] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:842; }","duration":"247.542924ms","start":"2026-09-08T22:52:18.980102Z","end":"2026-09-08T22:52:19.227645Z","steps":["trace[1946191508] 'agreement among raft nodes before linearized reading' (duration: 247.388328ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:52:19.227631Z","caller":"traceutil/trace.go:171","msg":"trace[307566160] transaction","detail":"{read_only:false; response_revision:842; number_of_response:1; }","duration":"404.84191ms","start":"2026-09-08T22:52:18.82261Z","end":"2026-09-08T22:52:19.227452Z","steps":["trace[307566160] 'process raft request' (duration: 404.642693ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:19.227908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:52:18.822592Z","time spent":"405.237024ms","remote":"127.0.0.1:45130","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-09-08T22:52:19.501685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.730609ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T22:52:19.501885Z","caller":"traceutil/trace.go:171","msg":"trace[1265509787] transaction","detail":"{read_only:false; response_revision:844; number_of_response:1; }","duration":"269.877768ms","start":"2026-09-08T22:52:19.231984Z","end":"2026-09-08T22:52:19.501862Z","steps":["trace[1265509787] 'process raft request' (duration: 269.804106ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:52:19.50201Z","caller":"traceutil/trace.go:171","msg":"trace[1495767217] transaction","detail":"{read_only:false; response_revision:843; number_of_response:1; }","duration":"521.54625ms","start":"2026-09-08T22:52:18.980335Z","end":"2026-09-08T22:52:19.501881Z","steps":["trace[1495767217] 'process raft request' (duration: 288.479679ms)","trace[1495767217] 'compare' (duration: 232.611995ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T22:52:19.502182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:52:18.980248Z","time spent":"521.84837ms","remote":"127.0.0.1:45130","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-09-08T22:52:19.5025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.783566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.44\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-09-08T22:52:19.502587Z","caller":"traceutil/trace.go:171","msg":"trace[2128318235] range","detail":"{range_begin:/registry/masterleases/162.253.55.44; range_end:; response_count:1; response_revision:844; }","duration":"347.885409ms","start":"2026-09-08T22:52:19.154683Z","end":"2026-09-08T22:52:19.502569Z","steps":["trace[2128318235] 'agreement among raft nodes before linearized reading' (duration: 347.62083ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:19.502579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.352168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-08T22:52:19.502607Z","caller":"traceutil/trace.go:171","msg":"trace[1555338204] linearizableReadLoop","detail":"{readStateIndex:874; appliedIndex:873; }","duration":"274.522395ms","start":"2026-09-08T22:52:19.227467Z","end":"2026-09-08T22:52:19.50199Z","steps":["trace[1555338204] 'read index received' (duration: 41.33507ms)","trace[1555338204] 'applied index is now lower than readState.Index' (duration: 233.183985ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:52:19.502641Z","caller":"traceutil/trace.go:171","msg":"trace[645167367] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:844; }","duration":"522.445941ms","start":"2026-09-08T22:52:18.98018Z","end":"2026-09-08T22:52:19.502625Z","steps":["trace[645167367] 'agreement among raft nodes before linearized reading' (duration: 522.258675ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:19.502837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:52:18.980167Z","time spent":"522.650778ms","remote":"127.0.0.1:45130","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":"warn","ts":"2026-09-08T22:52:19.502977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.079949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T22:52:19.503051Z","caller":"traceutil/trace.go:171","msg":"trace[1180314412] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:844; }","duration":"179.167502ms","start":"2026-09-08T22:52:19.323852Z","end":"2026-09-08T22:52:19.50302Z","steps":["trace[1180314412] 'agreement among raft nodes before linearized reading' (duration: 178.928644ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:19.503764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:52:19.154607Z","time spent":"349.035291ms","remote":"127.0.0.1:44868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/162.253.55.44\" "} {"level":"warn","ts":"2026-09-08T22:52:19.772659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.477767ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T22:52:19.772899Z","caller":"traceutil/trace.go:171","msg":"trace[86580096] transaction","detail":"{read_only:false; response_revision:846; number_of_response:1; }","duration":"177.755062ms","start":"2026-09-08T22:52:19.595119Z","end":"2026-09-08T22:52:19.772874Z","steps":["trace[86580096] 'process raft request' (duration: 177.646138ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:52:19.773037Z","caller":"traceutil/trace.go:171","msg":"trace[1253414299] transaction","detail":"{read_only:false; response_revision:845; number_of_response:1; }","duration":"265.251133ms","start":"2026-09-08T22:52:19.50776Z","end":"2026-09-08T22:52:19.773011Z","steps":["trace[1253414299] 'process raft request' (duration: 157.290069ms)","trace[1253414299] 'compare' (duration: 107.2945ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:52:20.826771Z","caller":"traceutil/trace.go:171","msg":"trace[1070330001] linearizableReadLoop","detail":"{readStateIndex:879; appliedIndex:878; }","duration":"370.08025ms","start":"2026-09-08T22:52:20.456665Z","end":"2026-09-08T22:52:20.826746Z","steps":["trace[1070330001] 'read index received' (duration: 369.864582ms)","trace[1070330001] 'applied index is now lower than readState.Index' (duration: 214.447µs)"],"step_count":2} {"level":"warn","ts":"2026-09-08T22:52:20.82701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.310468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T22:52:20.827095Z","caller":"traceutil/trace.go:171","msg":"trace[2101850785] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:847; }","duration":"370.443402ms","start":"2026-09-08T22:52:20.456631Z","end":"2026-09-08T22:52:20.827074Z","steps":["trace[2101850785] 'agreement among raft nodes before linearized reading' (duration: 370.282767ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:20.827141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:52:20.456617Z","time spent":"370.514435ms","remote":"127.0.0.1:45012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"info","ts":"2026-09-08T22:52:20.827147Z","caller":"traceutil/trace.go:171","msg":"trace[1485819635] transaction","detail":"{read_only:false; response_revision:847; number_of_response:1; }","duration":"461.025593ms","start":"2026-09-08T22:52:20.366062Z","end":"2026-09-08T22:52:20.827087Z","steps":["trace[1485819635] 'process raft request' (duration: 460.514034ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:20.827381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:52:20.366046Z","time spent":"461.201929ms","remote":"127.0.0.1:45130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T22:52:21.10983Z","caller":"traceutil/trace.go:171","msg":"trace[1023735209] transaction","detail":"{read_only:false; response_revision:848; number_of_response:1; }","duration":"277.122496ms","start":"2026-09-08T22:52:20.832681Z","end":"2026-09-08T22:52:21.109804Z","steps":["trace[1023735209] 'process raft request' (duration: 275.31889ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:21.454571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.526968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T22:52:21.454674Z","caller":"traceutil/trace.go:171","msg":"trace[1499974843] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:848; }","duration":"129.687023ms","start":"2026-09-08T22:52:21.324963Z","end":"2026-09-08T22:52:21.45465Z","steps":["trace[1499974843] 'range keys from in-memory index tree' (duration: 129.336831ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:21.45482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.300862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-08T22:52:21.45494Z","caller":"traceutil/trace.go:171","msg":"trace[1975882271] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:848; }","duration":"223.439997ms","start":"2026-09-08T22:52:21.231474Z","end":"2026-09-08T22:52:21.454914Z","steps":["trace[1975882271] 'range keys from in-memory index tree' (duration: 222.910848ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:21.917229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.851055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-08T22:52:21.917324Z","caller":"traceutil/trace.go:171","msg":"trace[1522676622] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:850; }","duration":"139.02223ms","start":"2026-09-08T22:52:21.778274Z","end":"2026-09-08T22:52:21.917296Z","steps":["trace[1522676622] 'range keys from in-memory index tree' (duration: 138.650847ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:52:22.704624Z","caller":"traceutil/trace.go:171","msg":"trace[844178641] transaction","detail":"{read_only:false; response_revision:852; number_of_response:1; }","duration":"160.964297ms","start":"2026-09-08T22:52:22.543644Z","end":"2026-09-08T22:52:22.704608Z","steps":["trace[844178641] 'process raft request' (duration: 160.823712ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:23.262828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.079856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T22:52:23.262931Z","caller":"traceutil/trace.go:171","msg":"trace[1107941745] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:852; }","duration":"437.239962ms","start":"2026-09-08T22:52:22.825662Z","end":"2026-09-08T22:52:23.262902Z","steps":["trace[1107941745] 'count revisions from in-memory index tree' (duration: 436.981121ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:23.26299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:52:22.825643Z","time spent":"437.329906ms","remote":"127.0.0.1:45018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":27,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-09-08T22:52:23.262972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.213352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-09-08T22:52:23.263079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.693844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-08T22:52:23.26315Z","caller":"traceutil/trace.go:171","msg":"trace[925788031] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:852; }","duration":"147.796858ms","start":"2026-09-08T22:52:23.115337Z","end":"2026-09-08T22:52:23.263134Z","steps":["trace[925788031] 'range keys from in-memory index tree' (duration: 147.423514ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:23.263311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.526411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-08T22:52:23.263382Z","caller":"traceutil/trace.go:171","msg":"trace[1956207131] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:852; }","duration":"432.635385ms","start":"2026-09-08T22:52:22.830727Z","end":"2026-09-08T22:52:23.263363Z","steps":["trace[1956207131] 'range keys from in-memory index tree' (duration: 432.353774ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:23.263423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:52:22.830715Z","time spent":"432.699828ms","remote":"127.0.0.1:45130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-09-08T22:52:23.263714Z","caller":"traceutil/trace.go:171","msg":"trace[1304301936] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:852; }","duration":"348.329177ms","start":"2026-09-08T22:52:22.914704Z","end":"2026-09-08T22:52:23.263033Z","steps":["trace[1304301936] 'count revisions from in-memory index tree' (duration: 348.14347ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:23.263797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:52:22.914693Z","time spent":"349.083655ms","remote":"127.0.0.1:45376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":90,"response size":29,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-09-08T22:52:23.824261Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7440967494780298367,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-08T22:52:23.858488Z","caller":"traceutil/trace.go:171","msg":"trace[1464629096] transaction","detail":"{read_only:false; response_revision:853; number_of_response:1; }","duration":"590.630368ms","start":"2026-09-08T22:52:23.267827Z","end":"2026-09-08T22:52:23.858457Z","steps":["trace[1464629096] 'process raft request' (duration: 590.430821ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:23.858691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:52:23.267798Z","time spent":"590.785234ms","remote":"127.0.0.1:45130","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-09-08T22:52:24.025627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.359904ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T22:52:24.025757Z","caller":"traceutil/trace.go:171","msg":"trace[433527917] linearizableReadLoop","detail":"{readStateIndex:886; appliedIndex:884; }","duration":"701.618644ms","start":"2026-09-08T22:52:23.324113Z","end":"2026-09-08T22:52:24.025732Z","steps":["trace[433527917] 'read index received' (duration: 534.136682ms)","trace[433527917] 'applied index is now lower than readState.Index' (duration: 167.480552ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T22:52:24.025965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"701.814771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T22:52:24.026069Z","caller":"traceutil/trace.go:171","msg":"trace[1662554773] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:854; }","duration":"701.975777ms","start":"2026-09-08T22:52:23.324067Z","end":"2026-09-08T22:52:24.026043Z","steps":["trace[1662554773] 'agreement among raft nodes before linearized reading' (duration: 701.77698ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:24.026138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:52:23.324045Z","time spent":"702.079161ms","remote":"127.0.0.1:44852","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-09-08T22:52:24.026247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.246773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-08T22:52:24.026523Z","caller":"traceutil/trace.go:171","msg":"trace[1463210453] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:854; }","duration":"472.644627ms","start":"2026-09-08T22:52:23.553858Z","end":"2026-09-08T22:52:24.026503Z","steps":["trace[1463210453] 'agreement among raft nodes before linearized reading' (duration: 472.042515ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:52:24.026558Z","caller":"traceutil/trace.go:171","msg":"trace[104211986] transaction","detail":"{read_only:false; response_revision:854; number_of_response:1; }","duration":"758.686042ms","start":"2026-09-08T22:52:23.267842Z","end":"2026-09-08T22:52:24.026528Z","steps":["trace[104211986] 'process raft request' (duration: 630.313541ms)","trace[104211986] 'compare' (duration: 127.204239ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T22:52:24.026576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:52:23.553831Z","time spent":"472.72934ms","remote":"127.0.0.1:45130","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-09-08T22:52:24.026607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.125259ms","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-09-08T22:52:24.026993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:52:23.267812Z","time spent":"758.799556ms","remote":"127.0.0.1:45130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T22:52:24.02705Z","caller":"traceutil/trace.go:171","msg":"trace[2147157452] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:854; }","duration":"533.615916ms","start":"2026-09-08T22:52:23.493417Z","end":"2026-09-08T22:52:24.027033Z","steps":["trace[2147157452] 'agreement among raft nodes before linearized reading' (duration: 532.486545ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:24.027188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:52:23.493392Z","time spent":"533.686609ms","remote":"127.0.0.1:45130","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-09-08T22:52:24.30332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.130208ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T22:52:24.303663Z","caller":"traceutil/trace.go:171","msg":"trace[1518426114] transaction","detail":"{read_only:false; response_revision:857; number_of_response:1; }","duration":"271.218675ms","start":"2026-09-08T22:52:24.032423Z","end":"2026-09-08T22:52:24.303642Z","steps":["trace[1518426114] 'process raft request' (duration: 271.07872ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:52:24.303679Z","caller":"traceutil/trace.go:171","msg":"trace[847463117] transaction","detail":"{read_only:false; response_revision:856; number_of_response:1; }","duration":"271.312919ms","start":"2026-09-08T22:52:24.032329Z","end":"2026-09-08T22:52:24.303642Z","steps":["trace[847463117] 'process raft request' (duration: 116.62895ms)","trace[847463117] 'compare' (duration: 153.859289ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:52:25.98956Z","caller":"traceutil/trace.go:171","msg":"trace[1826563452] transaction","detail":"{read_only:false; response_revision:858; number_of_response:1; }","duration":"120.539766ms","start":"2026-09-08T22:52:25.868994Z","end":"2026-09-08T22:52:25.989534Z","steps":["trace[1826563452] 'process raft request' (duration: 120.37016ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:26.142802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.470891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-08T22:52:26.142894Z","caller":"traceutil/trace.go:171","msg":"trace[2024816680] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:858; }","duration":"111.638507ms","start":"2026-09-08T22:52:26.031232Z","end":"2026-09-08T22:52:26.142871Z","steps":["trace[2024816680] 'range keys from in-memory index tree' (duration: 111.234282ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:52:26.391573Z","caller":"traceutil/trace.go:171","msg":"trace[243792889] transaction","detail":"{read_only:false; response_revision:859; number_of_response:1; }","duration":"244.244224ms","start":"2026-09-08T22:52:26.147298Z","end":"2026-09-08T22:52:26.391542Z","steps":["trace[243792889] 'process raft request' (duration: 244.039036ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:52:26.43499Z","caller":"traceutil/trace.go:171","msg":"trace[1604283685] linearizableReadLoop","detail":"{readStateIndex:893; appliedIndex:891; }","duration":"126.21488ms","start":"2026-09-08T22:52:26.308753Z","end":"2026-09-08T22:52:26.434968Z","steps":["trace[1604283685] 'read index received' (duration: 82.620589ms)","trace[1604283685] 'applied index is now lower than readState.Index' (duration: 43.593101ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:52:26.435112Z","caller":"traceutil/trace.go:171","msg":"trace[1759862488] transaction","detail":"{read_only:false; response_revision:860; number_of_response:1; }","duration":"286.611469ms","start":"2026-09-08T22:52:26.148487Z","end":"2026-09-08T22:52:26.435099Z","steps":["trace[1759862488] 'process raft request' (duration: 286.363789ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:26.435406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.556311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-08T22:52:26.435502Z","caller":"traceutil/trace.go:171","msg":"trace[1409484093] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:860; }","duration":"126.670796ms","start":"2026-09-08T22:52:26.30881Z","end":"2026-09-08T22:52:26.435481Z","steps":["trace[1409484093] 'agreement among raft nodes before linearized reading' (duration: 126.344474ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:26.435547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.769189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-08T22:52:26.435643Z","caller":"traceutil/trace.go:171","msg":"trace[2026503140] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:860; }","duration":"126.922706ms","start":"2026-09-08T22:52:26.308701Z","end":"2026-09-08T22:52:26.435624Z","steps":["trace[2026503140] 'agreement among raft nodes before linearized reading' (duration: 126.621035ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:26.591647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.858136ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T22:52:26.591965Z","caller":"traceutil/trace.go:171","msg":"trace[360680305] transaction","detail":"{read_only:false; response_revision:862; number_of_response:1; }","duration":"151.593562ms","start":"2026-09-08T22:52:26.440354Z","end":"2026-09-08T22:52:26.591948Z","steps":["trace[360680305] 'process raft request' (duration: 151.451777ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:52:26.592438Z","caller":"traceutil/trace.go:171","msg":"trace[79239991] transaction","detail":"{read_only:false; response_revision:861; number_of_response:1; }","duration":"152.08061ms","start":"2026-09-08T22:52:26.44034Z","end":"2026-09-08T22:52:26.592421Z","steps":["trace[79239991] 'process raft request' (duration: 35.371374ms)","trace[79239991] 'compare' (duration: 115.272343ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T22:52:27.628699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.025412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T22:52:27.628798Z","caller":"traceutil/trace.go:171","msg":"trace[1482795390] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:862; }","duration":"304.178968ms","start":"2026-09-08T22:52:27.324595Z","end":"2026-09-08T22:52:27.628774Z","steps":["trace[1482795390] 'range keys from in-memory index tree' (duration: 303.944539ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:27.628845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:52:27.324569Z","time spent":"304.265371ms","remote":"127.0.0.1:44852","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-09-08T22:52:27.628245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.938871ms","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":"info","ts":"2026-09-08T22:52:27.629077Z","caller":"traceutil/trace.go:171","msg":"trace[1044006975] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:862; }","duration":"506.837814ms","start":"2026-09-08T22:52:27.122202Z","end":"2026-09-08T22:52:27.62904Z","steps":["trace[1044006975] 'count revisions from in-memory index tree' (duration: 505.828426ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:27.62932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:52:27.122179Z","time spent":"507.077753ms","remote":"127.0.0.1:45184","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-09-08T22:52:28.619688Z","caller":"traceutil/trace.go:171","msg":"trace[311832300] transaction","detail":"{read_only:false; response_revision:865; number_of_response:1; }","duration":"177.498379ms","start":"2026-09-08T22:52:28.442148Z","end":"2026-09-08T22:52:28.619646Z","steps":["trace[311832300] 'process raft request' (duration: 176.367866ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:28.87964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.106346ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T22:52:28.879838Z","caller":"traceutil/trace.go:171","msg":"trace[445811128] linearizableReadLoop","detail":"{readStateIndex:900; appliedIndex:898; }","duration":"202.166816ms","start":"2026-09-08T22:52:28.677653Z","end":"2026-09-08T22:52:28.87982Z","steps":["trace[445811128] 'read index received' (duration: 81.900464ms)","trace[445811128] 'applied index is now lower than readState.Index' (duration: 120.265512ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T22:52:28.879937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.27692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-08T22:52:28.879967Z","caller":"traceutil/trace.go:171","msg":"trace[332583673] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:867; }","duration":"202.310771ms","start":"2026-09-08T22:52:28.677647Z","end":"2026-09-08T22:52:28.879958Z","steps":["trace[332583673] 'agreement among raft nodes before linearized reading' (duration: 202.224108ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:52:28.88011Z","caller":"traceutil/trace.go:171","msg":"trace[833182723] transaction","detail":"{read_only:false; response_revision:866; number_of_response:1; }","duration":"255.493926ms","start":"2026-09-08T22:52:28.624588Z","end":"2026-09-08T22:52:28.880081Z","steps":["trace[833182723] 'process raft request' (duration: 134.862761ms)","trace[833182723] 'compare' (duration: 119.757382ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:52:28.880151Z","caller":"traceutil/trace.go:171","msg":"trace[1342115115] transaction","detail":"{read_only:false; response_revision:867; number_of_response:1; }","duration":"254.396164ms","start":"2026-09-08T22:52:28.625726Z","end":"2026-09-08T22:52:28.880122Z","steps":["trace[1342115115] 'process raft request' (duration: 254.01496ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:29.357786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.846922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-09-08T22:52:29.357885Z","caller":"traceutil/trace.go:171","msg":"trace[1405616799] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:868; }","duration":"156.980987ms","start":"2026-09-08T22:52:29.200882Z","end":"2026-09-08T22:52:29.357863Z","steps":["trace[1405616799] 'range keys from in-memory index tree' (duration: 156.754719ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:52:30.805871Z","caller":"traceutil/trace.go:171","msg":"trace[830789781] linearizableReadLoop","detail":"{readStateIndex:905; appliedIndex:904; }","duration":"181.948101ms","start":"2026-09-08T22:52:30.62389Z","end":"2026-09-08T22:52:30.805838Z","steps":["trace[830789781] 'read index received' (duration: 181.760554ms)","trace[830789781] 'applied index is now lower than readState.Index' (duration: 186.207µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:52:30.805987Z","caller":"traceutil/trace.go:171","msg":"trace[157245802] transaction","detail":"{read_only:false; response_revision:871; number_of_response:1; }","duration":"295.460239ms","start":"2026-09-08T22:52:30.510489Z","end":"2026-09-08T22:52:30.805949Z","steps":["trace[157245802] 'process raft request' (duration: 295.168688ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:30.806078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.170868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-08T22:52:30.806116Z","caller":"traceutil/trace.go:171","msg":"trace[1235158933] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:871; }","duration":"182.278283ms","start":"2026-09-08T22:52:30.623828Z","end":"2026-09-08T22:52:30.806106Z","steps":["trace[1235158933] 'agreement among raft nodes before linearized reading' (duration: 182.119817ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:31.036855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.794996ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T22:52:31.03703Z","caller":"traceutil/trace.go:171","msg":"trace[1156941993] linearizableReadLoop","detail":"{readStateIndex:906; appliedIndex:905; }","duration":"151.525794ms","start":"2026-09-08T22:52:30.885481Z","end":"2026-09-08T22:52:31.037006Z","steps":["trace[1156941993] 'read index received' (duration: 9.419617ms)","trace[1156941993] 'applied index is now lower than readState.Index' (duration: 142.104387ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:52:31.037102Z","caller":"traceutil/trace.go:171","msg":"trace[797527253] transaction","detail":"{read_only:false; response_revision:872; number_of_response:1; }","duration":"225.523717ms","start":"2026-09-08T22:52:30.811556Z","end":"2026-09-08T22:52:31.03708Z","steps":["trace[797527253] 'process raft request' (duration: 83.399289ms)","trace[797527253] 'compare' (duration: 141.626128ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T22:52:31.037258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.779333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-08T22:52:31.03731Z","caller":"traceutil/trace.go:171","msg":"trace[607718267] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:872; }","duration":"151.872538ms","start":"2026-09-08T22:52:30.885423Z","end":"2026-09-08T22:52:31.037296Z","steps":["trace[607718267] 'agreement among raft nodes before linearized reading' (duration: 151.699992ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:31.03761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.67648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-08T22:52:31.037711Z","caller":"traceutil/trace.go:171","msg":"trace[318847279] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:872; }","duration":"151.812605ms","start":"2026-09-08T22:52:30.88588Z","end":"2026-09-08T22:52:31.037692Z","steps":["trace[318847279] 'agreement among raft nodes before linearized reading' (duration: 151.289895ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:52:32.263073Z","caller":"traceutil/trace.go:171","msg":"trace[1417510421] transaction","detail":"{read_only:false; response_revision:875; number_of_response:1; }","duration":"130.482355ms","start":"2026-09-08T22:52:32.132568Z","end":"2026-09-08T22:52:32.263051Z","steps":["trace[1417510421] 'process raft request' (duration: 130.34304ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:32.522965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.056996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T22:52:32.523062Z","caller":"traceutil/trace.go:171","msg":"trace[486489027] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:875; }","duration":"199.218202ms","start":"2026-09-08T22:52:32.323817Z","end":"2026-09-08T22:52:32.523035Z","steps":["trace[486489027] 'range keys from in-memory index tree' (duration: 198.959942ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:52:33.085733Z","caller":"traceutil/trace.go:171","msg":"trace[1793340869] transaction","detail":"{read_only:false; response_revision:876; number_of_response:1; }","duration":"271.760088ms","start":"2026-09-08T22:52:32.813945Z","end":"2026-09-08T22:52:33.085705Z","steps":["trace[1793340869] 'process raft request' (duration: 271.591992ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:52:33.224161Z","caller":"traceutil/trace.go:171","msg":"trace[653995712] linearizableReadLoop","detail":"{readStateIndex:911; appliedIndex:909; }","duration":"182.566256ms","start":"2026-09-08T22:52:33.041567Z","end":"2026-09-08T22:52:33.224133Z","steps":["trace[653995712] 'read index received' (duration: 44.004499ms)","trace[653995712] 'applied index is now lower than readState.Index' (duration: 138.560097ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:52:33.224328Z","caller":"traceutil/trace.go:171","msg":"trace[1173486115] transaction","detail":"{read_only:false; response_revision:877; number_of_response:1; }","duration":"351.505268ms","start":"2026-09-08T22:52:32.872728Z","end":"2026-09-08T22:52:33.224233Z","steps":["trace[1173486115] 'process raft request' (duration: 328.084379ms)","trace[1173486115] 'compare' (duration: 23.208051ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T22:52:33.224589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:52:32.8727Z","time spent":"351.780158ms","remote":"127.0.0.1:45130","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-09-08T22:52:33.224609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.97465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-08T22:52:33.224836Z","caller":"traceutil/trace.go:171","msg":"trace[1031643938] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:877; }","duration":"165.124878ms","start":"2026-09-08T22:52:33.059524Z","end":"2026-09-08T22:52:33.224649Z","steps":["trace[1031643938] 'agreement among raft nodes before linearized reading' (duration: 164.783434ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:33.225047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.352777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-08T22:52:33.225175Z","caller":"traceutil/trace.go:171","msg":"trace[1543711114] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:877; }","duration":"183.658299ms","start":"2026-09-08T22:52:33.04149Z","end":"2026-09-08T22:52:33.225149Z","steps":["trace[1543711114] 'agreement among raft nodes before linearized reading' (duration: 182.777434ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:33.465293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.681875ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T22:52:33.465657Z","caller":"traceutil/trace.go:171","msg":"trace[2075190187] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"236.163784ms","start":"2026-09-08T22:52:33.229467Z","end":"2026-09-08T22:52:33.465631Z","steps":["trace[2075190187] 'process raft request' (duration: 130.02998ms)","trace[2075190187] 'compare' (duration: 105.424166ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:52:33.466055Z","caller":"traceutil/trace.go:171","msg":"trace[850129947] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"235.513328ms","start":"2026-09-08T22:52:33.230524Z","end":"2026-09-08T22:52:33.466037Z","steps":["trace[850129947] 'process raft request' (duration: 235.003908ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:33.845915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.420083ms","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-09-08T22:52:33.846067Z","caller":"traceutil/trace.go:171","msg":"trace[1643677497] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:880; }","duration":"273.60919ms","start":"2026-09-08T22:52:33.572437Z","end":"2026-09-08T22:52:33.846046Z","steps":["trace[1643677497] 'range keys from in-memory index tree' (duration: 273.394591ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:35.382039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.125885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-09-08T22:52:35.382118Z","caller":"traceutil/trace.go:171","msg":"trace[863240729] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:882; }","duration":"108.26356ms","start":"2026-09-08T22:52:35.273836Z","end":"2026-09-08T22:52:35.3821Z","steps":["trace[863240729] 'range keys from in-memory index tree' (duration: 107.922507ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:52:35.65533Z","caller":"traceutil/trace.go:171","msg":"trace[569776549] linearizableReadLoop","detail":"{readStateIndex:918; appliedIndex:917; }","duration":"184.855424ms","start":"2026-09-08T22:52:35.470448Z","end":"2026-09-08T22:52:35.655304Z","steps":["trace[569776549] 'read index received' (duration: 184.552932ms)","trace[569776549] 'applied index is now lower than readState.Index' (duration: 301.242µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:52:35.655409Z","caller":"traceutil/trace.go:171","msg":"trace[1116434071] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"269.071104ms","start":"2026-09-08T22:52:35.38631Z","end":"2026-09-08T22:52:35.655381Z","steps":["trace[1116434071] 'process raft request' (duration: 268.784113ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:35.655635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.197468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-09-08T22:52:35.655863Z","caller":"traceutil/trace.go:171","msg":"trace[850542189] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:883; }","duration":"185.470868ms","start":"2026-09-08T22:52:35.470378Z","end":"2026-09-08T22:52:35.655849Z","steps":["trace[850542189] 'agreement among raft nodes before linearized reading' (duration: 185.098194ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:35.655684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.25903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-08T22:52:35.656054Z","caller":"traceutil/trace.go:171","msg":"trace[1127984729] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:883; }","duration":"185.650925ms","start":"2026-09-08T22:52:35.470378Z","end":"2026-09-08T22:52:35.656029Z","steps":["trace[1127984729] 'agreement among raft nodes before linearized reading' (duration: 185.087443ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:52:35.803825Z","caller":"traceutil/trace.go:171","msg":"trace[629417869] transaction","detail":"{read_only:false; response_revision:885; number_of_response:1; }","duration":"142.911551ms","start":"2026-09-08T22:52:35.660883Z","end":"2026-09-08T22:52:35.803795Z","steps":["trace[629417869] 'process raft request' (duration: 104.567297ms)","trace[629417869] 'compare' (duration: 38.224069ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:52:37.85626Z","caller":"traceutil/trace.go:171","msg":"trace[849582392] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"146.254117ms","start":"2026-09-08T22:52:37.709975Z","end":"2026-09-08T22:52:37.856229Z","steps":["trace[849582392] 'process raft request' (duration: 143.909335ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:52:40.675698Z","caller":"traceutil/trace.go:171","msg":"trace[1219527928] linearizableReadLoop","detail":"{readStateIndex:934; appliedIndex:933; }","duration":"159.199975ms","start":"2026-09-08T22:52:40.516467Z","end":"2026-09-08T22:52:40.675667Z","steps":["trace[1219527928] 'read index received' (duration: 158.942015ms)","trace[1219527928] 'applied index is now lower than readState.Index' (duration: 256.6µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:52:40.675758Z","caller":"traceutil/trace.go:171","msg":"trace[195331649] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"213.810859ms","start":"2026-09-08T22:52:40.461919Z","end":"2026-09-08T22:52:40.67573Z","steps":["trace[195331649] 'process raft request' (duration: 213.513488ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:40.675873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.379351ms","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-09-08T22:52:40.675927Z","caller":"traceutil/trace.go:171","msg":"trace[2037694957] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:898; }","duration":"159.513527ms","start":"2026-09-08T22:52:40.516404Z","end":"2026-09-08T22:52:40.675918Z","steps":["trace[2037694957] 'agreement among raft nodes before linearized reading' (duration: 159.389182ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:52:42.075184Z","caller":"traceutil/trace.go:171","msg":"trace[1148600033] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"129.228421ms","start":"2026-09-08T22:52:41.945921Z","end":"2026-09-08T22:52:42.07515Z","steps":["trace[1148600033] 'process raft request' (duration: 128.980291ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:52:43.130419Z","caller":"traceutil/trace.go:171","msg":"trace[1376009624] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"105.870184ms","start":"2026-09-08T22:52:43.024528Z","end":"2026-09-08T22:52:43.130398Z","steps":["trace[1376009624] 'process raft request' (duration: 105.742019ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:52:43.475514Z","caller":"traceutil/trace.go:171","msg":"trace[1384726963] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"156.460883ms","start":"2026-09-08T22:52:43.319028Z","end":"2026-09-08T22:52:43.475489Z","steps":["trace[1384726963] 'process raft request' (duration: 156.328358ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:52:43.539581Z","caller":"traceutil/trace.go:171","msg":"trace[211391916] linearizableReadLoop","detail":"{readStateIndex:944; appliedIndex:943; }","duration":"115.434088ms","start":"2026-09-08T22:52:43.424104Z","end":"2026-09-08T22:52:43.539538Z","steps":["trace[211391916] 'read index received' (duration: 51.838819ms)","trace[211391916] 'applied index is now lower than readState.Index' (duration: 63.594219ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T22:52:43.539835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.707828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-08T22:52:43.539883Z","caller":"traceutil/trace.go:171","msg":"trace[1862262333] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:908; }","duration":"115.797432ms","start":"2026-09-08T22:52:43.424072Z","end":"2026-09-08T22:52:43.539869Z","steps":["trace[1862262333] 'agreement among raft nodes before linearized reading' (duration: 115.610274ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:52:43.539917Z","caller":"traceutil/trace.go:171","msg":"trace[2060241357] transaction","detail":"{read_only:false; response_revision:908; number_of_response:1; }","duration":"119.544961ms","start":"2026-09-08T22:52:43.420324Z","end":"2026-09-08T22:52:43.539869Z","steps":["trace[2060241357] 'process raft request' (duration: 119.076811ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:43.857444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.6441ms","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-09-08T22:52:43.857714Z","caller":"traceutil/trace.go:171","msg":"trace[1321658203] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:908; }","duration":"284.937451ms","start":"2026-09-08T22:52:43.572743Z","end":"2026-09-08T22:52:43.857681Z","steps":["trace[1321658203] 'range keys from in-memory index tree' (duration: 284.619449ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:52:43.858455Z","caller":"traceutil/trace.go:171","msg":"trace[425205088] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"314.198489ms","start":"2026-09-08T22:52:43.544223Z","end":"2026-09-08T22:52:43.858422Z","steps":["trace[425205088] 'process raft request' (duration: 313.940518ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:43.858828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:52:43.544205Z","time spent":"314.424998ms","remote":"127.0.0.1:44952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4423,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-08T22:52:43.948832Z","caller":"traceutil/trace.go:171","msg":"trace[348685124] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"404.481343ms","start":"2026-09-08T22:52:43.54433Z","end":"2026-09-08T22:52:43.948812Z","steps":["trace[348685124] 'process raft request' (duration: 404.259464ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:43.948974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:52:43.544317Z","time spent":"404.588266ms","remote":"127.0.0.1:45130","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-09-08T22:52:43.949019Z","caller":"traceutil/trace.go:171","msg":"trace[1324347811] linearizableReadLoop","detail":"{readStateIndex:947; appliedIndex:944; }","duration":"176.365039ms","start":"2026-09-08T22:52:43.772634Z","end":"2026-09-08T22:52:43.948999Z","steps":["trace[1324347811] 'read index received' (duration: 85.503352ms)","trace[1324347811] 'applied index is now lower than readState.Index' (duration: 90.860157ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:52:43.949077Z","caller":"traceutil/trace.go:171","msg":"trace[1384402604] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"399.693231ms","start":"2026-09-08T22:52:43.549376Z","end":"2026-09-08T22:52:43.949069Z","steps":["trace[1384402604] 'process raft request' (duration: 399.367798ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:43.949113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:52:43.549355Z","time spent":"399.740083ms","remote":"127.0.0.1:45060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4024,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T22:52:43.949316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.582617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-08T22:52:43.950158Z","caller":"traceutil/trace.go:171","msg":"trace[872651400] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:911; }","duration":"177.589067ms","start":"2026-09-08T22:52:43.77255Z","end":"2026-09-08T22:52:43.950139Z","steps":["trace[872651400] 'agreement among raft nodes before linearized reading' (duration: 176.538946ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:52:44.096053Z","caller":"traceutil/trace.go:171","msg":"trace[511491635] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"139.894206ms","start":"2026-09-08T22:52:43.956135Z","end":"2026-09-08T22:52:44.096029Z","steps":["trace[511491635] 'process raft request' (duration: 116.5318ms)","trace[511491635] 'compare' (duration: 23.162038ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:52:44.190279Z","caller":"traceutil/trace.go:171","msg":"trace[37815667] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"233.922652ms","start":"2026-09-08T22:52:43.956315Z","end":"2026-09-08T22:52:44.190238Z","steps":["trace[37815667] 'process raft request' (duration: 233.596849ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:52:44.295377Z","caller":"traceutil/trace.go:171","msg":"trace[1672808513] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"100.579498ms","start":"2026-09-08T22:52:44.194766Z","end":"2026-09-08T22:52:44.295345Z","steps":["trace[1672808513] 'process raft request' (duration: 57.977362ms)","trace[1672808513] 'compare' (duration: 42.419169ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:52:44.296804Z","caller":"traceutil/trace.go:171","msg":"trace[563501182] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"100.448673ms","start":"2026-09-08T22:52:44.196346Z","end":"2026-09-08T22:52:44.296794Z","steps":["trace[563501182] 'process raft request' (duration: 100.296387ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:52:44.473023Z","caller":"traceutil/trace.go:171","msg":"trace[1344355686] linearizableReadLoop","detail":"{readStateIndex:953; appliedIndex:952; }","duration":"149.849101ms","start":"2026-09-08T22:52:44.323138Z","end":"2026-09-08T22:52:44.472987Z","steps":["trace[1344355686] 'read index received' (duration: 147.020968ms)","trace[1344355686] 'applied index is now lower than readState.Index' (duration: 2.826343ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:52:44.473247Z","caller":"traceutil/trace.go:171","msg":"trace[1270189785] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"164.194016ms","start":"2026-09-08T22:52:44.30902Z","end":"2026-09-08T22:52:44.473214Z","steps":["trace[1270189785] 'process raft request' (duration: 161.168305ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:44.473317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.102402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T22:52:44.473448Z","caller":"traceutil/trace.go:171","msg":"trace[578651372] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:917; }","duration":"150.31194ms","start":"2026-09-08T22:52:44.323088Z","end":"2026-09-08T22:52:44.4734Z","steps":["trace[578651372] 'agreement among raft nodes before linearized reading' (duration: 150.015818ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:52:45.02773Z","caller":"traceutil/trace.go:171","msg":"trace[59433768] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"262.037018ms","start":"2026-09-08T22:52:44.765667Z","end":"2026-09-08T22:52:45.027705Z","steps":["trace[59433768] 'process raft request' (duration: 261.815209ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:45.824528Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7440967494780298540,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-09-08T22:52:46.325743Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7440967494780298540,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-09-08T22:52:46.825972Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7440967494780298540,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-09-08T22:52:47.32421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999808713s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-09-08T22:52:47.324336Z","caller":"traceutil/trace.go:171","msg":"trace[515225859] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000055815s","start":"2026-09-08T22:52:45.324261Z","end":"2026-09-08T22:52:47.324316Z","steps":["trace[515225859] 'agreement among raft nodes before linearized reading' (duration: 1.999846205s)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:47.324393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:52:45.32423Z","time spent":"2.000152268s","remote":"127.0.0.1:44852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-08T22:52:47.326575Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7440967494780298540,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-09-08T22:52:47.831216Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7440967494780298540,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-09-08T22:52:47.83225Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.804689325s","expected-duration":"1s"} {"level":"info","ts":"2026-09-08T22:52:47.832757Z","caller":"traceutil/trace.go:171","msg":"trace[476439053] linearizableReadLoop","detail":"{readStateIndex:956; appliedIndex:955; }","duration":"2.508364927s","start":"2026-09-08T22:52:45.324376Z","end":"2026-09-08T22:52:47.832741Z","steps":["trace[476439053] 'read index received' (duration: 2.50818217s)","trace[476439053] 'applied index is now lower than readState.Index' (duration: 181.687µs)"],"step_count":2} {"level":"warn","ts":"2026-09-08T22:52:47.83295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.879032288s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-08T22:52:47.832989Z","caller":"traceutil/trace.go:171","msg":"trace[739774112] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:919; }","duration":"1.879123202s","start":"2026-09-08T22:52:45.953858Z","end":"2026-09-08T22:52:47.832981Z","steps":["trace[739774112] 'agreement among raft nodes before linearized reading' (duration: 1.878996057s)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:47.833019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:52:45.953834Z","time spent":"1.879178504s","remote":"127.0.0.1:45130","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-09-08T22:52:47.832995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.531338736s","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-09-08T22:52:47.833039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.532634488s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-08T22:52:47.833069Z","caller":"traceutil/trace.go:171","msg":"trace[1456901332] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:919; }","duration":"1.531478342s","start":"2026-09-08T22:52:46.301579Z","end":"2026-09-08T22:52:47.833058Z","steps":["trace[1456901332] 'agreement among raft nodes before linearized reading' (duration: 1.531298535s)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:47.83314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:52:46.301554Z","time spent":"1.531575266s","remote":"127.0.0.1:45130","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-09-08T22:52:47.832949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.178118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-08T22:52:47.833124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"801.238273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-09-08T22:52:47.833166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.733007387s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-08T22:52:47.833182Z","caller":"traceutil/trace.go:171","msg":"trace[497074990] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:919; }","duration":"507.46918ms","start":"2026-09-08T22:52:47.325705Z","end":"2026-09-08T22:52:47.833175Z","steps":["trace[497074990] 'agreement among raft nodes before linearized reading' (duration: 507.187158ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:47.833206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:52:47.325685Z","time spent":"507.515601ms","remote":"127.0.0.1:44836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-08T22:52:47.833205Z","caller":"traceutil/trace.go:171","msg":"trace[394553474] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:919; }","duration":"1.73309352s","start":"2026-09-08T22:52:46.100098Z","end":"2026-09-08T22:52:47.833191Z","steps":["trace[394553474] 'agreement among raft nodes before linearized reading' (duration: 1.733010437s)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:52:47.833214Z","caller":"traceutil/trace.go:171","msg":"trace[1235342520] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:919; }","duration":"801.373529ms","start":"2026-09-08T22:52:47.031816Z","end":"2026-09-08T22:52:47.83319Z","steps":["trace[1235342520] 'agreement among raft nodes before linearized reading' (duration: 801.116269ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:47.833235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:52:46.100067Z","time spent":"1.733161543s","remote":"127.0.0.1:45130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-08T22:52:47.833272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:52:47.031793Z","time spent":"801.468293ms","remote":"127.0.0.1:45130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-08T22:52:47.833073Z","caller":"traceutil/trace.go:171","msg":"trace[2134214670] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:919; }","duration":"1.532710411s","start":"2026-09-08T22:52:46.300353Z","end":"2026-09-08T22:52:47.833064Z","steps":["trace[2134214670] 'agreement among raft nodes before linearized reading' (duration: 1.532626278s)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:47.833319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:52:46.300325Z","time spent":"1.532988192s","remote":"127.0.0.1:45130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-09-08T22:52:47.833448Z","caller":"traceutil/trace.go:171","msg":"trace[37945971] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"2.87363273s","start":"2026-09-08T22:52:44.959182Z","end":"2026-09-08T22:52:47.832815Z","steps":["trace[37945971] 'process raft request' (duration: 2.873398841s)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:52:47.833571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:52:44.959156Z","time spent":"2.874330568s","remote":"127.0.0.1:45108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T22:52:48.666506Z","caller":"traceutil/trace.go:171","msg":"trace[2000855892] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"157.316675ms","start":"2026-09-08T22:52:48.509164Z","end":"2026-09-08T22:52:48.666481Z","steps":["trace[2000855892] 'process raft request' (duration: 157.127468ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:52:55.075383Z","caller":"traceutil/trace.go:171","msg":"trace[2104179477] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"109.274375ms","start":"2026-09-08T22:52:54.966091Z","end":"2026-09-08T22:52:55.075365Z","steps":["trace[2104179477] 'process raft request' (duration: 109.149339ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:53:52.618299Z","caller":"traceutil/trace.go:171","msg":"trace[744609469] linearizableReadLoop","detail":"{readStateIndex:1676; appliedIndex:1675; }","duration":"138.774764ms","start":"2026-09-08T22:53:52.479486Z","end":"2026-09-08T22:53:52.618261Z","steps":["trace[744609469] 'read index received' (duration: 138.581455ms)","trace[744609469] 'applied index is now lower than readState.Index' (duration: 192.549µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:53:52.618308Z","caller":"traceutil/trace.go:171","msg":"trace[421628622] transaction","detail":"{read_only:false; response_revision:1620; number_of_response:1; }","duration":"143.609994ms","start":"2026-09-08T22:53:52.474666Z","end":"2026-09-08T22:53:52.618276Z","steps":["trace[421628622] 'process raft request' (duration: 143.456127ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:53:52.618562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.048236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-08T22:53:52.619401Z","caller":"traceutil/trace.go:171","msg":"trace[559445363] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1620; }","duration":"139.911985ms","start":"2026-09-08T22:53:52.47946Z","end":"2026-09-08T22:53:52.619372Z","steps":["trace[559445363] 'agreement among raft nodes before linearized reading' (duration: 138.91201ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:54:18.951288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.545099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-08T22:54:18.952237Z","caller":"traceutil/trace.go:171","msg":"trace[1442573579] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2021; }","duration":"195.524814ms","start":"2026-09-08T22:54:18.756684Z","end":"2026-09-08T22:54:18.952209Z","steps":["trace[1442573579] 'range keys from in-memory index tree' (duration: 194.324558ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:54:18.951357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.120668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-08T22:54:18.952464Z","caller":"traceutil/trace.go:171","msg":"trace[226270562] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2021; }","duration":"310.260852ms","start":"2026-09-08T22:54:18.642183Z","end":"2026-09-08T22:54:18.952444Z","steps":["trace[226270562] 'range keys from in-memory index tree' (duration: 308.975361ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:54:18.952522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:54:18.642159Z","time spent":"310.345185ms","remote":"127.0.0.1:45130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-08T22:54:18.951724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.705246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-09-08T22:54:18.952541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.54825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-08T22:54:18.952683Z","caller":"traceutil/trace.go:171","msg":"trace[497622121] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2021; }","duration":"147.695912ms","start":"2026-09-08T22:54:18.804958Z","end":"2026-09-08T22:54:18.952654Z","steps":["trace[497622121] 'range keys from in-memory index tree' (duration: 146.557729ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:54:18.951872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.247175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-08T22:54:18.952883Z","caller":"traceutil/trace.go:171","msg":"trace[500203992] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2021; }","duration":"185.228221ms","start":"2026-09-08T22:54:18.767571Z","end":"2026-09-08T22:54:18.952799Z","steps":["trace[500203992] 'range keys from in-memory index tree' (duration: 184.122929ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:54:18.95293Z","caller":"traceutil/trace.go:171","msg":"trace[612671414] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2021; }","duration":"152.839533ms","start":"2026-09-08T22:54:18.799847Z","end":"2026-09-08T22:54:18.952686Z","steps":["trace[612671414] 'range keys from in-memory index tree' (duration: 151.949801ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:54:19.204945Z","caller":"traceutil/trace.go:171","msg":"trace[1448218434] transaction","detail":"{read_only:false; response_revision:2024; number_of_response:1; }","duration":"246.73133ms","start":"2026-09-08T22:54:18.958187Z","end":"2026-09-08T22:54:19.204918Z","steps":["trace[1448218434] 'process raft request' (duration: 246.471917ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:54:19.205284Z","caller":"traceutil/trace.go:171","msg":"trace[156011922] transaction","detail":"{read_only:false; response_revision:2025; number_of_response:1; }","duration":"246.989512ms","start":"2026-09-08T22:54:18.958269Z","end":"2026-09-08T22:54:19.205259Z","steps":["trace[156011922] 'process raft request' (duration: 246.597904ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:54:19.205609Z","caller":"traceutil/trace.go:171","msg":"trace[1976060647] transaction","detail":"{read_only:false; response_revision:2026; number_of_response:1; }","duration":"247.167671ms","start":"2026-09-08T22:54:18.958421Z","end":"2026-09-08T22:54:19.205588Z","steps":["trace[1976060647] 'process raft request' (duration: 246.750421ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:54:19.205617Z","caller":"traceutil/trace.go:171","msg":"trace[1827917962] linearizableReadLoop","detail":"{readStateIndex:2095; appliedIndex:2091; }","duration":"143.901009ms","start":"2026-09-08T22:54:19.061688Z","end":"2026-09-08T22:54:19.205589Z","steps":["trace[1827917962] 'read index received' (duration: 142.956885ms)","trace[1827917962] 'applied index is now lower than readState.Index' (duration: 943.174µs)"],"step_count":2} {"level":"warn","ts":"2026-09-08T22:54:19.205814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.100259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"info","ts":"2026-09-08T22:54:19.205872Z","caller":"traceutil/trace.go:171","msg":"trace[1592501279] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2027; }","duration":"144.265276ms","start":"2026-09-08T22:54:19.061587Z","end":"2026-09-08T22:54:19.205852Z","steps":["trace[1592501279] 'agreement among raft nodes before linearized reading' (duration: 144.065347ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:54:19.205953Z","caller":"traceutil/trace.go:171","msg":"trace[1509822197] transaction","detail":"{read_only:false; response_revision:2027; number_of_response:1; }","duration":"242.044231ms","start":"2026-09-08T22:54:18.963894Z","end":"2026-09-08T22:54:19.205938Z","steps":["trace[1509822197] 'process raft request' (duration: 241.582789ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:54:19.409047Z","caller":"traceutil/trace.go:171","msg":"trace[1354361524] transaction","detail":"{read_only:false; response_revision:2028; number_of_response:1; }","duration":"195.74265ms","start":"2026-09-08T22:54:19.213283Z","end":"2026-09-08T22:54:19.409026Z","steps":["trace[1354361524] 'process raft request' (duration: 195.469468ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:54:19.409399Z","caller":"traceutil/trace.go:171","msg":"trace[51593183] transaction","detail":"{read_only:false; response_revision:2029; number_of_response:1; }","duration":"195.589343ms","start":"2026-09-08T22:54:19.213754Z","end":"2026-09-08T22:54:19.409344Z","steps":["trace[51593183] 'process raft request' (duration: 195.225736ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:54:19.409406Z","caller":"traceutil/trace.go:171","msg":"trace[1720020096] transaction","detail":"{read_only:false; response_revision:2031; number_of_response:1; }","duration":"195.625305ms","start":"2026-09-08T22:54:19.213764Z","end":"2026-09-08T22:54:19.409389Z","steps":["trace[1720020096] 'process raft request' (duration: 195.542951ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:54:19.409423Z","caller":"traceutil/trace.go:171","msg":"trace[630177755] transaction","detail":"{read_only:false; response_revision:2030; number_of_response:1; }","duration":"195.629595ms","start":"2026-09-08T22:54:19.213756Z","end":"2026-09-08T22:54:19.409385Z","steps":["trace[630177755] 'process raft request' (duration: 195.461867ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:54:19.409494Z","caller":"traceutil/trace.go:171","msg":"trace[120808685] linearizableReadLoop","detail":"{readStateIndex:2100; appliedIndex:2095; }","duration":"127.233657ms","start":"2026-09-08T22:54:19.282239Z","end":"2026-09-08T22:54:19.409473Z","steps":["trace[120808685] 'read index received' (duration: 62.230039ms)","trace[120808685] 'applied index is now lower than readState.Index' (duration: 65.002038ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T22:54:19.409667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.413975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-81bbaded-3996-43dc-ba18-65091f2a3435\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-09-08T22:54:19.409709Z","caller":"traceutil/trace.go:171","msg":"trace[990557796] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-81bbaded-3996-43dc-ba18-65091f2a3435; range_end:; response_count:1; response_revision:2032; }","duration":"127.50741ms","start":"2026-09-08T22:54:19.282186Z","end":"2026-09-08T22:54:19.409693Z","steps":["trace[990557796] 'agreement among raft nodes before linearized reading' (duration: 127.351242ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:54:22.218812Z","caller":"traceutil/trace.go:171","msg":"trace[1754211089] transaction","detail":"{read_only:false; response_revision:2053; number_of_response:1; }","duration":"141.280718ms","start":"2026-09-08T22:54:22.077495Z","end":"2026-09-08T22:54:22.218775Z","steps":["trace[1754211089] 'process raft request' (duration: 141.055798ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:54:27.810466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.737344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11269"} {"level":"info","ts":"2026-09-08T22:54:27.810567Z","caller":"traceutil/trace.go:171","msg":"trace[2013274912] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2121; }","duration":"213.896382ms","start":"2026-09-08T22:54:27.596647Z","end":"2026-09-08T22:54:27.810543Z","steps":["trace[2013274912] 'range keys from in-memory index tree' (duration: 213.415169ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:54:27.810556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.214367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-08T22:54:27.81063Z","caller":"traceutil/trace.go:171","msg":"trace[2017993553] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:2121; }","duration":"200.350043ms","start":"2026-09-08T22:54:27.610257Z","end":"2026-09-08T22:54:27.810607Z","steps":["trace[2017993553] 'count revisions from in-memory index tree' (duration: 200.138293ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:54:52.697199Z","caller":"traceutil/trace.go:171","msg":"trace[1385951105] linearizableReadLoop","detail":"{readStateIndex:2380; appliedIndex:2379; }","duration":"126.035471ms","start":"2026-09-08T22:54:52.571138Z","end":"2026-09-08T22:54:52.697174Z","steps":["trace[1385951105] 'read index received' (duration: 125.8646ms)","trace[1385951105] 'applied index is now lower than readState.Index' (duration: 169.811µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:54:52.697234Z","caller":"traceutil/trace.go:171","msg":"trace[2136398431] transaction","detail":"{read_only:false; response_revision:2299; number_of_response:1; }","duration":"149.761873ms","start":"2026-09-08T22:54:52.547448Z","end":"2026-09-08T22:54:52.69721Z","steps":["trace[2136398431] 'process raft request' (duration: 149.566992ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:54:52.697471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.322362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"info","ts":"2026-09-08T22:54:52.697518Z","caller":"traceutil/trace.go:171","msg":"trace[853548104] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2299; }","duration":"126.414662ms","start":"2026-09-08T22:54:52.571088Z","end":"2026-09-08T22:54:52.697503Z","steps":["trace[853548104] 'agreement among raft nodes before linearized reading' (duration: 126.220951ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:54:53.011046Z","caller":"traceutil/trace.go:171","msg":"trace[814524180] linearizableReadLoop","detail":"{readStateIndex:2381; appliedIndex:2380; }","duration":"298.854714ms","start":"2026-09-08T22:54:52.712166Z","end":"2026-09-08T22:54:53.011021Z","steps":["trace[814524180] 'read index received' (duration: 226.260548ms)","trace[814524180] 'applied index is now lower than readState.Index' (duration: 72.593066ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:54:53.011081Z","caller":"traceutil/trace.go:171","msg":"trace[1260428956] transaction","detail":"{read_only:false; response_revision:2300; number_of_response:1; }","duration":"306.083581ms","start":"2026-09-08T22:54:52.704957Z","end":"2026-09-08T22:54:53.01104Z","steps":["trace[1260428956] 'process raft request' (duration: 233.561664ms)","trace[1260428956] 'compare' (duration: 72.377126ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T22:54:53.011286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:54:52.704932Z","time spent":"306.253561ms","remote":"127.0.0.1:44968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":644,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T22:54:53.011842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.664179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-09-08T22:54:53.0119Z","caller":"traceutil/trace.go:171","msg":"trace[295858243] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:2300; }","duration":"299.750159ms","start":"2026-09-08T22:54:52.712133Z","end":"2026-09-08T22:54:53.011883Z","steps":["trace[295858243] 'agreement among raft nodes before linearized reading' (duration: 298.993605ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:54:53.261121Z","caller":"traceutil/trace.go:171","msg":"trace[2126263209] transaction","detail":"{read_only:false; response_revision:2301; number_of_response:1; }","duration":"244.173192ms","start":"2026-09-08T22:54:53.016908Z","end":"2026-09-08T22:54:53.261081Z","steps":["trace[2126263209] 'process raft request' (duration: 243.432468ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:54:53.266139Z","caller":"traceutil/trace.go:171","msg":"trace[986338438] transaction","detail":"{read_only:false; response_revision:2302; number_of_response:1; }","duration":"246.392754ms","start":"2026-09-08T22:54:53.019703Z","end":"2026-09-08T22:54:53.266096Z","steps":["trace[986338438] 'process raft request' (duration: 246.183353ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:54:53.266629Z","caller":"traceutil/trace.go:171","msg":"trace[1729444568] transaction","detail":"{read_only:false; response_revision:2304; number_of_response:1; }","duration":"150.034278ms","start":"2026-09-08T22:54:53.116569Z","end":"2026-09-08T22:54:53.266603Z","steps":["trace[1729444568] 'process raft request' (duration: 149.761117ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:54:53.266762Z","caller":"traceutil/trace.go:171","msg":"trace[1309300555] transaction","detail":"{read_only:false; response_revision:2303; number_of_response:1; }","duration":"246.733237ms","start":"2026-09-08T22:54:53.019997Z","end":"2026-09-08T22:54:53.26673Z","steps":["trace[1309300555] 'process raft request' (duration: 245.998953ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:56:24.053096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.341894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-08T22:56:24.053209Z","caller":"traceutil/trace.go:171","msg":"trace[1110529945] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3023; }","duration":"106.500438ms","start":"2026-09-08T22:56:23.946689Z","end":"2026-09-08T22:56:24.053189Z","steps":["trace[1110529945] 'range keys from in-memory index tree' (duration: 105.964305ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:56:24.053799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.206239ms","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-09-08T22:56:24.053885Z","caller":"traceutil/trace.go:171","msg":"trace[1437737089] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3023; }","duration":"108.377624ms","start":"2026-09-08T22:56:23.945489Z","end":"2026-09-08T22:56:24.053867Z","steps":["trace[1437737089] 'range keys from in-memory index tree' (duration: 107.104172ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:56:50.146325Z","caller":"traceutil/trace.go:171","msg":"trace[1643868444] transaction","detail":"{read_only:false; response_revision:3197; number_of_response:1; }","duration":"109.74714ms","start":"2026-09-08T22:56:50.036535Z","end":"2026-09-08T22:56:50.146282Z","steps":["trace[1643868444] 'process raft request' (duration: 109.573966ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:58:58.642638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.807385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-09-08T22:58:58.642663Z","caller":"traceutil/trace.go:171","msg":"trace[2085580014] transaction","detail":"{read_only:false; response_revision:4766; number_of_response:1; }","duration":"119.80392ms","start":"2026-09-08T22:58:58.522836Z","end":"2026-09-08T22:58:58.64264Z","steps":["trace[2085580014] 'process raft request' (duration: 84.950115ms)","trace[2085580014] 'compare' (duration: 34.539563ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:58:58.642716Z","caller":"traceutil/trace.go:171","msg":"trace[821797386] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4766; }","duration":"121.917959ms","start":"2026-09-08T22:58:58.520785Z","end":"2026-09-08T22:58:58.642703Z","steps":["trace[821797386] 'agreement among raft nodes before linearized reading' (duration: 121.722592ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:58:58.893752Z","caller":"traceutil/trace.go:171","msg":"trace[300786547] linearizableReadLoop","detail":"{readStateIndex:4989; appliedIndex:4988; }","duration":"101.925686ms","start":"2026-09-08T22:58:58.791811Z","end":"2026-09-08T22:58:58.893736Z","steps":["trace[300786547] 'read index received' (duration: 101.883524ms)","trace[300786547] 'applied index is now lower than readState.Index' (duration: 41.592µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:58:58.893824Z","caller":"traceutil/trace.go:171","msg":"trace[1417917817] transaction","detail":"{read_only:false; response_revision:4778; number_of_response:1; }","duration":"102.882812ms","start":"2026-09-08T22:58:58.790935Z","end":"2026-09-08T22:58:58.893817Z","steps":["trace[1417917817] 'process raft request' (duration: 102.723046ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:58:58.894173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.337571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-09-08T22:58:58.89442Z","caller":"traceutil/trace.go:171","msg":"trace[1086176937] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:4778; }","duration":"102.57821ms","start":"2026-09-08T22:58:58.791785Z","end":"2026-09-08T22:58:58.894363Z","steps":["trace[1086176937] 'agreement among raft nodes before linearized reading' (duration: 102.153074ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:58:58.901777Z","caller":"traceutil/trace.go:171","msg":"trace[821255339] transaction","detail":"{read_only:false; response_revision:4780; number_of_response:1; }","duration":"102.594971ms","start":"2026-09-08T22:58:58.799158Z","end":"2026-09-08T22:58:58.901753Z","steps":["trace[821255339] 'process raft request' (duration: 102.393444ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:58:58.901656Z","caller":"traceutil/trace.go:171","msg":"trace[1017989719] transaction","detail":"{read_only:false; response_revision:4779; number_of_response:1; }","duration":"105.319274ms","start":"2026-09-08T22:58:58.796329Z","end":"2026-09-08T22:58:58.901649Z","steps":["trace[1017989719] 'process raft request' (duration: 105.176389ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:59:04.563473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.879566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-08T22:59:04.563528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.943961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-09-08T22:59:04.563577Z","caller":"traceutil/trace.go:171","msg":"trace[889671689] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4892; }","duration":"238.032422ms","start":"2026-09-08T22:59:04.325519Z","end":"2026-09-08T22:59:04.563551Z","steps":["trace[889671689] 'range keys from in-memory index tree' (duration: 237.789782ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:59:04.563628Z","caller":"traceutil/trace.go:171","msg":"trace[1104596109] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4892; }","duration":"202.125338ms","start":"2026-09-08T22:59:04.361478Z","end":"2026-09-08T22:59:04.563604Z","steps":["trace[1104596109] 'range keys from in-memory index tree' (duration: 201.691481ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:59:04.598245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.46801ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-09-08T22:59:04.598338Z","caller":"traceutil/trace.go:171","msg":"trace[637808464] linearizableReadLoop","detail":"{readStateIndex:5108; appliedIndex:5107; }","duration":"223.674136ms","start":"2026-09-08T22:59:04.374646Z","end":"2026-09-08T22:59:04.59832Z","steps":["trace[637808464] 'read index received' (duration: 16.146343ms)","trace[637808464] 'applied index is now lower than readState.Index' (duration: 207.526503ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T22:59:04.598552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.785243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b1436198-6549-43ea-9cbe-d24e8fd53e38\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-09-08T22:59:04.598592Z","caller":"traceutil/trace.go:171","msg":"trace[1012488743] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b1436198-6549-43ea-9cbe-d24e8fd53e38; range_end:; response_count:1; response_revision:4892; }","duration":"167.890567ms","start":"2026-09-08T22:59:04.43069Z","end":"2026-09-08T22:59:04.598581Z","steps":["trace[1012488743] 'agreement among raft nodes before linearized reading' (duration: 167.749602ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:59:04.598936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.253127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-09-08T22:59:04.598999Z","caller":"traceutil/trace.go:171","msg":"trace[1336188637] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4892; }","duration":"224.375702ms","start":"2026-09-08T22:59:04.37461Z","end":"2026-09-08T22:59:04.598986Z","steps":["trace[1336188637] 'agreement among raft nodes before linearized reading' (duration: 223.824951ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:59:04.599227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.051604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b1436198-6549-43ea-9cbe-d24e8fd53e38\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-09-08T22:59:04.599339Z","caller":"traceutil/trace.go:171","msg":"trace[1013423581] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b1436198-6549-43ea-9cbe-d24e8fd53e38; range_end:; response_count:1; response_revision:4892; }","duration":"138.231681ms","start":"2026-09-08T22:59:04.461086Z","end":"2026-09-08T22:59:04.599318Z","steps":["trace[1013423581] 'agreement among raft nodes before linearized reading' (duration: 137.541344ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:59:18.035786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.233853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10515"} {"level":"info","ts":"2026-09-08T22:59:18.035872Z","caller":"traceutil/trace.go:171","msg":"trace[1313183058] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5099; }","duration":"457.363977ms","start":"2026-09-08T22:59:17.578489Z","end":"2026-09-08T22:59:18.035853Z","steps":["trace[1313183058] 'range keys from in-memory index tree' (duration: 457.073627ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:59:18.035911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:59:17.578468Z","time spent":"457.43234ms","remote":"127.0.0.1:45060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":10537,"request content":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-09-08T22:59:18.035947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.484028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-09-08T22:59:18.036023Z","caller":"traceutil/trace.go:171","msg":"trace[1832774284] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5099; }","duration":"407.602882ms","start":"2026-09-08T22:59:17.628401Z","end":"2026-09-08T22:59:18.036004Z","steps":["trace[1832774284] 'range keys from in-memory index tree' (duration: 407.331331ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:59:18.036066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:59:17.628381Z","time spent":"407.674035ms","remote":"127.0.0.1:45022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1887,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-09-08T23:00:04.88226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.631621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T23:00:04.882453Z","caller":"traceutil/trace.go:171","msg":"trace[233712825] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5546; }","duration":"112.897011ms","start":"2026-09-08T23:00:04.769537Z","end":"2026-09-08T23:00:04.882434Z","steps":["trace[233712825] 'range keys from in-memory index tree' (duration: 112.485726ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T23:00:05.71861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.844493ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T23:00:05.718759Z","caller":"traceutil/trace.go:171","msg":"trace[1085204874] transaction","detail":"{read_only:false; response_revision:5556; number_of_response:1; }","duration":"121.658396ms","start":"2026-09-08T23:00:05.597084Z","end":"2026-09-08T23:00:05.718743Z","steps":["trace[1085204874] 'process raft request' (duration: 20.593504ms)","trace[1085204874] 'compare' (duration: 100.740459ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T23:00:05.719073Z","caller":"traceutil/trace.go:171","msg":"trace[1904772251] transaction","detail":"{read_only:false; response_revision:5557; number_of_response:1; }","duration":"118.432286ms","start":"2026-09-08T23:00:05.600576Z","end":"2026-09-08T23:00:05.719009Z","steps":["trace[1904772251] 'process raft request' (duration: 118.128454ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T23:00:55.692647Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2836} {"level":"info","ts":"2026-09-08T23:00:55.776217Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2836,"took":"81.645912ms","hash":1816298258,"current-db-size-bytes":56688640,"current-db-size":"57 MB","current-db-size-in-use-bytes":33472512,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-09-08T23:00:55.776314Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1816298258,"revision":2836,"compact-revision":-1} {"level":"info","ts":"2026-09-08T23:05:55.699914Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6011} {"level":"info","ts":"2026-09-08T23:05:55.788496Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6011,"took":"87.392233ms","hash":3700857722,"current-db-size-bytes":56688640,"current-db-size":"57 MB","current-db-size-in-use-bytes":19267584,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-08T23:05:55.788549Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3700857722,"revision":6011,"compact-revision":2836}