{"level":"warn","ts":"2026-08-24T15:16:56.082048Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-24T15:16:56.082155Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.129:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.129:2380","--initial-cluster=instance=https://199.19.213.129:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.129:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.129:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-08-24T15:16:56.082245Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-24T15:16:56.082259Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.129:2380"]} {"level":"info","ts":"2026-08-24T15:16:56.082282Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-24T15:16:56.08307Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.129:2379"]} {"level":"info","ts":"2026-08-24T15:16:56.08317Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.129:2380"],"listen-peer-urls":["https://199.19.213.129:2380"],"advertise-client-urls":["https://199.19.213.129:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.129:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.129:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-08-24T15:16:56.0916Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"8.173706ms"} {"level":"info","ts":"2026-08-24T15:16:56.102184Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d319cd7bf0a1887b","cluster-id":"2ab213cc48e2c85a"} {"level":"info","ts":"2026-08-24T15:16:56.102247Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d319cd7bf0a1887b switched to configuration voters=()"} {"level":"info","ts":"2026-08-24T15:16:56.102264Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d319cd7bf0a1887b became follower at term 0"} {"level":"info","ts":"2026-08-24T15:16:56.102271Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d319cd7bf0a1887b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-24T15:16:56.102278Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d319cd7bf0a1887b became follower at term 1"} {"level":"info","ts":"2026-08-24T15:16:56.102307Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d319cd7bf0a1887b switched to configuration voters=(15211415148622350459)"} {"level":"warn","ts":"2026-08-24T15:16:56.108685Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-24T15:16:56.109819Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-24T15:16:56.110734Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-08-24T15:16:56.112746Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d319cd7bf0a1887b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-24T15:16:56.113122Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d319cd7bf0a1887b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-24T15:16:56.11319Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-24T15:16:56.113267Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-24T15:16:56.113275Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-24T15:16:56.113818Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d319cd7bf0a1887b switched to configuration voters=(15211415148622350459)"} {"level":"info","ts":"2026-08-24T15:16:56.115956Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2ab213cc48e2c85a","local-member-id":"d319cd7bf0a1887b","added-peer-id":"d319cd7bf0a1887b","added-peer-peer-urls":["https://199.19.213.129:2380"]} {"level":"info","ts":"2026-08-24T15:16:56.118452Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-24T15:16:56.118664Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.129:2380"} {"level":"info","ts":"2026-08-24T15:16:56.118743Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.129:2380"} {"level":"info","ts":"2026-08-24T15:16:56.11898Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d319cd7bf0a1887b","initial-advertise-peer-urls":["https://199.19.213.129:2380"],"listen-peer-urls":["https://199.19.213.129:2380"],"advertise-client-urls":["https://199.19.213.129:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.129:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-24T15:16:56.119023Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-24T15:16:56.603137Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d319cd7bf0a1887b is starting a new election at term 1"} {"level":"info","ts":"2026-08-24T15:16:56.603192Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d319cd7bf0a1887b became pre-candidate at term 1"} {"level":"info","ts":"2026-08-24T15:16:56.603217Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d319cd7bf0a1887b received MsgPreVoteResp from d319cd7bf0a1887b at term 1"} {"level":"info","ts":"2026-08-24T15:16:56.60323Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d319cd7bf0a1887b became candidate at term 2"} {"level":"info","ts":"2026-08-24T15:16:56.603237Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d319cd7bf0a1887b received MsgVoteResp from d319cd7bf0a1887b at term 2"} {"level":"info","ts":"2026-08-24T15:16:56.603248Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d319cd7bf0a1887b became leader at term 2"} {"level":"info","ts":"2026-08-24T15:16:56.603264Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d319cd7bf0a1887b elected leader d319cd7bf0a1887b at term 2"} {"level":"info","ts":"2026-08-24T15:16:56.604257Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T15:16:56.604858Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d319cd7bf0a1887b","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.129:2379]}","request-path":"/0/members/d319cd7bf0a1887b/attributes","cluster-id":"2ab213cc48e2c85a","publish-timeout":"7s"} {"level":"info","ts":"2026-08-24T15:16:56.605103Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2ab213cc48e2c85a","local-member-id":"d319cd7bf0a1887b","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T15:16:56.605152Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-24T15:16:56.605164Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T15:16:56.605202Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T15:16:56.605217Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-24T15:16:56.607432Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.129:2379"} {"level":"info","ts":"2026-08-24T15:16:56.607512Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-24T15:16:56.607528Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-24T15:16:56.609049Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-24T15:17:01.442966Z","caller":"traceutil/trace.go:171","msg":"trace[879015330] transaction","detail":"{read_only:false; response_revision:267; number_of_response:1; }","duration":"105.729422ms","start":"2026-08-24T15:17:01.337188Z","end":"2026-08-24T15:17:01.442918Z","steps":["trace[879015330] 'process raft request' (duration: 105.501905ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:17:07.571549Z","caller":"traceutil/trace.go:171","msg":"trace[378103671] linearizableReadLoop","detail":"{readStateIndex:376; appliedIndex:375; }","duration":"202.941322ms","start":"2026-08-24T15:17:07.368585Z","end":"2026-08-24T15:17:07.571526Z","steps":["trace[378103671] 'read index received' (duration: 160.481805ms)","trace[378103671] 'applied index is now lower than readState.Index' (duration: 42.458487ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:17:07.57166Z","caller":"traceutil/trace.go:171","msg":"trace[149635902] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"247.116395ms","start":"2026-08-24T15:17:07.324509Z","end":"2026-08-24T15:17:07.571625Z","steps":["trace[149635902] 'process raft request' (duration: 204.484713ms)","trace[149635902] 'compare' (duration: 42.049463ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:17:07.571739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.143779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-08-24T15:17:07.571786Z","caller":"traceutil/trace.go:171","msg":"trace[775045495] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:365; }","duration":"203.241622ms","start":"2026-08-24T15:17:07.368536Z","end":"2026-08-24T15:17:07.571777Z","steps":["trace[775045495] 'agreement among raft nodes before linearized reading' (duration: 203.113888ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:07.571952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.569658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-08-24T15:17:07.572018Z","caller":"traceutil/trace.go:171","msg":"trace[964883673] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:365; }","duration":"154.62454ms","start":"2026-08-24T15:17:07.417346Z","end":"2026-08-24T15:17:07.571971Z","steps":["trace[964883673] 'agreement among raft nodes before linearized reading' (duration: 154.580518ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:07.572283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.858215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-08-24T15:17:07.572354Z","caller":"traceutil/trace.go:171","msg":"trace[1867667908] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:365; }","duration":"103.962029ms","start":"2026-08-24T15:17:07.468372Z","end":"2026-08-24T15:17:07.572334Z","steps":["trace[1867667908] 'agreement among raft nodes before linearized reading' (duration: 103.824894ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:17:07.845359Z","caller":"traceutil/trace.go:171","msg":"trace[1769679988] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"267.349702ms","start":"2026-08-24T15:17:07.577786Z","end":"2026-08-24T15:17:07.845136Z","steps":["trace[1769679988] 'process raft request' (duration: 261.957431ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:17:07.845604Z","caller":"traceutil/trace.go:171","msg":"trace[1112061596] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"259.550832ms","start":"2026-08-24T15:17:07.586015Z","end":"2026-08-24T15:17:07.845566Z","steps":["trace[1112061596] 'process raft request' (duration: 259.49013ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:17:07.845741Z","caller":"traceutil/trace.go:171","msg":"trace[1111508521] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"265.459569ms","start":"2026-08-24T15:17:07.580264Z","end":"2026-08-24T15:17:07.845724Z","steps":["trace[1111508521] 'process raft request' (duration: 265.162579ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:17:07.846451Z","caller":"traceutil/trace.go:171","msg":"trace[265461953] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"266.618837ms","start":"2026-08-24T15:17:07.579153Z","end":"2026-08-24T15:17:07.845772Z","steps":["trace[265461953] 'process raft request' (duration: 265.895733ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:17:08.191762Z","caller":"traceutil/trace.go:171","msg":"trace[1336643996] linearizableReadLoop","detail":"{readStateIndex:401; appliedIndex:400; }","duration":"116.002298ms","start":"2026-08-24T15:17:08.075733Z","end":"2026-08-24T15:17:08.191735Z","steps":["trace[1336643996] 'read index received' (duration: 115.931936ms)","trace[1336643996] 'applied index is now lower than readState.Index' (duration: 69.082µs)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:17:08.191908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.158933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-24T15:17:08.191963Z","caller":"traceutil/trace.go:171","msg":"trace[1081311287] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:390; }","duration":"116.248056ms","start":"2026-08-24T15:17:08.075707Z","end":"2026-08-24T15:17:08.191955Z","steps":["trace[1081311287] 'agreement among raft nodes before linearized reading' (duration: 116.152463ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:17:08.191806Z","caller":"traceutil/trace.go:171","msg":"trace[1430651209] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"119.533706ms","start":"2026-08-24T15:17:08.072259Z","end":"2026-08-24T15:17:08.191793Z","steps":["trace[1430651209] 'process raft request' (duration: 119.331419ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:17:08.294952Z","caller":"traceutil/trace.go:171","msg":"trace[212378801] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"113.324759ms","start":"2026-08-24T15:17:08.181596Z","end":"2026-08-24T15:17:08.29492Z","steps":["trace[212378801] 'process raft request' (duration: 103.876563ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:17:09.239942Z","caller":"traceutil/trace.go:171","msg":"trace[657036543] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"256.768511ms","start":"2026-08-24T15:17:08.98316Z","end":"2026-08-24T15:17:09.239928Z","steps":["trace[657036543] 'process raft request' (duration: 256.583975ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:17:09.239921Z","caller":"traceutil/trace.go:171","msg":"trace[376723811] linearizableReadLoop","detail":"{readStateIndex:412; appliedIndex:411; }","duration":"175.333284ms","start":"2026-08-24T15:17:09.064564Z","end":"2026-08-24T15:17:09.239897Z","steps":["trace[376723811] 'read index received' (duration: 175.19833ms)","trace[376723811] 'applied index is now lower than readState.Index' (duration: 134.084µs)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:17:09.24006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.490679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:17:09.240089Z","caller":"traceutil/trace.go:171","msg":"trace[1829308937] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:400; }","duration":"175.565091ms","start":"2026-08-24T15:17:09.064514Z","end":"2026-08-24T15:17:09.240079Z","steps":["trace[1829308937] 'agreement among raft nodes before linearized reading' (duration: 175.505349ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:17:10.319814Z","caller":"traceutil/trace.go:171","msg":"trace[714291202] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"119.841923ms","start":"2026-08-24T15:17:10.199953Z","end":"2026-08-24T15:17:10.319795Z","steps":["trace[714291202] 'process raft request' (duration: 119.687768ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:17:11.600244Z","caller":"traceutil/trace.go:171","msg":"trace[156029344] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"130.101195ms","start":"2026-08-24T15:17:11.47012Z","end":"2026-08-24T15:17:11.600221Z","steps":["trace[156029344] 'process raft request' (duration: 129.94861ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:12.988257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.446444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-08-24T15:17:12.988318Z","caller":"traceutil/trace.go:171","msg":"trace[2115315786] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:414; }","duration":"662.550117ms","start":"2026-08-24T15:17:12.325754Z","end":"2026-08-24T15:17:12.988304Z","steps":["trace[2115315786] 'range keys from in-memory index tree' (duration: 662.30962ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:12.988341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:12.325735Z","time spent":"662.601489ms","remote":"127.0.0.1:50254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-24T15:17:12.9885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.92635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-24T15:17:12.988596Z","caller":"traceutil/trace.go:171","msg":"trace[1130219270] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:414; }","duration":"595.067484ms","start":"2026-08-24T15:17:12.393511Z","end":"2026-08-24T15:17:12.988579Z","steps":["trace[1130219270] 'range keys from in-memory index tree' (duration: 594.828946ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:12.988643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:12.39349Z","time spent":"595.136727ms","remote":"127.0.0.1:50254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-24T15:17:12.988761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"630.462366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-24T15:17:12.988779Z","caller":"traceutil/trace.go:171","msg":"trace[504305940] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:414; }","duration":"630.511448ms","start":"2026-08-24T15:17:12.358263Z","end":"2026-08-24T15:17:12.988774Z","steps":["trace[504305940] 'range keys from in-memory index tree' (duration: 630.350383ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:12.988793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:12.358244Z","time spent":"630.545659ms","remote":"127.0.0.1:50254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-24T15:17:13.283181Z","caller":"traceutil/trace.go:171","msg":"trace[425350206] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"289.574518ms","start":"2026-08-24T15:17:12.993582Z","end":"2026-08-24T15:17:13.283157Z","steps":["trace[425350206] 'process raft request' (duration: 289.438813ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:17:13.289258Z","caller":"traceutil/trace.go:171","msg":"trace[296457569] linearizableReadLoop","detail":"{readStateIndex:430; appliedIndex:427; }","duration":"222.224072ms","start":"2026-08-24T15:17:13.067007Z","end":"2026-08-24T15:17:13.289231Z","steps":["trace[296457569] 'read index received' (duration: 216.108236ms)","trace[296457569] 'applied index is now lower than readState.Index' (duration: 6.114646ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:17:13.289278Z","caller":"traceutil/trace.go:171","msg":"trace[1353206492] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"295.320631ms","start":"2026-08-24T15:17:12.993936Z","end":"2026-08-24T15:17:13.289257Z","steps":["trace[1353206492] 'process raft request' (duration: 295.093354ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:17:13.289309Z","caller":"traceutil/trace.go:171","msg":"trace[2029106040] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"294.793533ms","start":"2026-08-24T15:17:12.99448Z","end":"2026-08-24T15:17:13.289274Z","steps":["trace[2029106040] 'process raft request' (duration: 294.70411ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:13.289401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.390718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:17:13.289438Z","caller":"traceutil/trace.go:171","msg":"trace[1299475947] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:417; }","duration":"222.476231ms","start":"2026-08-24T15:17:13.066951Z","end":"2026-08-24T15:17:13.289428Z","steps":["trace[1299475947] 'agreement among raft nodes before linearized reading' (duration: 222.374908ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:23.867249Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9834630357978622476,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-24T15:17:23.971327Z","caller":"traceutil/trace.go:171","msg":"trace[799787640] linearizableReadLoop","detail":"{readStateIndex:485; appliedIndex:484; }","duration":"604.663556ms","start":"2026-08-24T15:17:23.366638Z","end":"2026-08-24T15:17:23.971302Z","steps":["trace[799787640] 'read index received' (duration: 604.47779ms)","trace[799787640] 'applied index is now lower than readState.Index' (duration: 184.876µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:17:23.971443Z","caller":"traceutil/trace.go:171","msg":"trace[126188858] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"620.812161ms","start":"2026-08-24T15:17:23.350608Z","end":"2026-08-24T15:17:23.97142Z","steps":["trace[126188858] 'process raft request' (duration: 620.563863ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:23.971556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.900065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-24T15:17:23.971631Z","caller":"traceutil/trace.go:171","msg":"trace[1483907109] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:469; }","duration":"605.023559ms","start":"2026-08-24T15:17:23.366589Z","end":"2026-08-24T15:17:23.971613Z","steps":["trace[1483907109] 'agreement among raft nodes before linearized reading' (duration: 604.844503ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:23.971683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:23.366571Z","time spent":"605.099512ms","remote":"127.0.0.1:50254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-24T15:17:23.971711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.775581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-24T15:17:23.971773Z","caller":"traceutil/trace.go:171","msg":"trace[1117926603] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:469; }","duration":"604.867084ms","start":"2026-08-24T15:17:23.366892Z","end":"2026-08-24T15:17:23.971759Z","steps":["trace[1117926603] 'agreement among raft nodes before linearized reading' (duration: 604.770881ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:23.971805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:23.366877Z","time spent":"604.922805ms","remote":"127.0.0.1:50254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-24T15:17:23.973494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:23.350582Z","time spent":"620.962387ms","remote":"127.0.0.1:50254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:17:28.812827Z","caller":"traceutil/trace.go:171","msg":"trace[1776923703] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"309.731236ms","start":"2026-08-24T15:17:28.503058Z","end":"2026-08-24T15:17:28.812789Z","steps":["trace[1776923703] 'process raft request' (duration: 309.5367ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:28.812986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:28.503039Z","time spent":"309.869072ms","remote":"127.0.0.1:50254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:17:36.522265Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9834630357978622618,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:17:37.023231Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9834630357978622618,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:17:37.054804Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.377520015s","expected-duration":"1s"} {"level":"info","ts":"2026-08-24T15:17:37.055839Z","caller":"traceutil/trace.go:171","msg":"trace[560738382] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"1.378646184s","start":"2026-08-24T15:17:35.677152Z","end":"2026-08-24T15:17:37.055798Z","steps":["trace[560738382] 'process raft request' (duration: 1.378469838s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:37.055969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:35.677128Z","time spent":"1.378776299s","remote":"127.0.0.1:39494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-24T15:17:37.062648Z","caller":"traceutil/trace.go:171","msg":"trace[78920748] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"1.381732312s","start":"2026-08-24T15:17:35.68088Z","end":"2026-08-24T15:17:37.062612Z","steps":["trace[78920748] 'process raft request' (duration: 1.381597228s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:37.062785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:35.680861Z","time spent":"1.381860997s","remote":"127.0.0.1:39494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-24T15:17:37.062973Z","caller":"traceutil/trace.go:171","msg":"trace[1409507653] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"1.350548293s","start":"2026-08-24T15:17:35.712411Z","end":"2026-08-24T15:17:37.062959Z","steps":["trace[1409507653] 'process raft request' (duration: 1.350183071s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:37.063047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:35.712404Z","time spent":"1.350609625s","remote":"127.0.0.1:39494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-24T15:17:37.063061Z","caller":"traceutil/trace.go:171","msg":"trace[763187062] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"1.331215598s","start":"2026-08-24T15:17:35.73183Z","end":"2026-08-24T15:17:37.063046Z","steps":["trace[763187062] 'process raft request' (duration: 1.330899287s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:37.063134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:35.731816Z","time spent":"1.3312858s","remote":"127.0.0.1:39494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-24T15:17:37.063194Z","caller":"traceutil/trace.go:171","msg":"trace[1740295373] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"1.347790487s","start":"2026-08-24T15:17:35.715399Z","end":"2026-08-24T15:17:37.06319Z","steps":["trace[1740295373] 'process raft request' (duration: 1.347220838s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:37.063218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:35.715391Z","time spent":"1.347816428s","remote":"127.0.0.1:39494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-24T15:17:37.063227Z","caller":"traceutil/trace.go:171","msg":"trace[701715082] linearizableReadLoop","detail":"{readStateIndex:560; appliedIndex:548; }","duration":"1.041568259s","start":"2026-08-24T15:17:36.021625Z","end":"2026-08-24T15:17:37.063194Z","steps":["trace[701715082] 'read index received' (duration: 1.033548839s)","trace[701715082] 'applied index is now lower than readState.Index' (duration: 8.0182ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:17:37.063253Z","caller":"traceutil/trace.go:171","msg":"trace[839281574] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"1.337419734s","start":"2026-08-24T15:17:35.725829Z","end":"2026-08-24T15:17:37.063249Z","steps":["trace[839281574] 'process raft request' (duration: 1.336869755s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:37.063272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:35.725791Z","time spent":"1.337472236s","remote":"127.0.0.1:39494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-24T15:17:37.063292Z","caller":"traceutil/trace.go:171","msg":"trace[1987826279] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"1.327208288s","start":"2026-08-24T15:17:35.736074Z","end":"2026-08-24T15:17:37.063282Z","steps":["trace[1987826279] 'process raft request' (duration: 1.326679029s)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:17:37.063298Z","caller":"traceutil/trace.go:171","msg":"trace[1230783948] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"1.363421943s","start":"2026-08-24T15:17:35.699865Z","end":"2026-08-24T15:17:37.063287Z","steps":["trace[1230783948] 'process raft request' (duration: 1.362680187s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:37.063342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:35.736064Z","time spent":"1.327249989s","remote":"127.0.0.1:39494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-24T15:17:37.06335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:35.699825Z","time spent":"1.363502355s","remote":"127.0.0.1:39494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-24T15:17:37.063372Z","caller":"traceutil/trace.go:171","msg":"trace[242763002] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"1.307011562s","start":"2026-08-24T15:17:35.756355Z","end":"2026-08-24T15:17:37.063366Z","steps":["trace[242763002] 'process raft request' (duration: 1.306623709s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:37.063394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:35.756322Z","time spent":"1.307061514s","remote":"127.0.0.1:39494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-24T15:17:37.06343Z","caller":"traceutil/trace.go:171","msg":"trace[1370141535] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"1.351081211s","start":"2026-08-24T15:17:35.712339Z","end":"2026-08-24T15:17:37.06342Z","steps":["trace[1370141535] 'process raft request' (duration: 1.350230802s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:37.063481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.041853259s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-08-24T15:17:37.063499Z","caller":"traceutil/trace.go:171","msg":"trace[906409823] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:541; }","duration":"1.041902531s","start":"2026-08-24T15:17:36.021591Z","end":"2026-08-24T15:17:37.063494Z","steps":["trace[906409823] 'agreement among raft nodes before linearized reading' (duration: 1.041832218s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:37.063502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:35.712322Z","time spent":"1.351155384s","remote":"127.0.0.1:39494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-24T15:17:37.063528Z","caller":"traceutil/trace.go:171","msg":"trace[347845063] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"1.198037366s","start":"2026-08-24T15:17:35.865487Z","end":"2026-08-24T15:17:37.063524Z","steps":["trace[347845063] 'process raft request' (duration: 1.197617421s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:37.063547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:35.865456Z","time spent":"1.198082487s","remote":"127.0.0.1:39494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-24T15:17:37.063514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:36.021575Z","time spent":"1.041935512s","remote":"127.0.0.1:50254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-24T15:17:37.063507Z","caller":"traceutil/trace.go:171","msg":"trace[1652679003] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"1.321967084s","start":"2026-08-24T15:17:35.7415Z","end":"2026-08-24T15:17:37.063468Z","steps":["trace[1652679003] 'process raft request' (duration: 1.321379284s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:37.063798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:35.741491Z","time spent":"1.322224165s","remote":"127.0.0.1:39494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-24T15:17:37.074455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.039866381s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-24T15:17:37.074506Z","caller":"traceutil/trace.go:171","msg":"trace[635337505] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:542; }","duration":"1.039961544s","start":"2026-08-24T15:17:36.034533Z","end":"2026-08-24T15:17:37.074495Z","steps":["trace[635337505] 'agreement among raft nodes before linearized reading' (duration: 1.039812309s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:37.074537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:36.03451Z","time spent":"1.040012396s","remote":"127.0.0.1:50254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-24T15:17:37.074728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"850.27388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-24T15:17:37.074752Z","caller":"traceutil/trace.go:171","msg":"trace[851396178] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:542; }","duration":"850.332252ms","start":"2026-08-24T15:17:36.224414Z","end":"2026-08-24T15:17:37.074747Z","steps":["trace[851396178] 'agreement among raft nodes before linearized reading' (duration: 850.28637ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:37.074843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:36.224396Z","time spent":"850.419955ms","remote":"127.0.0.1:50254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-24T15:17:37.074953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.008782155s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:17:37.074988Z","caller":"traceutil/trace.go:171","msg":"trace[871335827] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:542; }","duration":"1.008864028s","start":"2026-08-24T15:17:36.066119Z","end":"2026-08-24T15:17:37.074983Z","steps":["trace[871335827] 'agreement among raft nodes before linearized reading' (duration: 1.008819476s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:37.075034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:36.066098Z","time spent":"1.00890053s","remote":"127.0.0.1:39444","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-08-24T15:17:37.07524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.040644479s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-08-24T15:17:37.075302Z","caller":"traceutil/trace.go:171","msg":"trace[575512429] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:542; }","duration":"1.040737602s","start":"2026-08-24T15:17:36.034546Z","end":"2026-08-24T15:17:37.075283Z","steps":["trace[575512429] 'agreement among raft nodes before linearized reading' (duration: 1.040550766s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:37.075335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:36.03453Z","time spent":"1.040798414s","remote":"127.0.0.1:50254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-24T15:17:41.199312Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.025390263s","expected-duration":"1s"} {"level":"info","ts":"2026-08-24T15:17:41.200767Z","caller":"traceutil/trace.go:171","msg":"trace[236097821] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:640; }","duration":"134.917287ms","start":"2026-08-24T15:17:41.065817Z","end":"2026-08-24T15:17:41.200735Z","steps":["trace[236097821] 'read index received' (duration: 133.667264ms)","trace[236097821] 'applied index is now lower than readState.Index' (duration: 1.249103ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:17:41.200888Z","caller":"traceutil/trace.go:171","msg":"trace[916802156] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"1.028391529s","start":"2026-08-24T15:17:40.172451Z","end":"2026-08-24T15:17:41.200843Z","steps":["trace[916802156] 'process raft request' (duration: 1.027373863s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:41.202808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:40.172426Z","time spent":"1.02984038s","remote":"127.0.0.1:39544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036023,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-24T15:17:41.204316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.895886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-24T15:17:41.204485Z","caller":"traceutil/trace.go:171","msg":"trace[1447311532] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:618; }","duration":"102.142104ms","start":"2026-08-24T15:17:41.10228Z","end":"2026-08-24T15:17:41.204422Z","steps":["trace[1447311532] 'agreement among raft nodes before linearized reading' (duration: 101.143719ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:41.205371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.740652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-08-24T15:17:41.205386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.505879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:17:41.205441Z","caller":"traceutil/trace.go:171","msg":"trace[226602106] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:618; }","duration":"107.881386ms","start":"2026-08-24T15:17:41.097546Z","end":"2026-08-24T15:17:41.205427Z","steps":["trace[226602106] 'agreement among raft nodes before linearized reading' (duration: 104.00083ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:17:41.205462Z","caller":"traceutil/trace.go:171","msg":"trace[456601951] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:618; }","duration":"139.674414ms","start":"2026-08-24T15:17:41.065766Z","end":"2026-08-24T15:17:41.205441Z","steps":["trace[456601951] 'agreement among raft nodes before linearized reading' (duration: 135.087802ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:41.711645Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9834630357978622761,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-24T15:17:41.838233Z","caller":"traceutil/trace.go:171","msg":"trace[1912300206] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"629.957004ms","start":"2026-08-24T15:17:41.208254Z","end":"2026-08-24T15:17:41.838211Z","steps":["trace[1912300206] 'process raft request' (duration: 629.761588ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:41.83836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:41.208229Z","time spent":"630.067618ms","remote":"127.0.0.1:50254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:17:41.856053Z","caller":"traceutil/trace.go:171","msg":"trace[205248853] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:641; }","duration":"645.370976ms","start":"2026-08-24T15:17:41.210662Z","end":"2026-08-24T15:17:41.856033Z","steps":["trace[205248853] 'read index received' (duration: 627.365013ms)","trace[205248853] 'applied index is now lower than readState.Index' (duration: 18.005263ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:17:41.856306Z","caller":"traceutil/trace.go:171","msg":"trace[1299681249] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"646.357751ms","start":"2026-08-24T15:17:41.209932Z","end":"2026-08-24T15:17:41.85629Z","steps":["trace[1299681249] 'process raft request' (duration: 646.075771ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:41.85645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:41.209928Z","time spent":"646.411103ms","remote":"127.0.0.1:50254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:17:41.856416Z","caller":"traceutil/trace.go:171","msg":"trace[1127897555] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"646.751225ms","start":"2026-08-24T15:17:41.209601Z","end":"2026-08-24T15:17:41.856352Z","steps":["trace[1127897555] 'process raft request' (duration: 646.272178ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:41.856483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.350344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:17:41.856537Z","caller":"traceutil/trace.go:171","msg":"trace[364655563] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:622; }","duration":"581.454377ms","start":"2026-08-24T15:17:41.275066Z","end":"2026-08-24T15:17:41.856521Z","steps":["trace[364655563] 'agreement among raft nodes before linearized reading' (duration: 581.361164ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:41.856535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.868903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-24T15:17:41.856566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:41.27504Z","time spent":"581.520889ms","remote":"127.0.0.1:50198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"info","ts":"2026-08-24T15:17:41.856498Z","caller":"traceutil/trace.go:171","msg":"trace[658513412] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"646.568847ms","start":"2026-08-24T15:17:41.209877Z","end":"2026-08-24T15:17:41.856445Z","steps":["trace[658513412] 'process raft request' (duration: 646.103651ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:17:41.856581Z","caller":"traceutil/trace.go:171","msg":"trace[1951701331] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:622; }","duration":"645.931166ms","start":"2026-08-24T15:17:41.210635Z","end":"2026-08-24T15:17:41.856566Z","steps":["trace[1951701331] 'agreement among raft nodes before linearized reading' (duration: 645.854603ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:41.85662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:41.210614Z","time spent":"646.000368ms","remote":"127.0.0.1:39432","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-08-24T15:17:41.856648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:41.209574Z","time spent":"646.945952ms","remote":"127.0.0.1:50254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:17:41.856774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:41.209869Z","time spent":"646.758375ms","remote":"127.0.0.1:50254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:17:42.380507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.908256ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-24T15:17:42.380647Z","caller":"traceutil/trace.go:171","msg":"trace[891572888] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"194.459649ms","start":"2026-08-24T15:17:42.18615Z","end":"2026-08-24T15:17:42.380609Z","steps":["trace[891572888] 'process raft request' (duration: 62.203871ms)","trace[891572888] 'compare' (duration: 131.758101ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:17:42.900056Z","caller":"traceutil/trace.go:171","msg":"trace[1747933254] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"580.476677ms","start":"2026-08-24T15:17:42.319553Z","end":"2026-08-24T15:17:42.900029Z","steps":["trace[1747933254] 'process raft request' (duration: 485.885115ms)","trace[1747933254] 'compare' (duration: 94.233779ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:17:42.900215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:42.319527Z","time spent":"580.607672ms","remote":"127.0.0.1:39544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036109,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:17:43.178126Z","caller":"traceutil/trace.go:171","msg":"trace[1618558193] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:647; }","duration":"114.228218ms","start":"2026-08-24T15:17:43.063849Z","end":"2026-08-24T15:17:43.178077Z","steps":["trace[1618558193] 'read index received' (duration: 113.42141ms)","trace[1618558193] 'applied index is now lower than readState.Index' (duration: 805.798µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:17:43.178173Z","caller":"traceutil/trace.go:171","msg":"trace[699244929] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"219.331363ms","start":"2026-08-24T15:17:42.958808Z","end":"2026-08-24T15:17:43.178139Z","steps":["trace[699244929] 'process raft request' (duration: 218.542645ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:43.178242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.383883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:17:43.178268Z","caller":"traceutil/trace.go:171","msg":"trace[1480531400] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:625; }","duration":"114.457597ms","start":"2026-08-24T15:17:43.063803Z","end":"2026-08-24T15:17:43.17826Z","steps":["trace[1480531400] 'agreement among raft nodes before linearized reading' (duration: 114.381904ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:17:43.878812Z","caller":"traceutil/trace.go:171","msg":"trace[892031269] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"114.5795ms","start":"2026-08-24T15:17:43.764195Z","end":"2026-08-24T15:17:43.878774Z","steps":["trace[892031269] 'process raft request' (duration: 90.003183ms)","trace[892031269] 'compare' (duration: 23.896074ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:17:45.375803Z","caller":"traceutil/trace.go:171","msg":"trace[621360701] linearizableReadLoop","detail":"{readStateIndex:658; appliedIndex:657; }","duration":"303.587459ms","start":"2026-08-24T15:17:45.072192Z","end":"2026-08-24T15:17:45.37578Z","steps":["trace[621360701] 'read index received' (duration: 302.706617ms)","trace[621360701] 'applied index is now lower than readState.Index' (duration: 880.022µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:17:45.375876Z","caller":"traceutil/trace.go:171","msg":"trace[1621546075] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"477.339646ms","start":"2026-08-24T15:17:44.898505Z","end":"2026-08-24T15:17:45.375844Z","steps":["trace[1621546075] 'process raft request' (duration: 476.657422ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:45.375925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.711133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:17:45.375957Z","caller":"traceutil/trace.go:171","msg":"trace[378747326] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:634; }","duration":"303.796087ms","start":"2026-08-24T15:17:45.072151Z","end":"2026-08-24T15:17:45.375947Z","steps":["trace[378747326] 'agreement among raft nodes before linearized reading' (duration: 303.725325ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:45.375995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:44.898485Z","time spent":"477.447419ms","remote":"127.0.0.1:39544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036229,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:17:45.375996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:45.072132Z","time spent":"303.857368ms","remote":"127.0.0.1:39444","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-08-24T15:17:45.647397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.244868ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-24T15:17:45.647614Z","caller":"traceutil/trace.go:171","msg":"trace[771241931] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"213.092151ms","start":"2026-08-24T15:17:45.434495Z","end":"2026-08-24T15:17:45.647587Z","steps":["trace[771241931] 'process raft request' (duration: 72.588474ms)","trace[771241931] 'compare' (duration: 140.05942ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:17:47.565475Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9834630357978622795,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:17:47.855746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.889200697s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-08-24T15:17:47.855786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.889193966s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-24T15:17:47.855822Z","caller":"traceutil/trace.go:171","msg":"trace[1454354178] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:635; }","duration":"1.889308511s","start":"2026-08-24T15:17:45.966497Z","end":"2026-08-24T15:17:47.855805Z","steps":["trace[1454354178] 'range keys from in-memory index tree' (duration: 1.88904705s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:47.855836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.889258468s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-24T15:17:47.85584Z","caller":"traceutil/trace.go:171","msg":"trace[1511658378] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:635; }","duration":"1.88928667s","start":"2026-08-24T15:17:45.96654Z","end":"2026-08-24T15:17:47.855826Z","steps":["trace[1511658378] 'range keys from in-memory index tree' (duration: 1.889064701s)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:17:47.855861Z","caller":"traceutil/trace.go:171","msg":"trace[890222435] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:635; }","duration":"1.889286829s","start":"2026-08-24T15:17:45.966566Z","end":"2026-08-24T15:17:47.855853Z","steps":["trace[890222435] 'range keys from in-memory index tree' (duration: 1.889169615s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:47.855873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:45.966524Z","time spent":"1.889342732s","remote":"127.0.0.1:50254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-24T15:17:47.855881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:45.966561Z","time spent":"1.889315941s","remote":"127.0.0.1:50254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-24T15:17:47.855866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:45.96648Z","time spent":"1.889379443s","remote":"127.0.0.1:50254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-24T15:17:47.855759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.922789053s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-24T15:17:47.855959Z","caller":"traceutil/trace.go:171","msg":"trace[97737225] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:635; }","duration":"1.923026282s","start":"2026-08-24T15:17:45.932927Z","end":"2026-08-24T15:17:47.855953Z","steps":["trace[97737225] 'range keys from in-memory index tree' (duration: 1.92268856s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:47.855975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:45.93291Z","time spent":"1.923061064s","remote":"127.0.0.1:50254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-24T15:17:47.860854Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.735811292s","expected-duration":"1s"} {"level":"info","ts":"2026-08-24T15:17:47.867765Z","caller":"traceutil/trace.go:171","msg":"trace[1052078718] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"1.744393826s","start":"2026-08-24T15:17:46.12336Z","end":"2026-08-24T15:17:47.867754Z","steps":["trace[1052078718] 'process raft request' (duration: 1.73858574s)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:17:47.86771Z","caller":"traceutil/trace.go:171","msg":"trace[1144049564] linearizableReadLoop","detail":"{readStateIndex:660; appliedIndex:659; }","duration":"802.649755ms","start":"2026-08-24T15:17:47.065013Z","end":"2026-08-24T15:17:47.867663Z","steps":["trace[1144049564] 'read index received' (duration: 796.927042ms)","trace[1144049564] 'applied index is now lower than readState.Index' (duration: 5.719813ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:17:47.867901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:46.123339Z","time spent":"1.74449221s","remote":"127.0.0.1:39544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036265,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:17:47.868385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"803.36629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:17:47.868431Z","caller":"traceutil/trace.go:171","msg":"trace[1147019967] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:638; }","duration":"803.438103ms","start":"2026-08-24T15:17:47.06498Z","end":"2026-08-24T15:17:47.868418Z","steps":["trace[1147019967] 'agreement among raft nodes before linearized reading' (duration: 802.734548ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:47.868454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:47.064964Z","time spent":"803.484755ms","remote":"127.0.0.1:39444","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-08-24T15:17:48.516359Z","caller":"traceutil/trace.go:171","msg":"trace[338155479] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"298.480142ms","start":"2026-08-24T15:17:48.21786Z","end":"2026-08-24T15:17:48.51634Z","steps":["trace[338155479] 'process raft request' (duration: 298.368348ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:17:48.617168Z","caller":"traceutil/trace.go:171","msg":"trace[2000715448] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"252.922157ms","start":"2026-08-24T15:17:48.36422Z","end":"2026-08-24T15:17:48.617142Z","steps":["trace[2000715448] 'process raft request' (duration: 251.59098ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:50.008853Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9834630357978622810,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:17:50.509535Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9834630357978622810,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:17:50.542721Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.304772683s","expected-duration":"1s"} {"level":"info","ts":"2026-08-24T15:17:50.54388Z","caller":"traceutil/trace.go:171","msg":"trace[303703062] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"1.311336175s","start":"2026-08-24T15:17:49.232512Z","end":"2026-08-24T15:17:50.543848Z","steps":["trace[303703062] 'process raft request' (duration: 1.310661011s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:50.54406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:49.232485Z","time spent":"1.3114827s","remote":"127.0.0.1:39544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036321,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:17:51.226059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.93637ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-24T15:17:51.226159Z","caller":"traceutil/trace.go:171","msg":"trace[1229176930] linearizableReadLoop","detail":"{readStateIndex:670; appliedIndex:668; }","duration":"1.718086631s","start":"2026-08-24T15:17:49.508056Z","end":"2026-08-24T15:17:51.226143Z","steps":["trace[1229176930] 'read index received' (duration: 1.034879834s)","trace[1229176930] 'applied index is now lower than readState.Index' (duration: 683.205597ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:17:51.226227Z","caller":"traceutil/trace.go:171","msg":"trace[563451482] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"1.934505062s","start":"2026-08-24T15:17:49.29171Z","end":"2026-08-24T15:17:51.226215Z","steps":["trace[563451482] 'process raft request' (duration: 1.629326124s)","trace[563451482] 'compare' (duration: 304.808165ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:17:51.226286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:49.291674Z","time spent":"1.934582235s","remote":"127.0.0.1:50254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:17:51.226522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.718449084s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.129\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-24T15:17:51.22663Z","caller":"traceutil/trace.go:171","msg":"trace[644283528] range","detail":"{range_begin:/registry/masterleases/199.19.213.129; range_end:; response_count:1; response_revision:646; }","duration":"1.71861321s","start":"2026-08-24T15:17:49.507989Z","end":"2026-08-24T15:17:51.226603Z","steps":["trace[644283528] 'agreement among raft nodes before linearized reading' (duration: 1.718273718s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:51.226687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:49.507959Z","time spent":"1.718716494s","remote":"127.0.0.1:39478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.129\" "} {"level":"warn","ts":"2026-08-24T15:17:51.235297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.17113686s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-24T15:17:51.235347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.357857258s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-24T15:17:51.235373Z","caller":"traceutil/trace.go:171","msg":"trace[1786394840] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:647; }","duration":"1.171249283s","start":"2026-08-24T15:17:50.064104Z","end":"2026-08-24T15:17:51.235354Z","steps":["trace[1786394840] 'agreement among raft nodes before linearized reading' (duration: 1.171122329s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:51.235373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.360194731s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-08-24T15:17:51.235414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:50.064087Z","time spent":"1.171318496s","remote":"127.0.0.1:39444","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-08-24T15:17:51.235436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.357810536s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-24T15:17:51.235393Z","caller":"traceutil/trace.go:171","msg":"trace[1219535140] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:647; }","duration":"1.357936861s","start":"2026-08-24T15:17:49.877444Z","end":"2026-08-24T15:17:51.235381Z","steps":["trace[1219535140] 'agreement among raft nodes before linearized reading' (duration: 1.357792806s)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:17:51.23547Z","caller":"traceutil/trace.go:171","msg":"trace[2009413661] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:647; }","duration":"1.35789253s","start":"2026-08-24T15:17:49.877555Z","end":"2026-08-24T15:17:51.235448Z","steps":["trace[2009413661] 'agreement among raft nodes before linearized reading' (duration: 1.357852788s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:51.235485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:49.877505Z","time spent":"1.357976402s","remote":"127.0.0.1:50254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-24T15:17:51.235488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:49.877417Z","time spent":"1.358062906s","remote":"127.0.0.1:50254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-24T15:17:51.235299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.363218699s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-24T15:17:51.235426Z","caller":"traceutil/trace.go:171","msg":"trace[673069421] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:647; }","duration":"1.360284664s","start":"2026-08-24T15:17:49.875126Z","end":"2026-08-24T15:17:51.235411Z","steps":["trace[673069421] 'agreement among raft nodes before linearized reading' (duration: 1.360143639s)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:17:51.235545Z","caller":"traceutil/trace.go:171","msg":"trace[554497390] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:647; }","duration":"1.363502529s","start":"2026-08-24T15:17:49.872028Z","end":"2026-08-24T15:17:51.235531Z","steps":["trace[554497390] 'agreement among raft nodes before linearized reading' (duration: 1.363099134s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:51.235565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:49.875106Z","time spent":"1.360455801s","remote":"127.0.0.1:50254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-24T15:17:51.235584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:49.872Z","time spent":"1.363574161s","remote":"127.0.0.1:50254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-24T15:17:51.235606Z","caller":"traceutil/trace.go:171","msg":"trace[76341080] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"638.661315ms","start":"2026-08-24T15:17:50.596938Z","end":"2026-08-24T15:17:51.2356Z","steps":["trace[76341080] 'process raft request' (duration: 638.073384ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:51.23567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:50.596921Z","time spent":"638.696657ms","remote":"127.0.0.1:50230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-24T15:17:51.507093Z","caller":"traceutil/trace.go:171","msg":"trace[55963661] linearizableReadLoop","detail":"{readStateIndex:676; appliedIndex:672; }","duration":"238.425718ms","start":"2026-08-24T15:17:51.268646Z","end":"2026-08-24T15:17:51.507072Z","steps":["trace[55963661] 'read index received' (duration: 201.665341ms)","trace[55963661] 'applied index is now lower than readState.Index' (duration: 36.759437ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:17:51.507202Z","caller":"traceutil/trace.go:171","msg":"trace[622557393] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"267.438829ms","start":"2026-08-24T15:17:51.239752Z","end":"2026-08-24T15:17:51.507191Z","steps":["trace[622557393] 'process raft request' (duration: 267.18718ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:17:51.507427Z","caller":"traceutil/trace.go:171","msg":"trace[1084549157] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"266.943361ms","start":"2026-08-24T15:17:51.240455Z","end":"2026-08-24T15:17:51.507398Z","steps":["trace[1084549157] 'process raft request' (duration: 266.580349ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:17:51.507133Z","caller":"traceutil/trace.go:171","msg":"trace[1252634086] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"268.127553ms","start":"2026-08-24T15:17:51.238957Z","end":"2026-08-24T15:17:51.507084Z","steps":["trace[1252634086] 'process raft request' (duration: 231.389807ms)","trace[1252634086] 'compare' (duration: 36.347423ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:17:51.507605Z","caller":"traceutil/trace.go:171","msg":"trace[284856529] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"267.45834ms","start":"2026-08-24T15:17:51.240105Z","end":"2026-08-24T15:17:51.507563Z","steps":["trace[284856529] 'process raft request' (duration: 266.89558ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:51.507512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.766401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-08-24T15:17:51.507768Z","caller":"traceutil/trace.go:171","msg":"trace[1054325466] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:651; }","duration":"268.066582ms","start":"2026-08-24T15:17:51.239688Z","end":"2026-08-24T15:17:51.507755Z","steps":["trace[1054325466] 'agreement among raft nodes before linearized reading' (duration: 267.712119ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:17:51.576305Z","caller":"traceutil/trace.go:171","msg":"trace[331216651] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"306.361804ms","start":"2026-08-24T15:17:51.269911Z","end":"2026-08-24T15:17:51.576273Z","steps":["trace[331216651] 'process raft request' (duration: 306.125416ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:51.576428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.618385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-24T15:17:51.576509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:51.269658Z","time spent":"306.729926ms","remote":"127.0.0.1:39478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:17:51.576576Z","caller":"traceutil/trace.go:171","msg":"trace[181864136] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:652; }","duration":"301.886824ms","start":"2026-08-24T15:17:51.274652Z","end":"2026-08-24T15:17:51.576539Z","steps":["trace[181864136] 'agreement among raft nodes before linearized reading' (duration: 301.654526ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:51.576626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:51.274635Z","time spent":"301.982018ms","remote":"127.0.0.1:39444","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-08-24T15:17:51.991391Z","caller":"traceutil/trace.go:171","msg":"trace[46479557] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"343.232314ms","start":"2026-08-24T15:17:51.648139Z","end":"2026-08-24T15:17:51.991371Z","steps":["trace[46479557] 'process raft request' (duration: 343.10845ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:17:51.991493Z","caller":"traceutil/trace.go:171","msg":"trace[1045902287] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"342.586691ms","start":"2026-08-24T15:17:51.648864Z","end":"2026-08-24T15:17:51.991451Z","steps":["trace[1045902287] 'process raft request' (duration: 342.435086ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:51.991571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:51.648113Z","time spent":"343.368719ms","remote":"127.0.0.1:50182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:17:51.991663Z","caller":"traceutil/trace.go:171","msg":"trace[1025416015] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"344.700077ms","start":"2026-08-24T15:17:51.646946Z","end":"2026-08-24T15:17:51.991646Z","steps":["trace[1025416015] 'process raft request' (duration: 344.192019ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:51.991687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:51.648853Z","time spent":"342.740576ms","remote":"127.0.0.1:39526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-24T15:17:51.991699Z","caller":"traceutil/trace.go:171","msg":"trace[107385479] linearizableReadLoop","detail":"{readStateIndex:683; appliedIndex:679; }","duration":"215.99052ms","start":"2026-08-24T15:17:51.7757Z","end":"2026-08-24T15:17:51.99169Z","steps":["trace[107385479] 'read index received' (duration: 215.379329ms)","trace[107385479] 'applied index is now lower than readState.Index' (duration: 610.361µs)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:17:51.991738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:51.646911Z","time spent":"344.78302ms","remote":"127.0.0.1:39526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":688,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-24T15:17:51.991785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.089183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-08-24T15:17:51.991823Z","caller":"traceutil/trace.go:171","msg":"trace[1404120072] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:658; }","duration":"216.161406ms","start":"2026-08-24T15:17:51.775654Z","end":"2026-08-24T15:17:51.991815Z","steps":["trace[1404120072] 'agreement among raft nodes before linearized reading' (duration: 216.068543ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:17:51.991834Z","caller":"traceutil/trace.go:171","msg":"trace[1594222036] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"342.779988ms","start":"2026-08-24T15:17:51.649044Z","end":"2026-08-24T15:17:51.991824Z","steps":["trace[1594222036] 'process raft request' (duration: 342.282321ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:51.991889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:51.649034Z","time spent":"342.827309ms","remote":"127.0.0.1:50230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:17:53.564617Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9834630357978622840,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:17:53.731816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.161424431s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-8t6hc\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-08-24T15:17:53.731884Z","caller":"traceutil/trace.go:171","msg":"trace[1928028227] range","detail":"{range_begin:/registry/pods/kube-system/cilium-8t6hc; range_end:; response_count:1; response_revision:659; }","duration":"1.161540215s","start":"2026-08-24T15:17:52.57033Z","end":"2026-08-24T15:17:53.73187Z","steps":["trace[1928028227] 'range keys from in-memory index tree' (duration: 1.161231374s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:53.731915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:52.570309Z","time spent":"1.161597857s","remote":"127.0.0.1:50182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-8t6hc\" "} {"level":"info","ts":"2026-08-24T15:17:53.732596Z","caller":"traceutil/trace.go:171","msg":"trace[1838342863] linearizableReadLoop","detail":"{readStateIndex:685; appliedIndex:684; }","duration":"668.726964ms","start":"2026-08-24T15:17:53.063851Z","end":"2026-08-24T15:17:53.732578Z","steps":["trace[1838342863] 'read index received' (duration: 658.099475ms)","trace[1838342863] 'applied index is now lower than readState.Index' (duration: 10.626709ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:17:53.732753Z","caller":"traceutil/trace.go:171","msg":"trace[1206001871] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"982.40337ms","start":"2026-08-24T15:17:52.750342Z","end":"2026-08-24T15:17:53.732746Z","steps":["trace[1206001871] 'process raft request' (duration: 971.594045ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:53.732824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:52.750319Z","time spent":"982.452732ms","remote":"127.0.0.1:50254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:17:53.73294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"669.088476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:17:53.732961Z","caller":"traceutil/trace.go:171","msg":"trace[2010437880] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:660; }","duration":"669.133478ms","start":"2026-08-24T15:17:53.06382Z","end":"2026-08-24T15:17:53.732953Z","steps":["trace[2010437880] 'agreement among raft nodes before linearized reading' (duration: 669.100567ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:53.73298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:53.063805Z","time spent":"669.16936ms","remote":"127.0.0.1:39444","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-08-24T15:17:53.733182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.510136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-24T15:17:53.733213Z","caller":"traceutil/trace.go:171","msg":"trace[1278163689] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:660; }","duration":"220.576148ms","start":"2026-08-24T15:17:53.512627Z","end":"2026-08-24T15:17:53.733203Z","steps":["trace[1278163689] 'agreement among raft nodes before linearized reading' (duration: 220.474614ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:53.733451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.185354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-24T15:17:53.73348Z","caller":"traceutil/trace.go:171","msg":"trace[142181601] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:660; }","duration":"220.236106ms","start":"2026-08-24T15:17:53.513235Z","end":"2026-08-24T15:17:53.733472Z","steps":["trace[142181601] 'agreement among raft nodes before linearized reading' (duration: 220.153013ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:53.733635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.399272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-24T15:17:53.733687Z","caller":"traceutil/trace.go:171","msg":"trace[1700683707] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:660; }","duration":"220.474294ms","start":"2026-08-24T15:17:53.513199Z","end":"2026-08-24T15:17:53.733673Z","steps":["trace[1700683707] 'agreement among raft nodes before linearized reading' (duration: 220.309719ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:53.733877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.863578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-24T15:17:53.733896Z","caller":"traceutil/trace.go:171","msg":"trace[1229216845] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:660; }","duration":"220.935211ms","start":"2026-08-24T15:17:53.512955Z","end":"2026-08-24T15:17:53.73389Z","steps":["trace[1229216845] 'agreement among raft nodes before linearized reading' (duration: 220.864178ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:55.56552Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9834630357978622844,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:17:55.747363Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.994106203s","expected-duration":"1s"} {"level":"warn","ts":"2026-08-24T15:17:57.065648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000793996s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-08-24T15:17:57.065734Z","caller":"traceutil/trace.go:171","msg":"trace[1381044438] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000923181s","start":"2026-08-24T15:17:55.064793Z","end":"2026-08-24T15:17:57.065716Z","steps":["trace[1381044438] 'agreement among raft nodes before linearized reading' (duration: 2.000817857s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:57.066049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:55.064778Z","time spent":"2.001221832s","remote":"127.0.0.1:39444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/24 15:17:57 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-24T15:17:58.736139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:53.737567Z","time spent":"4.99856539s","remote":"127.0.0.1:50254","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/08/24 15:17:58 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-24T15:17:58.737269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:53.737685Z","time spent":"4.999582085s","remote":"127.0.0.1:50254","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-08-24T15:17:58.816342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.068635765s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-24T15:17:58.816875Z","caller":"traceutil/trace.go:171","msg":"trace[984449958] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"5.079185546s","start":"2026-08-24T15:17:53.737664Z","end":"2026-08-24T15:17:58.816849Z","steps":["trace[984449958] 'process raft request' (duration: 5.07878025s)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:17:58.816901Z","caller":"traceutil/trace.go:171","msg":"trace[955203756] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"5.072483966s","start":"2026-08-24T15:17:53.744376Z","end":"2026-08-24T15:17:58.81686Z","steps":["trace[955203756] 'process raft request' (duration: 5.072216406s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:58.817052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:53.744356Z","time spent":"5.072646142s","remote":"127.0.0.1:50182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:17:58.817615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:53.737658Z","time spent":"5.079276789s","remote":"127.0.0.1:50254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:17:58.852669Z","caller":"traceutil/trace.go:171","msg":"trace[2096756095] linearizableReadLoop","detail":"{readStateIndex:691; appliedIndex:686; }","duration":"3.787822858s","start":"2026-08-24T15:17:55.064825Z","end":"2026-08-24T15:17:58.852648Z","steps":["trace[2096756095] 'read index received' (duration: 682.797321ms)","trace[2096756095] 'applied index is now lower than readState.Index' (duration: 3.105024567s)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:17:58.852885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.355811163s","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":"warn","ts":"2026-08-24T15:17:58.852896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.097389025s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-08-24T15:17:58.852964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"945.550064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-24T15:17:58.852974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.78669722s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:17:58.852972Z","caller":"traceutil/trace.go:171","msg":"trace[30076983] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:665; }","duration":"3.097512529s","start":"2026-08-24T15:17:55.755443Z","end":"2026-08-24T15:17:58.852956Z","steps":["trace[30076983] 'agreement among raft nodes before linearized reading' (duration: 3.097327763s)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:17:58.852992Z","caller":"traceutil/trace.go:171","msg":"trace[1540962393] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:665; }","duration":"945.616006ms","start":"2026-08-24T15:17:57.907369Z","end":"2026-08-24T15:17:58.852985Z","steps":["trace[1540962393] 'agreement among raft nodes before linearized reading' (duration: 945.574045ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:17:58.853003Z","caller":"traceutil/trace.go:171","msg":"trace[443448577] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:665; }","duration":"1.786761501s","start":"2026-08-24T15:17:57.066233Z","end":"2026-08-24T15:17:58.852994Z","steps":["trace[443448577] 'agreement among raft nodes before linearized reading' (duration: 1.78671719s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:58.853022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:57.066212Z","time spent":"1.786806243s","remote":"127.0.0.1:39432","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-08-24T15:17:58.853024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:57.907349Z","time spent":"945.664428ms","remote":"127.0.0.1:50168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":2,"response size":30,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-08-24T15:17:58.853013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:55.755428Z","time spent":"3.097575762s","remote":"127.0.0.1:50254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-24T15:17:58.852932Z","caller":"traceutil/trace.go:171","msg":"trace[200699959] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:665; }","duration":"1.355916488s","start":"2026-08-24T15:17:57.496998Z","end":"2026-08-24T15:17:58.852915Z","steps":["trace[200699959] 'agreement among raft nodes before linearized reading' (duration: 1.355795863s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:58.853224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:57.496979Z","time spent":"1.356234339s","remote":"127.0.0.1:50182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":12,"response size":30,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-08-24T15:18:00.094712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.093345ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-24T15:18:00.094933Z","caller":"traceutil/trace.go:171","msg":"trace[967371537] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"501.960841ms","start":"2026-08-24T15:17:59.592951Z","end":"2026-08-24T15:18:00.094912Z","steps":["trace[967371537] 'process raft request' (duration: 501.881639ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:18:00.094988Z","caller":"traceutil/trace.go:171","msg":"trace[292375586] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"532.621709ms","start":"2026-08-24T15:17:59.56234Z","end":"2026-08-24T15:18:00.094962Z","steps":["trace[292375586] 'process raft request' (duration: 94.185193ms)","trace[292375586] 'compare' (duration: 437.918548ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:18:00.095026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:59.592928Z","time spent":"502.060115ms","remote":"127.0.0.1:39526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":765,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-24T15:18:00.095004Z","caller":"traceutil/trace.go:171","msg":"trace[335376320] linearizableReadLoop","detail":"{readStateIndex:696; appliedIndex:695; }","duration":"503.751025ms","start":"2026-08-24T15:17:59.591215Z","end":"2026-08-24T15:18:00.094966Z","steps":["trace[335376320] 'read index received' (duration: 65.322509ms)","trace[335376320] 'applied index is now lower than readState.Index' (duration: 438.423846ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:18:00.095056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:59.562325Z","time spent":"532.696502ms","remote":"127.0.0.1:39478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:18:00.095241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.026416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-8t6hc\" ","response":"range_response_count:1 size:17149"} {"level":"info","ts":"2026-08-24T15:18:00.095273Z","caller":"traceutil/trace.go:171","msg":"trace[203375096] range","detail":"{range_begin:/registry/pods/kube-system/cilium-8t6hc; range_end:; response_count:1; response_revision:669; }","duration":"504.057297ms","start":"2026-08-24T15:17:59.591208Z","end":"2026-08-24T15:18:00.095266Z","steps":["trace[203375096] 'agreement among raft nodes before linearized reading' (duration: 503.87402ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:18:00.095297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:59.59116Z","time spent":"504.132469ms","remote":"127.0.0.1:50182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17172,"request content":"key:\"/registry/pods/kube-system/cilium-8t6hc\" "} {"level":"info","ts":"2026-08-24T15:18:00.361753Z","caller":"traceutil/trace.go:171","msg":"trace[1146691084] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"246.953323ms","start":"2026-08-24T15:18:00.114776Z","end":"2026-08-24T15:18:00.361729Z","steps":["trace[1146691084] 'process raft request' (duration: 246.785907ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:18:01.161209Z","caller":"traceutil/trace.go:171","msg":"trace[662263466] linearizableReadLoop","detail":"{readStateIndex:701; appliedIndex:700; }","duration":"251.914515ms","start":"2026-08-24T15:18:00.909269Z","end":"2026-08-24T15:18:01.161183Z","steps":["trace[662263466] 'read index received' (duration: 251.77543ms)","trace[662263466] 'applied index is now lower than readState.Index' (duration: 137.955µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:18:01.161334Z","caller":"traceutil/trace.go:171","msg":"trace[120578863] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"324.744735ms","start":"2026-08-24T15:18:00.836532Z","end":"2026-08-24T15:18:01.161277Z","steps":["trace[120578863] 'process raft request' (duration: 324.491576ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:18:01.161497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.206287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-24T15:18:01.161559Z","caller":"traceutil/trace.go:171","msg":"trace[2006141720] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:673; }","duration":"252.3085ms","start":"2026-08-24T15:18:00.909235Z","end":"2026-08-24T15:18:01.161544Z","steps":["trace[2006141720] 'agreement among raft nodes before linearized reading' (duration: 252.130273ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:18:01.161704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:18:00.836518Z","time spent":"325.031396ms","remote":"127.0.0.1:50254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:18:02.741846Z","caller":"traceutil/trace.go:171","msg":"trace[1569220997] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"213.268137ms","start":"2026-08-24T15:18:02.528546Z","end":"2026-08-24T15:18:02.741814Z","steps":["trace[1569220997] 'process raft request' (duration: 213.093951ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:18:04.342116Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9834630357978622920,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-24T15:18:04.432158Z","caller":"traceutil/trace.go:171","msg":"trace[265739495] linearizableReadLoop","detail":"{readStateIndex:736; appliedIndex:735; }","duration":"590.795832ms","start":"2026-08-24T15:18:03.841334Z","end":"2026-08-24T15:18:04.43213Z","steps":["trace[265739495] 'read index received' (duration: 583.475648ms)","trace[265739495] 'applied index is now lower than readState.Index' (duration: 7.318964ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:18:04.432202Z","caller":"traceutil/trace.go:171","msg":"trace[2093019752] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"616.132622ms","start":"2026-08-24T15:18:03.816036Z","end":"2026-08-24T15:18:04.432169Z","steps":["trace[2093019752] 'process raft request' (duration: 608.904212ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:18:04.43233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.452302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-24T15:18:04.432371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:18:03.816015Z","time spent":"616.264657ms","remote":"127.0.0.1:39494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:18:04.432503Z","caller":"traceutil/trace.go:171","msg":"trace[1271746844] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:708; }","duration":"390.588327ms","start":"2026-08-24T15:18:04.041817Z","end":"2026-08-24T15:18:04.432405Z","steps":["trace[1271746844] 'agreement among raft nodes before linearized reading' (duration: 390.459592ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:18:04.43253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.407148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:18:04.432592Z","caller":"traceutil/trace.go:171","msg":"trace[2106190584] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:708; }","duration":"190.530232ms","start":"2026-08-24T15:18:04.242048Z","end":"2026-08-24T15:18:04.432578Z","steps":["trace[2106190584] 'agreement among raft nodes before linearized reading' (duration: 190.415198ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:18:04.432559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:18:04.041799Z","time spent":"390.748452ms","remote":"127.0.0.1:39494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"warn","ts":"2026-08-24T15:18:04.432728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.376872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:18:04.432807Z","caller":"traceutil/trace.go:171","msg":"trace[831063419] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:708; }","duration":"591.494637ms","start":"2026-08-24T15:18:03.841291Z","end":"2026-08-24T15:18:04.432785Z","steps":["trace[831063419] 'agreement among raft nodes before linearized reading' (duration: 590.958648ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:18:04.432863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:18:03.841269Z","time spent":"591.580549ms","remote":"127.0.0.1:39494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"info","ts":"2026-08-24T15:18:04.780006Z","caller":"traceutil/trace.go:171","msg":"trace[2130176121] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"150.823804ms","start":"2026-08-24T15:18:04.629154Z","end":"2026-08-24T15:18:04.779978Z","steps":["trace[2130176121] 'process raft request' (duration: 150.623467ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:18:05.021768Z","caller":"traceutil/trace.go:171","msg":"trace[93069116] linearizableReadLoop","detail":"{readStateIndex:745; appliedIndex:744; }","duration":"212.285694ms","start":"2026-08-24T15:18:04.809462Z","end":"2026-08-24T15:18:05.021747Z","steps":["trace[93069116] 'read index received' (duration: 211.450484ms)","trace[93069116] 'applied index is now lower than readState.Index' (duration: 833.98µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:18:05.021849Z","caller":"traceutil/trace.go:171","msg":"trace[1316299369] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"378.67861ms","start":"2026-08-24T15:18:04.643148Z","end":"2026-08-24T15:18:05.021827Z","steps":["trace[1316299369] 'process raft request' (duration: 377.86356ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:18:05.022043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.173111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-24T15:18:05.022119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.642547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-24T15:18:05.022146Z","caller":"traceutil/trace.go:171","msg":"trace[1619094837] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:717; }","duration":"180.314086ms","start":"2026-08-24T15:18:04.841816Z","end":"2026-08-24T15:18:05.02213Z","steps":["trace[1619094837] 'agreement among raft nodes before linearized reading' (duration: 180.186841ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:18:05.022154Z","caller":"traceutil/trace.go:171","msg":"trace[24760912] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:717; }","duration":"212.71676ms","start":"2026-08-24T15:18:04.809427Z","end":"2026-08-24T15:18:05.022144Z","steps":["trace[24760912] 'agreement among raft nodes before linearized reading' (duration: 212.488662ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:18:05.022175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.175753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-24T15:18:05.022214Z","caller":"traceutil/trace.go:171","msg":"trace[411901682] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:717; }","duration":"177.252215ms","start":"2026-08-24T15:18:04.844952Z","end":"2026-08-24T15:18:05.022204Z","steps":["trace[411901682] 'agreement among raft nodes before linearized reading' (duration: 177.080619ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:18:05.022334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:18:04.643124Z","time spent":"378.819705ms","remote":"127.0.0.1:39494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3104,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-24T15:18:07.128513Z","caller":"traceutil/trace.go:171","msg":"trace[1827103473] linearizableReadLoop","detail":"{readStateIndex:773; appliedIndex:772; }","duration":"235.810798ms","start":"2026-08-24T15:18:06.892661Z","end":"2026-08-24T15:18:07.128472Z","steps":["trace[1827103473] 'read index received' (duration: 235.557968ms)","trace[1827103473] 'applied index is now lower than readState.Index' (duration: 251.53µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:18:07.128651Z","caller":"traceutil/trace.go:171","msg":"trace[1791571887] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"260.171274ms","start":"2026-08-24T15:18:06.86847Z","end":"2026-08-24T15:18:07.128642Z","steps":["trace[1791571887] 'process raft request' (duration: 259.771979ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:18:07.12894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.246924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:18:07.129016Z","caller":"traceutil/trace.go:171","msg":"trace[1054328655] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:744; }","duration":"236.370888ms","start":"2026-08-24T15:18:06.892629Z","end":"2026-08-24T15:18:07.129Z","steps":["trace[1054328655] 'agreement among raft nodes before linearized reading' (duration: 236.165361ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:18:07.134157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.470696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:18:07.134215Z","caller":"traceutil/trace.go:171","msg":"trace[410837837] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:745; }","duration":"157.571439ms","start":"2026-08-24T15:18:06.976631Z","end":"2026-08-24T15:18:07.134202Z","steps":["trace[410837837] 'agreement among raft nodes before linearized reading' (duration: 157.481486ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:18:07.134247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.680562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:18:07.134287Z","caller":"traceutil/trace.go:171","msg":"trace[224870142] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:745; }","duration":"185.771235ms","start":"2026-08-24T15:18:06.948506Z","end":"2026-08-24T15:18:07.134278Z","steps":["trace[224870142] 'agreement among raft nodes before linearized reading' (duration: 185.696562ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:18:07.134422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.260374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:18:07.134447Z","caller":"traceutil/trace.go:171","msg":"trace[284718513] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:745; }","duration":"158.286875ms","start":"2026-08-24T15:18:06.976154Z","end":"2026-08-24T15:18:07.134441Z","steps":["trace[284718513] 'agreement among raft nodes before linearized reading' (duration: 158.249514ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:18:07.134545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.051978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:18:07.134568Z","caller":"traceutil/trace.go:171","msg":"trace[1321006488] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:745; }","duration":"185.086909ms","start":"2026-08-24T15:18:06.949476Z","end":"2026-08-24T15:18:07.134562Z","steps":["trace[1321006488] 'agreement among raft nodes before linearized reading' (duration: 185.051858ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:18:07.134612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.5464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:18:07.134638Z","caller":"traceutil/trace.go:171","msg":"trace[1460730602] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:745; }","duration":"119.586581ms","start":"2026-08-24T15:18:07.015046Z","end":"2026-08-24T15:18:07.134632Z","steps":["trace[1460730602] 'agreement among raft nodes before linearized reading' (duration: 119.545719ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:18:07.134736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.383889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:18:07.134755Z","caller":"traceutil/trace.go:171","msg":"trace[1213149839] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:745; }","duration":"120.433011ms","start":"2026-08-24T15:18:07.014317Z","end":"2026-08-24T15:18:07.13475Z","steps":["trace[1213149839] 'agreement among raft nodes before linearized reading' (duration: 120.40543ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:18:07.134824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.501773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:18:07.134844Z","caller":"traceutil/trace.go:171","msg":"trace[1042964864] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:745; }","duration":"120.550015ms","start":"2026-08-24T15:18:07.014289Z","end":"2026-08-24T15:18:07.13484Z","steps":["trace[1042964864] 'agreement among raft nodes before linearized reading' (duration: 120.520104ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:18:07.134998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.881235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:18:07.135027Z","caller":"traceutil/trace.go:171","msg":"trace[1205589681] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:745; }","duration":"216.937848ms","start":"2026-08-24T15:18:06.918082Z","end":"2026-08-24T15:18:07.13502Z","steps":["trace[1205589681] 'agreement among raft nodes before linearized reading' (duration: 216.896886ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:18:07.135114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.711669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:18:07.135135Z","caller":"traceutil/trace.go:171","msg":"trace[1094889349] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:745; }","duration":"186.75796ms","start":"2026-08-24T15:18:06.948372Z","end":"2026-08-24T15:18:07.13513Z","steps":["trace[1094889349] 'agreement among raft nodes before linearized reading' (duration: 186.726749ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:18:07.135202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.099124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:18:07.13523Z","caller":"traceutil/trace.go:171","msg":"trace[1602421967] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:745; }","duration":"217.165776ms","start":"2026-08-24T15:18:06.918057Z","end":"2026-08-24T15:18:07.135223Z","steps":["trace[1602421967] 'agreement among raft nodes before linearized reading' (duration: 217.120314ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:18:07.134066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.316106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:18:07.135356Z","caller":"traceutil/trace.go:171","msg":"trace[213949035] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:745; }","duration":"242.633603ms","start":"2026-08-24T15:18:06.892712Z","end":"2026-08-24T15:18:07.135345Z","steps":["trace[213949035] 'agreement among raft nodes before linearized reading' (duration: 241.288584ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:18:17.253142Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9834630357978623227,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:18:17.753615Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9834630357978623227,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:18:18.253795Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9834630357978623227,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:18:18.739126Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.032197924s","expected-duration":"1s"} {"level":"info","ts":"2026-08-24T15:18:18.739656Z","caller":"traceutil/trace.go:171","msg":"trace[495732534] linearizableReadLoop","detail":"{readStateIndex:854; appliedIndex:853; }","duration":"1.98686487s","start":"2026-08-24T15:18:16.75277Z","end":"2026-08-24T15:18:18.739635Z","steps":["trace[495732534] 'read index received' (duration: 1.986469706s)","trace[495732534] 'applied index is now lower than readState.Index' (duration: 394.144µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:18:18.739766Z","caller":"traceutil/trace.go:171","msg":"trace[1194060872] transaction","detail":"{read_only:false; response_revision:822; number_of_response:1; }","duration":"2.055308263s","start":"2026-08-24T15:18:16.684405Z","end":"2026-08-24T15:18:18.739713Z","steps":["trace[1194060872] 'process raft request' (duration: 2.055011912s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:18:18.739804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.987034306s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-24T15:18:18.739864Z","caller":"traceutil/trace.go:171","msg":"trace[1734330015] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:822; }","duration":"1.987152291s","start":"2026-08-24T15:18:16.7527Z","end":"2026-08-24T15:18:18.739853Z","steps":["trace[1734330015] 'agreement among raft nodes before linearized reading' (duration: 1.987038057s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:18:18.739903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:18:16.752675Z","time spent":"1.987218513s","remote":"127.0.0.1:39566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":5,"response size":30,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-08-24T15:18:18.739968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.194390224s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-24T15:18:18.740007Z","caller":"traceutil/trace.go:171","msg":"trace[540776033] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:822; }","duration":"1.194472527s","start":"2026-08-24T15:18:17.545526Z","end":"2026-08-24T15:18:18.739998Z","steps":["trace[540776033] 'agreement among raft nodes before linearized reading' (duration: 1.194366903s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:18:18.740035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:18:17.5455Z","time spent":"1.194527939s","remote":"127.0.0.1:50254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":7,"response size":30,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-08-24T15:18:18.740088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.323713308s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-24T15:18:18.740174Z","caller":"traceutil/trace.go:171","msg":"trace[2111281720] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:822; }","duration":"1.323832993s","start":"2026-08-24T15:18:17.416319Z","end":"2026-08-24T15:18:18.740152Z","steps":["trace[2111281720] 'agreement among raft nodes before linearized reading' (duration: 1.323627335s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:18:18.740225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:18:17.416306Z","time spent":"1.323906095s","remote":"127.0.0.1:50408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-08-24T15:18:18.740217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.548587739s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-08-24T15:18:18.7402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.016165276s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-08-24T15:18:18.740338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.549494584s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-08-24T15:18:18.740359Z","caller":"traceutil/trace.go:171","msg":"trace[161270958] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:822; }","duration":"1.016357043s","start":"2026-08-24T15:18:17.723988Z","end":"2026-08-24T15:18:18.740345Z","steps":["trace[161270958] 'agreement among raft nodes before linearized reading' (duration: 1.015886936s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:18:18.740408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.97798322s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:18:18.740994Z","caller":"traceutil/trace.go:171","msg":"trace[1741376432] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:822; }","duration":"1.978631763s","start":"2026-08-24T15:18:16.762345Z","end":"2026-08-24T15:18:18.740977Z","steps":["trace[1741376432] 'agreement among raft nodes before linearized reading' (duration: 1.97745637s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:18:18.741005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:18:17.723973Z","time spent":"1.017022028s","remote":"127.0.0.1:50254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-24T15:18:18.740988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.674858273s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-24T15:18:18.741053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:18:16.762322Z","time spent":"1.978707875s","remote":"127.0.0.1:39616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"info","ts":"2026-08-24T15:18:18.741108Z","caller":"traceutil/trace.go:171","msg":"trace[174402451] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:822; }","duration":"1.674995298s","start":"2026-08-24T15:18:17.066048Z","end":"2026-08-24T15:18:18.741043Z","steps":["trace[174402451] 'agreement among raft nodes before linearized reading' (duration: 1.674327144s)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:18:18.740374Z","caller":"traceutil/trace.go:171","msg":"trace[833010097] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:822; }","duration":"1.549554446s","start":"2026-08-24T15:18:17.190811Z","end":"2026-08-24T15:18:18.740365Z","steps":["trace[833010097] 'agreement among raft nodes before linearized reading' (duration: 1.549462103s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:18:18.741209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:18:17.066019Z","time spent":"1.675175764s","remote":"127.0.0.1:39444","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-08-24T15:18:18.74122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:18:17.190794Z","time spent":"1.550414667s","remote":"127.0.0.1:50254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-24T15:18:18.740393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.499348854s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-08-24T15:18:18.741577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:18:16.684382Z","time spent":"2.055448247s","remote":"127.0.0.1:50182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4025,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:18:18.741639Z","caller":"traceutil/trace.go:171","msg":"trace[1604696944] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:822; }","duration":"1.50062529s","start":"2026-08-24T15:18:17.240999Z","end":"2026-08-24T15:18:18.741625Z","steps":["trace[1604696944] 'agreement among raft nodes before linearized reading' (duration: 1.499312162s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:18:18.741693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:18:17.240981Z","time spent":"1.500699943s","remote":"127.0.0.1:50254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-24T15:18:18.740853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.450556322s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-24T15:18:18.741993Z","caller":"traceutil/trace.go:171","msg":"trace[1612183060] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:822; }","duration":"1.451737374s","start":"2026-08-24T15:18:17.290244Z","end":"2026-08-24T15:18:18.741982Z","steps":["trace[1612183060] 'agreement among raft nodes before linearized reading' (duration: 1.450536141s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:18:18.742038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:18:17.290219Z","time spent":"1.451800377s","remote":"127.0.0.1:50254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-24T15:18:18.740283Z","caller":"traceutil/trace.go:171","msg":"trace[1754704713] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:822; }","duration":"1.548721705s","start":"2026-08-24T15:18:17.191545Z","end":"2026-08-24T15:18:18.740267Z","steps":["trace[1754704713] 'agreement among raft nodes before linearized reading' (duration: 1.548488655s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:18:18.742278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:18:17.191526Z","time spent":"1.550731237s","remote":"127.0.0.1:50254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-24T15:18:18.939681Z","caller":"traceutil/trace.go:171","msg":"trace[350296944] transaction","detail":"{read_only:false; response_revision:823; number_of_response:1; }","duration":"193.701814ms","start":"2026-08-24T15:18:18.745959Z","end":"2026-08-24T15:18:18.939661Z","steps":["trace[350296944] 'process raft request' (duration: 193.539198ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:18:18.941588Z","caller":"traceutil/trace.go:171","msg":"trace[1062978531] transaction","detail":"{read_only:false; response_revision:824; number_of_response:1; }","duration":"194.135839ms","start":"2026-08-24T15:18:18.747433Z","end":"2026-08-24T15:18:18.941569Z","steps":["trace[1062978531] 'process raft request' (duration: 193.911141ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:18:18.941595Z","caller":"traceutil/trace.go:171","msg":"trace[1345888488] transaction","detail":"{read_only:false; response_revision:825; number_of_response:1; }","duration":"191.767403ms","start":"2026-08-24T15:18:18.7498Z","end":"2026-08-24T15:18:18.941567Z","steps":["trace[1345888488] 'process raft request' (duration: 191.67921ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:18:18.941867Z","caller":"traceutil/trace.go:171","msg":"trace[2041553524] transaction","detail":"{read_only:false; response_revision:826; number_of_response:1; }","duration":"191.782274ms","start":"2026-08-24T15:18:18.750058Z","end":"2026-08-24T15:18:18.94184Z","steps":["trace[2041553524] 'process raft request' (duration: 191.467543ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:18:18.941962Z","caller":"traceutil/trace.go:171","msg":"trace[458903500] linearizableReadLoop","detail":"{readStateIndex:859; appliedIndex:854; }","duration":"190.121704ms","start":"2026-08-24T15:18:18.75183Z","end":"2026-08-24T15:18:18.941951Z","steps":["trace[458903500] 'read index received' (duration: 187.730728ms)","trace[458903500] 'applied index is now lower than readState.Index' (duration: 2.390346ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:18:18.942044Z","caller":"traceutil/trace.go:171","msg":"trace[1548227743] transaction","detail":"{read_only:false; response_revision:827; number_of_response:1; }","duration":"191.919091ms","start":"2026-08-24T15:18:18.750101Z","end":"2026-08-24T15:18:18.94202Z","steps":["trace[1548227743] 'process raft request' (duration: 191.66334ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:18:18.942116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.26972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-24T15:18:18.942142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.07572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-t88qg\" ","response":"range_response_count:1 size:5050"} {"level":"info","ts":"2026-08-24T15:18:18.942167Z","caller":"traceutil/trace.go:171","msg":"trace[1887641361] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-t88qg; range_end:; response_count:1; response_revision:827; }","duration":"104.137823ms","start":"2026-08-24T15:18:18.83802Z","end":"2026-08-24T15:18:18.942158Z","steps":["trace[1887641361] 'agreement among raft nodes before linearized reading' (duration: 104.003848ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:18:18.94219Z","caller":"traceutil/trace.go:171","msg":"trace[611896216] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:827; }","duration":"190.340423ms","start":"2026-08-24T15:18:18.751818Z","end":"2026-08-24T15:18:18.942158Z","steps":["trace[611896216] 'agreement among raft nodes before linearized reading' (duration: 190.205278ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:18:25.097857Z","caller":"traceutil/trace.go:171","msg":"trace[251632358] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"125.173525ms","start":"2026-08-24T15:18:24.972655Z","end":"2026-08-24T15:18:25.097828Z","steps":["trace[251632358] 'process raft request' (duration: 124.973007ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:18:25.103226Z","caller":"traceutil/trace.go:171","msg":"trace[1881452060] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"129.628245ms","start":"2026-08-24T15:18:24.973573Z","end":"2026-08-24T15:18:25.103201Z","steps":["trace[1881452060] 'process raft request' (duration: 129.442689ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:18:25.103578Z","caller":"traceutil/trace.go:171","msg":"trace[1816376814] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"129.828963ms","start":"2026-08-24T15:18:24.973724Z","end":"2026-08-24T15:18:25.103553Z","steps":["trace[1816376814] 'process raft request' (duration: 129.437299ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:18:25.103827Z","caller":"traceutil/trace.go:171","msg":"trace[825977053] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"128.07726ms","start":"2026-08-24T15:18:24.975728Z","end":"2026-08-24T15:18:25.103805Z","steps":["trace[825977053] 'process raft request' (duration: 127.79691ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:18:25.103845Z","caller":"traceutil/trace.go:171","msg":"trace[469052126] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"128.049739ms","start":"2026-08-24T15:18:24.975756Z","end":"2026-08-24T15:18:25.103806Z","steps":["trace[469052126] 'process raft request' (duration: 127.981447ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:18:26.000337Z","caller":"traceutil/trace.go:171","msg":"trace[559122381] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"211.877807ms","start":"2026-08-24T15:18:25.788441Z","end":"2026-08-24T15:18:26.000318Z","steps":["trace[559122381] 'process raft request' (duration: 211.742302ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:18:26.373574Z","caller":"traceutil/trace.go:171","msg":"trace[1686660944] linearizableReadLoop","detail":"{readStateIndex:939; appliedIndex:938; }","duration":"104.126069ms","start":"2026-08-24T15:18:26.269422Z","end":"2026-08-24T15:18:26.373548Z","steps":["trace[1686660944] 'read index received' (duration: 17.130405ms)","trace[1686660944] 'applied index is now lower than readState.Index' (duration: 86.994494ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:18:26.3737Z","caller":"traceutil/trace.go:171","msg":"trace[844297281] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"251.87942ms","start":"2026-08-24T15:18:26.121807Z","end":"2026-08-24T15:18:26.373686Z","steps":["trace[844297281] 'process raft request' (duration: 164.785943ms)","trace[844297281] 'compare' (duration: 86.661582ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:18:26.373764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.290537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:179"} {"level":"info","ts":"2026-08-24T15:18:26.373827Z","caller":"traceutil/trace.go:171","msg":"trace[894995336] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:905; }","duration":"103.369722ms","start":"2026-08-24T15:18:26.27044Z","end":"2026-08-24T15:18:26.37381Z","steps":["trace[894995336] 'agreement among raft nodes before linearized reading' (duration: 103.271718ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:18:26.373986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.562715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:179"} {"level":"info","ts":"2026-08-24T15:18:26.374009Z","caller":"traceutil/trace.go:171","msg":"trace[191749889] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:905; }","duration":"104.612307ms","start":"2026-08-24T15:18:26.269391Z","end":"2026-08-24T15:18:26.374003Z","steps":["trace[191749889] 'agreement among raft nodes before linearized reading' (duration: 104.574905ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:18:29.011497Z","caller":"traceutil/trace.go:171","msg":"trace[2063090658] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"264.320724ms","start":"2026-08-24T15:18:28.747115Z","end":"2026-08-24T15:18:29.011435Z","steps":["trace[2063090658] 'process raft request' (duration: 264.170229ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:18:29.154224Z","caller":"traceutil/trace.go:171","msg":"trace[1114333976] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"137.671791ms","start":"2026-08-24T15:18:29.016534Z","end":"2026-08-24T15:18:29.154206Z","steps":["trace[1114333976] 'process raft request' (duration: 137.493915ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:18:29.318119Z","caller":"traceutil/trace.go:171","msg":"trace[1265230378] linearizableReadLoop","detail":"{readStateIndex:957; appliedIndex:954; }","duration":"252.955404ms","start":"2026-08-24T15:18:29.065135Z","end":"2026-08-24T15:18:29.318091Z","steps":["trace[1265230378] 'read index received' (duration: 88.871868ms)","trace[1265230378] 'applied index is now lower than readState.Index' (duration: 164.082396ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:18:29.318171Z","caller":"traceutil/trace.go:171","msg":"trace[90500549] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"301.248508ms","start":"2026-08-24T15:18:29.016884Z","end":"2026-08-24T15:18:29.318132Z","steps":["trace[90500549] 'process raft request' (duration: 255.919732ms)","trace[90500549] 'compare' (duration: 44.998524ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:18:29.31824Z","caller":"traceutil/trace.go:171","msg":"trace[1641529982] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"295.424296ms","start":"2026-08-24T15:18:29.022803Z","end":"2026-08-24T15:18:29.318227Z","steps":["trace[1641529982] 'process raft request' (duration: 295.195407ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:18:29.318339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.190533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-24T15:18:29.318355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:18:29.016867Z","time spent":"301.400544ms","remote":"127.0.0.1:50168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":897,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:18:29.318391Z","caller":"traceutil/trace.go:171","msg":"trace[489913518] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:923; }","duration":"253.299086ms","start":"2026-08-24T15:18:29.065077Z","end":"2026-08-24T15:18:29.318377Z","steps":["trace[489913518] 'agreement among raft nodes before linearized reading' (duration: 253.200892ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:18:29.318427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.631124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-24T15:18:29.318491Z","caller":"traceutil/trace.go:171","msg":"trace[530344578] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:923; }","duration":"217.739828ms","start":"2026-08-24T15:18:29.100734Z","end":"2026-08-24T15:18:29.318474Z","steps":["trace[530344578] 'agreement among raft nodes before linearized reading' (duration: 217.627612ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:18:29.318618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.541748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:18:29.318658Z","caller":"traceutil/trace.go:171","msg":"trace[65150197] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:0; response_revision:923; }","duration":"125.59885ms","start":"2026-08-24T15:18:29.19305Z","end":"2026-08-24T15:18:29.318649Z","steps":["trace[65150197] 'agreement among raft nodes before linearized reading' (duration: 125.540798ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:18:29.318745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.651487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-08-24T15:18:29.318849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.802116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-08-24T15:18:29.318874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.021714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"warn","ts":"2026-08-24T15:18:29.318857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.042054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-08-24T15:18:29.318861Z","caller":"traceutil/trace.go:171","msg":"trace[1146846342] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:923; }","duration":"164.817553ms","start":"2026-08-24T15:18:29.154012Z","end":"2026-08-24T15:18:29.31883Z","steps":["trace[1146846342] 'agreement among raft nodes before linearized reading' (duration: 164.45043ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:18:29.318894Z","caller":"traceutil/trace.go:171","msg":"trace[918993588] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:923; }","duration":"169.887759ms","start":"2026-08-24T15:18:29.148994Z","end":"2026-08-24T15:18:29.318882Z","steps":["trace[918993588] 'agreement among raft nodes before linearized reading' (duration: 169.721803ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:18:29.318916Z","caller":"traceutil/trace.go:171","msg":"trace[1484598209] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:923; }","duration":"196.125377ms","start":"2026-08-24T15:18:29.122771Z","end":"2026-08-24T15:18:29.318896Z","steps":["trace[1484598209] 'agreement among raft nodes before linearized reading' (duration: 195.983972ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:18:29.318907Z","caller":"traceutil/trace.go:171","msg":"trace[1132577194] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:923; }","duration":"170.079096ms","start":"2026-08-24T15:18:29.148809Z","end":"2026-08-24T15:18:29.318888Z","steps":["trace[1132577194] 'agreement among raft nodes before linearized reading' (duration: 170.014003ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:18:29.318908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.43887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-24T15:18:29.31912Z","caller":"traceutil/trace.go:171","msg":"trace[1994320818] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:923; }","duration":"164.696788ms","start":"2026-08-24T15:18:29.154409Z","end":"2026-08-24T15:18:29.319106Z","steps":["trace[1994320818] 'agreement among raft nodes before linearized reading' (duration: 164.347366ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:18:29.748628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.624518ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:18:29.74874Z","caller":"traceutil/trace.go:171","msg":"trace[1308631073] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:932; }","duration":"279.746343ms","start":"2026-08-24T15:18:29.468976Z","end":"2026-08-24T15:18:29.748723Z","steps":["trace[1308631073] 'range keys from in-memory index tree' (duration: 279.610668ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:18:29.748773Z","caller":"traceutil/trace.go:171","msg":"trace[1385247301] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"390.291132ms","start":"2026-08-24T15:18:29.358467Z","end":"2026-08-24T15:18:29.748758Z","steps":["trace[1385247301] 'process raft request' (duration: 390.193388ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:18:29.748865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:18:29.358441Z","time spent":"390.374655ms","remote":"127.0.0.1:50460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3888,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:18:29.898675Z","caller":"traceutil/trace.go:171","msg":"trace[1461492783] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"539.374751ms","start":"2026-08-24T15:18:29.359266Z","end":"2026-08-24T15:18:29.898641Z","steps":["trace[1461492783] 'process raft request' (duration: 539.283297ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:18:29.898817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:18:29.359241Z","time spent":"539.502335ms","remote":"127.0.0.1:50168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1011,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:18:29.898669Z","caller":"traceutil/trace.go:171","msg":"trace[1497913945] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"539.555867ms","start":"2026-08-24T15:18:29.359066Z","end":"2026-08-24T15:18:29.898622Z","steps":["trace[1497913945] 'process raft request' (duration: 506.061353ms)","trace[1497913945] 'compare' (duration: 33.309307ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:18:29.899035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:18:29.359055Z","time spent":"539.874939ms","remote":"127.0.0.1:50260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1606,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:18:29.899658Z","caller":"traceutil/trace.go:171","msg":"trace[1812385569] linearizableReadLoop","detail":"{readStateIndex:970; appliedIndex:967; }","duration":"389.963161ms","start":"2026-08-24T15:18:29.509672Z","end":"2026-08-24T15:18:29.899636Z","steps":["trace[1812385569] 'read index received' (duration: 239.074126ms)","trace[1812385569] 'applied index is now lower than readState.Index' (duration: 150.888195ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:18:29.899852Z","caller":"traceutil/trace.go:171","msg":"trace[1213696863] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"540.531313ms","start":"2026-08-24T15:18:29.359306Z","end":"2026-08-24T15:18:29.899837Z","steps":["trace[1213696863] 'process raft request' (duration: 539.281878ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:18:29.899998Z","caller":"traceutil/trace.go:171","msg":"trace[1090276735] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"343.382488ms","start":"2026-08-24T15:18:29.556599Z","end":"2026-08-24T15:18:29.899981Z","steps":["trace[1090276735] 'process raft request' (duration: 342.946383ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:18:29.900045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:18:29.359292Z","time spent":"540.628076ms","remote":"127.0.0.1:50460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3852,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:18:29.900302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:18:29.556572Z","time spent":"343.602656ms","remote":"127.0.0.1:50254","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-08-24T15:18:29.900782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.103792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.129\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-24T15:18:29.900833Z","caller":"traceutil/trace.go:171","msg":"trace[343975894] range","detail":"{range_begin:/registry/masterleases/199.19.213.129; range_end:; response_count:1; response_revision:937; }","duration":"391.158674ms","start":"2026-08-24T15:18:29.509662Z","end":"2026-08-24T15:18:29.900821Z","steps":["trace[343975894] 'agreement among raft nodes before linearized reading' (duration: 390.066344ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:18:29.900913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:18:29.509588Z","time spent":"391.31398ms","remote":"127.0.0.1:39478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.129\" "} {"level":"info","ts":"2026-08-24T15:18:30.210636Z","caller":"traceutil/trace.go:171","msg":"trace[880559375] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"274.443713ms","start":"2026-08-24T15:18:29.93617Z","end":"2026-08-24T15:18:30.210614Z","steps":["trace[880559375] 'process raft request' (duration: 274.386641ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:18:30.210665Z","caller":"traceutil/trace.go:171","msg":"trace[1028947353] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"275.257473ms","start":"2026-08-24T15:18:29.935386Z","end":"2026-08-24T15:18:30.210644Z","steps":["trace[1028947353] 'process raft request' (duration: 236.05534ms)","trace[1028947353] 'compare' (duration: 38.999275ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:18:30.217245Z","caller":"traceutil/trace.go:171","msg":"trace[1286347162] linearizableReadLoop","detail":"{readStateIndex:977; appliedIndex:974; }","duration":"150.553434ms","start":"2026-08-24T15:18:30.066669Z","end":"2026-08-24T15:18:30.217222Z","steps":["trace[1286347162] 'read index received' (duration: 104.809032ms)","trace[1286347162] 'applied index is now lower than readState.Index' (duration: 45.743262ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:18:30.217558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.855645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:18:30.217625Z","caller":"traceutil/trace.go:171","msg":"trace[214639832] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:942; }","duration":"151.01379ms","start":"2026-08-24T15:18:30.066596Z","end":"2026-08-24T15:18:30.21761Z","steps":["trace[214639832] 'agreement among raft nodes before linearized reading' (duration: 150.7287ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:18:30.218246Z","caller":"traceutil/trace.go:171","msg":"trace[1638179788] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"221.466617ms","start":"2026-08-24T15:18:29.996496Z","end":"2026-08-24T15:18:30.217963Z","steps":["trace[1638179788] 'process raft request' (duration: 220.608105ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:18:30.586611Z","caller":"traceutil/trace.go:171","msg":"trace[1525689915] linearizableReadLoop","detail":"{readStateIndex:979; appliedIndex:977; }","duration":"369.270831ms","start":"2026-08-24T15:18:30.217319Z","end":"2026-08-24T15:18:30.58659Z","steps":["trace[1525689915] 'read index received' (duration: 328.46163ms)","trace[1525689915] 'applied index is now lower than readState.Index' (duration: 40.808221ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:18:30.58665Z","caller":"traceutil/trace.go:171","msg":"trace[1992163660] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"369.840682ms","start":"2026-08-24T15:18:30.216774Z","end":"2026-08-24T15:18:30.586615Z","steps":["trace[1992163660] 'process raft request' (duration: 369.728398ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:18:30.586679Z","caller":"traceutil/trace.go:171","msg":"trace[1174779994] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"370.028018ms","start":"2026-08-24T15:18:30.216627Z","end":"2026-08-24T15:18:30.586655Z","steps":["trace[1174779994] 'process raft request' (duration: 329.30586ms)","trace[1174779994] 'compare' (duration: 40.296513ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:18:30.586782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:18:30.216751Z","time spent":"369.969737ms","remote":"127.0.0.1:39526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-24T15:18:30.586804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:18:30.216612Z","time spent":"370.118872ms","remote":"127.0.0.1:50460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3852,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:18:30.586946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.874343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-t88qg\" ","response":"range_response_count:1 size:4884"} {"level":"info","ts":"2026-08-24T15:18:30.587006Z","caller":"traceutil/trace.go:171","msg":"trace[242892359] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-t88qg; range_end:; response_count:1; response_revision:944; }","duration":"369.971957ms","start":"2026-08-24T15:18:30.217002Z","end":"2026-08-24T15:18:30.586974Z","steps":["trace[242892359] 'agreement among raft nodes before linearized reading' (duration: 369.825812ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:18:30.587037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:18:30.21698Z","time spent":"370.04895ms","remote":"127.0.0.1:50182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":4907,"request content":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-t88qg\" "} {"level":"warn","ts":"2026-08-24T15:18:30.587383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.932271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-08-24T15:18:30.587573Z","caller":"traceutil/trace.go:171","msg":"trace[95111573] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:944; }","duration":"374.029676ms","start":"2026-08-24T15:18:30.213419Z","end":"2026-08-24T15:18:30.587449Z","steps":["trace[95111573] 'agreement among raft nodes before linearized reading' (duration: 373.255917ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:18:30.587611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:18:30.213381Z","time spent":"374.220543ms","remote":"127.0.0.1:50168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-08-24T15:18:30.64794Z","caller":"traceutil/trace.go:171","msg":"trace[217879844] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"429.854836ms","start":"2026-08-24T15:18:30.218052Z","end":"2026-08-24T15:18:30.647906Z","steps":["trace[217879844] 'process raft request' (duration: 429.635858ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:18:30.647982Z","caller":"traceutil/trace.go:171","msg":"trace[2062575603] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"429.41034ms","start":"2026-08-24T15:18:30.218544Z","end":"2026-08-24T15:18:30.647955Z","steps":["trace[2062575603] 'process raft request' (duration: 429.282805ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:18:30.6481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:18:30.218031Z","time spent":"429.99326ms","remote":"127.0.0.1:39526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":723,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-24T15:18:30.648171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:18:30.218529Z","time spent":"429.526453ms","remote":"127.0.0.1:50168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":740,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:18:30.648351Z","caller":"traceutil/trace.go:171","msg":"trace[552532550] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"429.14612ms","start":"2026-08-24T15:18:30.219175Z","end":"2026-08-24T15:18:30.648321Z","steps":["trace[552532550] 'process raft request' (duration: 428.724605ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:18:30.648552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:18:30.219055Z","time spent":"429.404699ms","remote":"127.0.0.1:50260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1451,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:18:31.03378Z","caller":"traceutil/trace.go:171","msg":"trace[2060024445] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"132.296347ms","start":"2026-08-24T15:18:30.901455Z","end":"2026-08-24T15:18:31.033751Z","steps":["trace[2060024445] 'process raft request' (duration: 130.497001ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:18:31.219373Z","caller":"traceutil/trace.go:171","msg":"trace[679999999] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"111.336523ms","start":"2026-08-24T15:18:31.10801Z","end":"2026-08-24T15:18:31.219347Z","steps":["trace[679999999] 'process raft request' (duration: 30.90004ms)","trace[679999999] 'compare' (duration: 80.310239ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:18:31.470357Z","caller":"traceutil/trace.go:171","msg":"trace[311012437] linearizableReadLoop","detail":"{readStateIndex:997; appliedIndex:995; }","duration":"337.170995ms","start":"2026-08-24T15:18:31.133168Z","end":"2026-08-24T15:18:31.470339Z","steps":["trace[311012437] 'read index received' (duration: 5.7391ms)","trace[311012437] 'applied index is now lower than readState.Index' (duration: 331.431275ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:18:31.470468Z","caller":"traceutil/trace.go:171","msg":"trace[1906497251] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"337.089731ms","start":"2026-08-24T15:18:31.13337Z","end":"2026-08-24T15:18:31.470459Z","steps":["trace[1906497251] 'process raft request' (duration: 336.918795ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:18:31.470545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:18:31.133351Z","time spent":"337.135883ms","remote":"127.0.0.1:50434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3371,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:18:31.470628Z","caller":"traceutil/trace.go:171","msg":"trace[686721193] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"338.221443ms","start":"2026-08-24T15:18:31.132367Z","end":"2026-08-24T15:18:31.470589Z","steps":["trace[686721193] 'process raft request' (duration: 275.686515ms)","trace[686721193] 'compare' (duration: 62.116423ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:18:31.470786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.448278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:1 size:12755"} {"level":"warn","ts":"2026-08-24T15:18:31.470802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:18:31.132335Z","time spent":"338.391219ms","remote":"127.0.0.1:39526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":760,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-24T15:18:31.470828Z","caller":"traceutil/trace.go:171","msg":"trace[1325756478] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:963; }","duration":"149.51221ms","start":"2026-08-24T15:18:31.321301Z","end":"2026-08-24T15:18:31.470813Z","steps":["trace[1325756478] 'agreement among raft nodes before linearized reading' (duration: 149.400336ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:18:31.470677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.511127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/local-path-storage/\" range_end:\"/registry/limitranges/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-24T15:18:31.471064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.38967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-08-24T15:18:31.471086Z","caller":"traceutil/trace.go:171","msg":"trace[1695634749] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:963; }","duration":"133.438682ms","start":"2026-08-24T15:18:31.337641Z","end":"2026-08-24T15:18:31.47108Z","steps":["trace[1695634749] 'agreement among raft nodes before linearized reading' (duration: 133.372539ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:18:31.47109Z","caller":"traceutil/trace.go:171","msg":"trace[1949380973] range","detail":"{range_begin:/registry/limitranges/local-path-storage/; range_end:/registry/limitranges/local-path-storage0; response_count:0; response_revision:963; }","duration":"337.936592ms","start":"2026-08-24T15:18:31.133124Z","end":"2026-08-24T15:18:31.47106Z","steps":["trace[1949380973] 'agreement among raft nodes before linearized reading' (duration: 337.525567ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:18:31.471198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:18:31.13311Z","time spent":"338.039946ms","remote":"127.0.0.1:39604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/local-path-storage/\" range_end:\"/registry/limitranges/local-path-storage0\" "} {"level":"warn","ts":"2026-08-24T15:18:31.471284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.805995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-08-24T15:18:31.471309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.133397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-24T15:18:31.471345Z","caller":"traceutil/trace.go:171","msg":"trace[1984299895] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:963; }","duration":"133.880608ms","start":"2026-08-24T15:18:31.337448Z","end":"2026-08-24T15:18:31.471328Z","steps":["trace[1984299895] 'agreement among raft nodes before linearized reading' (duration: 133.749623ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:18:31.471373Z","caller":"traceutil/trace.go:171","msg":"trace[87534991] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:963; }","duration":"134.21467ms","start":"2026-08-24T15:18:31.337145Z","end":"2026-08-24T15:18:31.47136Z","steps":["trace[87534991] 'agreement among raft nodes before linearized reading' (duration: 134.027503ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:18:31.471388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.082168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-24T15:18:31.471435Z","caller":"traceutil/trace.go:171","msg":"trace[1605299167] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:963; }","duration":"139.160831ms","start":"2026-08-24T15:18:31.332256Z","end":"2026-08-24T15:18:31.471417Z","steps":["trace[1605299167] 'agreement among raft nodes before linearized reading' (duration: 138.999175ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:18:31.471526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.268422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-08-24T15:18:31.471555Z","caller":"traceutil/trace.go:171","msg":"trace[1674344962] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:963; }","duration":"134.343445ms","start":"2026-08-24T15:18:31.337204Z","end":"2026-08-24T15:18:31.471548Z","steps":["trace[1674344962] 'agreement among raft nodes before linearized reading' (duration: 134.229401ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:18:31.724297Z","caller":"traceutil/trace.go:171","msg":"trace[842269336] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"250.69081ms","start":"2026-08-24T15:18:31.473568Z","end":"2026-08-24T15:18:31.724259Z","steps":["trace[842269336] 'process raft request' (duration: 246.28645ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:18:31.731044Z","caller":"traceutil/trace.go:171","msg":"trace[1239283049] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"254.141907ms","start":"2026-08-24T15:18:31.476881Z","end":"2026-08-24T15:18:31.731023Z","steps":["trace[1239283049] 'process raft request' (duration: 253.891578ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:18:31.731078Z","caller":"traceutil/trace.go:171","msg":"trace[980607416] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"254.071844ms","start":"2026-08-24T15:18:31.47698Z","end":"2026-08-24T15:18:31.731051Z","steps":["trace[980607416] 'process raft request' (duration: 253.934239ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:18:31.731245Z","caller":"traceutil/trace.go:171","msg":"trace[1843814891] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"254.177638ms","start":"2026-08-24T15:18:31.477022Z","end":"2026-08-24T15:18:31.7312Z","steps":["trace[1843814891] 'process raft request' (duration: 253.930489ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:18:31.731577Z","caller":"traceutil/trace.go:171","msg":"trace[450253060] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"254.428647ms","start":"2026-08-24T15:18:31.477127Z","end":"2026-08-24T15:18:31.731555Z","steps":["trace[450253060] 'process raft request' (duration: 253.859227ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:18:31.731686Z","caller":"traceutil/trace.go:171","msg":"trace[1279119710] linearizableReadLoop","detail":"{readStateIndex:1003; appliedIndex:998; }","duration":"253.774033ms","start":"2026-08-24T15:18:31.477897Z","end":"2026-08-24T15:18:31.731671Z","steps":["trace[1279119710] 'read index received' (duration: 241.965242ms)","trace[1279119710] 'applied index is now lower than readState.Index' (duration: 11.807171ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:18:31.732285Z","caller":"traceutil/trace.go:171","msg":"trace[1294075048] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"254.004832ms","start":"2026-08-24T15:18:31.478266Z","end":"2026-08-24T15:18:31.732271Z","steps":["trace[1294075048] 'process raft request' (duration: 253.063858ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:18:31.732435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.521141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:3386"} {"level":"info","ts":"2026-08-24T15:18:31.732495Z","caller":"traceutil/trace.go:171","msg":"trace[2083003622] range","detail":"{range_begin:/registry/deployments/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:971; }","duration":"254.629945ms","start":"2026-08-24T15:18:31.477849Z","end":"2026-08-24T15:18:31.732479Z","steps":["trace[2083003622] 'agreement among raft nodes before linearized reading' (duration: 254.425177ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:18:31.74169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.37293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:1 size:12755"} {"level":"info","ts":"2026-08-24T15:18:31.741746Z","caller":"traceutil/trace.go:171","msg":"trace[1050350307] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:972; }","duration":"206.474073ms","start":"2026-08-24T15:18:31.535259Z","end":"2026-08-24T15:18:31.741733Z","steps":["trace[1050350307] 'agreement among raft nodes before linearized reading' (duration: 206.335477ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:18:33.006672Z","caller":"traceutil/trace.go:171","msg":"trace[1509692599] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"150.045951ms","start":"2026-08-24T15:18:32.8566Z","end":"2026-08-24T15:18:33.006646Z","steps":["trace[1509692599] 'process raft request' (duration: 140.005233ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:18:52.347992Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9834630357978623684,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-24T15:18:52.365826Z","caller":"traceutil/trace.go:171","msg":"trace[1792607398] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"525.871297ms","start":"2026-08-24T15:18:51.839926Z","end":"2026-08-24T15:18:52.365797Z","steps":["trace[1792607398] 'process raft request' (duration: 525.700221ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:18:52.366108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:18:51.839902Z","time spent":"526.028533ms","remote":"127.0.0.1:50254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:18:52.370839Z","caller":"traceutil/trace.go:171","msg":"trace[836324335] linearizableReadLoop","detail":"{readStateIndex:1099; appliedIndex:1097; }","duration":"523.188288ms","start":"2026-08-24T15:18:51.847624Z","end":"2026-08-24T15:18:52.370812Z","steps":["trace[836324335] 'read index received' (duration: 517.954414ms)","trace[836324335] 'applied index is now lower than readState.Index' (duration: 5.232474ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:18:52.370979Z","caller":"traceutil/trace.go:171","msg":"trace[308112322] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"373.035548ms","start":"2026-08-24T15:18:51.997932Z","end":"2026-08-24T15:18:52.370967Z","steps":["trace[308112322] 'process raft request' (duration: 372.742078ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:18:52.371119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:18:51.997906Z","time spent":"373.107441ms","remote":"127.0.0.1:50254","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-08-24T15:18:52.371153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.471549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-24T15:18:52.371197Z","caller":"traceutil/trace.go:171","msg":"trace[101042598] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1060; }","duration":"523.54231ms","start":"2026-08-24T15:18:51.847642Z","end":"2026-08-24T15:18:52.371184Z","steps":["trace[101042598] 'agreement among raft nodes before linearized reading' (duration: 523.395235ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:18:52.371274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.331573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:18:52.371299Z","caller":"traceutil/trace.go:171","msg":"trace[1406180529] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1060; }","duration":"307.355474ms","start":"2026-08-24T15:18:52.063935Z","end":"2026-08-24T15:18:52.371291Z","steps":["trace[1406180529] 'agreement among raft nodes before linearized reading' (duration: 307.315743ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:18:52.371272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.606652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-after-finished-controller\" ","response":"range_response_count:1 size:224"} {"level":"warn","ts":"2026-08-24T15:18:52.371328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:18:52.063878Z","time spent":"307.445418ms","remote":"127.0.0.1:39444","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-08-24T15:18:52.371358Z","caller":"traceutil/trace.go:171","msg":"trace[1790775093] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-after-finished-controller; range_end:; response_count:1; response_revision:1060; }","duration":"369.720226ms","start":"2026-08-24T15:18:52.001605Z","end":"2026-08-24T15:18:52.371325Z","steps":["trace[1790775093] 'agreement among raft nodes before linearized reading' (duration: 369.601452ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:18:52.371346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.712268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-24T15:18:52.371429Z","caller":"traceutil/trace.go:171","msg":"trace[1553185062] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1060; }","duration":"523.822581ms","start":"2026-08-24T15:18:51.847587Z","end":"2026-08-24T15:18:52.371409Z","steps":["trace[1553185062] 'agreement among raft nodes before linearized reading' (duration: 523.651145ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:18:52.37144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:18:52.001583Z","time spent":"369.84251ms","remote":"127.0.0.1:50198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":247,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-after-finished-controller\" "} {"level":"warn","ts":"2026-08-24T15:18:52.37152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:18:51.847562Z","time spent":"523.936116ms","remote":"127.0.0.1:50254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-24T15:18:52.371565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.721991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-24T15:18:52.371614Z","caller":"traceutil/trace.go:171","msg":"trace[417840215] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1060; }","duration":"519.821195ms","start":"2026-08-24T15:18:51.851781Z","end":"2026-08-24T15:18:52.371602Z","steps":["trace[417840215] 'agreement among raft nodes before linearized reading' (duration: 519.631258ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:18:52.371606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.879144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-08-24T15:18:52.371641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:18:51.851759Z","time spent":"519.877087ms","remote":"127.0.0.1:50254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-24T15:18:52.371648Z","caller":"traceutil/trace.go:171","msg":"trace[1881236152] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1060; }","duration":"523.948687ms","start":"2026-08-24T15:18:51.847688Z","end":"2026-08-24T15:18:52.371637Z","steps":["trace[1881236152] 'agreement among raft nodes before linearized reading' (duration: 523.806802ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:18:52.371227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:18:51.847601Z","time spent":"523.619635ms","remote":"127.0.0.1:50254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-24T15:18:52.371684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:18:51.847665Z","time spent":"524.006679ms","remote":"127.0.0.1:50254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-24T15:18:52.880078Z","caller":"traceutil/trace.go:171","msg":"trace[1941725431] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1068; }","duration":"417.77745ms","start":"2026-08-24T15:18:52.462262Z","end":"2026-08-24T15:18:52.880039Z","steps":["trace[1941725431] 'process raft request' (duration: 417.563462ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:18:52.880341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:18:52.462244Z","time spent":"417.914544ms","remote":"127.0.0.1:50182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80,"response count":0,"response size":41,"request content":"compare: success: > failure: >"} {"level":"info","ts":"2026-08-24T15:19:23.09155Z","caller":"traceutil/trace.go:171","msg":"trace[1145248578] transaction","detail":"{read_only:false; response_revision:1169; number_of_response:1; }","duration":"102.178675ms","start":"2026-08-24T15:19:22.989343Z","end":"2026-08-24T15:19:23.091521Z","steps":["trace[1145248578] 'process raft request' (duration: 101.975217ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:19:24.803283Z","caller":"traceutil/trace.go:171","msg":"trace[1815249253] transaction","detail":"{read_only:false; response_revision:1171; number_of_response:1; }","duration":"221.539901ms","start":"2026-08-24T15:19:24.581716Z","end":"2026-08-24T15:19:24.803256Z","steps":["trace[1815249253] 'process raft request' (duration: 220.886756ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:19:24.804552Z","caller":"traceutil/trace.go:171","msg":"trace[1247292550] linearizableReadLoop","detail":"{readStateIndex:1218; appliedIndex:1216; }","duration":"210.748408ms","start":"2026-08-24T15:19:24.593788Z","end":"2026-08-24T15:19:24.804536Z","steps":["trace[1247292550] 'read index received' (duration: 209.004533ms)","trace[1247292550] 'applied index is now lower than readState.Index' (duration: 1.743095ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:19:24.80463Z","caller":"traceutil/trace.go:171","msg":"trace[1908546438] transaction","detail":"{read_only:false; response_revision:1172; number_of_response:1; }","duration":"222.278047ms","start":"2026-08-24T15:19:24.582346Z","end":"2026-08-24T15:19:24.804624Z","steps":["trace[1908546438] 'process raft request' (duration: 222.056609ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:19:24.804846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.878902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-24T15:19:24.804865Z","caller":"traceutil/trace.go:171","msg":"trace[1315176604] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1172; }","duration":"210.908034ms","start":"2026-08-24T15:19:24.593952Z","end":"2026-08-24T15:19:24.80486Z","steps":["trace[1315176604] 'agreement among raft nodes before linearized reading' (duration: 210.860432ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:19:24.804971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.017798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-08-24T15:19:24.804777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.974936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-24T15:19:24.805045Z","caller":"traceutil/trace.go:171","msg":"trace[1569716038] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1172; }","duration":"211.269327ms","start":"2026-08-24T15:19:24.593769Z","end":"2026-08-24T15:19:24.805039Z","steps":["trace[1569716038] 'agreement among raft nodes before linearized reading' (duration: 210.931704ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:19:24.805062Z","caller":"traceutil/trace.go:171","msg":"trace[508871853] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1172; }","duration":"211.158043ms","start":"2026-08-24T15:19:24.593878Z","end":"2026-08-24T15:19:24.805036Z","steps":["trace[508871853] 'agreement among raft nodes before linearized reading' (duration: 210.932644ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:20:08.843233Z","caller":"traceutil/trace.go:171","msg":"trace[313074652] transaction","detail":"{read_only:false; response_revision:1347; number_of_response:1; }","duration":"110.511967ms","start":"2026-08-24T15:20:08.732697Z","end":"2026-08-24T15:20:08.843209Z","steps":["trace[313074652] 'process raft request' (duration: 110.365315ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:20:09.116993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.737406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-24T15:20:09.117176Z","caller":"traceutil/trace.go:171","msg":"trace[1826709263] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1347; }","duration":"128.886517ms","start":"2026-08-24T15:20:08.988189Z","end":"2026-08-24T15:20:09.117076Z","steps":["trace[1826709263] 'range keys from in-memory index tree' (duration: 128.530263ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:20:09.116993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.835238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-08-24T15:20:09.117229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.086616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-24T15:20:09.117309Z","caller":"traceutil/trace.go:171","msg":"trace[2006702731] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1347; }","duration":"101.189458ms","start":"2026-08-24T15:20:09.016103Z","end":"2026-08-24T15:20:09.117293Z","steps":["trace[2006702731] 'range keys from in-memory index tree' (duration: 100.936475ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:20:09.117303Z","caller":"traceutil/trace.go:171","msg":"trace[717984099] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1347; }","duration":"122.200483ms","start":"2026-08-24T15:20:08.995084Z","end":"2026-08-24T15:20:09.117284Z","steps":["trace[717984099] 'range keys from in-memory index tree' (duration: 121.646266ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:20:09.117229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.338688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-24T15:20:09.117438Z","caller":"traceutil/trace.go:171","msg":"trace[1923570161] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1347; }","duration":"108.606382ms","start":"2026-08-24T15:20:09.008824Z","end":"2026-08-24T15:20:09.11743Z","steps":["trace[1923570161] 'range keys from in-memory index tree' (duration: 108.163986ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:20:17.660494Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9834630357978624536,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-24T15:20:17.942066Z","caller":"traceutil/trace.go:171","msg":"trace[964342021] linearizableReadLoop","detail":"{readStateIndex:1439; appliedIndex:1438; }","duration":"781.818312ms","start":"2026-08-24T15:20:17.160209Z","end":"2026-08-24T15:20:17.942027Z","steps":["trace[964342021] 'read index received' (duration: 781.447696ms)","trace[964342021] 'applied index is now lower than readState.Index' (duration: 369.166µs)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:20:17.942564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"782.316209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-24T15:20:17.94261Z","caller":"traceutil/trace.go:171","msg":"trace[271707623] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1381; }","duration":"782.408441ms","start":"2026-08-24T15:20:17.16019Z","end":"2026-08-24T15:20:17.942598Z","steps":["trace[271707623] 'agreement among raft nodes before linearized reading' (duration: 782.204688ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:20:17.942645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:20:17.16018Z","time spent":"782.459002ms","remote":"127.0.0.1:50254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-24T15:20:17.942564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"781.079561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-08-24T15:20:17.942752Z","caller":"traceutil/trace.go:171","msg":"trace[2101893829] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1381; }","duration":"781.290945ms","start":"2026-08-24T15:20:17.161441Z","end":"2026-08-24T15:20:17.942732Z","steps":["trace[2101893829] 'agreement among raft nodes before linearized reading' (duration: 780.989811ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:20:17.942849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:20:17.161432Z","time spent":"781.396156ms","remote":"127.0.0.1:50254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-24T15:20:17.943071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"781.01505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-24T15:20:17.943097Z","caller":"traceutil/trace.go:171","msg":"trace[1354700390] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1381; }","duration":"781.051661ms","start":"2026-08-24T15:20:17.162039Z","end":"2026-08-24T15:20:17.94309Z","steps":["trace[1354700390] 'agreement among raft nodes before linearized reading' (duration: 780.98185ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:20:17.943116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:20:17.162033Z","time spent":"781.077811ms","remote":"127.0.0.1:50254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-24T15:20:17.943206Z","caller":"traceutil/trace.go:171","msg":"trace[1140014235] transaction","detail":"{read_only:false; response_revision:1381; number_of_response:1; }","duration":"783.237052ms","start":"2026-08-24T15:20:17.159943Z","end":"2026-08-24T15:20:17.94318Z","steps":["trace[1140014235] 'process raft request' (duration: 781.807541ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:20:17.943323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:20:17.159928Z","time spent":"783.315703ms","remote":"127.0.0.1:50254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:20:17.943351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"781.077222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-24T15:20:17.943501Z","caller":"traceutil/trace.go:171","msg":"trace[62246075] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1381; }","duration":"781.409986ms","start":"2026-08-24T15:20:17.162068Z","end":"2026-08-24T15:20:17.943478Z","steps":["trace[62246075] 'agreement among raft nodes before linearized reading' (duration: 780.93672ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:20:17.943554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:20:17.16206Z","time spent":"781.476797ms","remote":"127.0.0.1:50254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}