{"level":"warn","ts":"2026-04-27T02:43:37.100436Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-27T02:43:37.100623Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.176: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.176:2380","--initial-cluster=instance=https://199.19.213.176:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.176:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.176: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-04-27T02:43:37.100778Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-27T02:43:37.100806Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.176:2380"]} {"level":"info","ts":"2026-04-27T02:43:37.100858Z","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-04-27T02:43:37.102416Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.176:2379"]} {"level":"info","ts":"2026-04-27T02:43:37.102588Z","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.176:2380"],"listen-peer-urls":["https://199.19.213.176:2380"],"advertise-client-urls":["https://199.19.213.176:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.176:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.176: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-04-27T02:43:37.108318Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.298034ms"} {"level":"info","ts":"2026-04-27T02:43:37.119322Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6e120e57916b9bd4","cluster-id":"e644555f29703ff6"} {"level":"info","ts":"2026-04-27T02:43:37.119478Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e120e57916b9bd4 switched to configuration voters=()"} {"level":"info","ts":"2026-04-27T02:43:37.119517Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e120e57916b9bd4 became follower at term 0"} {"level":"info","ts":"2026-04-27T02:43:37.119552Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6e120e57916b9bd4 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-27T02:43:37.119571Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e120e57916b9bd4 became follower at term 1"} {"level":"info","ts":"2026-04-27T02:43:37.119635Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e120e57916b9bd4 switched to configuration voters=(7931417663017556948)"} {"level":"warn","ts":"2026-04-27T02:43:37.129625Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-27T02:43:37.133139Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-27T02:43:37.134811Z","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-04-27T02:43:37.138185Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6e120e57916b9bd4","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-27T02:43:37.13971Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6e120e57916b9bd4","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-27T02:43:37.140006Z","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-04-27T02:43:37.140074Z","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-04-27T02:43:37.140099Z","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-04-27T02:43:37.140628Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e120e57916b9bd4 switched to configuration voters=(7931417663017556948)"} {"level":"info","ts":"2026-04-27T02:43:37.140767Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e644555f29703ff6","local-member-id":"6e120e57916b9bd4","added-peer-id":"6e120e57916b9bd4","added-peer-peer-urls":["https://199.19.213.176:2380"]} {"level":"info","ts":"2026-04-27T02:43:37.14622Z","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-04-27T02:43:37.146432Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6e120e57916b9bd4","initial-advertise-peer-urls":["https://199.19.213.176:2380"],"listen-peer-urls":["https://199.19.213.176:2380"],"advertise-client-urls":["https://199.19.213.176:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.176:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-27T02:43:37.146454Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-27T02:43:37.146509Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.176:2380"} {"level":"info","ts":"2026-04-27T02:43:37.146515Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.176:2380"} {"level":"info","ts":"2026-04-27T02:43:37.920167Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e120e57916b9bd4 is starting a new election at term 1"} {"level":"info","ts":"2026-04-27T02:43:37.920259Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e120e57916b9bd4 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-27T02:43:37.920299Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e120e57916b9bd4 received MsgPreVoteResp from 6e120e57916b9bd4 at term 1"} {"level":"info","ts":"2026-04-27T02:43:37.920321Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e120e57916b9bd4 became candidate at term 2"} {"level":"info","ts":"2026-04-27T02:43:37.920332Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e120e57916b9bd4 received MsgVoteResp from 6e120e57916b9bd4 at term 2"} {"level":"info","ts":"2026-04-27T02:43:37.920348Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e120e57916b9bd4 became leader at term 2"} {"level":"info","ts":"2026-04-27T02:43:37.920361Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6e120e57916b9bd4 elected leader 6e120e57916b9bd4 at term 2"} {"level":"info","ts":"2026-04-27T02:43:37.921511Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T02:43:37.922421Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-27T02:43:37.922445Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-27T02:43:37.922951Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6e120e57916b9bd4","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.176:2379]}","request-path":"/0/members/6e120e57916b9bd4/attributes","cluster-id":"e644555f29703ff6","publish-timeout":"7s"} {"level":"info","ts":"2026-04-27T02:43:37.92328Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e644555f29703ff6","local-member-id":"6e120e57916b9bd4","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T02:43:37.923868Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-27T02:43:37.926433Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-27T02:43:37.924614Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-27T02:43:37.926451Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T02:43:37.926514Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T02:43:37.926247Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.176:2379"} {"level":"info","ts":"2026-04-27T02:43:39.762562Z","caller":"traceutil/trace.go:171","msg":"trace[1490949485] transaction","detail":"{read_only:false; response_revision:46; number_of_response:1; }","duration":"108.295726ms","start":"2026-04-27T02:43:39.654236Z","end":"2026-04-27T02:43:39.762531Z","steps":["trace[1490949485] 'process raft request' (duration: 108.231094ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T02:43:39.762808Z","caller":"traceutil/trace.go:171","msg":"trace[1682611152] transaction","detail":"{read_only:false; response_revision:45; number_of_response:1; }","duration":"111.229945ms","start":"2026-04-27T02:43:39.651435Z","end":"2026-04-27T02:43:39.762665Z","steps":["trace[1682611152] 'process raft request' (duration: 45.536842ms)","trace[1682611152] 'compare' (duration: 65.281781ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T02:43:40.07129Z","caller":"traceutil/trace.go:171","msg":"trace[41629368] transaction","detail":"{read_only:false; response_revision:66; number_of_response:1; }","duration":"182.375182ms","start":"2026-04-27T02:43:39.888884Z","end":"2026-04-27T02:43:40.071259Z","steps":["trace[41629368] 'process raft request' (duration: 151.166318ms)","trace[41629368] 'compare' (duration: 30.899175ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T02:43:40.071303Z","caller":"traceutil/trace.go:171","msg":"trace[2101185104] transaction","detail":"{read_only:false; response_revision:67; number_of_response:1; }","duration":"141.7693ms","start":"2026-04-27T02:43:39.929478Z","end":"2026-04-27T02:43:40.071248Z","steps":["trace[2101185104] 'process raft request' (duration: 141.601255ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T02:43:40.935249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.448697ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-27T02:43:40.935404Z","caller":"traceutil/trace.go:171","msg":"trace[1610726609] transaction","detail":"{read_only:false; response_revision:95; number_of_response:1; }","duration":"387.397208ms","start":"2026-04-27T02:43:40.547983Z","end":"2026-04-27T02:43:40.93538Z","steps":["trace[1610726609] 'process raft request' (duration: 67.210518ms)","trace[1610726609] 'compare' (duration: 319.284312ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T02:43:40.93548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T02:43:40.547967Z","time spent":"387.48204ms","remote":"127.0.0.1:46302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":693,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T02:43:42.768393Z","caller":"traceutil/trace.go:171","msg":"trace[869473063] transaction","detail":"{read_only:false; response_revision:200; number_of_response:1; }","duration":"122.693762ms","start":"2026-04-27T02:43:42.645654Z","end":"2026-04-27T02:43:42.768348Z","steps":["trace[869473063] 'process raft request' (duration: 101.639337ms)","trace[869473063] 'compare' (duration: 20.876669ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T02:43:42.768428Z","caller":"traceutil/trace.go:171","msg":"trace[616975840] linearizableReadLoop","detail":"{readStateIndex:205; appliedIndex:204; }","duration":"120.021927ms","start":"2026-04-27T02:43:42.648375Z","end":"2026-04-27T02:43:42.768397Z","steps":["trace[616975840] 'read index received' (duration: 98.960292ms)","trace[616975840] 'applied index is now lower than readState.Index' (duration: 21.058555ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T02:43:42.768644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.182982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-27T02:43:42.768711Z","caller":"traceutil/trace.go:171","msg":"trace[2032992544] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:200; }","duration":"120.356427ms","start":"2026-04-27T02:43:42.648335Z","end":"2026-04-27T02:43:42.768691Z","steps":["trace[2032992544] 'agreement among raft nodes before linearized reading' (duration: 120.151781ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T02:43:42.768853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.842824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/statefulset-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T02:43:42.768974Z","caller":"traceutil/trace.go:171","msg":"trace[259015813] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/statefulset-controller; range_end:; response_count:0; response_revision:200; }","duration":"111.980249ms","start":"2026-04-27T02:43:42.656951Z","end":"2026-04-27T02:43:42.768932Z","steps":["trace[259015813] 'agreement among raft nodes before linearized reading' (duration: 111.840884ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T02:43:43.244972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.21891ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-27T02:43:43.245109Z","caller":"traceutil/trace.go:171","msg":"trace[1171587715] transaction","detail":"{read_only:false; response_revision:219; number_of_response:1; }","duration":"209.307853ms","start":"2026-04-27T02:43:43.035779Z","end":"2026-04-27T02:43:43.245087Z","steps":["trace[1171587715] 'process raft request' (duration: 88.894258ms)","trace[1171587715] 'compare' (duration: 119.983681ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T02:43:44.863417Z","caller":"traceutil/trace.go:171","msg":"trace[234971401] linearizableReadLoop","detail":"{readStateIndex:264; appliedIndex:263; }","duration":"187.691421ms","start":"2026-04-27T02:43:44.675695Z","end":"2026-04-27T02:43:44.863387Z","steps":["trace[234971401] 'read index received' (duration: 156.129885ms)","trace[234971401] 'applied index is now lower than readState.Index' (duration: 31.560766ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T02:43:44.863546Z","caller":"traceutil/trace.go:171","msg":"trace[727173294] transaction","detail":"{read_only:false; response_revision:258; number_of_response:1; }","duration":"189.381427ms","start":"2026-04-27T02:43:44.674119Z","end":"2026-04-27T02:43:44.863501Z","steps":["trace[727173294] 'process raft request' (duration: 157.656395ms)","trace[727173294] 'compare' (duration: 31.448433ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T02:43:44.863622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.906758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:115"} {"level":"info","ts":"2026-04-27T02:43:44.863675Z","caller":"traceutil/trace.go:171","msg":"trace[187446737] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:258; }","duration":"188.023363ms","start":"2026-04-27T02:43:44.675641Z","end":"2026-04-27T02:43:44.863664Z","steps":["trace[187446737] 'agreement among raft nodes before linearized reading' (duration: 187.879928ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T02:43:44.86502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.080069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T02:43:44.865082Z","caller":"traceutil/trace.go:171","msg":"trace[1222666783] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:259; }","duration":"142.174932ms","start":"2026-04-27T02:43:44.722895Z","end":"2026-04-27T02:43:44.86507Z","steps":["trace[1222666783] 'agreement among raft nodes before linearized reading' (duration: 142.058358ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T02:43:44.865298Z","caller":"traceutil/trace.go:171","msg":"trace[1120986729] transaction","detail":"{read_only:false; response_revision:259; number_of_response:1; }","duration":"142.125891ms","start":"2026-04-27T02:43:44.723161Z","end":"2026-04-27T02:43:44.865287Z","steps":["trace[1120986729] 'process raft request' (duration: 141.664596ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T02:43:44.865501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.528765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-04-27T02:43:44.865543Z","caller":"traceutil/trace.go:171","msg":"trace[1699742742] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:259; }","duration":"142.603956ms","start":"2026-04-27T02:43:44.722926Z","end":"2026-04-27T02:43:44.86553Z","steps":["trace[1699742742] 'agreement among raft nodes before linearized reading' (duration: 142.512263ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T02:43:45.022075Z","caller":"traceutil/trace.go:171","msg":"trace[1196713809] transaction","detail":"{read_only:false; response_revision:265; number_of_response:1; }","duration":"137.268189ms","start":"2026-04-27T02:43:44.884778Z","end":"2026-04-27T02:43:45.022046Z","steps":["trace[1196713809] 'process raft request' (duration: 137.198367ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T02:43:45.022371Z","caller":"traceutil/trace.go:171","msg":"trace[1943379009] transaction","detail":"{read_only:false; response_revision:262; number_of_response:1; }","duration":"152.08896ms","start":"2026-04-27T02:43:44.87022Z","end":"2026-04-27T02:43:45.022309Z","steps":["trace[1943379009] 'process raft request' (duration: 106.169609ms)","trace[1943379009] 'compare' (duration: 45.362202ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T02:43:45.022422Z","caller":"traceutil/trace.go:171","msg":"trace[1054268693] linearizableReadLoop","detail":"{readStateIndex:270; appliedIndex:268; }","duration":"151.133538ms","start":"2026-04-27T02:43:44.871221Z","end":"2026-04-27T02:43:45.022355Z","steps":["trace[1054268693] 'read index received' (duration: 7.099135ms)","trace[1054268693] 'applied index is now lower than readState.Index' (duration: 144.031643ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T02:43:45.02255Z","caller":"traceutil/trace.go:171","msg":"trace[156916176] transaction","detail":"{read_only:false; response_revision:264; number_of_response:1; }","duration":"150.000981ms","start":"2026-04-27T02:43:44.872497Z","end":"2026-04-27T02:43:45.022498Z","steps":["trace[156916176] 'process raft request' (duration: 149.437242ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T02:43:45.022605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.361856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-27T02:43:45.02267Z","caller":"traceutil/trace.go:171","msg":"trace[2107153681] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:265; }","duration":"151.444248ms","start":"2026-04-27T02:43:44.871212Z","end":"2026-04-27T02:43:45.022657Z","steps":["trace[2107153681] 'agreement among raft nodes before linearized reading' (duration: 151.278983ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T02:43:45.022461Z","caller":"traceutil/trace.go:171","msg":"trace[12954756] transaction","detail":"{read_only:false; response_revision:263; number_of_response:1; }","duration":"151.871743ms","start":"2026-04-27T02:43:44.870569Z","end":"2026-04-27T02:43:45.022441Z","steps":["trace[12954756] 'process raft request' (duration: 151.307414ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T02:43:45.045366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.553834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4017"} {"level":"info","ts":"2026-04-27T02:43:45.045459Z","caller":"traceutil/trace.go:171","msg":"trace[1695033417] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:266; }","duration":"173.690829ms","start":"2026-04-27T02:43:44.871747Z","end":"2026-04-27T02:43:45.045437Z","steps":["trace[1695033417] 'agreement among raft nodes before linearized reading' (duration: 173.458941ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T02:43:45.178934Z","caller":"traceutil/trace.go:171","msg":"trace[1001902417] transaction","detail":"{read_only:false; response_revision:268; number_of_response:1; }","duration":"151.579455ms","start":"2026-04-27T02:43:45.027306Z","end":"2026-04-27T02:43:45.178885Z","steps":["trace[1001902417] 'process raft request' (duration: 151.452591ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T02:43:45.179029Z","caller":"traceutil/trace.go:171","msg":"trace[1478470830] linearizableReadLoop","detail":"{readStateIndex:280; appliedIndex:277; }","duration":"131.259821ms","start":"2026-04-27T02:43:45.04769Z","end":"2026-04-27T02:43:45.178949Z","steps":["trace[1478470830] 'read index received' (duration: 82.119586ms)","trace[1478470830] 'applied index is now lower than readState.Index' (duration: 49.135055ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T02:43:45.179067Z","caller":"traceutil/trace.go:171","msg":"trace[1686369972] transaction","detail":"{read_only:false; response_revision:269; number_of_response:1; }","duration":"150.793449ms","start":"2026-04-27T02:43:45.02826Z","end":"2026-04-27T02:43:45.179054Z","steps":["trace[1686369972] 'process raft request' (duration: 150.54886ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T02:43:45.179122Z","caller":"traceutil/trace.go:171","msg":"trace[633866314] transaction","detail":"{read_only:false; response_revision:267; number_of_response:1; }","duration":"152.184836ms","start":"2026-04-27T02:43:45.026867Z","end":"2026-04-27T02:43:45.179052Z","steps":["trace[633866314] 'process raft request' (duration: 102.771492ms)","trace[633866314] 'compare' (duration: 48.947638ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T02:43:45.179259Z","caller":"traceutil/trace.go:171","msg":"trace[570899759] transaction","detail":"{read_only:false; response_revision:270; number_of_response:1; }","duration":"128.481207ms","start":"2026-04-27T02:43:45.05076Z","end":"2026-04-27T02:43:45.179241Z","steps":["trace[570899759] 'process raft request' (duration: 128.109375ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T02:43:45.179414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.641414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3466"} {"level":"info","ts":"2026-04-27T02:43:45.179005Z","caller":"traceutil/trace.go:171","msg":"trace[1595198520] transaction","detail":"{read_only:false; response_revision:271; number_of_response:1; }","duration":"120.955163ms","start":"2026-04-27T02:43:45.058025Z","end":"2026-04-27T02:43:45.17898Z","steps":["trace[1595198520] 'process raft request' (duration: 120.905232ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T02:43:45.179531Z","caller":"traceutil/trace.go:171","msg":"trace[1640447041] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:271; }","duration":"131.851551ms","start":"2026-04-27T02:43:45.047649Z","end":"2026-04-27T02:43:45.179501Z","steps":["trace[1640447041] 'agreement among raft nodes before linearized reading' (duration: 131.51783ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T02:43:45.179633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.454087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:5895"} {"level":"info","ts":"2026-04-27T02:43:45.179699Z","caller":"traceutil/trace.go:171","msg":"trace[1705775072] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:271; }","duration":"131.53238ms","start":"2026-04-27T02:43:45.048137Z","end":"2026-04-27T02:43:45.17967Z","steps":["trace[1705775072] 'agreement among raft nodes before linearized reading' (duration: 131.415956ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T02:43:45.179751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.641594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:3980"} {"level":"info","ts":"2026-04-27T02:43:45.179865Z","caller":"traceutil/trace.go:171","msg":"trace[168892121] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:271; }","duration":"131.801659ms","start":"2026-04-27T02:43:45.048044Z","end":"2026-04-27T02:43:45.179845Z","steps":["trace[168892121] 'agreement among raft nodes before linearized reading' (duration: 131.603833ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T02:43:45.180303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.724447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:2918"} {"level":"info","ts":"2026-04-27T02:43:45.1804Z","caller":"traceutil/trace.go:171","msg":"trace[1788705239] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:271; }","duration":"132.43348ms","start":"2026-04-27T02:43:45.047943Z","end":"2026-04-27T02:43:45.180376Z","steps":["trace[1788705239] 'agreement among raft nodes before linearized reading' (duration: 131.54671ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T02:43:45.378611Z","caller":"traceutil/trace.go:171","msg":"trace[1408329305] transaction","detail":"{read_only:false; response_revision:278; number_of_response:1; }","duration":"103.094282ms","start":"2026-04-27T02:43:45.275476Z","end":"2026-04-27T02:43:45.37857Z","steps":["trace[1408329305] 'process raft request' (duration: 103.0384ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T02:43:45.378689Z","caller":"traceutil/trace.go:171","msg":"trace[1639567806] transaction","detail":"{read_only:false; response_revision:277; number_of_response:1; }","duration":"115.80182ms","start":"2026-04-27T02:43:45.262825Z","end":"2026-04-27T02:43:45.378627Z","steps":["trace[1639567806] 'process raft request' (duration: 67.347928ms)","trace[1639567806] 'compare' (duration: 48.121981ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T02:43:45.872775Z","caller":"traceutil/trace.go:171","msg":"trace[1009557273] transaction","detail":"{read_only:false; number_of_response:0; response_revision:280; }","duration":"131.622462ms","start":"2026-04-27T02:43:45.741133Z","end":"2026-04-27T02:43:45.872755Z","steps":["trace[1009557273] 'process raft request' (duration: 131.55221ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T02:43:45.886694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.700732ms","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-04-27T02:43:45.886767Z","caller":"traceutil/trace.go:171","msg":"trace[1596329356] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:281; }","duration":"106.811646ms","start":"2026-04-27T02:43:45.779936Z","end":"2026-04-27T02:43:45.886748Z","steps":["trace[1596329356] 'agreement among raft nodes before linearized reading' (duration: 106.659041ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T02:43:46.557048Z","caller":"traceutil/trace.go:171","msg":"trace[2115794524] transaction","detail":"{read_only:false; response_revision:291; number_of_response:1; }","duration":"232.639448ms","start":"2026-04-27T02:43:46.324385Z","end":"2026-04-27T02:43:46.557024Z","steps":["trace[2115794524] 'process raft request' (duration: 146.004664ms)","trace[2115794524] 'compare' (duration: 86.477888ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T02:43:47.527646Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11228773273965961667,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T02:43:48.027856Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11228773273965961667,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T02:43:48.52825Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11228773273965961667,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T02:43:48.943498Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.269428792s","expected-duration":"1s"} {"level":"info","ts":"2026-04-27T02:43:48.943941Z","caller":"traceutil/trace.go:171","msg":"trace[334002433] linearizableReadLoop","detail":"{readStateIndex:305; appliedIndex:304; }","duration":"1.916804179s","start":"2026-04-27T02:43:47.027113Z","end":"2026-04-27T02:43:48.943917Z","steps":["trace[334002433] 'read index received' (duration: 1.91655809s)","trace[334002433] 'applied index is now lower than readState.Index' (duration: 245.209µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T02:43:48.944325Z","caller":"traceutil/trace.go:171","msg":"trace[900508866] transaction","detail":"{read_only:false; response_revision:293; number_of_response:1; }","duration":"2.270384195s","start":"2026-04-27T02:43:46.67391Z","end":"2026-04-27T02:43:48.944294Z","steps":["trace[900508866] 'process raft request' (duration: 2.269833896s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T02:43:48.944362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.917238374s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-27T02:43:48.944407Z","caller":"traceutil/trace.go:171","msg":"trace[1944184343] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:293; }","duration":"1.917333437s","start":"2026-04-27T02:43:47.027061Z","end":"2026-04-27T02:43:48.944394Z","steps":["trace[1944184343] 'agreement among raft nodes before linearized reading' (duration: 1.917189712s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T02:43:48.944428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T02:43:46.673877Z","time spent":"2.270508169s","remote":"127.0.0.1:46154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":217,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T02:43:48.944458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T02:43:47.027039Z","time spent":"1.91741035s","remote":"127.0.0.1:46216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-27T02:43:48.944463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.041683471s","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-04-27T02:43:48.944509Z","caller":"traceutil/trace.go:171","msg":"trace[1631525580] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:293; }","duration":"1.041755673s","start":"2026-04-27T02:43:47.90274Z","end":"2026-04-27T02:43:48.944496Z","steps":["trace[1631525580] 'agreement among raft nodes before linearized reading' (duration: 1.041697761s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T02:43:48.944544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T02:43:47.902723Z","time spent":"1.041814856s","remote":"127.0.0.1:46216","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-04-27T02:43:48.944405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"889.016224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-27T02:43:48.944614Z","caller":"traceutil/trace.go:171","msg":"trace[19920034] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:293; }","duration":"889.270283ms","start":"2026-04-27T02:43:48.055338Z","end":"2026-04-27T02:43:48.944608Z","steps":["trace[19920034] 'agreement among raft nodes before linearized reading' (duration: 888.997584ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T02:43:48.944632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T02:43:48.055323Z","time spent":"889.305224ms","remote":"127.0.0.1:46216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-27T02:43:48.944352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.759960183s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-27T02:43:48.944904Z","caller":"traceutil/trace.go:171","msg":"trace[659136692] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:293; }","duration":"1.760522662s","start":"2026-04-27T02:43:47.184315Z","end":"2026-04-27T02:43:48.944838Z","steps":["trace[659136692] 'agreement among raft nodes before linearized reading' (duration: 1.75988237s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T02:43:48.945038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T02:43:47.184299Z","time spent":"1.760724869s","remote":"127.0.0.1:46216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-27T02:43:49.985773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"598.115613ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T02:43:49.985968Z","caller":"traceutil/trace.go:171","msg":"trace[645025387] transaction","detail":"{read_only:false; response_revision:295; number_of_response:1; }","duration":"1.036912726s","start":"2026-04-27T02:43:48.949042Z","end":"2026-04-27T02:43:49.985954Z","steps":["trace[645025387] 'process raft request' (duration: 1.036829223s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T02:43:49.986024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T02:43:48.949032Z","time spent":"1.036967059s","remote":"127.0.0.1:46216","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-04-27T02:43:49.98611Z","caller":"traceutil/trace.go:171","msg":"trace[222475999] linearizableReadLoop","detail":"{readStateIndex:306; appliedIndex:305; }","duration":"1.037341571s","start":"2026-04-27T02:43:48.948758Z","end":"2026-04-27T02:43:49.9861Z","steps":["trace[222475999] 'read index received' (duration: 438.391388ms)","trace[222475999] 'applied index is now lower than readState.Index' (duration: 598.947773ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T02:43:49.986003Z","caller":"traceutil/trace.go:171","msg":"trace[1778175962] transaction","detail":"{read_only:false; response_revision:294; number_of_response:1; }","duration":"1.037382303s","start":"2026-04-27T02:43:48.948503Z","end":"2026-04-27T02:43:49.985885Z","steps":["trace[1778175962] 'process raft request' (duration: 438.400718ms)","trace[1778175962] 'compare' (duration: 597.914696ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T02:43:49.986207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.037445866s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/pod-garbage-collector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T02:43:49.986233Z","caller":"traceutil/trace.go:171","msg":"trace[945675913] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/pod-garbage-collector; range_end:; response_count:0; response_revision:296; }","duration":"1.037496448s","start":"2026-04-27T02:43:48.948727Z","end":"2026-04-27T02:43:49.986224Z","steps":["trace[945675913] 'agreement among raft nodes before linearized reading' (duration: 1.037425775s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T02:43:49.986256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T02:43:48.948717Z","time spent":"1.037533889s","remote":"127.0.0.1:46154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/pod-garbage-collector\" "} {"level":"info","ts":"2026-04-27T02:43:49.986229Z","caller":"traceutil/trace.go:171","msg":"trace[574961645] transaction","detail":"{read_only:false; response_revision:296; number_of_response:1; }","duration":"1.036923577s","start":"2026-04-27T02:43:48.949278Z","end":"2026-04-27T02:43:49.986201Z","steps":["trace[574961645] 'process raft request' (duration: 1.036643637s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T02:43:49.986338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T02:43:48.948481Z","time spent":"1.037703854s","remote":"127.0.0.1:46216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T02:43:49.986384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T02:43:48.949257Z","time spent":"1.037046081s","remote":"127.0.0.1:46216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T02:43:49.986414Z","caller":"traceutil/trace.go:171","msg":"trace[962663277] transaction","detail":"{read_only:false; response_revision:297; number_of_response:1; }","duration":"1.035504287s","start":"2026-04-27T02:43:48.950866Z","end":"2026-04-27T02:43:49.98637Z","steps":["trace[962663277] 'process raft request' (duration: 1.035244438s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T02:43:49.986623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T02:43:48.950856Z","time spent":"1.035651173s","remote":"127.0.0.1:46216","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-04-27T02:43:49.993458Z","caller":"traceutil/trace.go:171","msg":"trace[2129262362] transaction","detail":"{read_only:false; response_revision:298; number_of_response:1; }","duration":"178.964236ms","start":"2026-04-27T02:43:49.814481Z","end":"2026-04-27T02:43:49.993445Z","steps":["trace[2129262362] 'process raft request' (duration: 178.785139ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T02:43:49.993619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.738189ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T02:43:49.993694Z","caller":"traceutil/trace.go:171","msg":"trace[1633833414] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:298; }","duration":"623.813981ms","start":"2026-04-27T02:43:49.36987Z","end":"2026-04-27T02:43:49.993684Z","steps":["trace[1633833414] 'agreement among raft nodes before linearized reading' (duration: 623.728928ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T02:43:56.125579Z","caller":"traceutil/trace.go:171","msg":"trace[2021009114] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"104.863154ms","start":"2026-04-27T02:43:56.020689Z","end":"2026-04-27T02:43:56.125552Z","steps":["trace[2021009114] 'process raft request' (duration: 104.795212ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T02:43:56.125645Z","caller":"traceutil/trace.go:171","msg":"trace[618611326] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"104.979569ms","start":"2026-04-27T02:43:56.020632Z","end":"2026-04-27T02:43:56.125612Z","steps":["trace[618611326] 'process raft request' (duration: 94.673276ms)","trace[618611326] 'compare' (duration: 10.034872ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T02:43:56.125807Z","caller":"traceutil/trace.go:171","msg":"trace[262681840] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"105.036331ms","start":"2026-04-27T02:43:56.020736Z","end":"2026-04-27T02:43:56.125772Z","steps":["trace[262681840] 'process raft request' (duration: 104.778211ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T02:44:09.078367Z","caller":"traceutil/trace.go:171","msg":"trace[1560544004] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"429.898427ms","start":"2026-04-27T02:44:08.648362Z","end":"2026-04-27T02:44:09.07826Z","steps":["trace[1560544004] 'process raft request' (duration: 428.298077ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T02:44:09.078763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T02:44:08.648333Z","time spent":"430.166769ms","remote":"127.0.0.1:46014","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-04-27T02:44:09.089611Z","caller":"traceutil/trace.go:171","msg":"trace[121744566] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"389.185414ms","start":"2026-04-27T02:44:08.700375Z","end":"2026-04-27T02:44:09.08956Z","steps":["trace[121744566] 'process raft request' (duration: 389.001786ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T02:44:09.089696Z","caller":"traceutil/trace.go:171","msg":"trace[867011307] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"365.217641ms","start":"2026-04-27T02:44:08.724451Z","end":"2026-04-27T02:44:09.089669Z","steps":["trace[867011307] 'process raft request' (duration: 365.081825ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T02:44:09.089733Z","caller":"traceutil/trace.go:171","msg":"trace[1142520437] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"335.517817ms","start":"2026-04-27T02:44:08.754183Z","end":"2026-04-27T02:44:09.0897Z","steps":["trace[1142520437] 'process raft request' (duration: 335.388481ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T02:44:09.089776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T02:44:08.72442Z","time spent":"365.321606ms","remote":"127.0.0.1:46014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T02:44:09.089777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T02:44:08.700343Z","time spent":"389.351341ms","remote":"127.0.0.1:46014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T02:44:09.089817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T02:44:08.75411Z","time spent":"335.670123ms","remote":"127.0.0.1:46014","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-04-27T02:44:09.090002Z","caller":"traceutil/trace.go:171","msg":"trace[1287129657] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"330.834171ms","start":"2026-04-27T02:44:08.759155Z","end":"2026-04-27T02:44:09.089989Z","steps":["trace[1287129657] 'process raft request' (duration: 330.743827ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T02:44:09.089999Z","caller":"traceutil/trace.go:171","msg":"trace[1984597301] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"333.425584ms","start":"2026-04-27T02:44:08.756552Z","end":"2026-04-27T02:44:09.089977Z","steps":["trace[1984597301] 'process raft request' (duration: 333.068808ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T02:44:09.090113Z","caller":"traceutil/trace.go:171","msg":"trace[1121880220] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"328.370933ms","start":"2026-04-27T02:44:08.761735Z","end":"2026-04-27T02:44:09.090106Z","steps":["trace[1121880220] 'process raft request' (duration: 328.253498ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T02:44:09.090156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T02:44:08.761729Z","time spent":"328.407014ms","remote":"127.0.0.1:46014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T02:44:09.090202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T02:44:08.756532Z","time spent":"333.569751ms","remote":"127.0.0.1:46014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T02:44:09.090052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T02:44:08.759135Z","time spent":"330.893104ms","remote":"127.0.0.1:46014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T02:44:09.090073Z","caller":"traceutil/trace.go:171","msg":"trace[2132300462] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"330.328589ms","start":"2026-04-27T02:44:08.759728Z","end":"2026-04-27T02:44:09.090057Z","steps":["trace[2132300462] 'process raft request' (duration: 330.221424ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T02:44:09.095673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T02:44:08.759722Z","time spent":"335.868654ms","remote":"127.0.0.1:46014","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-04-27T02:44:09.090249Z","caller":"traceutil/trace.go:171","msg":"trace[432086387] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"319.909072ms","start":"2026-04-27T02:44:08.77033Z","end":"2026-04-27T02:44:09.090239Z","steps":["trace[432086387] 'process raft request' (duration: 319.710103ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T02:44:09.095798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T02:44:08.770308Z","time spent":"325.469567ms","remote":"127.0.0.1:46014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T02:44:09.090344Z","caller":"traceutil/trace.go:171","msg":"trace[1905499794] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"292.127911ms","start":"2026-04-27T02:44:08.798207Z","end":"2026-04-27T02:44:09.090335Z","steps":["trace[1905499794] 'process raft request' (duration: 291.889841ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T02:44:09.090355Z","caller":"traceutil/trace.go:171","msg":"trace[1624142800] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"388.932872ms","start":"2026-04-27T02:44:08.700721Z","end":"2026-04-27T02:44:09.089654Z","steps":["trace[1624142800] 'process raft request' (duration: 388.771545ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T02:44:09.095927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T02:44:08.700712Z","time spent":"395.19827ms","remote":"127.0.0.1:46014","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-04-27T02:44:09.090412Z","caller":"traceutil/trace.go:171","msg":"trace[1461382426] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"198.134963ms","start":"2026-04-27T02:44:08.892245Z","end":"2026-04-27T02:44:09.09038Z","steps":["trace[1461382426] 'process raft request' (duration: 198.018808ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T02:44:13.153784Z","caller":"traceutil/trace.go:171","msg":"trace[1384444027] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"340.401668ms","start":"2026-04-27T02:44:12.813345Z","end":"2026-04-27T02:44:13.153747Z","steps":["trace[1384444027] 'process raft request' (duration: 339.485526ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T02:44:13.154397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T02:44:12.81329Z","time spent":"340.720273ms","remote":"127.0.0.1:46072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036109,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T02:44:15.749059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.373125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T02:44:15.749123Z","caller":"traceutil/trace.go:171","msg":"trace[420294691] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:567; }","duration":"130.444368ms","start":"2026-04-27T02:44:15.618665Z","end":"2026-04-27T02:44:15.74911Z","steps":["trace[420294691] 'range keys from in-memory index tree' (duration: 130.276771ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T02:44:16.05433Z","caller":"traceutil/trace.go:171","msg":"trace[902514652] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"371.500132ms","start":"2026-04-27T02:44:15.682793Z","end":"2026-04-27T02:44:16.054293Z","steps":["trace[902514652] 'process raft request' (duration: 370.801829ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T02:44:16.054588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T02:44:15.682749Z","time spent":"371.67923ms","remote":"127.0.0.1:46072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T02:44:16.58994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.628523ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T02:44:16.59036Z","caller":"traceutil/trace.go:171","msg":"trace[1632169608] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"900.048194ms","start":"2026-04-27T02:44:15.690269Z","end":"2026-04-27T02:44:16.590317Z","steps":["trace[1632169608] 'process raft request' (duration: 524.908307ms)","trace[1632169608] 'compare' (duration: 374.453825ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T02:44:16.590437Z","caller":"traceutil/trace.go:171","msg":"trace[1162577335] linearizableReadLoop","detail":"{readStateIndex:594; appliedIndex:592; }","duration":"756.643584ms","start":"2026-04-27T02:44:15.833751Z","end":"2026-04-27T02:44:16.590395Z","steps":["trace[1162577335] 'read index received' (duration: 219.703553ms)","trace[1162577335] 'applied index is now lower than readState.Index' (duration: 536.937141ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T02:44:16.590713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T02:44:15.690235Z","time spent":"900.227452ms","remote":"127.0.0.1:46216","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-04-27T02:44:16.590879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"757.035433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-7gtdc\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-27T02:44:16.590989Z","caller":"traceutil/trace.go:171","msg":"trace[388270246] range","detail":"{range_begin:/registry/pods/kube-system/cilium-7gtdc; range_end:; response_count:1; response_revision:570; }","duration":"757.324106ms","start":"2026-04-27T02:44:15.833643Z","end":"2026-04-27T02:44:16.590967Z","steps":["trace[388270246] 'agreement among raft nodes before linearized reading' (duration: 756.99185ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T02:44:16.591083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T02:44:15.833589Z","time spent":"757.475083ms","remote":"127.0.0.1:46140","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-7gtdc\" "} {"level":"warn","ts":"2026-04-27T02:44:16.591201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.584095ms","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-04-27T02:44:16.59125Z","caller":"traceutil/trace.go:171","msg":"trace[231407221] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:570; }","duration":"403.741733ms","start":"2026-04-27T02:44:16.187495Z","end":"2026-04-27T02:44:16.591237Z","steps":["trace[231407221] 'agreement among raft nodes before linearized reading' (duration: 403.2535ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T02:44:16.591272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.937845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-27T02:44:16.591419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T02:44:16.187468Z","time spent":"403.870128ms","remote":"127.0.0.1:46216","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-04-27T02:44:16.591478Z","caller":"traceutil/trace.go:171","msg":"trace[1318122994] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:570; }","duration":"282.178306ms","start":"2026-04-27T02:44:16.309243Z","end":"2026-04-27T02:44:16.591421Z","steps":["trace[1318122994] 'agreement among raft nodes before linearized reading' (duration: 281.565808ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T02:44:16.591597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.46824ms","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-04-27T02:44:16.591727Z","caller":"traceutil/trace.go:171","msg":"trace[1980985909] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:570; }","duration":"309.593596ms","start":"2026-04-27T02:44:16.282079Z","end":"2026-04-27T02:44:16.591673Z","steps":["trace[1980985909] 'agreement among raft nodes before linearized reading' (duration: 309.385246ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T02:44:16.591209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.750667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-27T02:44:16.591864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T02:44:16.282057Z","time spent":"309.791655ms","remote":"127.0.0.1:46216","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-04-27T02:44:16.59187Z","caller":"traceutil/trace.go:171","msg":"trace[2088274329] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:570; }","duration":"282.428638ms","start":"2026-04-27T02:44:16.309353Z","end":"2026-04-27T02:44:16.591782Z","steps":["trace[2088274329] 'agreement among raft nodes before linearized reading' (duration: 281.39433ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T02:44:16.590436Z","caller":"traceutil/trace.go:171","msg":"trace[1356850436] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"450.857222ms","start":"2026-04-27T02:44:16.139555Z","end":"2026-04-27T02:44:16.590412Z","steps":["trace[1356850436] 'process raft request' (duration: 450.775748ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T02:44:16.592241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T02:44:16.139528Z","time spent":"452.668557ms","remote":"127.0.0.1:46462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T02:44:18.669616Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.603558455s","expected-duration":"1s"} {"level":"info","ts":"2026-04-27T02:44:18.671102Z","caller":"traceutil/trace.go:171","msg":"trace[1950023664] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"1.608233435s","start":"2026-04-27T02:44:17.062843Z","end":"2026-04-27T02:44:18.671077Z","steps":["trace[1950023664] 'process raft request' (duration: 1.607729651s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T02:44:18.671293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T02:44:17.062793Z","time spent":"1.608387603s","remote":"127.0.0.1:46072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T02:44:18.998449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.923977ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-27T02:44:18.998848Z","caller":"traceutil/trace.go:171","msg":"trace[1303247388] linearizableReadLoop","detail":"{readStateIndex:602; appliedIndex:600; }","duration":"387.910021ms","start":"2026-04-27T02:44:18.610882Z","end":"2026-04-27T02:44:18.998792Z","steps":["trace[1303247388] 'read index received' (duration: 59.081556ms)","trace[1303247388] 'applied index is now lower than readState.Index' (duration: 328.819765ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T02:44:18.999042Z","caller":"traceutil/trace.go:171","msg":"trace[1021070138] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"242.290189ms","start":"2026-04-27T02:44:18.75664Z","end":"2026-04-27T02:44:18.99893Z","steps":["trace[1021070138] 'process raft request' (duration: 242.082169ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T02:44:18.999108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.213646ms","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-04-27T02:44:18.999204Z","caller":"traceutil/trace.go:171","msg":"trace[1569794649] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:577; }","duration":"388.372673ms","start":"2026-04-27T02:44:18.610801Z","end":"2026-04-27T02:44:18.999173Z","steps":["trace[1569794649] 'agreement among raft nodes before linearized reading' (duration: 388.09559ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T02:44:18.999246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T02:44:18.610775Z","time spent":"388.463588ms","remote":"127.0.0.1:46216","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-04-27T02:44:18.999358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.893875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-27T02:44:18.999408Z","caller":"traceutil/trace.go:171","msg":"trace[294231920] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:577; }","duration":"359.96439ms","start":"2026-04-27T02:44:18.639429Z","end":"2026-04-27T02:44:18.999394Z","steps":["trace[294231920] 'agreement among raft nodes before linearized reading' (duration: 359.729168ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T02:44:18.999127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.90431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T02:44:18.999337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.847027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-27T02:44:18.999447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T02:44:18.639415Z","time spent":"360.022972ms","remote":"127.0.0.1:46216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-27T02:44:18.999649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.273314ms","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-04-27T02:44:18.99949Z","caller":"traceutil/trace.go:171","msg":"trace[1165980241] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:577; }","duration":"381.304338ms","start":"2026-04-27T02:44:18.618173Z","end":"2026-04-27T02:44:18.999477Z","steps":["trace[1165980241] 'agreement among raft nodes before linearized reading' (duration: 380.866828ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T02:44:18.999687Z","caller":"traceutil/trace.go:171","msg":"trace[533710830] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:577; }","duration":"359.171302ms","start":"2026-04-27T02:44:18.640377Z","end":"2026-04-27T02:44:18.999549Z","steps":["trace[533710830] 'agreement among raft nodes before linearized reading' (duration: 358.674888ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T02:44:18.999753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T02:44:18.618166Z","time spent":"381.564061ms","remote":"127.0.0.1:45954","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-04-27T02:44:18.999752Z","caller":"traceutil/trace.go:171","msg":"trace[254323574] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:577; }","duration":"360.4129ms","start":"2026-04-27T02:44:18.639308Z","end":"2026-04-27T02:44:18.99972Z","steps":["trace[254323574] 'agreement among raft nodes before linearized reading' (duration: 360.088575ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T02:44:18.999874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T02:44:18.639293Z","time spent":"360.563467ms","remote":"127.0.0.1:46216","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-04-27T02:44:18.999755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T02:44:18.640367Z","time spent":"359.372641ms","remote":"127.0.0.1:46216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-27T02:44:23.457805Z","caller":"traceutil/trace.go:171","msg":"trace[1213324843] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"157.461043ms","start":"2026-04-27T02:44:23.300291Z","end":"2026-04-27T02:44:23.457752Z","steps":["trace[1213324843] 'process raft request' (duration: 81.208568ms)","trace[1213324843] 'compare' (duration: 75.939199ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T02:44:24.048466Z","caller":"traceutil/trace.go:171","msg":"trace[50535364] linearizableReadLoop","detail":"{readStateIndex:680; appliedIndex:679; }","duration":"156.182073ms","start":"2026-04-27T02:44:23.892255Z","end":"2026-04-27T02:44:24.048437Z","steps":["trace[50535364] 'read index received' (duration: 155.877678ms)","trace[50535364] 'applied index is now lower than readState.Index' (duration: 303.105µs)"],"step_count":2} {"level":"warn","ts":"2026-04-27T02:44:24.048884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.588243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-7gtdc\" ","response":"range_response_count:1 size:18067"} {"level":"info","ts":"2026-04-27T02:44:24.04898Z","caller":"traceutil/trace.go:171","msg":"trace[1658337867] range","detail":"{range_begin:/registry/pods/kube-system/cilium-7gtdc; range_end:; response_count:1; response_revision:652; }","duration":"156.745281ms","start":"2026-04-27T02:44:23.892218Z","end":"2026-04-27T02:44:24.048963Z","steps":["trace[1658337867] 'agreement among raft nodes before linearized reading' (duration: 156.346521ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T02:44:24.049268Z","caller":"traceutil/trace.go:171","msg":"trace[2020074897] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"197.063ms","start":"2026-04-27T02:44:23.851456Z","end":"2026-04-27T02:44:24.048519Z","steps":["trace[2020074897] 'process raft request' (duration: 196.801987ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T02:44:24.067765Z","caller":"traceutil/trace.go:171","msg":"trace[270840653] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"174.275192ms","start":"2026-04-27T02:44:23.893446Z","end":"2026-04-27T02:44:24.067722Z","steps":["trace[270840653] 'process raft request' (duration: 174.081833ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T02:44:31.104911Z","caller":"traceutil/trace.go:171","msg":"trace[1971514007] transaction","detail":"{read_only:false; response_revision:709; number_of_response:1; }","duration":"131.502925ms","start":"2026-04-27T02:44:30.973368Z","end":"2026-04-27T02:44:31.104871Z","steps":["trace[1971514007] 'process raft request' (duration: 131.235131ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T02:44:32.236218Z","caller":"traceutil/trace.go:171","msg":"trace[159577070] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"209.137919ms","start":"2026-04-27T02:44:32.027044Z","end":"2026-04-27T02:44:32.236182Z","steps":["trace[159577070] 'process raft request' (duration: 208.872865ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T02:44:32.343792Z","caller":"traceutil/trace.go:171","msg":"trace[1557123955] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"315.539347ms","start":"2026-04-27T02:44:32.028229Z","end":"2026-04-27T02:44:32.343768Z","steps":["trace[1557123955] 'process raft request' (duration: 308.936318ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T02:44:32.344114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T02:44:32.028219Z","time spent":"315.716177ms","remote":"127.0.0.1:46122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":781,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T02:44:32.344159Z","caller":"traceutil/trace.go:171","msg":"trace[854997165] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"311.975345ms","start":"2026-04-27T02:44:32.032118Z","end":"2026-04-27T02:44:32.344093Z","steps":["trace[854997165] 'process raft request' (duration: 311.536492ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T02:44:32.344141Z","caller":"traceutil/trace.go:171","msg":"trace[1422787766] linearizableReadLoop","detail":"{readStateIndex:750; appliedIndex:747; }","duration":"281.380895ms","start":"2026-04-27T02:44:32.06258Z","end":"2026-04-27T02:44:32.34396Z","steps":["trace[1422787766] 'read index received' (duration: 173.396065ms)","trace[1422787766] 'applied index is now lower than readState.Index' (duration: 107.98293ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T02:44:32.344444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T02:44:32.032109Z","time spent":"312.190875ms","remote":"127.0.0.1:46140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4857,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T02:44:32.344417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.796127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T02:44:32.344673Z","caller":"traceutil/trace.go:171","msg":"trace[1404895880] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:720; }","duration":"282.078741ms","start":"2026-04-27T02:44:32.062528Z","end":"2026-04-27T02:44:32.344607Z","steps":["trace[1404895880] 'agreement among raft nodes before linearized reading' (duration: 281.729893ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T02:44:32.498181Z","caller":"traceutil/trace.go:171","msg":"trace[1399794441] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"145.724565ms","start":"2026-04-27T02:44:32.352428Z","end":"2026-04-27T02:44:32.498153Z","steps":["trace[1399794441] 'process raft request' (duration: 131.450292ms)","trace[1399794441] 'compare' (duration: 14.075033ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T02:44:32.498446Z","caller":"traceutil/trace.go:171","msg":"trace[1167495130] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"144.886282ms","start":"2026-04-27T02:44:32.353521Z","end":"2026-04-27T02:44:32.498407Z","steps":["trace[1167495130] 'process raft request' (duration: 144.583406ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T02:44:33.279956Z","caller":"traceutil/trace.go:171","msg":"trace[1615412813] linearizableReadLoop","detail":"{readStateIndex:759; appliedIndex:757; }","duration":"126.562024ms","start":"2026-04-27T02:44:33.153369Z","end":"2026-04-27T02:44:33.279932Z","steps":["trace[1615412813] 'read index received' (duration: 68.85838ms)","trace[1615412813] 'applied index is now lower than readState.Index' (duration: 57.702334ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T02:44:33.280105Z","caller":"traceutil/trace.go:171","msg":"trace[1086623771] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"128.788179ms","start":"2026-04-27T02:44:33.151254Z","end":"2026-04-27T02:44:33.280042Z","steps":["trace[1086623771] 'process raft request' (duration: 128.519695ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T02:44:33.280455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.05684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-27T02:44:33.28052Z","caller":"traceutil/trace.go:171","msg":"trace[313910030] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:728; }","duration":"127.158345ms","start":"2026-04-27T02:44:33.153348Z","end":"2026-04-27T02:44:33.280506Z","steps":["trace[313910030] 'agreement among raft nodes before linearized reading' (duration: 126.752534ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T02:44:33.281112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.86771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-27T02:44:33.281189Z","caller":"traceutil/trace.go:171","msg":"trace[2105900124] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:728; }","duration":"126.985596ms","start":"2026-04-27T02:44:33.154185Z","end":"2026-04-27T02:44:33.281171Z","steps":["trace[2105900124] 'agreement among raft nodes before linearized reading' (duration: 126.685141ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T02:44:33.409244Z","caller":"traceutil/trace.go:171","msg":"trace[977635842] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"121.782248ms","start":"2026-04-27T02:44:33.287418Z","end":"2026-04-27T02:44:33.409201Z","steps":["trace[977635842] 'process raft request' (duration: 121.578148ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T02:44:35.504721Z","caller":"traceutil/trace.go:171","msg":"trace[1845444373] linearizableReadLoop","detail":"{readStateIndex:762; appliedIndex:761; }","duration":"277.450569ms","start":"2026-04-27T02:44:35.227175Z","end":"2026-04-27T02:44:35.504626Z","steps":["trace[1845444373] 'read index received' (duration: 277.270579ms)","trace[1845444373] 'applied index is now lower than readState.Index' (duration: 179.02µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T02:44:35.50516Z","caller":"traceutil/trace.go:171","msg":"trace[1829326271] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"694.095395ms","start":"2026-04-27T02:44:34.811055Z","end":"2026-04-27T02:44:35.50515Z","steps":["trace[1829326271] 'process raft request' (duration: 693.444391ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T02:44:35.505308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T02:44:34.811036Z","time spent":"694.157998ms","remote":"127.0.0.1:46216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T02:44:35.505431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.107403ms","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-04-27T02:44:35.505554Z","caller":"traceutil/trace.go:171","msg":"trace[1009697304] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:731; }","duration":"219.319164ms","start":"2026-04-27T02:44:35.286205Z","end":"2026-04-27T02:44:35.505524Z","steps":["trace[1009697304] 'agreement among raft nodes before linearized reading' (duration: 218.957205ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T02:44:35.50573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.572488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-27T02:44:35.505772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.66625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-27T02:44:35.50579Z","caller":"traceutil/trace.go:171","msg":"trace[879610565] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:731; }","duration":"278.678323ms","start":"2026-04-27T02:44:35.227098Z","end":"2026-04-27T02:44:35.505776Z","steps":["trace[879610565] 'agreement among raft nodes before linearized reading' (duration: 278.567107ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T02:44:35.505885Z","caller":"traceutil/trace.go:171","msg":"trace[1792115644] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:731; }","duration":"140.737305ms","start":"2026-04-27T02:44:35.365058Z","end":"2026-04-27T02:44:35.505795Z","steps":["trace[1792115644] 'agreement among raft nodes before linearized reading' (duration: 140.65109ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T02:44:39.123122Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11228773273965962586,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T02:44:39.624128Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11228773273965962586,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T02:44:39.829052Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.862924159s","expected-duration":"1s"} {"level":"info","ts":"2026-04-27T02:44:39.829421Z","caller":"traceutil/trace.go:171","msg":"trace[1512034896] linearizableReadLoop","detail":"{readStateIndex:782; appliedIndex:781; }","duration":"1.20656595s","start":"2026-04-27T02:44:38.622829Z","end":"2026-04-27T02:44:39.829395Z","steps":["trace[1512034896] 'read index received' (duration: 1.206360939s)","trace[1512034896] 'applied index is now lower than readState.Index' (duration: 204.021µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T02:44:39.829505Z","caller":"traceutil/trace.go:171","msg":"trace[1362834212] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"1.863520641s","start":"2026-04-27T02:44:37.965976Z","end":"2026-04-27T02:44:39.829496Z","steps":["trace[1362834212] 'process raft request' (duration: 1.863271848s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T02:44:39.829622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T02:44:37.965946Z","time spent":"1.863590664s","remote":"127.0.0.1:46140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4022,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T02:44:39.830148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.779359ms","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-04-27T02:44:39.830304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.634465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-27T02:44:39.830356Z","caller":"traceutil/trace.go:171","msg":"trace[492576526] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:750; }","duration":"290.72684ms","start":"2026-04-27T02:44:39.539617Z","end":"2026-04-27T02:44:39.830344Z","steps":["trace[492576526] 'agreement among raft nodes before linearized reading' (duration: 290.548421ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T02:44:39.830412Z","caller":"traceutil/trace.go:171","msg":"trace[1546923456] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:750; }","duration":"310.012162ms","start":"2026-04-27T02:44:39.520296Z","end":"2026-04-27T02:44:39.830309Z","steps":["trace[1546923456] 'agreement among raft nodes before linearized reading' (duration: 309.655103ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T02:44:39.830406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.705416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-27T02:44:39.830787Z","caller":"traceutil/trace.go:171","msg":"trace[1350559343] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:750; }","duration":"290.144219ms","start":"2026-04-27T02:44:39.540578Z","end":"2026-04-27T02:44:39.830722Z","steps":["trace[1350559343] 'agreement among raft nodes before linearized reading' (duration: 289.536157ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T02:44:39.830587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.02122ms","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-04-27T02:44:39.830992Z","caller":"traceutil/trace.go:171","msg":"trace[1520533837] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:750; }","duration":"289.491345ms","start":"2026-04-27T02:44:39.541477Z","end":"2026-04-27T02:44:39.830968Z","steps":["trace[1520533837] 'agreement among raft nodes before linearized reading' (duration: 288.801449ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T02:44:39.831466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.773037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-27T02:44:39.831587Z","caller":"traceutil/trace.go:171","msg":"trace[1575250515] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:750; }","duration":"306.171609ms","start":"2026-04-27T02:44:39.525388Z","end":"2026-04-27T02:44:39.83156Z","steps":["trace[1575250515] 'agreement among raft nodes before linearized reading' (duration: 304.847208ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T02:44:39.831646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T02:44:39.525368Z","time spent":"306.266854ms","remote":"127.0.0.1:46216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-27T02:44:39.830532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T02:44:39.520279Z","time spent":"310.234014ms","remote":"127.0.0.1:46216","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-04-27T02:44:39.830218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.207386594s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T02:44:39.832048Z","caller":"traceutil/trace.go:171","msg":"trace[107018924] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:750; }","duration":"1.209244602s","start":"2026-04-27T02:44:38.622783Z","end":"2026-04-27T02:44:39.832027Z","steps":["trace[107018924] 'agreement among raft nodes before linearized reading' (duration: 1.207379694s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T02:44:39.832112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T02:44:38.622766Z","time spent":"1.209331747s","remote":"127.0.0.1:45954","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-04-27T02:44:47.835621Z","caller":"traceutil/trace.go:171","msg":"trace[335956300] transaction","detail":"{read_only:false; response_revision:834; number_of_response:1; }","duration":"107.1713ms","start":"2026-04-27T02:44:47.728412Z","end":"2026-04-27T02:44:47.835583Z","steps":["trace[335956300] 'process raft request' (duration: 78.655621ms)","trace[335956300] 'compare' (duration: 27.295282ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T02:44:52.341427Z","caller":"traceutil/trace.go:171","msg":"trace[56389740] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"104.800471ms","start":"2026-04-27T02:44:52.236591Z","end":"2026-04-27T02:44:52.341392Z","steps":["trace[56389740] 'process raft request' (duration: 103.844688ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T02:45:07.866662Z","caller":"traceutil/trace.go:171","msg":"trace[1812950056] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"375.14193ms","start":"2026-04-27T02:45:07.491485Z","end":"2026-04-27T02:45:07.866626Z","steps":["trace[1812950056] 'process raft request' (duration: 374.97845ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T02:45:07.866821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T02:45:07.491458Z","time spent":"375.279907ms","remote":"127.0.0.1:46216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T02:45:13.19632Z","caller":"traceutil/trace.go:171","msg":"trace[1484914995] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"179.223004ms","start":"2026-04-27T02:45:13.017069Z","end":"2026-04-27T02:45:13.196292Z","steps":["trace[1484914995] 'process raft request' (duration: 179.023133ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T02:45:13.344529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.51467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-x59kw\" ","response":"range_response_count:1 size:4123"} {"level":"info","ts":"2026-04-27T02:45:13.344633Z","caller":"traceutil/trace.go:171","msg":"trace[952943597] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-x59kw; range_end:; response_count:1; response_revision:992; }","duration":"133.643078ms","start":"2026-04-27T02:45:13.210966Z","end":"2026-04-27T02:45:13.34461Z","steps":["trace[952943597] 'range keys from in-memory index tree' (duration: 133.34632ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T02:45:13.580124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.929016ms","expected-duration":"100ms","prefix":"","request":"header: txn: success: > failure: >>","response":"size:4146"} {"level":"info","ts":"2026-04-27T02:45:13.58026Z","caller":"traceutil/trace.go:171","msg":"trace[1592238320] transaction","detail":"{read_only:false; number_of_response:1; response_revision:993; }","duration":"161.430566ms","start":"2026-04-27T02:45:13.418807Z","end":"2026-04-27T02:45:13.580238Z","steps":["trace[1592238320] 'process raft request' (duration: 38.211803ms)","trace[1592238320] 'compare' (duration: 122.812179ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T02:45:14.527291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.16077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-27T02:45:14.527615Z","caller":"traceutil/trace.go:171","msg":"trace[431529541] linearizableReadLoop","detail":"{readStateIndex:1039; appliedIndex:1038; }","duration":"203.314101ms","start":"2026-04-27T02:45:14.324271Z","end":"2026-04-27T02:45:14.527585Z","steps":["trace[431529541] 'read index received' (duration: 142.909122ms)","trace[431529541] 'applied index is now lower than readState.Index' (duration: 60.403989ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T02:45:14.527898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.595997ms","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-04-27T02:45:14.527951Z","caller":"traceutil/trace.go:171","msg":"trace[258905999] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:998; }","duration":"203.693734ms","start":"2026-04-27T02:45:14.324246Z","end":"2026-04-27T02:45:14.52794Z","steps":["trace[258905999] 'agreement among raft nodes before linearized reading' (duration: 203.449499ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T02:45:14.527389Z","caller":"traceutil/trace.go:171","msg":"trace[880575030] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:997; }","duration":"205.305509ms","start":"2026-04-27T02:45:14.32206Z","end":"2026-04-27T02:45:14.527366Z","steps":["trace[880575030] 'range keys from in-memory index tree' (duration: 204.970629ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T02:45:14.541962Z","caller":"traceutil/trace.go:171","msg":"trace[304468411] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"204.843281ms","start":"2026-04-27T02:45:14.323278Z","end":"2026-04-27T02:45:14.528121Z","steps":["trace[304468411] 'process raft request' (duration: 143.931723ms)","trace[304468411] 'compare' (duration: 60.170755ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T02:45:18.164946Z","caller":"traceutil/trace.go:171","msg":"trace[2072510591] linearizableReadLoop","detail":"{readStateIndex:1065; appliedIndex:1064; }","duration":"321.080011ms","start":"2026-04-27T02:45:17.843824Z","end":"2026-04-27T02:45:18.164904Z","steps":["trace[2072510591] 'read index received' (duration: 259.239626ms)","trace[2072510591] 'applied index is now lower than readState.Index' (duration: 61.809783ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T02:45:18.165127Z","caller":"traceutil/trace.go:171","msg":"trace[243953091] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"279.796037ms","start":"2026-04-27T02:45:17.885233Z","end":"2026-04-27T02:45:18.165029Z","steps":["trace[243953091] 'process raft request' (duration: 279.564493ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T02:45:18.165623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.778162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9c6a20c4-4b58-47b0-8767-72908836d654\" ","response":"range_response_count:1 size:3579"} {"level":"info","ts":"2026-04-27T02:45:18.165737Z","caller":"traceutil/trace.go:171","msg":"trace[776901227] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9c6a20c4-4b58-47b0-8767-72908836d654; range_end:; response_count:1; response_revision:1024; }","duration":"321.959093ms","start":"2026-04-27T02:45:17.843755Z","end":"2026-04-27T02:45:18.165715Z","steps":["trace[776901227] 'agreement among raft nodes before linearized reading' (duration: 321.249841ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T02:45:18.165793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T02:45:17.843735Z","time spent":"322.044338ms","remote":"127.0.0.1:46140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3602,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9c6a20c4-4b58-47b0-8767-72908836d654\" "} {"level":"info","ts":"2026-04-27T02:45:18.343074Z","caller":"traceutil/trace.go:171","msg":"trace[1920018267] linearizableReadLoop","detail":"{readStateIndex:1067; appliedIndex:1066; }","duration":"131.311334ms","start":"2026-04-27T02:45:18.211739Z","end":"2026-04-27T02:45:18.34305Z","steps":["trace[1920018267] 'read index received' (duration: 114.791219ms)","trace[1920018267] 'applied index is now lower than readState.Index' (duration: 16.518994ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T02:45:18.343302Z","caller":"traceutil/trace.go:171","msg":"trace[1037189549] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"171.266244ms","start":"2026-04-27T02:45:18.171999Z","end":"2026-04-27T02:45:18.343265Z","steps":["trace[1037189549] 'process raft request' (duration: 154.620172ms)","trace[1037189549] 'compare' (duration: 16.283881ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T02:45:18.343541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.769311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-27T02:45:18.34362Z","caller":"traceutil/trace.go:171","msg":"trace[453704535] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1025; }","duration":"131.91626ms","start":"2026-04-27T02:45:18.211685Z","end":"2026-04-27T02:45:18.343601Z","steps":["trace[453704535] 'agreement among raft nodes before linearized reading' (duration: 131.533668ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T02:45:18.343903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.723487ms","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-04-27T02:45:18.343967Z","caller":"traceutil/trace.go:171","msg":"trace[721736651] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1025; }","duration":"110.899947ms","start":"2026-04-27T02:45:18.233053Z","end":"2026-04-27T02:45:18.343953Z","steps":["trace[721736651] 'agreement among raft nodes before linearized reading' (duration: 110.207606ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T02:45:20.312272Z","caller":"traceutil/trace.go:171","msg":"trace[381533220] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"169.418946ms","start":"2026-04-27T02:45:20.142829Z","end":"2026-04-27T02:45:20.312248Z","steps":["trace[381533220] 'process raft request' (duration: 169.065495ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T02:45:20.312428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.468564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9c6a20c4-4b58-47b0-8767-72908836d654\" ","response":"range_response_count:1 size:3882"} {"level":"info","ts":"2026-04-27T02:45:20.3125Z","caller":"traceutil/trace.go:171","msg":"trace[832770957] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9c6a20c4-4b58-47b0-8767-72908836d654; range_end:; response_count:1; response_revision:1033; }","duration":"136.594032ms","start":"2026-04-27T02:45:20.175888Z","end":"2026-04-27T02:45:20.312482Z","steps":["trace[832770957] 'agreement among raft nodes before linearized reading' (duration: 136.438422ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T02:45:20.312182Z","caller":"traceutil/trace.go:171","msg":"trace[688749368] linearizableReadLoop","detail":"{readStateIndex:1075; appliedIndex:1073; }","duration":"136.2203ms","start":"2026-04-27T02:45:20.175928Z","end":"2026-04-27T02:45:20.312149Z","steps":["trace[688749368] 'read index received' (duration: 62.976358ms)","trace[688749368] 'applied index is now lower than readState.Index' (duration: 73.238421ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T02:45:26.974979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.50362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-27T02:45:26.975084Z","caller":"traceutil/trace.go:171","msg":"trace[333315442] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1074; }","duration":"138.644839ms","start":"2026-04-27T02:45:26.836418Z","end":"2026-04-27T02:45:26.975063Z","steps":["trace[333315442] 'count revisions from in-memory index tree' (duration: 138.388773ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T02:45:28.571453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.683821ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-27T02:45:28.571719Z","caller":"traceutil/trace.go:171","msg":"trace[2019890135] linearizableReadLoop","detail":"{readStateIndex:1120; appliedIndex:1118; }","duration":"130.542096ms","start":"2026-04-27T02:45:28.44116Z","end":"2026-04-27T02:45:28.571702Z","steps":["trace[2019890135] 'read index received' (duration: 18.710626ms)","trace[2019890135] 'applied index is now lower than readState.Index' (duration: 111.83037ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T02:45:28.571775Z","caller":"traceutil/trace.go:171","msg":"trace[812171344] transaction","detail":"{read_only:false; response_revision:1075; number_of_response:1; }","duration":"351.725736ms","start":"2026-04-27T02:45:28.220004Z","end":"2026-04-27T02:45:28.571729Z","steps":["trace[812171344] 'process raft request' (duration: 351.577637ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T02:45:28.571856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.695905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T02:45:28.571891Z","caller":"traceutil/trace.go:171","msg":"trace[1254896584] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1075; }","duration":"130.742938ms","start":"2026-04-27T02:45:28.441137Z","end":"2026-04-27T02:45:28.57188Z","steps":["trace[1254896584] 'agreement among raft nodes before linearized reading' (duration: 130.646992ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T02:45:28.571963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T02:45:28.219971Z","time spent":"351.912067ms","remote":"127.0.0.1:46216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T02:45:28.87039Z","caller":"traceutil/trace.go:171","msg":"trace[338968363] transaction","detail":"{read_only:false; response_revision:1079; number_of_response:1; }","duration":"103.697096ms","start":"2026-04-27T02:45:28.766663Z","end":"2026-04-27T02:45:28.870361Z","steps":["trace[338968363] 'process raft request' (duration: 103.495854ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T02:45:28.871189Z","caller":"traceutil/trace.go:171","msg":"trace[764164838] transaction","detail":"{read_only:false; response_revision:1080; number_of_response:1; }","duration":"104.490884ms","start":"2026-04-27T02:45:28.766679Z","end":"2026-04-27T02:45:28.87117Z","steps":["trace[764164838] 'process raft request' (duration: 104.210497ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T02:45:38.164835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.357401ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-27T02:45:38.164995Z","caller":"traceutil/trace.go:171","msg":"trace[1919004650] linearizableReadLoop","detail":"{readStateIndex:1160; appliedIndex:1159; }","duration":"177.344549ms","start":"2026-04-27T02:45:37.987627Z","end":"2026-04-27T02:45:38.164971Z","steps":["trace[1919004650] 'read index received' (duration: 35.482µs)","trace[1919004650] 'applied index is now lower than readState.Index' (duration: 177.307246ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T02:45:38.165251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.471076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T02:45:38.165297Z","caller":"traceutil/trace.go:171","msg":"trace[123167466] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1113; }","duration":"177.713341ms","start":"2026-04-27T02:45:37.987571Z","end":"2026-04-27T02:45:38.165284Z","steps":["trace[123167466] 'agreement among raft nodes before linearized reading' (duration: 177.463435ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T02:45:41.012393Z","caller":"traceutil/trace.go:171","msg":"trace[1659522093] transaction","detail":"{read_only:false; response_revision:1125; number_of_response:1; }","duration":"201.431762ms","start":"2026-04-27T02:45:40.810935Z","end":"2026-04-27T02:45:41.012367Z","steps":["trace[1659522093] 'process raft request' (duration: 201.290873ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T02:45:41.153129Z","caller":"traceutil/trace.go:171","msg":"trace[1954202324] linearizableReadLoop","detail":"{readStateIndex:1173; appliedIndex:1171; }","duration":"339.343896ms","start":"2026-04-27T02:45:40.813759Z","end":"2026-04-27T02:45:41.153103Z","steps":["trace[1954202324] 'read index received' (duration: 198.47184ms)","trace[1954202324] 'applied index is now lower than readState.Index' (duration: 140.871026ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T02:45:41.153181Z","caller":"traceutil/trace.go:171","msg":"trace[53033782] transaction","detail":"{read_only:false; response_revision:1126; number_of_response:1; }","duration":"341.222172ms","start":"2026-04-27T02:45:40.811916Z","end":"2026-04-27T02:45:41.153138Z","steps":["trace[53033782] 'process raft request' (duration: 253.062861ms)","trace[53033782] 'compare' (duration: 88.024023ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T02:45:41.153428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.516235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-27T02:45:41.153506Z","caller":"traceutil/trace.go:171","msg":"trace[647175365] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1126; }","duration":"204.655414ms","start":"2026-04-27T02:45:40.948833Z","end":"2026-04-27T02:45:41.153489Z","steps":["trace[647175365] 'agreement among raft nodes before linearized reading' (duration: 204.413189ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T02:45:41.153584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.601998ms","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-04-27T02:45:41.153663Z","caller":"traceutil/trace.go:171","msg":"trace[314990476] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1126; }","duration":"160.730856ms","start":"2026-04-27T02:45:40.992913Z","end":"2026-04-27T02:45:41.153644Z","steps":["trace[314990476] 'agreement among raft nodes before linearized reading' (duration: 160.508633ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T02:45:41.153657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T02:45:40.811893Z","time spent":"341.557282ms","remote":"127.0.0.1:46216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T02:45:41.154267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.471416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-27T02:45:41.154455Z","caller":"traceutil/trace.go:171","msg":"trace[1196170387] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1126; }","duration":"340.691509ms","start":"2026-04-27T02:45:40.813729Z","end":"2026-04-27T02:45:41.154421Z","steps":["trace[1196170387] 'agreement among raft nodes before linearized reading' (duration: 339.59289ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T02:45:41.154565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T02:45:40.813716Z","time spent":"340.829737ms","remote":"127.0.0.1:46216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-27T02:45:41.300497Z","caller":"traceutil/trace.go:171","msg":"trace[1515200030] transaction","detail":"{read_only:false; response_revision:1128; number_of_response:1; }","duration":"141.748671ms","start":"2026-04-27T02:45:41.158707Z","end":"2026-04-27T02:45:41.300456Z","steps":["trace[1515200030] 'process raft request' (duration: 116.587353ms)","trace[1515200030] 'compare' (duration: 24.816527ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T02:45:41.300567Z","caller":"traceutil/trace.go:171","msg":"trace[1513931814] transaction","detail":"{read_only:false; response_revision:1130; number_of_response:1; }","duration":"141.167255ms","start":"2026-04-27T02:45:41.159363Z","end":"2026-04-27T02:45:41.300531Z","steps":["trace[1513931814] 'process raft request' (duration: 141.08165ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T02:45:41.300728Z","caller":"traceutil/trace.go:171","msg":"trace[1916901240] transaction","detail":"{read_only:false; response_revision:1129; number_of_response:1; }","duration":"141.926872ms","start":"2026-04-27T02:45:41.158793Z","end":"2026-04-27T02:45:41.30072Z","steps":["trace[1916901240] 'process raft request' (duration: 141.566889ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T02:45:45.489947Z","caller":"traceutil/trace.go:171","msg":"trace[1085204652] transaction","detail":"{read_only:false; response_revision:1143; number_of_response:1; }","duration":"167.919718ms","start":"2026-04-27T02:45:45.321987Z","end":"2026-04-27T02:45:45.489907Z","steps":["trace[1085204652] 'process raft request' (duration: 118.998311ms)","trace[1085204652] 'compare' (duration: 48.660331ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T02:45:51.215736Z","caller":"traceutil/trace.go:171","msg":"trace[198879971] linearizableReadLoop","detail":"{readStateIndex:1208; appliedIndex:1207; }","duration":"135.568585ms","start":"2026-04-27T02:45:51.080137Z","end":"2026-04-27T02:45:51.215705Z","steps":["trace[198879971] 'read index received' (duration: 135.436046ms)","trace[198879971] 'applied index is now lower than readState.Index' (duration: 131.318µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T02:45:51.215846Z","caller":"traceutil/trace.go:171","msg":"trace[1011119601] transaction","detail":"{read_only:false; response_revision:1159; number_of_response:1; }","duration":"140.381546ms","start":"2026-04-27T02:45:51.075403Z","end":"2026-04-27T02:45:51.215784Z","steps":["trace[1011119601] 'process raft request' (duration: 140.135561ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T02:45:51.21597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.793388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T02:45:51.216039Z","caller":"traceutil/trace.go:171","msg":"trace[1468587390] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:1159; }","duration":"135.917696ms","start":"2026-04-27T02:45:51.080104Z","end":"2026-04-27T02:45:51.216021Z","steps":["trace[1468587390] 'agreement among raft nodes before linearized reading' (duration: 135.776847ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T02:45:51.216027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.303934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:811"} {"level":"info","ts":"2026-04-27T02:45:51.216121Z","caller":"traceutil/trace.go:171","msg":"trace[1025005482] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:1159; }","duration":"119.444482ms","start":"2026-04-27T02:45:51.096638Z","end":"2026-04-27T02:45:51.216083Z","steps":["trace[1025005482] 'agreement among raft nodes before linearized reading' (duration: 119.2487ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T02:45:51.644847Z","caller":"traceutil/trace.go:171","msg":"trace[1827206845] linearizableReadLoop","detail":"{readStateIndex:1212; appliedIndex:1211; }","duration":"106.378885ms","start":"2026-04-27T02:45:51.538453Z","end":"2026-04-27T02:45:51.644832Z","steps":["trace[1827206845] 'read index received' (duration: 96.674436ms)","trace[1827206845] 'applied index is now lower than readState.Index' (duration: 9.703668ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T02:45:51.644987Z","caller":"traceutil/trace.go:171","msg":"trace[1153191911] transaction","detail":"{read_only:false; response_revision:1163; number_of_response:1; }","duration":"107.58518ms","start":"2026-04-27T02:45:51.537382Z","end":"2026-04-27T02:45:51.644967Z","steps":["trace[1153191911] 'process raft request' (duration: 97.772074ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T02:45:51.645073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.611978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-27T02:45:51.645113Z","caller":"traceutil/trace.go:171","msg":"trace[1131348248] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1163; }","duration":"106.685274ms","start":"2026-04-27T02:45:51.538418Z","end":"2026-04-27T02:45:51.645104Z","steps":["trace[1131348248] 'agreement among raft nodes before linearized reading' (duration: 106.567906ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T02:45:59.078521Z","caller":"traceutil/trace.go:171","msg":"trace[1420418758] transaction","detail":"{read_only:false; response_revision:1185; number_of_response:1; }","duration":"123.017382ms","start":"2026-04-27T02:45:58.955463Z","end":"2026-04-27T02:45:59.07848Z","steps":["trace[1420418758] 'process raft request' (duration: 122.786607ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T02:46:11.941319Z","caller":"traceutil/trace.go:171","msg":"trace[1244126828] transaction","detail":"{read_only:false; response_revision:1230; number_of_response:1; }","duration":"158.60018ms","start":"2026-04-27T02:46:11.782692Z","end":"2026-04-27T02:46:11.941293Z","steps":["trace[1244126828] 'process raft request' (duration: 158.528665ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T02:46:11.941342Z","caller":"traceutil/trace.go:171","msg":"trace[1296150865] transaction","detail":"{read_only:false; response_revision:1229; number_of_response:1; }","duration":"158.730138ms","start":"2026-04-27T02:46:11.782567Z","end":"2026-04-27T02:46:11.941297Z","steps":["trace[1296150865] 'process raft request' (duration: 147.058131ms)","trace[1296150865] 'compare' (duration: 11.428361ms)"],"step_count":2}