{"level":"warn","ts":"2026-06-03T17:39:57.399503Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-03T17:39:57.399684Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.4:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.4:2380","--initial-cluster=instance=https://199.204.45.4:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.4:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.4:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-06-03T17:39:57.39981Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-03T17:39:57.399869Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.4:2380"]} {"level":"info","ts":"2026-06-03T17:39:57.399929Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-06-03T17:39:57.400795Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.4:2379"]} {"level":"info","ts":"2026-06-03T17:39:57.400912Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.4:2380"],"listen-peer-urls":["https://199.204.45.4:2380"],"advertise-client-urls":["https://199.204.45.4:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.4:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.4:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-06-03T17:39:57.405284Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.069023ms"} {"level":"info","ts":"2026-06-03T17:39:57.411423Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"66f7b57d56ad567d","cluster-id":"ec267dcaac715770"} {"level":"info","ts":"2026-06-03T17:39:57.411553Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d switched to configuration voters=()"} {"level":"info","ts":"2026-06-03T17:39:57.411616Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became follower at term 0"} {"level":"info","ts":"2026-06-03T17:39:57.411643Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 66f7b57d56ad567d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-03T17:39:57.411666Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became follower at term 1"} {"level":"info","ts":"2026-06-03T17:39:57.411719Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d switched to configuration voters=(7419598461045921405)"} {"level":"warn","ts":"2026-06-03T17:39:57.421286Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-03T17:39:57.426864Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-03T17:39:57.429106Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-06-03T17:39:57.436646Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"66f7b57d56ad567d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-03T17:39:57.436781Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"66f7b57d56ad567d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-03T17:39:57.438433Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-03T17:39:57.43858Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-03T17:39:57.438668Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-03T17:39:57.441347Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d switched to configuration voters=(7419598461045921405)"} {"level":"info","ts":"2026-06-03T17:39:57.441715Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ec267dcaac715770","local-member-id":"66f7b57d56ad567d","added-peer-id":"66f7b57d56ad567d","added-peer-peer-urls":["https://199.204.45.4:2380"]} {"level":"info","ts":"2026-06-03T17:39:57.443902Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-06-03T17:39:57.443979Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.4:2380"} {"level":"info","ts":"2026-06-03T17:39:57.444034Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.4:2380"} {"level":"info","ts":"2026-06-03T17:39:57.444201Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"66f7b57d56ad567d","initial-advertise-peer-urls":["https://199.204.45.4:2380"],"listen-peer-urls":["https://199.204.45.4:2380"],"advertise-client-urls":["https://199.204.45.4:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.4:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-03T17:39:57.444261Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-03T17:39:58.212409Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d is starting a new election at term 1"} {"level":"info","ts":"2026-06-03T17:39:58.212477Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became pre-candidate at term 1"} {"level":"info","ts":"2026-06-03T17:39:58.212499Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d received MsgPreVoteResp from 66f7b57d56ad567d at term 1"} {"level":"info","ts":"2026-06-03T17:39:58.212518Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became candidate at term 2"} {"level":"info","ts":"2026-06-03T17:39:58.212529Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d received MsgVoteResp from 66f7b57d56ad567d at term 2"} {"level":"info","ts":"2026-06-03T17:39:58.212545Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became leader at term 2"} {"level":"info","ts":"2026-06-03T17:39:58.212558Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 66f7b57d56ad567d elected leader 66f7b57d56ad567d at term 2"} {"level":"info","ts":"2026-06-03T17:39:58.213676Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-03T17:39:58.214583Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-03T17:39:58.21458Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"66f7b57d56ad567d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.4:2379]}","request-path":"/0/members/66f7b57d56ad567d/attributes","cluster-id":"ec267dcaac715770","publish-timeout":"7s"} {"level":"info","ts":"2026-06-03T17:39:58.214676Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-03T17:39:58.214895Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-03T17:39:58.214946Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-03T17:39:58.215238Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ec267dcaac715770","local-member-id":"66f7b57d56ad567d","cluster-version":"3.5"} {"level":"info","ts":"2026-06-03T17:39:58.215376Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-03T17:39:58.215428Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-03T17:39:58.219043Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-03T17:39:58.219052Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.4:2379"} {"level":"warn","ts":"2026-06-03T17:40:02.364842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.120573ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-03T17:40:02.36502Z","caller":"traceutil/trace.go:171","msg":"trace[933561000] linearizableReadLoop","detail":"{readStateIndex:215; appliedIndex:214; }","duration":"229.482914ms","start":"2026-06-03T17:40:02.135521Z","end":"2026-06-03T17:40:02.365004Z","steps":["trace[933561000] 'read index received' (duration: 83.703104ms)","trace[933561000] 'applied index is now lower than readState.Index' (duration: 145.77821ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T17:40:02.365078Z","caller":"traceutil/trace.go:171","msg":"trace[1993687734] transaction","detail":"{read_only:false; response_revision:209; number_of_response:1; }","duration":"270.988728ms","start":"2026-06-03T17:40:02.094048Z","end":"2026-06-03T17:40:02.365037Z","steps":["trace[1993687734] 'process raft request' (duration: 125.183789ms)","trace[1993687734] 'compare' (duration: 144.990602ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T17:40:02.365168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.658075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T17:40:02.365201Z","caller":"traceutil/trace.go:171","msg":"trace[2045075139] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:209; }","duration":"229.739665ms","start":"2026-06-03T17:40:02.135451Z","end":"2026-06-03T17:40:02.365191Z","steps":["trace[2045075139] 'agreement among raft nodes before linearized reading' (duration: 229.649084ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T17:40:02.36533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.286002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3490"} {"level":"info","ts":"2026-06-03T17:40:02.365841Z","caller":"traceutil/trace.go:171","msg":"trace[765666354] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:209; }","duration":"211.487784ms","start":"2026-06-03T17:40:02.15395Z","end":"2026-06-03T17:40:02.365438Z","steps":["trace[765666354] 'agreement among raft nodes before linearized reading' (duration: 211.255312ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:40:02.537404Z","caller":"traceutil/trace.go:171","msg":"trace[1420032053] transaction","detail":"{read_only:false; response_revision:210; number_of_response:1; }","duration":"168.763999ms","start":"2026-06-03T17:40:02.368612Z","end":"2026-06-03T17:40:02.537376Z","steps":["trace[1420032053] 'process raft request' (duration: 168.622158ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:40:02.541894Z","caller":"traceutil/trace.go:171","msg":"trace[902239258] transaction","detail":"{read_only:false; response_revision:211; number_of_response:1; }","duration":"170.644434ms","start":"2026-06-03T17:40:02.37121Z","end":"2026-06-03T17:40:02.541854Z","steps":["trace[902239258] 'process raft request' (duration: 170.479563ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:40:02.542334Z","caller":"traceutil/trace.go:171","msg":"trace[475619420] transaction","detail":"{read_only:false; response_revision:212; number_of_response:1; }","duration":"167.728151ms","start":"2026-06-03T17:40:02.374592Z","end":"2026-06-03T17:40:02.54232Z","steps":["trace[475619420] 'process raft request' (duration: 167.205527ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:40:36.304091Z","caller":"traceutil/trace.go:171","msg":"trace[980210804] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"100.28494ms","start":"2026-06-03T17:40:36.203786Z","end":"2026-06-03T17:40:36.304071Z","steps":["trace[980210804] 'process raft request' (duration: 100.243238ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:40:36.30422Z","caller":"traceutil/trace.go:171","msg":"trace[182722660] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"103.59132ms","start":"2026-06-03T17:40:36.200615Z","end":"2026-06-03T17:40:36.304206Z","steps":["trace[182722660] 'process raft request' (duration: 103.334599ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:40:36.304267Z","caller":"traceutil/trace.go:171","msg":"trace[1637957547] transaction","detail":"{read_only:false; number_of_response:1; response_revision:542; }","duration":"103.672053ms","start":"2026-06-03T17:40:36.200584Z","end":"2026-06-03T17:40:36.304256Z","steps":["trace[1637957547] 'process raft request' (duration: 103.317837ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:40:36.304231Z","caller":"traceutil/trace.go:171","msg":"trace[2090132000] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"103.703705ms","start":"2026-06-03T17:40:36.200498Z","end":"2026-06-03T17:40:36.304202Z","steps":["trace[2090132000] 'process raft request' (duration: 102.314901ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:40:36.309753Z","caller":"traceutil/trace.go:171","msg":"trace[573578087] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"102.190367ms","start":"2026-06-03T17:40:36.207544Z","end":"2026-06-03T17:40:36.309734Z","steps":["trace[573578087] 'process raft request' (duration: 101.319067ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T17:40:37.385999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.533492ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-03T17:40:37.386304Z","caller":"traceutil/trace.go:171","msg":"trace[1677912051] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"343.763755ms","start":"2026-06-03T17:40:37.042526Z","end":"2026-06-03T17:40:37.38629Z","steps":["trace[1677912051] 'process raft request' (duration: 144.880427ms)","trace[1677912051] 'compare' (duration: 198.25397ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T17:40:37.386341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T17:40:37.042495Z","time spent":"343.831258ms","remote":"127.0.0.1:41018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-03T17:40:42.800979Z","caller":"traceutil/trace.go:171","msg":"trace[96959376] linearizableReadLoop","detail":"{readStateIndex:655; appliedIndex:654; }","duration":"162.209165ms","start":"2026-06-03T17:40:42.638714Z","end":"2026-06-03T17:40:42.800923Z","steps":["trace[96959376] 'read index received' (duration: 154.979999ms)","trace[96959376] 'applied index is now lower than readState.Index' (duration: 7.227846ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T17:40:42.801204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.467976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-lmz85\" ","response":"range_response_count:1 size:16957"} {"level":"info","ts":"2026-06-03T17:40:42.801313Z","caller":"traceutil/trace.go:171","msg":"trace[789627911] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"216.525653ms","start":"2026-06-03T17:40:42.584751Z","end":"2026-06-03T17:40:42.801277Z","steps":["trace[789627911] 'process raft request' (duration: 209.914373ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:40:42.801352Z","caller":"traceutil/trace.go:171","msg":"trace[22019292] range","detail":"{range_begin:/registry/pods/kube-system/cilium-lmz85; range_end:; response_count:1; response_revision:633; }","duration":"162.659514ms","start":"2026-06-03T17:40:42.638671Z","end":"2026-06-03T17:40:42.80133Z","steps":["trace[22019292] 'agreement among raft nodes before linearized reading' (duration: 162.385623ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:40:42.935576Z","caller":"traceutil/trace.go:171","msg":"trace[1741007502] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"100.971114ms","start":"2026-06-03T17:40:42.834561Z","end":"2026-06-03T17:40:42.935532Z","steps":["trace[1741007502] 'process raft request' (duration: 57.545306ms)","trace[1741007502] 'compare' (duration: 43.039821ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T17:40:43.570401Z","caller":"traceutil/trace.go:171","msg":"trace[257272803] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"222.824782ms","start":"2026-06-03T17:40:43.347558Z","end":"2026-06-03T17:40:43.570383Z","steps":["trace[257272803] 'process raft request' (duration: 217.170135ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:40:44.921343Z","caller":"traceutil/trace.go:171","msg":"trace[159475423] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"121.35383ms","start":"2026-06-03T17:40:44.799965Z","end":"2026-06-03T17:40:44.921319Z","steps":["trace[159475423] 'process raft request' (duration: 116.27519ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T17:40:57.903419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.935543ms","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-06-03T17:40:57.903531Z","caller":"traceutil/trace.go:171","msg":"trace[1651662939] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:802; }","duration":"259.101969ms","start":"2026-06-03T17:40:57.644406Z","end":"2026-06-03T17:40:57.903508Z","steps":["trace[1651662939] 'range keys from in-memory index tree' (duration: 258.729175ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T17:40:57.903557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.560734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-w66wt\" ","response":"range_response_count:1 size:3963"} {"level":"info","ts":"2026-06-03T17:40:57.903675Z","caller":"traceutil/trace.go:171","msg":"trace[1256290817] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-w66wt; range_end:; response_count:1; response_revision:802; }","duration":"158.709309ms","start":"2026-06-03T17:40:57.74493Z","end":"2026-06-03T17:40:57.903639Z","steps":["trace[1256290817] 'range keys from in-memory index tree' (duration: 158.283975ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:41:11.379317Z","caller":"traceutil/trace.go:171","msg":"trace[895050527] linearizableReadLoop","detail":"{readStateIndex:989; appliedIndex:988; }","duration":"210.566836ms","start":"2026-06-03T17:41:11.168728Z","end":"2026-06-03T17:41:11.379294Z","steps":["trace[895050527] 'read index received' (duration: 210.437102ms)","trace[895050527] 'applied index is now lower than readState.Index' (duration: 128.824µs)"],"step_count":2} {"level":"info","ts":"2026-06-03T17:41:11.379393Z","caller":"traceutil/trace.go:171","msg":"trace[1527187409] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"438.364226ms","start":"2026-06-03T17:41:10.940983Z","end":"2026-06-03T17:41:11.379347Z","steps":["trace[1527187409] 'process raft request' (duration: 438.16371ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T17:41:11.379741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T17:41:10.940953Z","time spent":"438.499921ms","remote":"127.0.0.1:41230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T17:41:11.37971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.925036ms","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-06-03T17:41:11.379893Z","caller":"traceutil/trace.go:171","msg":"trace[135342058] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:959; }","duration":"211.155434ms","start":"2026-06-03T17:41:11.168688Z","end":"2026-06-03T17:41:11.379843Z","steps":["trace[135342058] 'agreement among raft nodes before linearized reading' (duration: 210.754421ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:41:11.788733Z","caller":"traceutil/trace.go:171","msg":"trace[1989170379] linearizableReadLoop","detail":"{readStateIndex:990; appliedIndex:989; }","duration":"409.288394ms","start":"2026-06-03T17:41:11.379417Z","end":"2026-06-03T17:41:11.788706Z","steps":["trace[1989170379] 'read index received' (duration: 400.943805ms)","trace[1989170379] 'applied index is now lower than readState.Index' (duration: 8.343359ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T17:41:11.788824Z","caller":"traceutil/trace.go:171","msg":"trace[741608679] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"590.633951ms","start":"2026-06-03T17:41:11.198171Z","end":"2026-06-03T17:41:11.788805Z","steps":["trace[741608679] 'process raft request' (duration: 582.21243ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T17:41:11.788922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T17:41:11.198126Z","time spent":"590.739485ms","remote":"127.0.0.1:41230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T17:41:11.788964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.47183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.4\" ","response":"range_response_count:1 size:131"} {"level":"info","ts":"2026-06-03T17:41:11.788998Z","caller":"traceutil/trace.go:171","msg":"trace[836671212] range","detail":"{range_begin:/registry/masterleases/199.204.45.4; range_end:; response_count:1; response_revision:960; }","duration":"438.557882ms","start":"2026-06-03T17:41:11.35043Z","end":"2026-06-03T17:41:11.788987Z","steps":["trace[836671212] 'agreement among raft nodes before linearized reading' (duration: 438.370196ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T17:41:11.789031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T17:41:11.350408Z","time spent":"438.615874ms","remote":"127.0.0.1:40996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":154,"request content":"key:\"/registry/masterleases/199.204.45.4\" "} {"level":"info","ts":"2026-06-03T17:41:11.828775Z","caller":"traceutil/trace.go:171","msg":"trace[1005420259] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"444.169147ms","start":"2026-06-03T17:41:11.384575Z","end":"2026-06-03T17:41:11.828745Z","steps":["trace[1005420259] 'process raft request' (duration: 444.040103ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T17:41:11.829016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T17:41:11.384558Z","time spent":"444.371953ms","remote":"127.0.0.1:41230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T17:41:11.978074Z","caller":"traceutil/trace.go:171","msg":"trace[1892726026] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"113.699789ms","start":"2026-06-03T17:41:11.864342Z","end":"2026-06-03T17:41:11.978042Z","steps":["trace[1892726026] 'process raft request' (duration: 48.179546ms)","trace[1892726026] 'compare' (duration: 65.374959ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T17:41:12.226907Z","caller":"traceutil/trace.go:171","msg":"trace[1219847137] linearizableReadLoop","detail":"{readStateIndex:994; appliedIndex:993; }","duration":"133.0571ms","start":"2026-06-03T17:41:12.093827Z","end":"2026-06-03T17:41:12.226884Z","steps":["trace[1219847137] 'read index received' (duration: 132.897765ms)","trace[1219847137] 'applied index is now lower than readState.Index' (duration: 158.555µs)"],"step_count":2} {"level":"info","ts":"2026-06-03T17:41:12.227017Z","caller":"traceutil/trace.go:171","msg":"trace[1616784760] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"152.967222ms","start":"2026-06-03T17:41:12.074034Z","end":"2026-06-03T17:41:12.227001Z","steps":["trace[1616784760] 'process raft request' (duration: 152.683543ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T17:41:12.227117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.281196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-03T17:41:12.227165Z","caller":"traceutil/trace.go:171","msg":"trace[1557131405] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:963; }","duration":"133.371979ms","start":"2026-06-03T17:41:12.093776Z","end":"2026-06-03T17:41:12.227148Z","steps":["trace[1557131405] 'agreement among raft nodes before linearized reading' (duration: 133.232375ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T17:41:12.227251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.890892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-03T17:41:12.227296Z","caller":"traceutil/trace.go:171","msg":"trace[135830242] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:963; }","duration":"129.956573ms","start":"2026-06-03T17:41:12.097331Z","end":"2026-06-03T17:41:12.227287Z","steps":["trace[135830242] 'agreement among raft nodes before linearized reading' (duration: 129.83325ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:41:12.51205Z","caller":"traceutil/trace.go:171","msg":"trace[666648058] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"278.895082ms","start":"2026-06-03T17:41:12.233137Z","end":"2026-06-03T17:41:12.512032Z","steps":["trace[666648058] 'process raft request' (duration: 278.727367ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:41:12.512241Z","caller":"traceutil/trace.go:171","msg":"trace[392076425] linearizableReadLoop","detail":"{readStateIndex:997; appliedIndex:995; }","duration":"213.904875ms","start":"2026-06-03T17:41:12.298313Z","end":"2026-06-03T17:41:12.512218Z","steps":["trace[392076425] 'read index received' (duration: 213.504893ms)","trace[392076425] 'applied index is now lower than readState.Index' (duration: 398.252µs)"],"step_count":2} {"level":"info","ts":"2026-06-03T17:41:12.512285Z","caller":"traceutil/trace.go:171","msg":"trace[316940667] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"275.094445ms","start":"2026-06-03T17:41:12.237181Z","end":"2026-06-03T17:41:12.512275Z","steps":["trace[316940667] 'process raft request' (duration: 274.801277ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T17:41:12.512434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.038323ms","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-06-03T17:41:12.512471Z","caller":"traceutil/trace.go:171","msg":"trace[503361989] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:966; }","duration":"179.117305ms","start":"2026-06-03T17:41:12.333345Z","end":"2026-06-03T17:41:12.512463Z","steps":["trace[503361989] 'agreement among raft nodes before linearized reading' (duration: 178.997842ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T17:41:12.51257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.208774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/kube-system/coredns-7c96b6546b-b6kph\" ","response":"range_response_count:1 size:2274"} {"level":"info","ts":"2026-06-03T17:41:12.512657Z","caller":"traceutil/trace.go:171","msg":"trace[570841847] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/kube-system/coredns-7c96b6546b-b6kph; range_end:; response_count:1; response_revision:966; }","duration":"214.356958ms","start":"2026-06-03T17:41:12.29827Z","end":"2026-06-03T17:41:12.512627Z","steps":["trace[570841847] 'agreement among raft nodes before linearized reading' (duration: 214.0874ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:41:12.646619Z","caller":"traceutil/trace.go:171","msg":"trace[2101234815] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"130.029267ms","start":"2026-06-03T17:41:12.516542Z","end":"2026-06-03T17:41:12.646571Z","steps":["trace[2101234815] 'process raft request' (duration: 102.567173ms)","trace[2101234815] 'compare' (duration: 27.246188ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T17:41:12.646728Z","caller":"traceutil/trace.go:171","msg":"trace[1892555526] linearizableReadLoop","detail":"{readStateIndex:1002; appliedIndex:998; }","duration":"104.42427ms","start":"2026-06-03T17:41:12.542281Z","end":"2026-06-03T17:41:12.646705Z","steps":["trace[1892555526] 'read index received' (duration: 76.914465ms)","trace[1892555526] 'applied index is now lower than readState.Index' (duration: 27.506275ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T17:41:12.646871Z","caller":"traceutil/trace.go:171","msg":"trace[1317782723] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"128.105868ms","start":"2026-06-03T17:41:12.518742Z","end":"2026-06-03T17:41:12.646848Z","steps":["trace[1317782723] 'process raft request' (duration: 127.86304ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:41:12.646876Z","caller":"traceutil/trace.go:171","msg":"trace[2136535149] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"129.914244ms","start":"2026-06-03T17:41:12.516943Z","end":"2026-06-03T17:41:12.646857Z","steps":["trace[2136535149] 'process raft request' (duration: 129.553073ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T17:41:12.646937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.117833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T17:41:12.64701Z","caller":"traceutil/trace.go:171","msg":"trace[477290522] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:971; }","duration":"109.250388ms","start":"2026-06-03T17:41:12.537745Z","end":"2026-06-03T17:41:12.646995Z","steps":["trace[477290522] 'agreement among raft nodes before linearized reading' (duration: 109.109774ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:41:12.647106Z","caller":"traceutil/trace.go:171","msg":"trace[1794587415] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"129.49726ms","start":"2026-06-03T17:41:12.517558Z","end":"2026-06-03T17:41:12.647055Z","steps":["trace[1794587415] 'process raft request' (duration: 128.993145ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:41:12.669792Z","caller":"traceutil/trace.go:171","msg":"trace[1025545166] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"120.786542ms","start":"2026-06-03T17:41:12.548982Z","end":"2026-06-03T17:41:12.669768Z","steps":["trace[1025545166] 'process raft request' (duration: 120.640928ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:41:12.814634Z","caller":"traceutil/trace.go:171","msg":"trace[1021600754] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"158.212004ms","start":"2026-06-03T17:41:12.656396Z","end":"2026-06-03T17:41:12.814608Z","steps":["trace[1021600754] 'process raft request' (duration: 124.391424ms)","trace[1021600754] 'compare' (duration: 33.702236ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T17:41:13.012999Z","caller":"traceutil/trace.go:171","msg":"trace[433814653] linearizableReadLoop","detail":"{readStateIndex:1005; appliedIndex:1003; }","duration":"331.004552ms","start":"2026-06-03T17:41:12.681972Z","end":"2026-06-03T17:41:13.012977Z","steps":["trace[433814653] 'read index received' (duration: 98.926052ms)","trace[433814653] 'applied index is now lower than readState.Index' (duration: 232.0773ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T17:41:13.013146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.148896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T17:41:13.013196Z","caller":"traceutil/trace.go:171","msg":"trace[115845230] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:974; }","duration":"331.252679ms","start":"2026-06-03T17:41:12.681928Z","end":"2026-06-03T17:41:13.013181Z","steps":["trace[115845230] 'agreement among raft nodes before linearized reading' (duration: 331.162466ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T17:41:13.013197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.7752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-b6kph\" ","response":"range_response_count:1 size:5070"} {"level":"info","ts":"2026-06-03T17:41:13.013172Z","caller":"traceutil/trace.go:171","msg":"trace[1607515262] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"338.444679ms","start":"2026-06-03T17:41:12.674671Z","end":"2026-06-03T17:41:13.013116Z","steps":["trace[1607515262] 'process raft request' (duration: 248.826366ms)","trace[1607515262] 'compare' (duration: 89.371296ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T17:41:13.013236Z","caller":"traceutil/trace.go:171","msg":"trace[721656603] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-b6kph; range_end:; response_count:1; response_revision:974; }","duration":"202.853002ms","start":"2026-06-03T17:41:12.810373Z","end":"2026-06-03T17:41:13.013226Z","steps":["trace[721656603] 'agreement among raft nodes before linearized reading' (duration: 202.723368ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T17:41:13.013237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T17:41:12.681908Z","time spent":"331.31912ms","remote":"127.0.0.1:41364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"warn","ts":"2026-06-03T17:41:13.013361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T17:41:12.674649Z","time spent":"338.619406ms","remote":"127.0.0.1:41242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1448,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T17:41:13.137262Z","caller":"traceutil/trace.go:171","msg":"trace[20583166] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"115.695042ms","start":"2026-06-03T17:41:13.021536Z","end":"2026-06-03T17:41:13.137231Z","steps":["trace[20583166] 'process raft request' (duration: 94.734544ms)","trace[20583166] 'compare' (duration: 20.583107ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T17:41:15.80241Z","caller":"traceutil/trace.go:171","msg":"trace[76020917] linearizableReadLoop","detail":"{readStateIndex:1016; appliedIndex:1015; }","duration":"241.234081ms","start":"2026-06-03T17:41:15.561155Z","end":"2026-06-03T17:41:15.802389Z","steps":["trace[76020917] 'read index received' (duration: 241.125978ms)","trace[76020917] 'applied index is now lower than readState.Index' (duration: 107.303µs)"],"step_count":2} {"level":"warn","ts":"2026-06-03T17:41:15.802592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.412086ms","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-06-03T17:41:15.802563Z","caller":"traceutil/trace.go:171","msg":"trace[334134642] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"383.877629ms","start":"2026-06-03T17:41:15.418641Z","end":"2026-06-03T17:41:15.802519Z","steps":["trace[334134642] 'process raft request' (duration: 383.601251ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:41:15.802618Z","caller":"traceutil/trace.go:171","msg":"trace[166366127] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:984; }","duration":"241.488838ms","start":"2026-06-03T17:41:15.561119Z","end":"2026-06-03T17:41:15.802608Z","steps":["trace[166366127] 'agreement among raft nodes before linearized reading' (duration: 241.386935ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T17:41:15.802756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T17:41:15.418622Z","time spent":"384.022492ms","remote":"127.0.0.1:41230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T17:41:16.662722Z","caller":"traceutil/trace.go:171","msg":"trace[792859312] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"116.156864ms","start":"2026-06-03T17:41:16.546542Z","end":"2026-06-03T17:41:16.662699Z","steps":["trace[792859312] 'process raft request' (duration: 115.898256ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T17:41:16.662977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.281169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-03T17:41:16.663005Z","caller":"traceutil/trace.go:171","msg":"trace[670890409] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:996; }","duration":"111.378681ms","start":"2026-06-03T17:41:16.551619Z","end":"2026-06-03T17:41:16.662998Z","steps":["trace[670890409] 'agreement among raft nodes before linearized reading' (duration: 111.243428ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:41:16.662955Z","caller":"traceutil/trace.go:171","msg":"trace[441828478] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"116.239796ms","start":"2026-06-03T17:41:16.546497Z","end":"2026-06-03T17:41:16.662737Z","steps":["trace[441828478] 'process raft request' (duration: 116.144943ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:41:16.798731Z","caller":"traceutil/trace.go:171","msg":"trace[1196534167] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"112.694891ms","start":"2026-06-03T17:41:16.686011Z","end":"2026-06-03T17:41:16.798706Z","steps":["trace[1196534167] 'process raft request' (duration: 110.485536ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:41:16.798882Z","caller":"traceutil/trace.go:171","msg":"trace[1298507304] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"112.63602ms","start":"2026-06-03T17:41:16.68621Z","end":"2026-06-03T17:41:16.798846Z","steps":["trace[1298507304] 'process raft request' (duration: 112.370693ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:41:16.799195Z","caller":"traceutil/trace.go:171","msg":"trace[1625720226] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"109.773405ms","start":"2026-06-03T17:41:16.689407Z","end":"2026-06-03T17:41:16.79918Z","steps":["trace[1625720226] 'process raft request' (duration: 109.301831ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:41:17.234969Z","caller":"traceutil/trace.go:171","msg":"trace[1429315254] transaction","detail":"{read_only:false; response_revision:1053; number_of_response:1; }","duration":"108.909779ms","start":"2026-06-03T17:41:17.126035Z","end":"2026-06-03T17:41:17.234944Z","steps":["trace[1429315254] 'process raft request' (duration: 108.576709ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:41:17.235026Z","caller":"traceutil/trace.go:171","msg":"trace[1978342119] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"108.420185ms","start":"2026-06-03T17:41:17.126578Z","end":"2026-06-03T17:41:17.234998Z","steps":["trace[1978342119] 'process raft request' (duration: 108.304161ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:41:17.378952Z","caller":"traceutil/trace.go:171","msg":"trace[1336502665] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"123.445165ms","start":"2026-06-03T17:41:17.255471Z","end":"2026-06-03T17:41:17.378916Z","steps":["trace[1336502665] 'process raft request' (duration: 123.27423ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:41:17.37905Z","caller":"traceutil/trace.go:171","msg":"trace[1679412230] transaction","detail":"{read_only:false; response_revision:1058; number_of_response:1; }","duration":"123.410234ms","start":"2026-06-03T17:41:17.255621Z","end":"2026-06-03T17:41:17.379031Z","steps":["trace[1679412230] 'process raft request' (duration: 123.223269ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:41:17.379078Z","caller":"traceutil/trace.go:171","msg":"trace[331803473] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"112.748441ms","start":"2026-06-03T17:41:17.266315Z","end":"2026-06-03T17:41:17.379063Z","steps":["trace[331803473] 'process raft request' (duration: 112.680969ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:41:17.379221Z","caller":"traceutil/trace.go:171","msg":"trace[1027679496] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"116.614515ms","start":"2026-06-03T17:41:17.262593Z","end":"2026-06-03T17:41:17.379208Z","steps":["trace[1027679496] 'process raft request' (duration: 116.312916ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:41:17.587636Z","caller":"traceutil/trace.go:171","msg":"trace[662033699] transaction","detail":"{read_only:false; response_revision:1069; number_of_response:1; }","duration":"110.226527ms","start":"2026-06-03T17:41:17.477371Z","end":"2026-06-03T17:41:17.587597Z","steps":["trace[662033699] 'process raft request' (duration: 63.775247ms)","trace[662033699] 'compare' (duration: 46.271915ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T17:41:17.587694Z","caller":"traceutil/trace.go:171","msg":"trace[1118876085] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"110.172895ms","start":"2026-06-03T17:41:17.477503Z","end":"2026-06-03T17:41:17.587676Z","steps":["trace[1118876085] 'process raft request' (duration: 110.026011ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:41:17.587937Z","caller":"traceutil/trace.go:171","msg":"trace[1311918315] transaction","detail":"{read_only:false; response_revision:1072; number_of_response:1; }","duration":"100.585986ms","start":"2026-06-03T17:41:17.487343Z","end":"2026-06-03T17:41:17.587929Z","steps":["trace[1311918315] 'process raft request' (duration: 100.236994ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:41:17.58793Z","caller":"traceutil/trace.go:171","msg":"trace[1903947049] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"107.552199ms","start":"2026-06-03T17:41:17.480334Z","end":"2026-06-03T17:41:17.587886Z","steps":["trace[1903947049] 'process raft request' (duration: 107.21682ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T17:41:17.588047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.267936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/cert-manager/\" range_end:\"/registry/limitranges/cert-manager0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T17:41:17.588081Z","caller":"traceutil/trace.go:171","msg":"trace[953152771] range","detail":"{range_begin:/registry/limitranges/cert-manager/; range_end:/registry/limitranges/cert-manager0; response_count:0; response_revision:1073; }","duration":"100.360049ms","start":"2026-06-03T17:41:17.48771Z","end":"2026-06-03T17:41:17.58807Z","steps":["trace[953152771] 'agreement among raft nodes before linearized reading' (duration: 100.282206ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:41:17.827171Z","caller":"traceutil/trace.go:171","msg":"trace[1796356078] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"140.157394ms","start":"2026-06-03T17:41:17.686993Z","end":"2026-06-03T17:41:17.82715Z","steps":["trace[1796356078] 'process raft request' (duration: 139.531256ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:41:17.82726Z","caller":"traceutil/trace.go:171","msg":"trace[1419667397] linearizableReadLoop","detail":"{readStateIndex:1120; appliedIndex:1116; }","duration":"135.824547ms","start":"2026-06-03T17:41:17.691165Z","end":"2026-06-03T17:41:17.826989Z","steps":["trace[1419667397] 'read index received' (duration: 71.067931ms)","trace[1419667397] 'applied index is now lower than readState.Index' (duration: 64.755576ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T17:41:17.8273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.958701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/cert-manager/cert-manager-cainjector-64b59ddb75-ztnlj\" ","response":"range_response_count:1 size:2702"} {"level":"info","ts":"2026-06-03T17:41:17.827452Z","caller":"traceutil/trace.go:171","msg":"trace[1803921642] range","detail":"{range_begin:/registry/pods/cert-manager/cert-manager-cainjector-64b59ddb75-ztnlj; range_end:; response_count:1; response_revision:1092; }","duration":"136.272721ms","start":"2026-06-03T17:41:17.691136Z","end":"2026-06-03T17:41:17.827408Z","steps":["trace[1803921642] 'agreement among raft nodes before linearized reading' (duration: 135.866919ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:41:17.827515Z","caller":"traceutil/trace.go:171","msg":"trace[1748814591] transaction","detail":"{read_only:false; response_revision:1092; number_of_response:1; }","duration":"134.341394ms","start":"2026-06-03T17:41:17.693138Z","end":"2026-06-03T17:41:17.82748Z","steps":["trace[1748814591] 'process raft request' (duration: 133.817508ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:41:17.827612Z","caller":"traceutil/trace.go:171","msg":"trace[1707350043] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"136.498308ms","start":"2026-06-03T17:41:17.691085Z","end":"2026-06-03T17:41:17.827584Z","steps":["trace[1707350043] 'process raft request' (duration: 135.682874ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:41:17.827613Z","caller":"traceutil/trace.go:171","msg":"trace[2071569776] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"134.387775ms","start":"2026-06-03T17:41:17.693086Z","end":"2026-06-03T17:41:17.827474Z","steps":["trace[2071569776] 'process raft request' (duration: 133.831689ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:41:17.827755Z","caller":"traceutil/trace.go:171","msg":"trace[892026978] transaction","detail":"{read_only:false; response_revision:1088; number_of_response:1; }","duration":"136.521318ms","start":"2026-06-03T17:41:17.691219Z","end":"2026-06-03T17:41:17.82774Z","steps":["trace[892026978] 'process raft request' (duration: 135.598371ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:41:17.827723Z","caller":"traceutil/trace.go:171","msg":"trace[409818668] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"136.776346ms","start":"2026-06-03T17:41:17.690913Z","end":"2026-06-03T17:41:17.82769Z","steps":["trace[409818668] 'process raft request' (duration: 135.796797ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:41:17.827877Z","caller":"traceutil/trace.go:171","msg":"trace[2052723751] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"135.690523ms","start":"2026-06-03T17:41:17.692174Z","end":"2026-06-03T17:41:17.827865Z","steps":["trace[2052723751] 'process raft request' (duration: 134.714484ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:41:17.827441Z","caller":"traceutil/trace.go:171","msg":"trace[1281627576] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"135.954491ms","start":"2026-06-03T17:41:17.691414Z","end":"2026-06-03T17:41:17.827369Z","steps":["trace[1281627576] 'process raft request' (duration: 135.439266ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:41:17.942723Z","caller":"traceutil/trace.go:171","msg":"trace[1442537367] linearizableReadLoop","detail":"{readStateIndex:1127; appliedIndex:1125; }","duration":"115.421279ms","start":"2026-06-03T17:41:17.827275Z","end":"2026-06-03T17:41:17.942696Z","steps":["trace[1442537367] 'read index received' (duration: 99.321787ms)","trace[1442537367] 'applied index is now lower than readState.Index' (duration: 16.098012ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T17:41:17.942754Z","caller":"traceutil/trace.go:171","msg":"trace[1288076286] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"173.778639ms","start":"2026-06-03T17:41:17.768939Z","end":"2026-06-03T17:41:17.942718Z","steps":["trace[1288076286] 'process raft request' (duration: 157.578244ms)","trace[1288076286] 'compare' (duration: 15.956938ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T17:41:17.943113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.009161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/cert-manager/cert-manager-startupapicheck\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-03T17:41:17.943216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.815433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/cert-manager/cert-manager-webhook\" ","response":"range_response_count:1 size:4640"} {"level":"info","ts":"2026-06-03T17:41:17.94323Z","caller":"traceutil/trace.go:171","msg":"trace[1441448253] range","detail":"{range_begin:/registry/serviceaccounts/cert-manager/cert-manager-startupapicheck; range_end:; response_count:0; response_revision:1094; }","duration":"250.157155ms","start":"2026-06-03T17:41:17.693043Z","end":"2026-06-03T17:41:17.9432Z","steps":["trace[1441448253] 'agreement among raft nodes before linearized reading' (duration: 249.914588ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T17:41:17.943255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.914407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/cert-manager/cert-manager\" ","response":"range_response_count:1 size:4374"} {"level":"info","ts":"2026-06-03T17:41:17.943279Z","caller":"traceutil/trace.go:171","msg":"trace[1972578105] range","detail":"{range_begin:/registry/deployments/cert-manager/cert-manager-webhook; range_end:; response_count:1; response_revision:1094; }","duration":"251.901496ms","start":"2026-06-03T17:41:17.691359Z","end":"2026-06-03T17:41:17.94326Z","steps":["trace[1972578105] 'agreement among raft nodes before linearized reading' (duration: 251.68899ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:41:17.943306Z","caller":"traceutil/trace.go:171","msg":"trace[1851593987] range","detail":"{range_begin:/registry/deployments/cert-manager/cert-manager; range_end:; response_count:1; response_revision:1094; }","duration":"252.004959ms","start":"2026-06-03T17:41:17.691292Z","end":"2026-06-03T17:41:17.943296Z","steps":["trace[1851593987] 'agreement among raft nodes before linearized reading' (duration: 251.832124ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:41:17.943064Z","caller":"traceutil/trace.go:171","msg":"trace[1989829614] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"133.400586ms","start":"2026-06-03T17:41:17.809637Z","end":"2026-06-03T17:41:17.943038Z","steps":["trace[1989829614] 'process raft request' (duration: 132.989274ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T17:41:17.943623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.435746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-03T17:41:17.943718Z","caller":"traceutil/trace.go:171","msg":"trace[749163561] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1094; }","duration":"245.585402ms","start":"2026-06-03T17:41:17.698111Z","end":"2026-06-03T17:41:17.943697Z","steps":["trace[749163561] 'agreement among raft nodes before linearized reading' (duration: 245.030475ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:41:18.02562Z","caller":"traceutil/trace.go:171","msg":"trace[587227916] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"191.0667ms","start":"2026-06-03T17:41:17.834521Z","end":"2026-06-03T17:41:18.025588Z","steps":["trace[587227916] 'process raft request' (duration: 190.569515ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:41:18.026051Z","caller":"traceutil/trace.go:171","msg":"trace[949303638] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"188.084772ms","start":"2026-06-03T17:41:17.837932Z","end":"2026-06-03T17:41:18.026016Z","steps":["trace[949303638] 'process raft request' (duration: 187.477284ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:41:18.026177Z","caller":"traceutil/trace.go:171","msg":"trace[1943714502] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"190.380419ms","start":"2026-06-03T17:41:17.835714Z","end":"2026-06-03T17:41:18.026095Z","steps":["trace[1943714502] 'process raft request' (duration: 189.512624ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:41:18.026336Z","caller":"traceutil/trace.go:171","msg":"trace[683189912] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"189.345619ms","start":"2026-06-03T17:41:17.836958Z","end":"2026-06-03T17:41:18.026303Z","steps":["trace[683189912] 'process raft request' (duration: 188.302669ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T17:41:18.026588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.437762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/cert-manager/cert-manager-cainjector\" ","response":"range_response_count:1 size:986"} {"level":"info","ts":"2026-06-03T17:41:18.026649Z","caller":"traceutil/trace.go:171","msg":"trace[663752580] range","detail":"{range_begin:/registry/serviceaccounts/cert-manager/cert-manager-cainjector; range_end:; response_count:1; response_revision:1098; }","duration":"188.542215ms","start":"2026-06-03T17:41:17.838089Z","end":"2026-06-03T17:41:18.026631Z","steps":["trace[663752580] 'agreement among raft nodes before linearized reading' (duration: 188.331489ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T17:41:18.028045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.591283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/cert-manager/cert-manager-webhook-548949fc64\" ","response":"range_response_count:1 size:4690"} {"level":"info","ts":"2026-06-03T17:41:18.028108Z","caller":"traceutil/trace.go:171","msg":"trace[993107738] range","detail":"{range_begin:/registry/replicasets/cert-manager/cert-manager-webhook-548949fc64; range_end:; response_count:1; response_revision:1098; }","duration":"192.675656ms","start":"2026-06-03T17:41:17.835414Z","end":"2026-06-03T17:41:18.02809Z","steps":["trace[993107738] 'agreement among raft nodes before linearized reading' (duration: 190.610666ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:41:18.109837Z","caller":"traceutil/trace.go:171","msg":"trace[1230542300] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"159.368708ms","start":"2026-06-03T17:41:17.950438Z","end":"2026-06-03T17:41:18.109807Z","steps":["trace[1230542300] 'process raft request' (duration: 101.093868ms)","trace[1230542300] 'compare' (duration: 58.068874ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T17:41:18.110095Z","caller":"traceutil/trace.go:171","msg":"trace[1371764490] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"157.914905ms","start":"2026-06-03T17:41:17.952158Z","end":"2026-06-03T17:41:18.110073Z","steps":["trace[1371764490] 'process raft request' (duration: 157.598706ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T17:41:18.110315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.399876ms","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-06-03T17:41:18.110812Z","caller":"traceutil/trace.go:171","msg":"trace[735299919] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1100; }","duration":"121.925801ms","start":"2026-06-03T17:41:17.988832Z","end":"2026-06-03T17:41:18.110758Z","steps":["trace[735299919] 'agreement among raft nodes before linearized reading' (duration: 121.266113ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T17:41:18.33814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.575545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/cert-manager/cert-manager-webhook-548949fc64\" ","response":"range_response_count:1 size:4735"} {"level":"info","ts":"2026-06-03T17:41:18.338246Z","caller":"traceutil/trace.go:171","msg":"trace[910991018] range","detail":"{range_begin:/registry/replicasets/cert-manager/cert-manager-webhook-548949fc64; range_end:; response_count:1; response_revision:1108; }","duration":"132.7131ms","start":"2026-06-03T17:41:18.205505Z","end":"2026-06-03T17:41:18.338218Z","steps":["trace[910991018] 'agreement among raft nodes before linearized reading' (duration: 132.479243ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:41:18.33831Z","caller":"traceutil/trace.go:171","msg":"trace[582888055] transaction","detail":"{read_only:false; response_revision:1108; number_of_response:1; }","duration":"130.219186ms","start":"2026-06-03T17:41:18.208017Z","end":"2026-06-03T17:41:18.338236Z","steps":["trace[582888055] 'process raft request' (duration: 129.733052ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:41:18.743213Z","caller":"traceutil/trace.go:171","msg":"trace[623741187] linearizableReadLoop","detail":"{readStateIndex:1143; appliedIndex:1142; }","duration":"315.09426ms","start":"2026-06-03T17:41:18.428096Z","end":"2026-06-03T17:41:18.743191Z","steps":["trace[623741187] 'read index received' (duration: 314.73034ms)","trace[623741187] 'applied index is now lower than readState.Index' (duration: 362.99µs)"],"step_count":2} {"level":"info","ts":"2026-06-03T17:41:18.743293Z","caller":"traceutil/trace.go:171","msg":"trace[529827358] transaction","detail":"{read_only:false; response_revision:1110; number_of_response:1; }","duration":"348.347223ms","start":"2026-06-03T17:41:18.394902Z","end":"2026-06-03T17:41:18.74325Z","steps":["trace[529827358] 'process raft request' (duration: 348.046064ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T17:41:18.743449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T17:41:18.394881Z","time spent":"348.471787ms","remote":"127.0.0.1:41076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":211618,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T17:41:18.743471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.350317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-03T17:41:18.743548Z","caller":"traceutil/trace.go:171","msg":"trace[388363416] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1110; }","duration":"315.464061ms","start":"2026-06-03T17:41:18.428059Z","end":"2026-06-03T17:41:18.743523Z","steps":["trace[388363416] 'agreement among raft nodes before linearized reading' (duration: 315.232834ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T17:41:18.743582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T17:41:18.428042Z","time spent":"315.533492ms","remote":"127.0.0.1:41230","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-06-03T17:41:18.92238Z","caller":"traceutil/trace.go:171","msg":"trace[446810339] transaction","detail":"{read_only:false; response_revision:1113; number_of_response:1; }","duration":"161.355611ms","start":"2026-06-03T17:41:18.760999Z","end":"2026-06-03T17:41:18.922354Z","steps":["trace[446810339] 'process raft request' (duration: 161.3137ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:41:18.922498Z","caller":"traceutil/trace.go:171","msg":"trace[1612150424] linearizableReadLoop","detail":"{readStateIndex:1144; appliedIndex:1143; }","duration":"179.182937ms","start":"2026-06-03T17:41:18.743295Z","end":"2026-06-03T17:41:18.922478Z","steps":["trace[1612150424] 'read index received' (duration: 111.33607ms)","trace[1612150424] 'applied index is now lower than readState.Index' (duration: 67.844337ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T17:41:18.922538Z","caller":"traceutil/trace.go:171","msg":"trace[1205589862] transaction","detail":"{read_only:false; response_revision:1112; number_of_response:1; }","duration":"174.038128ms","start":"2026-06-03T17:41:18.74847Z","end":"2026-06-03T17:41:18.922508Z","steps":["trace[1205589862] 'process raft request' (duration: 173.76298ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:41:18.922977Z","caller":"traceutil/trace.go:171","msg":"trace[2075889710] transaction","detail":"{read_only:false; response_revision:1111; number_of_response:1; }","duration":"202.287107ms","start":"2026-06-03T17:41:18.720671Z","end":"2026-06-03T17:41:18.922958Z","steps":["trace[2075889710] 'process raft request' (duration: 133.961076ms)","trace[2075889710] 'compare' (duration: 67.070305ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T17:41:18.92319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.878186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-06-03T17:41:18.923166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.383328ms","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-06-03T17:41:18.923264Z","caller":"traceutil/trace.go:171","msg":"trace[138650367] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1113; }","duration":"190.990349ms","start":"2026-06-03T17:41:18.732257Z","end":"2026-06-03T17:41:18.923247Z","steps":["trace[138650367] 'agreement among raft nodes before linearized reading' (duration: 190.825605ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T17:41:18.923186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.077099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T17:41:18.923299Z","caller":"traceutil/trace.go:171","msg":"trace[2041180790] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1113; }","duration":"385.245364ms","start":"2026-06-03T17:41:18.538046Z","end":"2026-06-03T17:41:18.923292Z","steps":["trace[2041180790] 'agreement among raft nodes before linearized reading' (duration: 385.072439ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T17:41:18.923323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T17:41:18.538028Z","time spent":"385.284594ms","remote":"127.0.0.1:40954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-03T17:41:18.923272Z","caller":"traceutil/trace.go:171","msg":"trace[1887453370] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1113; }","duration":"103.539783ms","start":"2026-06-03T17:41:18.819723Z","end":"2026-06-03T17:41:18.923263Z","steps":["trace[1887453370] 'agreement among raft nodes before linearized reading' (duration: 103.335617ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:41:19.130365Z","caller":"traceutil/trace.go:171","msg":"trace[643580049] linearizableReadLoop","detail":"{readStateIndex:1148; appliedIndex:1146; }","duration":"198.500254ms","start":"2026-06-03T17:41:18.931847Z","end":"2026-06-03T17:41:19.130347Z","steps":["trace[643580049] 'read index received' (duration: 36.109707ms)","trace[643580049] 'applied index is now lower than readState.Index' (duration: 162.389857ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T17:41:19.130454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.588176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/cert-manager/cert-manager-startupapicheck:create-cert\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T17:41:19.130482Z","caller":"traceutil/trace.go:171","msg":"trace[669745780] range","detail":"{range_begin:/registry/rolebindings/cert-manager/cert-manager-startupapicheck:create-cert; range_end:; response_count:0; response_revision:1116; }","duration":"198.662618ms","start":"2026-06-03T17:41:18.931811Z","end":"2026-06-03T17:41:19.130473Z","steps":["trace[669745780] 'agreement among raft nodes before linearized reading' (duration: 198.603486ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:41:19.130524Z","caller":"traceutil/trace.go:171","msg":"trace[516891083] transaction","detail":"{read_only:false; response_revision:1116; number_of_response:1; }","duration":"197.045551ms","start":"2026-06-03T17:41:18.933448Z","end":"2026-06-03T17:41:19.130493Z","steps":["trace[516891083] 'process raft request' (duration: 196.859255ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:41:19.130633Z","caller":"traceutil/trace.go:171","msg":"trace[858889749] transaction","detail":"{read_only:false; response_revision:1115; number_of_response:1; }","duration":"201.730557ms","start":"2026-06-03T17:41:18.928882Z","end":"2026-06-03T17:41:19.130612Z","steps":["trace[858889749] 'process raft request' (duration: 113.807521ms)","trace[858889749] 'compare' (duration: 87.508754ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T17:41:19.474307Z","caller":"traceutil/trace.go:171","msg":"trace[625878461] linearizableReadLoop","detail":"{readStateIndex:1152; appliedIndex:1151; }","duration":"154.805138ms","start":"2026-06-03T17:41:19.319478Z","end":"2026-06-03T17:41:19.474283Z","steps":["trace[625878461] 'read index received' (duration: 118.271028ms)","trace[625878461] 'applied index is now lower than readState.Index' (duration: 36.53306ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T17:41:19.474455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.957812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/cert-manager/cert-manager-startupapicheck\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T17:41:19.474488Z","caller":"traceutil/trace.go:171","msg":"trace[920847394] range","detail":"{range_begin:/registry/jobs/cert-manager/cert-manager-startupapicheck; range_end:; response_count:0; response_revision:1119; }","duration":"155.031704ms","start":"2026-06-03T17:41:19.319445Z","end":"2026-06-03T17:41:19.474477Z","steps":["trace[920847394] 'agreement among raft nodes before linearized reading' (duration: 154.938882ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:41:19.474526Z","caller":"traceutil/trace.go:171","msg":"trace[177301310] transaction","detail":"{read_only:false; response_revision:1119; number_of_response:1; }","duration":"180.496277ms","start":"2026-06-03T17:41:19.29396Z","end":"2026-06-03T17:41:19.474456Z","steps":["trace[177301310] 'process raft request' (duration: 143.811363ms)","trace[177301310] 'compare' (duration: 36.357764ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T17:41:19.638519Z","caller":"traceutil/trace.go:171","msg":"trace[1039928782] transaction","detail":"{read_only:false; response_revision:1121; number_of_response:1; }","duration":"155.663602ms","start":"2026-06-03T17:41:19.482826Z","end":"2026-06-03T17:41:19.63849Z","steps":["trace[1039928782] 'process raft request' (duration: 96.084851ms)","trace[1039928782] 'compare' (duration: 59.441368ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T17:41:19.651005Z","caller":"traceutil/trace.go:171","msg":"trace[1315809669] transaction","detail":"{read_only:false; response_revision:1122; number_of_response:1; }","duration":"158.801033ms","start":"2026-06-03T17:41:19.492187Z","end":"2026-06-03T17:41:19.650988Z","steps":["trace[1315809669] 'process raft request' (duration: 158.719351ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:41:19.736823Z","caller":"traceutil/trace.go:171","msg":"trace[813595195] transaction","detail":"{read_only:false; response_revision:1123; number_of_response:1; }","duration":"153.398188ms","start":"2026-06-03T17:41:19.583408Z","end":"2026-06-03T17:41:19.736806Z","steps":["trace[813595195] 'process raft request' (duration: 153.244553ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:41:19.934531Z","caller":"traceutil/trace.go:171","msg":"trace[1702904399] transaction","detail":"{read_only:false; response_revision:1125; number_of_response:1; }","duration":"135.569715ms","start":"2026-06-03T17:41:19.79893Z","end":"2026-06-03T17:41:19.9345Z","steps":["trace[1702904399] 'process raft request' (duration: 86.950268ms)","trace[1702904399] 'compare' (duration: 48.426611ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T17:41:20.061609Z","caller":"traceutil/trace.go:171","msg":"trace[193095581] transaction","detail":"{read_only:false; response_revision:1127; number_of_response:1; }","duration":"121.62201ms","start":"2026-06-03T17:41:19.939955Z","end":"2026-06-03T17:41:20.061577Z","steps":["trace[193095581] 'process raft request' (duration: 100.387335ms)","trace[193095581] 'compare' (duration: 21.045329ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T17:41:20.061663Z","caller":"traceutil/trace.go:171","msg":"trace[911467615] transaction","detail":"{read_only:false; response_revision:1129; number_of_response:1; }","duration":"113.148836ms","start":"2026-06-03T17:41:19.948494Z","end":"2026-06-03T17:41:20.061642Z","steps":["trace[911467615] 'process raft request' (duration: 113.082494ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:41:20.061801Z","caller":"traceutil/trace.go:171","msg":"trace[1364179082] transaction","detail":"{read_only:false; response_revision:1128; number_of_response:1; }","duration":"121.581818ms","start":"2026-06-03T17:41:19.94021Z","end":"2026-06-03T17:41:20.061791Z","steps":["trace[1364179082] 'process raft request' (duration: 121.295689ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:41:20.25769Z","caller":"traceutil/trace.go:171","msg":"trace[875141246] linearizableReadLoop","detail":"{readStateIndex:1165; appliedIndex:1164; }","duration":"112.558164ms","start":"2026-06-03T17:41:20.145104Z","end":"2026-06-03T17:41:20.257662Z","steps":["trace[875141246] 'read index received' (duration: 95.315293ms)","trace[875141246] 'applied index is now lower than readState.Index' (duration: 17.241541ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T17:41:20.257933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.798191ms","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-06-03T17:41:20.257979Z","caller":"traceutil/trace.go:171","msg":"trace[832136661] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1132; }","duration":"112.889934ms","start":"2026-06-03T17:41:20.145078Z","end":"2026-06-03T17:41:20.257968Z","steps":["trace[832136661] 'agreement among raft nodes before linearized reading' (duration: 112.682698ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:41:20.258597Z","caller":"traceutil/trace.go:171","msg":"trace[1683245428] transaction","detail":"{read_only:false; response_revision:1132; number_of_response:1; }","duration":"156.661779ms","start":"2026-06-03T17:41:20.101254Z","end":"2026-06-03T17:41:20.257916Z","steps":["trace[1683245428] 'process raft request' (duration: 139.15014ms)","trace[1683245428] 'compare' (duration: 17.100577ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T17:41:21.113277Z","caller":"traceutil/trace.go:171","msg":"trace[16522935] transaction","detail":"{read_only:false; response_revision:1137; number_of_response:1; }","duration":"136.869768ms","start":"2026-06-03T17:41:20.976377Z","end":"2026-06-03T17:41:21.113247Z","steps":["trace[16522935] 'process raft request' (duration: 136.675832ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:41:21.213979Z","caller":"traceutil/trace.go:171","msg":"trace[1274093801] transaction","detail":"{read_only:false; response_revision:1138; number_of_response:1; }","duration":"142.91201ms","start":"2026-06-03T17:41:21.071028Z","end":"2026-06-03T17:41:21.21394Z","steps":["trace[1274093801] 'process raft request' (duration: 116.795524ms)","trace[1274093801] 'compare' (duration: 25.918081ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T17:41:21.238373Z","caller":"traceutil/trace.go:171","msg":"trace[1159177664] linearizableReadLoop","detail":"{readStateIndex:1172; appliedIndex:1170; }","duration":"103.574231ms","start":"2026-06-03T17:41:21.13478Z","end":"2026-06-03T17:41:21.238354Z","steps":["trace[1159177664] 'read index received' (duration: 53.053236ms)","trace[1159177664] 'applied index is now lower than readState.Index' (duration: 50.520115ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T17:41:21.238395Z","caller":"traceutil/trace.go:171","msg":"trace[735230878] transaction","detail":"{read_only:false; response_revision:1139; number_of_response:1; }","duration":"123.189054ms","start":"2026-06-03T17:41:21.115183Z","end":"2026-06-03T17:41:21.238372Z","steps":["trace[735230878] 'process raft request' (duration: 123.0741ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T17:41:21.238657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.874459ms","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-06-03T17:41:21.238682Z","caller":"traceutil/trace.go:171","msg":"trace[736410769] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1139; }","duration":"103.954271ms","start":"2026-06-03T17:41:21.134722Z","end":"2026-06-03T17:41:21.238676Z","steps":["trace[736410769] 'agreement among raft nodes before linearized reading' (duration: 103.701464ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:41:21.446238Z","caller":"traceutil/trace.go:171","msg":"trace[1455378459] transaction","detail":"{read_only:false; response_revision:1140; number_of_response:1; }","duration":"222.69188ms","start":"2026-06-03T17:41:21.223511Z","end":"2026-06-03T17:41:21.446203Z","steps":["trace[1455378459] 'process raft request' (duration: 222.440183ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:41:21.468973Z","caller":"traceutil/trace.go:171","msg":"trace[281332058] linearizableReadLoop","detail":"{readStateIndex:1174; appliedIndex:1172; }","duration":"117.427821ms","start":"2026-06-03T17:41:21.351525Z","end":"2026-06-03T17:41:21.468953Z","steps":["trace[281332058] 'read index received' (duration: 94.464434ms)","trace[281332058] 'applied index is now lower than readState.Index' (duration: 22.961497ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T17:41:21.469066Z","caller":"traceutil/trace.go:171","msg":"trace[28890637] transaction","detail":"{read_only:false; response_revision:1141; number_of_response:1; }","duration":"226.99127ms","start":"2026-06-03T17:41:21.242039Z","end":"2026-06-03T17:41:21.46903Z","steps":["trace[28890637] 'process raft request' (duration: 226.754894ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T17:41:21.469294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.74315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.4\" ","response":"range_response_count:1 size:131"} {"level":"info","ts":"2026-06-03T17:41:21.469366Z","caller":"traceutil/trace.go:171","msg":"trace[653122038] range","detail":"{range_begin:/registry/masterleases/199.204.45.4; range_end:; response_count:1; response_revision:1141; }","duration":"117.896494ms","start":"2026-06-03T17:41:21.351453Z","end":"2026-06-03T17:41:21.46935Z","steps":["trace[653122038] 'agreement among raft nodes before linearized reading' (duration: 117.595296ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:41:35.522522Z","caller":"traceutil/trace.go:171","msg":"trace[1739478297] transaction","detail":"{read_only:false; response_revision:1233; number_of_response:1; }","duration":"215.026195ms","start":"2026-06-03T17:41:35.307473Z","end":"2026-06-03T17:41:35.522499Z","steps":["trace[1739478297] 'process raft request' (duration: 214.87388ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:42:25.415684Z","caller":"traceutil/trace.go:171","msg":"trace[1244689428] linearizableReadLoop","detail":"{readStateIndex:1980; appliedIndex:1979; }","duration":"199.069469ms","start":"2026-06-03T17:42:25.216589Z","end":"2026-06-03T17:42:25.415658Z","steps":["trace[1244689428] 'read index received' (duration: 198.763624ms)","trace[1244689428] 'applied index is now lower than readState.Index' (duration: 304.995µs)"],"step_count":2} {"level":"warn","ts":"2026-06-03T17:42:25.415972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.363154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-06-03T17:42:25.416016Z","caller":"traceutil/trace.go:171","msg":"trace[78150143] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1909; }","duration":"199.452045ms","start":"2026-06-03T17:42:25.21655Z","end":"2026-06-03T17:42:25.416002Z","steps":["trace[78150143] 'agreement among raft nodes before linearized reading' (duration: 199.220631ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:42:25.41629Z","caller":"traceutil/trace.go:171","msg":"trace[1146219513] transaction","detail":"{read_only:false; response_revision:1909; number_of_response:1; }","duration":"232.281985ms","start":"2026-06-03T17:42:25.18398Z","end":"2026-06-03T17:42:25.416262Z","steps":["trace[1146219513] 'process raft request' (duration: 231.512011ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T17:42:25.76076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.159926ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-03T17:42:25.760934Z","caller":"traceutil/trace.go:171","msg":"trace[1769299242] transaction","detail":"{read_only:false; response_revision:1910; number_of_response:1; }","duration":"337.224394ms","start":"2026-06-03T17:42:25.423684Z","end":"2026-06-03T17:42:25.760908Z","steps":["trace[1769299242] 'process raft request' (duration: 194.801083ms)","trace[1769299242] 'compare' (duration: 141.920872ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T17:42:25.761088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T17:42:25.423666Z","time spent":"337.311625ms","remote":"127.0.0.1:41230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T17:42:25.774705Z","caller":"traceutil/trace.go:171","msg":"trace[251472551] transaction","detail":"{read_only:false; response_revision:1911; number_of_response:1; }","duration":"350.631601ms","start":"2026-06-03T17:42:25.424054Z","end":"2026-06-03T17:42:25.774686Z","steps":["trace[251472551] 'process raft request' (duration: 350.455488ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T17:42:25.774873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T17:42:25.424042Z","time spent":"350.726042ms","remote":"127.0.0.1:41168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":892,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T17:42:25.774954Z","caller":"traceutil/trace.go:171","msg":"trace[1836168024] transaction","detail":"{read_only:false; response_revision:1912; number_of_response:1; }","duration":"350.820644ms","start":"2026-06-03T17:42:25.424114Z","end":"2026-06-03T17:42:25.774935Z","steps":["trace[1836168024] 'process raft request' (duration: 350.528779ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T17:42:25.775072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T17:42:25.424094Z","time spent":"350.913995ms","remote":"127.0.0.1:41242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1470,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T17:42:25.775168Z","caller":"traceutil/trace.go:171","msg":"trace[296476534] transaction","detail":"{read_only:false; response_revision:1913; number_of_response:1; }","duration":"351.004717ms","start":"2026-06-03T17:42:25.424156Z","end":"2026-06-03T17:42:25.77516Z","steps":["trace[296476534] 'process raft request' (duration: 350.676011ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T17:42:25.775206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T17:42:25.424149Z","time spent":"351.035227ms","remote":"127.0.0.1:41168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":976,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T17:42:25.775217Z","caller":"traceutil/trace.go:171","msg":"trace[153904049] linearizableReadLoop","detail":"{readStateIndex:1986; appliedIndex:1980; }","duration":"308.455047ms","start":"2026-06-03T17:42:25.466751Z","end":"2026-06-03T17:42:25.775206Z","steps":["trace[153904049] 'read index received' (duration: 151.754515ms)","trace[153904049] 'applied index is now lower than readState.Index' (duration: 156.698222ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T17:42:25.775355Z","caller":"traceutil/trace.go:171","msg":"trace[88300708] transaction","detail":"{read_only:false; response_revision:1915; number_of_response:1; }","duration":"349.416289ms","start":"2026-06-03T17:42:25.425928Z","end":"2026-06-03T17:42:25.775345Z","steps":["trace[88300708] 'process raft request' (duration: 349.221816ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T17:42:25.77541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T17:42:25.425919Z","time spent":"349.46517ms","remote":"127.0.0.1:41172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3724,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T17:42:25.775423Z","caller":"traceutil/trace.go:171","msg":"trace[1434895736] transaction","detail":"{read_only:false; response_revision:1914; number_of_response:1; }","duration":"351.253341ms","start":"2026-06-03T17:42:25.424162Z","end":"2026-06-03T17:42:25.775415Z","steps":["trace[1434895736] 'process raft request' (duration: 350.939176ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T17:42:25.775461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T17:42:25.424154Z","time spent":"351.286452ms","remote":"127.0.0.1:41242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1528,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T17:42:25.775458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.703511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T17:42:25.775494Z","caller":"traceutil/trace.go:171","msg":"trace[643423833] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:1915; }","duration":"308.771413ms","start":"2026-06-03T17:42:25.466713Z","end":"2026-06-03T17:42:25.775484Z","steps":["trace[643423833] 'agreement among raft nodes before linearized reading' (duration: 308.717062ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T17:42:25.775549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T17:42:25.466697Z","time spent":"308.845884ms","remote":"127.0.0.1:46968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"info","ts":"2026-06-03T17:42:26.310132Z","caller":"traceutil/trace.go:171","msg":"trace[777560120] linearizableReadLoop","detail":"{readStateIndex:1987; appliedIndex:1986; }","duration":"534.87521ms","start":"2026-06-03T17:42:25.775237Z","end":"2026-06-03T17:42:26.310112Z","steps":["trace[777560120] 'read index received' (duration: 483.311236ms)","trace[777560120] 'applied index is now lower than readState.Index' (duration: 51.563244ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T17:42:26.310272Z","caller":"traceutil/trace.go:171","msg":"trace[678491063] transaction","detail":"{read_only:false; response_revision:1916; number_of_response:1; }","duration":"675.06982ms","start":"2026-06-03T17:42:25.635193Z","end":"2026-06-03T17:42:26.310263Z","steps":["trace[678491063] 'process raft request' (duration: 623.339604ms)","trace[678491063] 'compare' (duration: 51.285169ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T17:42:26.310681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"772.672921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T17:42:26.310745Z","caller":"traceutil/trace.go:171","msg":"trace[1437147814] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1917; }","duration":"772.770503ms","start":"2026-06-03T17:42:25.537958Z","end":"2026-06-03T17:42:26.310728Z","steps":["trace[1437147814] 'agreement among raft nodes before linearized reading' (duration: 772.673942ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T17:42:26.310773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T17:42:25.537942Z","time spent":"772.824204ms","remote":"127.0.0.1:40964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-03T17:42:26.310768Z","caller":"traceutil/trace.go:171","msg":"trace[187759775] transaction","detail":"{read_only:false; response_revision:1918; number_of_response:1; }","duration":"144.31659ms","start":"2026-06-03T17:42:26.166432Z","end":"2026-06-03T17:42:26.310748Z","steps":["trace[187759775] 'process raft request' (duration: 143.885752ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T17:42:26.3108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"735.113798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-03T17:42:26.310838Z","caller":"traceutil/trace.go:171","msg":"trace[2030780974] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1917; }","duration":"735.20252ms","start":"2026-06-03T17:42:25.575624Z","end":"2026-06-03T17:42:26.310827Z","steps":["trace[2030780974] 'agreement among raft nodes before linearized reading' (duration: 735.033747ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T17:42:26.310873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T17:42:25.575605Z","time spent":"735.259851ms","remote":"127.0.0.1:41230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-03T17:42:26.310889Z","caller":"traceutil/trace.go:171","msg":"trace[435203307] transaction","detail":"{read_only:false; response_revision:1917; number_of_response:1; }","duration":"484.002482ms","start":"2026-06-03T17:42:25.826876Z","end":"2026-06-03T17:42:26.310878Z","steps":["trace[435203307] 'process raft request' (duration: 483.173358ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T17:42:26.310925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T17:42:25.635162Z","time spent":"675.127922ms","remote":"127.0.0.1:41230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T17:42:26.31097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T17:42:25.826848Z","time spent":"484.061394ms","remote":"127.0.0.1:41230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T17:42:26.311122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.863042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7f0af5f5-c38e-4150-b645-6eb74bd0cc7b\" ","response":"range_response_count:1 size:3739"} {"level":"info","ts":"2026-06-03T17:42:26.311206Z","caller":"traceutil/trace.go:171","msg":"trace[533032256] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7f0af5f5-c38e-4150-b645-6eb74bd0cc7b; range_end:; response_count:1; response_revision:1918; }","duration":"245.066487ms","start":"2026-06-03T17:42:26.066114Z","end":"2026-06-03T17:42:26.31118Z","steps":["trace[533032256] 'agreement among raft nodes before linearized reading' (duration: 244.837982ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T17:42:26.312002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.153598ms","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-06-03T17:42:26.312092Z","caller":"traceutil/trace.go:171","msg":"trace[935388844] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1918; }","duration":"403.29918ms","start":"2026-06-03T17:42:25.908775Z","end":"2026-06-03T17:42:26.312074Z","steps":["trace[935388844] 'agreement among raft nodes before linearized reading' (duration: 402.175309ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T17:42:26.312145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T17:42:25.908753Z","time spent":"403.382212ms","remote":"127.0.0.1:41230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-03T17:42:37.948263Z","caller":"traceutil/trace.go:171","msg":"trace[602953242] transaction","detail":"{read_only:false; response_revision:2017; number_of_response:1; }","duration":"118.423215ms","start":"2026-06-03T17:42:37.829809Z","end":"2026-06-03T17:42:37.948232Z","steps":["trace[602953242] 'process raft request' (duration: 118.153069ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:43:04.733544Z","caller":"traceutil/trace.go:171","msg":"trace[915535910] linearizableReadLoop","detail":"{readStateIndex:2259; appliedIndex:2257; }","duration":"101.148281ms","start":"2026-06-03T17:43:04.632375Z","end":"2026-06-03T17:43:04.733523Z","steps":["trace[915535910] 'read index received' (duration: 39.810491ms)","trace[915535910] 'applied index is now lower than readState.Index' (duration: 61.33668ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T17:43:04.733913Z","caller":"traceutil/trace.go:171","msg":"trace[863089399] transaction","detail":"{read_only:false; response_revision:2177; number_of_response:1; }","duration":"119.87854ms","start":"2026-06-03T17:43:04.614021Z","end":"2026-06-03T17:43:04.733899Z","steps":["trace[863089399] 'process raft request' (duration: 58.154394ms)","trace[863089399] 'compare' (duration: 61.196938ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T17:43:04.734005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.554147ms","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-06-03T17:43:04.734128Z","caller":"traceutil/trace.go:171","msg":"trace[641705787] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2178; }","duration":"101.75249ms","start":"2026-06-03T17:43:04.632343Z","end":"2026-06-03T17:43:04.734095Z","steps":["trace[641705787] 'agreement among raft nodes before linearized reading' (duration: 101.440325ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:43:04.734298Z","caller":"traceutil/trace.go:171","msg":"trace[1741423458] transaction","detail":"{read_only:false; response_revision:2178; number_of_response:1; }","duration":"119.878058ms","start":"2026-06-03T17:43:04.614406Z","end":"2026-06-03T17:43:04.734284Z","steps":["trace[1741423458] 'process raft request' (duration: 119.076277ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T17:43:04.970073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.045101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1988"} {"level":"info","ts":"2026-06-03T17:43:04.970136Z","caller":"traceutil/trace.go:171","msg":"trace[190515318] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:2179; }","duration":"120.148822ms","start":"2026-06-03T17:43:04.849973Z","end":"2026-06-03T17:43:04.970122Z","steps":["trace[190515318] 'range keys from in-memory index tree' (duration: 119.910289ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:43:05.147412Z","caller":"traceutil/trace.go:171","msg":"trace[505952477] linearizableReadLoop","detail":"{readStateIndex:2261; appliedIndex:2260; }","duration":"170.107047ms","start":"2026-06-03T17:43:04.977283Z","end":"2026-06-03T17:43:05.14739Z","steps":["trace[505952477] 'read index received' (duration: 169.876614ms)","trace[505952477] 'applied index is now lower than readState.Index' (duration: 229.493µs)"],"step_count":2} {"level":"warn","ts":"2026-06-03T17:43:05.147632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.32755ms","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-06-03T17:43:05.147672Z","caller":"traceutil/trace.go:171","msg":"trace[338047446] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2180; }","duration":"170.422022ms","start":"2026-06-03T17:43:04.97724Z","end":"2026-06-03T17:43:05.147662Z","steps":["trace[338047446] 'agreement among raft nodes before linearized reading' (duration: 170.24717ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:43:05.148644Z","caller":"traceutil/trace.go:171","msg":"trace[1290015165] transaction","detail":"{read_only:false; response_revision:2180; number_of_response:1; }","duration":"177.006269ms","start":"2026-06-03T17:43:04.971624Z","end":"2026-06-03T17:43:05.14863Z","steps":["trace[1290015165] 'process raft request' (duration: 175.620959ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:44:22.908026Z","caller":"traceutil/trace.go:171","msg":"trace[2131159376] transaction","detail":"{read_only:false; response_revision:2706; number_of_response:1; }","duration":"121.230176ms","start":"2026-06-03T17:44:22.786778Z","end":"2026-06-03T17:44:22.908008Z","steps":["trace[2131159376] 'process raft request' (duration: 121.119455ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T17:44:23.519036Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232311794523666828,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-03T17:44:23.639489Z","caller":"traceutil/trace.go:171","msg":"trace[62136624] transaction","detail":"{read_only:false; response_revision:2707; number_of_response:1; }","duration":"713.014453ms","start":"2026-06-03T17:44:22.926447Z","end":"2026-06-03T17:44:23.639461Z","steps":["trace[62136624] 'process raft request' (duration: 703.836598ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T17:44:23.640316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T17:44:22.926413Z","time spent":"713.821413ms","remote":"127.0.0.1:41230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T17:44:23.676326Z","caller":"traceutil/trace.go:171","msg":"trace[2050383895] transaction","detail":"{read_only:false; response_revision:2708; number_of_response:1; }","duration":"742.467991ms","start":"2026-06-03T17:44:22.933836Z","end":"2026-06-03T17:44:23.676304Z","steps":["trace[2050383895] 'process raft request' (duration: 742.250498ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T17:44:23.676522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.226416ms","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-06-03T17:44:23.676578Z","caller":"traceutil/trace.go:171","msg":"trace[129943955] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2710; }","duration":"658.331258ms","start":"2026-06-03T17:44:23.018235Z","end":"2026-06-03T17:44:23.676566Z","steps":["trace[129943955] 'agreement among raft nodes before linearized reading' (duration: 658.150815ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T17:44:23.67662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T17:44:23.018218Z","time spent":"658.393298ms","remote":"127.0.0.1:41230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-03T17:44:23.676694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T17:44:22.933821Z","time spent":"742.665953ms","remote":"127.0.0.1:41230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T17:44:23.67633Z","caller":"traceutil/trace.go:171","msg":"trace[1380608170] linearizableReadLoop","detail":"{readStateIndex:2809; appliedIndex:2805; }","duration":"658.029033ms","start":"2026-06-03T17:44:23.018276Z","end":"2026-06-03T17:44:23.676305Z","steps":["trace[1380608170] 'read index received' (duration: 612.029648ms)","trace[1380608170] 'applied index is now lower than readState.Index' (duration: 45.998245ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T17:44:23.67704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.767559ms","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-06-03T17:44:23.67704Z","caller":"traceutil/trace.go:171","msg":"trace[329765839] transaction","detail":"{read_only:false; response_revision:2709; number_of_response:1; }","duration":"523.414592ms","start":"2026-06-03T17:44:23.153566Z","end":"2026-06-03T17:44:23.676981Z","steps":["trace[329765839] 'process raft request' (duration: 522.659292ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:44:23.677152Z","caller":"traceutil/trace.go:171","msg":"trace[1305758944] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2710; }","duration":"316.905561ms","start":"2026-06-03T17:44:23.360236Z","end":"2026-06-03T17:44:23.677142Z","steps":["trace[1305758944] 'agreement among raft nodes before linearized reading' (duration: 316.710959ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T17:44:23.677252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T17:44:23.360221Z","time spent":"317.000492ms","remote":"127.0.0.1:41230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-03T17:44:23.67737Z","caller":"traceutil/trace.go:171","msg":"trace[1081164911] transaction","detail":"{read_only:false; response_revision:2710; number_of_response:1; }","duration":"479.498682ms","start":"2026-06-03T17:44:23.197857Z","end":"2026-06-03T17:44:23.677355Z","steps":["trace[1081164911] 'process raft request' (duration: 478.410898ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T17:44:23.677476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T17:44:23.19784Z","time spent":"479.572563ms","remote":"127.0.0.1:41230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T17:44:23.677461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T17:44:23.15354Z","time spent":"523.781896ms","remote":"127.0.0.1:41230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T17:44:23.6777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.855536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-06-03T17:44:23.678109Z","caller":"traceutil/trace.go:171","msg":"trace[1292380171] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2710; }","duration":"167.368672ms","start":"2026-06-03T17:44:23.510731Z","end":"2026-06-03T17:44:23.678099Z","steps":["trace[1292380171] 'agreement among raft nodes before linearized reading' (duration: 166.767214ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T17:44:24.029944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.883061ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-03T17:44:24.030276Z","caller":"traceutil/trace.go:171","msg":"trace[106833853] transaction","detail":"{read_only:false; response_revision:2711; number_of_response:1; }","duration":"346.998597ms","start":"2026-06-03T17:44:23.683264Z","end":"2026-06-03T17:44:24.030262Z","steps":["trace[106833853] 'process raft request' (duration: 113.65278ms)","trace[106833853] 'compare' (duration: 232.689279ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T17:44:24.030313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T17:44:23.683224Z","time spent":"347.071757ms","remote":"127.0.0.1:41230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T17:44:24.404648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.880062ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-03T17:44:24.405731Z","caller":"traceutil/trace.go:171","msg":"trace[35923213] transaction","detail":"{read_only:false; response_revision:2712; number_of_response:1; }","duration":"722.201251ms","start":"2026-06-03T17:44:23.683505Z","end":"2026-06-03T17:44:24.405706Z","steps":["trace[35923213] 'process raft request' (duration: 529.799689ms)","trace[35923213] 'compare' (duration: 190.786071ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T17:44:24.405853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T17:44:23.683494Z","time spent":"722.294752ms","remote":"127.0.0.1:41230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T17:44:24.406648Z","caller":"traceutil/trace.go:171","msg":"trace[738812931] transaction","detail":"{read_only:false; response_revision:2713; number_of_response:1; }","duration":"722.95476ms","start":"2026-06-03T17:44:23.683676Z","end":"2026-06-03T17:44:24.406631Z","steps":["trace[738812931] 'process raft request' (duration: 721.042266ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T17:44:24.406753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T17:44:23.683667Z","time spent":"723.033991ms","remote":"127.0.0.1:41230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T17:44:24.407293Z","caller":"traceutil/trace.go:171","msg":"trace[1582665811] linearizableReadLoop","detail":"{readStateIndex:2812; appliedIndex:2809; }","duration":"721.163907ms","start":"2026-06-03T17:44:23.686116Z","end":"2026-06-03T17:44:24.40728Z","steps":["trace[1582665811] 'read index received' (duration: 110.781584ms)","trace[1582665811] 'applied index is now lower than readState.Index' (duration: 610.380923ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T17:44:24.407535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"721.42466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak\" ","response":"range_response_count:1 size:1317"} {"level":"info","ts":"2026-06-03T17:44:24.407576Z","caller":"traceutil/trace.go:171","msg":"trace[1836342225] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak; range_end:; response_count:1; response_revision:2713; }","duration":"721.514291ms","start":"2026-06-03T17:44:23.686052Z","end":"2026-06-03T17:44:24.407566Z","steps":["trace[1836342225] 'agreement among raft nodes before linearized reading' (duration: 721.317609ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T17:44:24.40761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T17:44:23.686027Z","time spent":"721.575662ms","remote":"127.0.0.1:41174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":1340,"request content":"key:\"/registry/services/specs/auth-system/keycloak\" "} {"level":"warn","ts":"2026-06-03T17:44:24.407743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"614.894839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-06-03T17:44:24.407941Z","caller":"traceutil/trace.go:171","msg":"trace[1962416177] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2713; }","duration":"615.101992ms","start":"2026-06-03T17:44:23.79276Z","end":"2026-06-03T17:44:24.407862Z","steps":["trace[1962416177] 'agreement among raft nodes before linearized reading' (duration: 614.782407ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T17:44:24.407982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T17:44:23.792719Z","time spent":"615.253424ms","remote":"127.0.0.1:41230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-03T17:44:24.408185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.394402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18b5a32ebbaf493c\" ","response":"range_response_count:1 size:779"} {"level":"info","ts":"2026-06-03T17:44:24.408283Z","caller":"traceutil/trace.go:171","msg":"trace[1937051757] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18b5a32ebbaf493c; range_end:; response_count:1; response_revision:2713; }","duration":"597.617534ms","start":"2026-06-03T17:44:23.810647Z","end":"2026-06-03T17:44:24.408265Z","steps":["trace[1937051757] 'agreement among raft nodes before linearized reading' (duration: 597.247909ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T17:44:24.408614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T17:44:23.810622Z","time spent":"597.781565ms","remote":"127.0.0.1:41056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":802,"request content":"key:\"/registry/events/auth-system/keycloak-0.18b5a32ebbaf493c\" "} {"level":"warn","ts":"2026-06-03T17:44:24.40939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.482475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-06-03T17:44:24.409447Z","caller":"traceutil/trace.go:171","msg":"trace[1715393834] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2713; }","duration":"342.541406ms","start":"2026-06-03T17:44:24.066892Z","end":"2026-06-03T17:44:24.409433Z","steps":["trace[1715393834] 'agreement among raft nodes before linearized reading' (duration: 342.387074ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T17:44:24.409482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T17:44:24.066838Z","time spent":"342.636166ms","remote":"127.0.0.1:41230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":581,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-06-03T17:44:24.898833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.99828ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-03T17:44:24.899014Z","caller":"traceutil/trace.go:171","msg":"trace[874502814] linearizableReadLoop","detail":"{readStateIndex:2814; appliedIndex:2812; }","duration":"482.726275ms","start":"2026-06-03T17:44:24.416268Z","end":"2026-06-03T17:44:24.898995Z","steps":["trace[874502814] 'read index received' (duration: 38.386038ms)","trace[874502814] 'applied index is now lower than readState.Index' (duration: 444.339057ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T17:44:24.899119Z","caller":"traceutil/trace.go:171","msg":"trace[336661716] transaction","detail":"{read_only:false; response_revision:2715; number_of_response:1; }","duration":"484.782502ms","start":"2026-06-03T17:44:24.414326Z","end":"2026-06-03T17:44:24.899108Z","steps":["trace[336661716] 'process raft request' (duration: 162.400217ms)","trace[336661716] 'compare' (duration: 321.875759ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T17:44:24.899186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T17:44:24.414307Z","time spent":"484.847552ms","remote":"127.0.0.1:41230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T17:44:24.89936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.952063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T17:44:24.899392Z","caller":"traceutil/trace.go:171","msg":"trace[446850162] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2716; }","duration":"360.051954ms","start":"2026-06-03T17:44:24.539331Z","end":"2026-06-03T17:44:24.899383Z","steps":["trace[446850162] 'agreement among raft nodes before linearized reading' (duration: 359.983343ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T17:44:24.899419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T17:44:24.539311Z","time spent":"360.102675ms","remote":"127.0.0.1:40964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-03T17:44:24.899761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.467494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/auth-system/keycloak\" ","response":"range_response_count:1 size:7462"} {"level":"info","ts":"2026-06-03T17:44:24.899883Z","caller":"traceutil/trace.go:171","msg":"trace[609270953] range","detail":"{range_begin:/registry/statefulsets/auth-system/keycloak; range_end:; response_count:1; response_revision:2716; }","duration":"483.611946ms","start":"2026-06-03T17:44:24.416253Z","end":"2026-06-03T17:44:24.899865Z","steps":["trace[609270953] 'agreement among raft nodes before linearized reading' (duration: 483.046689ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T17:44:24.899988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T17:44:24.416246Z","time spent":"483.712488ms","remote":"127.0.0.1:41472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":7485,"request content":"key:\"/registry/statefulsets/auth-system/keycloak\" "} {"level":"info","ts":"2026-06-03T17:44:24.899889Z","caller":"traceutil/trace.go:171","msg":"trace[368246350] transaction","detail":"{read_only:false; response_revision:2716; number_of_response:1; }","duration":"482.313751ms","start":"2026-06-03T17:44:24.417563Z","end":"2026-06-03T17:44:24.899877Z","steps":["trace[368246350] 'process raft request' (duration: 481.37021ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T17:44:24.900845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T17:44:24.417558Z","time spent":"483.0397ms","remote":"127.0.0.1:41230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T17:44:30.718186Z","caller":"traceutil/trace.go:171","msg":"trace[95049861] transaction","detail":"{read_only:false; response_revision:2746; number_of_response:1; }","duration":"271.247653ms","start":"2026-06-03T17:44:30.446863Z","end":"2026-06-03T17:44:30.718111Z","steps":["trace[95049861] 'process raft request' (duration: 271.01886ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:44:30.720326Z","caller":"traceutil/trace.go:171","msg":"trace[1899499987] linearizableReadLoop","detail":"{readStateIndex:2847; appliedIndex:2846; }","duration":"180.496278ms","start":"2026-06-03T17:44:30.539806Z","end":"2026-06-03T17:44:30.720302Z","steps":["trace[1899499987] 'read index received' (duration: 178.810276ms)","trace[1899499987] 'applied index is now lower than readState.Index' (duration: 1.684872ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T17:44:30.720524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.607559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T17:44:30.720569Z","caller":"traceutil/trace.go:171","msg":"trace[547315297] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2747; }","duration":"180.798003ms","start":"2026-06-03T17:44:30.53976Z","end":"2026-06-03T17:44:30.720558Z","steps":["trace[547315297] 'agreement among raft nodes before linearized reading' (duration: 180.627541ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:44:30.720826Z","caller":"traceutil/trace.go:171","msg":"trace[718778493] transaction","detail":"{read_only:false; response_revision:2747; number_of_response:1; }","duration":"273.795775ms","start":"2026-06-03T17:44:30.446992Z","end":"2026-06-03T17:44:30.720787Z","steps":["trace[718778493] 'process raft request' (duration: 273.174638ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:48:17.287987Z","caller":"traceutil/trace.go:171","msg":"trace[1558309725] transaction","detail":"{read_only:false; response_revision:4563; number_of_response:1; }","duration":"133.662ms","start":"2026-06-03T17:48:17.154282Z","end":"2026-06-03T17:48:17.287944Z","steps":["trace[1558309725] 'process raft request' (duration: 74.300596ms)","trace[1558309725] 'compare' (duration: 59.197031ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T17:48:17.426651Z","caller":"traceutil/trace.go:171","msg":"trace[1644699770] transaction","detail":"{read_only:false; response_revision:4564; number_of_response:1; }","duration":"123.827672ms","start":"2026-06-03T17:48:17.302803Z","end":"2026-06-03T17:48:17.42663Z","steps":["trace[1644699770] 'process raft request' (duration: 122.663669ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T17:48:25.629306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.723771ms","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-06-03T17:48:25.62944Z","caller":"traceutil/trace.go:171","msg":"trace[695690950] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4751; }","duration":"174.902426ms","start":"2026-06-03T17:48:25.454512Z","end":"2026-06-03T17:48:25.629414Z","steps":["trace[695690950] 'range keys from in-memory index tree' (duration: 174.523878ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:48:26.17303Z","caller":"traceutil/trace.go:171","msg":"trace[2048058331] linearizableReadLoop","detail":"{readStateIndex:4934; appliedIndex:4933; }","duration":"261.69934ms","start":"2026-06-03T17:48:25.9113Z","end":"2026-06-03T17:48:26.172999Z","steps":["trace[2048058331] 'read index received' (duration: 256.823663ms)","trace[2048058331] 'applied index is now lower than readState.Index' (duration: 4.874377ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T17:48:26.173157Z","caller":"traceutil/trace.go:171","msg":"trace[1317808787] transaction","detail":"{read_only:false; response_revision:4756; number_of_response:1; }","duration":"263.228129ms","start":"2026-06-03T17:48:25.909918Z","end":"2026-06-03T17:48:26.173146Z","steps":["trace[1317808787] 'process raft request' (duration: 258.337952ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T17:48:26.173331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.615631ms","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-06-03T17:48:26.17339Z","caller":"traceutil/trace.go:171","msg":"trace[2027138378] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4756; }","duration":"191.724563ms","start":"2026-06-03T17:48:25.98165Z","end":"2026-06-03T17:48:26.173374Z","steps":["trace[2027138378] 'agreement among raft nodes before linearized reading' (duration: 191.55712ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T17:48:26.173615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.294551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-db3b61e6-f3ae-4ddf-a718-fdf83f35e733\" ","response":"range_response_count:1 size:1450"} {"level":"warn","ts":"2026-06-03T17:48:26.173646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.006372ms","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-06-03T17:48:26.173672Z","caller":"traceutil/trace.go:171","msg":"trace[1899119675] range","detail":"{range_begin:/registry/persistentvolumes/pvc-db3b61e6-f3ae-4ddf-a718-fdf83f35e733; range_end:; response_count:1; response_revision:4756; }","duration":"262.407433ms","start":"2026-06-03T17:48:25.911249Z","end":"2026-06-03T17:48:26.173657Z","steps":["trace[1899119675] 'agreement among raft nodes before linearized reading' (duration: 262.21464ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:48:26.173722Z","caller":"traceutil/trace.go:171","msg":"trace[307293206] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4756; }","duration":"168.196606ms","start":"2026-06-03T17:48:26.005508Z","end":"2026-06-03T17:48:26.173704Z","steps":["trace[307293206] 'agreement among raft nodes before linearized reading' (duration: 167.991532ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:48:26.323696Z","caller":"traceutil/trace.go:171","msg":"trace[1091782953] linearizableReadLoop","detail":"{readStateIndex:4943; appliedIndex:4942; }","duration":"102.005737ms","start":"2026-06-03T17:48:26.221635Z","end":"2026-06-03T17:48:26.32364Z","steps":["trace[1091782953] 'read index received' (duration: 61.105459ms)","trace[1091782953] 'applied index is now lower than readState.Index' (duration: 40.899398ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T17:48:26.324084Z","caller":"traceutil/trace.go:171","msg":"trace[891351174] transaction","detail":"{read_only:false; response_revision:4765; number_of_response:1; }","duration":"126.816008ms","start":"2026-06-03T17:48:26.197123Z","end":"2026-06-03T17:48:26.323939Z","steps":["trace[891351174] 'process raft request' (duration: 85.690345ms)","trace[891351174] 'compare' (duration: 40.602732ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T17:48:26.324166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.246611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-1c467879-1ba3-4ba1-9ed4-c6462126c571\" ","response":"range_response_count:1 size:1435"} {"level":"info","ts":"2026-06-03T17:48:26.324224Z","caller":"traceutil/trace.go:171","msg":"trace[1513535703] range","detail":"{range_begin:/registry/persistentvolumes/pvc-1c467879-1ba3-4ba1-9ed4-c6462126c571; range_end:; response_count:1; response_revision:4765; }","duration":"102.596029ms","start":"2026-06-03T17:48:26.221613Z","end":"2026-06-03T17:48:26.324209Z","steps":["trace[1513535703] 'agreement among raft nodes before linearized reading' (duration: 102.147989ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:48:40.106448Z","caller":"traceutil/trace.go:171","msg":"trace[924128072] transaction","detail":"{read_only:false; response_revision:4935; number_of_response:1; }","duration":"106.444853ms","start":"2026-06-03T17:48:39.999977Z","end":"2026-06-03T17:48:40.106422Z","steps":["trace[924128072] 'process raft request' (duration: 106.264089ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T17:49:58.249883Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2891} {"level":"info","ts":"2026-06-03T17:49:58.355323Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2891,"took":"103.907443ms","hash":987698005,"current-db-size-bytes":53047296,"current-db-size":"53 MB","current-db-size-in-use-bytes":29253632,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-06-03T17:49:58.355371Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":987698005,"revision":2891,"compact-revision":-1} {"level":"info","ts":"2026-06-03T17:54:58.259563Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5560} {"level":"info","ts":"2026-06-03T17:54:58.387841Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5560,"took":"125.490659ms","hash":1798118504,"current-db-size-bytes":53047296,"current-db-size":"53 MB","current-db-size-in-use-bytes":18407424,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-06-03T17:54:58.38795Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1798118504,"revision":5560,"compact-revision":2891}