{"level":"warn","ts":"2026-07-08T23:30:30.101442Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-08T23:30:30.101539Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.74:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.74:2380","--initial-cluster=instance=https://199.204.45.74:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.74:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.74: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-07-08T23:30:30.101602Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-08T23:30:30.101613Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.74:2380"]} {"level":"info","ts":"2026-07-08T23:30:30.101629Z","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-07-08T23:30:30.102205Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"]} {"level":"info","ts":"2026-07-08T23:30:30.102268Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.74:2380"],"listen-peer-urls":["https://199.204.45.74:2380"],"advertise-client-urls":["https://199.204.45.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.74: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-07-08T23:30:30.114748Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"12.269383ms"} {"level":"info","ts":"2026-07-08T23:30:30.125024Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"89ba38f964b95aa0","cluster-id":"54492f71bf65c400"} {"level":"info","ts":"2026-07-08T23:30:30.125133Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=()"} {"level":"info","ts":"2026-07-08T23:30:30.125183Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became follower at term 0"} {"level":"info","ts":"2026-07-08T23:30:30.125206Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 89ba38f964b95aa0 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-08T23:30:30.125218Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became follower at term 1"} {"level":"info","ts":"2026-07-08T23:30:30.125273Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=(9924307372652190368)"} {"level":"warn","ts":"2026-07-08T23:30:30.137431Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-08T23:30:30.141188Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-08T23:30:30.144354Z","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-07-08T23:30:30.147964Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"89ba38f964b95aa0","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-08T23:30:30.148071Z","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-07-08T23:30:30.14812Z","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-07-08T23:30:30.148128Z","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-07-08T23:30:30.148286Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"89ba38f964b95aa0","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-08T23:30:30.14938Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=(9924307372652190368)"} {"level":"info","ts":"2026-07-08T23:30:30.149625Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"54492f71bf65c400","local-member-id":"89ba38f964b95aa0","added-peer-id":"89ba38f964b95aa0","added-peer-peer-urls":["https://199.204.45.74:2380"]} {"level":"info","ts":"2026-07-08T23:30:30.151935Z","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-07-08T23:30:30.151992Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.74:2380"} {"level":"info","ts":"2026-07-08T23:30:30.15218Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.74:2380"} {"level":"info","ts":"2026-07-08T23:30:30.153329Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-08T23:30:30.153284Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"89ba38f964b95aa0","initial-advertise-peer-urls":["https://199.204.45.74:2380"],"listen-peer-urls":["https://199.204.45.74:2380"],"advertise-client-urls":["https://199.204.45.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-08T23:30:30.52584Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 is starting a new election at term 1"} {"level":"info","ts":"2026-07-08T23:30:30.525881Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-08T23:30:30.525894Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 received MsgPreVoteResp from 89ba38f964b95aa0 at term 1"} {"level":"info","ts":"2026-07-08T23:30:30.525905Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became candidate at term 2"} {"level":"info","ts":"2026-07-08T23:30:30.52591Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 received MsgVoteResp from 89ba38f964b95aa0 at term 2"} {"level":"info","ts":"2026-07-08T23:30:30.525918Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became leader at term 2"} {"level":"info","ts":"2026-07-08T23:30:30.525925Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 89ba38f964b95aa0 elected leader 89ba38f964b95aa0 at term 2"} {"level":"info","ts":"2026-07-08T23:30:30.594327Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"89ba38f964b95aa0","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.74:2379]}","request-path":"/0/members/89ba38f964b95aa0/attributes","cluster-id":"54492f71bf65c400","publish-timeout":"7s"} {"level":"info","ts":"2026-07-08T23:30:30.594367Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-08T23:30:30.594442Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-08T23:30:30.594769Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T23:30:30.595067Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-08T23:30:30.595177Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-08T23:30:30.597088Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-08T23:30:30.597125Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.74:2379"} {"level":"info","ts":"2026-07-08T23:30:30.59803Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"54492f71bf65c400","local-member-id":"89ba38f964b95aa0","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T23:30:30.598191Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T23:30:30.598265Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T23:30:39.122162Z","caller":"traceutil/trace.go:171","msg":"trace[893760332] linearizableReadLoop","detail":"{readStateIndex:312; appliedIndex:310; }","duration":"123.200931ms","start":"2026-07-08T23:30:38.998939Z","end":"2026-07-08T23:30:39.12214Z","steps":["trace[893760332] 'read index received' (duration: 29.03633ms)","trace[893760332] 'applied index is now lower than readState.Index' (duration: 94.163481ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:30:39.122281Z","caller":"traceutil/trace.go:171","msg":"trace[106537215] transaction","detail":"{read_only:false; response_revision:300; number_of_response:1; }","duration":"134.728795ms","start":"2026-07-08T23:30:38.987527Z","end":"2026-07-08T23:30:39.122255Z","steps":["trace[106537215] 'process raft request' (duration: 109.495842ms)","trace[106537215] 'compare' (duration: 25.046812ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T23:30:39.122513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.476722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4322"} {"level":"warn","ts":"2026-07-08T23:30:39.122463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.497522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7294"} {"level":"warn","ts":"2026-07-08T23:30:39.122546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.301231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6961"} {"level":"warn","ts":"2026-07-08T23:30:39.12253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.444112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5326"} {"level":"info","ts":"2026-07-08T23:30:39.122572Z","caller":"traceutil/trace.go:171","msg":"trace[1321850285] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:300; }","duration":"123.571312ms","start":"2026-07-08T23:30:38.998987Z","end":"2026-07-08T23:30:39.122558Z","steps":["trace[1321850285] 'agreement among raft nodes before linearized reading' (duration: 123.454561ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:30:39.122595Z","caller":"traceutil/trace.go:171","msg":"trace[1256799443] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:300; }","duration":"123.372011ms","start":"2026-07-08T23:30:38.999211Z","end":"2026-07-08T23:30:39.122583Z","steps":["trace[1256799443] 'agreement among raft nodes before linearized reading' (duration: 123.23295ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:30:39.122618Z","caller":"traceutil/trace.go:171","msg":"trace[458596849] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:300; }","duration":"123.562932ms","start":"2026-07-08T23:30:38.999034Z","end":"2026-07-08T23:30:39.122596Z","steps":["trace[458596849] 'agreement among raft nodes before linearized reading' (duration: 123.395271ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:30:39.122594Z","caller":"traceutil/trace.go:171","msg":"trace[631212538] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:300; }","duration":"123.692843ms","start":"2026-07-08T23:30:38.99888Z","end":"2026-07-08T23:30:39.122573Z","steps":["trace[631212538] 'agreement among raft nodes before linearized reading' (duration: 123.399141ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:30:39.122421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.308291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:4792"} {"level":"info","ts":"2026-07-08T23:30:39.122944Z","caller":"traceutil/trace.go:171","msg":"trace[574542495] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:300; }","duration":"123.965416ms","start":"2026-07-08T23:30:38.998966Z","end":"2026-07-08T23:30:39.122932Z","steps":["trace[574542495] 'agreement among raft nodes before linearized reading' (duration: 123.315821ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:30:39.599803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.69783ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-08T23:30:39.599926Z","caller":"traceutil/trace.go:171","msg":"trace[978570066] linearizableReadLoop","detail":"{readStateIndex:336; appliedIndex:335; }","duration":"123.21943ms","start":"2026-07-08T23:30:39.47669Z","end":"2026-07-08T23:30:39.599909Z","steps":["trace[978570066] 'read index received' (duration: 11.706096ms)","trace[978570066] 'applied index is now lower than readState.Index' (duration: 111.511534ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:30:39.599993Z","caller":"traceutil/trace.go:171","msg":"trace[525294153] transaction","detail":"{read_only:false; response_revision:324; number_of_response:1; }","duration":"165.831327ms","start":"2026-07-08T23:30:39.434124Z","end":"2026-07-08T23:30:39.599955Z","steps":["trace[525294153] 'process raft request' (duration: 54.342432ms)","trace[525294153] 'compare' (duration: 110.484529ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T23:30:39.600178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.431802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-07-08T23:30:39.60028Z","caller":"traceutil/trace.go:171","msg":"trace[567634545] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:324; }","duration":"123.613303ms","start":"2026-07-08T23:30:39.476648Z","end":"2026-07-08T23:30:39.600261Z","steps":["trace[567634545] 'agreement among raft nodes before linearized reading' (duration: 123.310471ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:30:45.526767Z","caller":"traceutil/trace.go:171","msg":"trace[745531314] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"144.473874ms","start":"2026-07-08T23:30:45.381923Z","end":"2026-07-08T23:30:45.526397Z","steps":["trace[745531314] 'process raft request' (duration: 144.241692ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:30:45.561212Z","caller":"traceutil/trace.go:171","msg":"trace[1688125558] linearizableReadLoop","detail":"{readStateIndex:384; appliedIndex:382; }","duration":"126.633111ms","start":"2026-07-08T23:30:45.434557Z","end":"2026-07-08T23:30:45.56119Z","steps":["trace[1688125558] 'read index received' (duration: 91.686789ms)","trace[1688125558] 'applied index is now lower than readState.Index' (duration: 34.945292ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:30:45.561336Z","caller":"traceutil/trace.go:171","msg":"trace[511525279] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"164.37949ms","start":"2026-07-08T23:30:45.396924Z","end":"2026-07-08T23:30:45.561303Z","steps":["trace[511525279] 'process raft request' (duration: 164.144269ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:30:45.561392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.813313ms","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-07-08T23:30:45.561448Z","caller":"traceutil/trace.go:171","msg":"trace[1417159585] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:370; }","duration":"126.926793ms","start":"2026-07-08T23:30:45.434503Z","end":"2026-07-08T23:30:45.56143Z","steps":["trace[1417159585] 'agreement among raft nodes before linearized reading' (duration: 126.815622ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:30:45.702746Z","caller":"traceutil/trace.go:171","msg":"trace[2034994354] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"137.440149ms","start":"2026-07-08T23:30:45.565284Z","end":"2026-07-08T23:30:45.702724Z","steps":["trace[2034994354] 'process raft request' (duration: 137.311459ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:30:53.879282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.24133ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T23:30:53.879412Z","caller":"traceutil/trace.go:171","msg":"trace[1767612601] linearizableReadLoop","detail":"{readStateIndex:402; appliedIndex:401; }","duration":"338.465837ms","start":"2026-07-08T23:30:53.54091Z","end":"2026-07-08T23:30:53.879376Z","steps":["trace[1767612601] 'read index received' (duration: 48.963824ms)","trace[1767612601] 'applied index is now lower than readState.Index' (duration: 289.500693ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:30:53.879527Z","caller":"traceutil/trace.go:171","msg":"trace[398127555] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"1.169230878s","start":"2026-07-08T23:30:52.710285Z","end":"2026-07-08T23:30:53.879516Z","steps":["trace[398127555] 'process raft request' (duration: 879.527176ms)","trace[398127555] 'compare' (duration: 288.885124ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T23:30:53.880092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.16332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-08T23:30:53.880097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:30:52.710262Z","time spent":"1.169790524s","remote":"127.0.0.1:53368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T23:30:53.880186Z","caller":"traceutil/trace.go:171","msg":"trace[100479515] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:387; }","duration":"339.317206ms","start":"2026-07-08T23:30:53.540848Z","end":"2026-07-08T23:30:53.880165Z","steps":["trace[100479515] 'agreement among raft nodes before linearized reading' (duration: 339.18508ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:30:53.880264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:30:53.540821Z","time spent":"339.407421ms","remote":"127.0.0.1:53114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-08T23:30:53.880376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.288469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4368"} {"level":"info","ts":"2026-07-08T23:30:53.880525Z","caller":"traceutil/trace.go:171","msg":"trace[1674768467] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:387; }","duration":"233.661567ms","start":"2026-07-08T23:30:53.64682Z","end":"2026-07-08T23:30:53.880481Z","steps":["trace[1674768467] 'agreement among raft nodes before linearized reading' (duration: 233.167043ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:30:53.880579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.872376ms","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-07-08T23:30:53.880889Z","caller":"traceutil/trace.go:171","msg":"trace[1518351320] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:387; }","duration":"133.233232ms","start":"2026-07-08T23:30:53.747635Z","end":"2026-07-08T23:30:53.880868Z","steps":["trace[1518351320] 'agreement among raft nodes before linearized reading' (duration: 132.552389ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:30:54.484833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.477424ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-07-08T23:30:54.484948Z","caller":"traceutil/trace.go:171","msg":"trace[2052603349] linearizableReadLoop","detail":"{readStateIndex:403; appliedIndex:402; }","duration":"603.5329ms","start":"2026-07-08T23:30:53.881398Z","end":"2026-07-08T23:30:54.484931Z","steps":["trace[2052603349] 'read index received' (duration: 197.973452ms)","trace[2052603349] 'applied index is now lower than readState.Index' (duration: 405.558078ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T23:30:54.485045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.634715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-08T23:30:54.485001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:30:53.881196Z","time spent":"603.791863ms","remote":"127.0.0.1:53134","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-08T23:30:54.485073Z","caller":"traceutil/trace.go:171","msg":"trace[698673171] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:387; }","duration":"603.695308ms","start":"2026-07-08T23:30:53.881368Z","end":"2026-07-08T23:30:54.485063Z","steps":["trace[698673171] 'agreement among raft nodes before linearized reading' (duration: 603.633196ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:30:54.485154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:30:53.881354Z","time spent":"603.791062ms","remote":"127.0.0.1:53102","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-07-08T23:30:54.53881Z","caller":"traceutil/trace.go:171","msg":"trace[2044153992] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"653.330284ms","start":"2026-07-08T23:30:53.885461Z","end":"2026-07-08T23:30:54.538791Z","steps":["trace[2044153992] 'process raft request' (duration: 653.159826ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:30:54.538936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:30:53.885441Z","time spent":"653.426218ms","remote":"127.0.0.1:53368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T23:30:54.539285Z","caller":"traceutil/trace.go:171","msg":"trace[272077908] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"652.523955ms","start":"2026-07-08T23:30:53.886749Z","end":"2026-07-08T23:30:54.539273Z","steps":["trace[272077908] 'process raft request' (duration: 651.999351ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:30:54.539358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:30:53.886733Z","time spent":"652.590388ms","remote":"127.0.0.1:53368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T23:30:54.539635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.660049ms","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-07-08T23:30:54.539721Z","caller":"traceutil/trace.go:171","msg":"trace[714455208] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:390; }","duration":"175.798506ms","start":"2026-07-08T23:30:54.363906Z","end":"2026-07-08T23:30:54.539704Z","steps":["trace[714455208] 'agreement among raft nodes before linearized reading' (duration: 175.650309ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:30:54.53964Z","caller":"traceutil/trace.go:171","msg":"trace[684331581] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"648.211284ms","start":"2026-07-08T23:30:53.891403Z","end":"2026-07-08T23:30:54.539615Z","steps":["trace[684331581] 'process raft request' (duration: 647.755783ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:30:54.539994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:30:53.891378Z","time spent":"648.515648ms","remote":"127.0.0.1:53264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4360,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T23:31:00.680615Z","caller":"traceutil/trace.go:171","msg":"trace[770705982] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"100.653423ms","start":"2026-07-08T23:31:00.579936Z","end":"2026-07-08T23:31:00.680589Z","steps":["trace[770705982] 'process raft request' (duration: 100.487006ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:31:10.017226Z","caller":"traceutil/trace.go:171","msg":"trace[2040715793] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"949.409091ms","start":"2026-07-08T23:31:09.067778Z","end":"2026-07-08T23:31:10.017187Z","steps":["trace[2040715793] 'process raft request' (duration: 949.207714ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:31:10.018282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:31:09.067744Z","time spent":"949.600869ms","remote":"127.0.0.1:53164","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-07-08T23:31:10.02844Z","caller":"traceutil/trace.go:171","msg":"trace[301753356] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"960.554838ms","start":"2026-07-08T23:31:09.067861Z","end":"2026-07-08T23:31:10.028415Z","steps":["trace[301753356] 'process raft request' (duration: 960.294058ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:31:10.02857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:31:09.067835Z","time spent":"960.670332ms","remote":"127.0.0.1:53164","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-07-08T23:31:10.028667Z","caller":"traceutil/trace.go:171","msg":"trace[1454433869] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"934.014681ms","start":"2026-07-08T23:31:09.094634Z","end":"2026-07-08T23:31:10.028649Z","steps":["trace[1454433869] 'process raft request' (duration: 933.840834ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:31:10.028744Z","caller":"traceutil/trace.go:171","msg":"trace[645918665] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"960.808258ms","start":"2026-07-08T23:31:09.067929Z","end":"2026-07-08T23:31:10.028738Z","steps":["trace[645918665] 'process raft request' (duration: 960.364131ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:31:10.028761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:31:09.094626Z","time spent":"934.088493ms","remote":"127.0.0.1:53164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-08T23:31:10.028788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:31:09.067922Z","time spent":"960.840789ms","remote":"127.0.0.1:53164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-08T23:31:10.028843Z","caller":"traceutil/trace.go:171","msg":"trace[1255748964] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"936.35314ms","start":"2026-07-08T23:31:09.092485Z","end":"2026-07-08T23:31:10.028838Z","steps":["trace[1255748964] 'process raft request' (duration: 935.897563ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:31:10.028866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:31:09.092459Z","time spent":"936.396422ms","remote":"127.0.0.1:53164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-08T23:31:10.028933Z","caller":"traceutil/trace.go:171","msg":"trace[150239946] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"911.094152ms","start":"2026-07-08T23:31:09.117832Z","end":"2026-07-08T23:31:10.028927Z","steps":["trace[150239946] 'process raft request' (duration: 910.819712ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:31:10.028962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:31:09.117821Z","time spent":"911.129363ms","remote":"127.0.0.1:53164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-08T23:31:10.02893Z","caller":"traceutil/trace.go:171","msg":"trace[2054733911] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"919.855201ms","start":"2026-07-08T23:31:09.109048Z","end":"2026-07-08T23:31:10.028903Z","steps":["trace[2054733911] 'process raft request' (duration: 919.54152ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:31:10.028973Z","caller":"traceutil/trace.go:171","msg":"trace[1187767147] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"908.612096ms","start":"2026-07-08T23:31:09.120354Z","end":"2026-07-08T23:31:10.028966Z","steps":["trace[1187767147] 'process raft request' (duration: 908.333505ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:31:10.028961Z","caller":"traceutil/trace.go:171","msg":"trace[121086329] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"931.665339ms","start":"2026-07-08T23:31:09.097255Z","end":"2026-07-08T23:31:10.02892Z","steps":["trace[121086329] 'process raft request' (duration: 931.276794ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:31:10.029009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:31:09.120347Z","time spent":"908.648048ms","remote":"127.0.0.1:53164","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-07-08T23:31:10.029096Z","caller":"traceutil/trace.go:171","msg":"trace[1124554741] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"877.681188ms","start":"2026-07-08T23:31:09.151365Z","end":"2026-07-08T23:31:10.029046Z","steps":["trace[1124554741] 'process raft request' (duration: 877.358466ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:31:10.029161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:31:09.09723Z","time spent":"931.783934ms","remote":"127.0.0.1:53164","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-07-08T23:31:10.029269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:31:09.151336Z","time spent":"877.852694ms","remote":"127.0.0.1:53164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-08T23:31:10.02948Z","caller":"traceutil/trace.go:171","msg":"trace[505332618] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"926.77792ms","start":"2026-07-08T23:31:09.102226Z","end":"2026-07-08T23:31:10.029004Z","steps":["trace[505332618] 'process raft request' (duration: 926.331003ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:31:10.029693Z","caller":"traceutil/trace.go:171","msg":"trace[372069970] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"802.345751ms","start":"2026-07-08T23:31:09.22733Z","end":"2026-07-08T23:31:10.029676Z","steps":["trace[372069970] 'process raft request' (duration: 802.080971ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:31:10.0297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:31:09.1022Z","time spent":"927.30861ms","remote":"127.0.0.1:53164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-08T23:31:10.029782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:31:09.227301Z","time spent":"802.430854ms","remote":"127.0.0.1:53164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-08T23:31:10.030631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:31:09.109025Z","time spent":"921.569698ms","remote":"127.0.0.1:53164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-08T23:31:10.043997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.112896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T23:31:10.04413Z","caller":"traceutil/trace.go:171","msg":"trace[1972650134] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:502; }","duration":"503.299553ms","start":"2026-07-08T23:31:09.540806Z","end":"2026-07-08T23:31:10.044106Z","steps":["trace[1972650134] 'agreement among raft nodes before linearized reading' (duration: 503.068024ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:31:10.044184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:31:09.540783Z","time spent":"503.391157ms","remote":"127.0.0.1:53102","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-07-08T23:31:10.043867Z","caller":"traceutil/trace.go:171","msg":"trace[1235899583] linearizableReadLoop","detail":"{readStateIndex:521; appliedIndex:508; }","duration":"502.933199ms","start":"2026-07-08T23:31:09.540875Z","end":"2026-07-08T23:31:10.043809Z","steps":["trace[1235899583] 'read index received' (duration: 476.188226ms)","trace[1235899583] 'applied index is now lower than readState.Index' (duration: 26.739263ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:31:11.00308Z","caller":"traceutil/trace.go:171","msg":"trace[755178810] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"259.506425ms","start":"2026-07-08T23:31:10.74353Z","end":"2026-07-08T23:31:11.003036Z","steps":["trace[755178810] 'process raft request' (duration: 257.608202ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:31:11.013783Z","caller":"traceutil/trace.go:171","msg":"trace[830144660] linearizableReadLoop","detail":"{readStateIndex:578; appliedIndex:576; }","duration":"200.960919ms","start":"2026-07-08T23:31:10.812792Z","end":"2026-07-08T23:31:11.013753Z","steps":["trace[830144660] 'read index received' (duration: 187.598292ms)","trace[830144660] 'applied index is now lower than readState.Index' (duration: 13.361697ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:31:11.014072Z","caller":"traceutil/trace.go:171","msg":"trace[2030213756] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"224.658827ms","start":"2026-07-08T23:31:10.7894Z","end":"2026-07-08T23:31:11.014059Z","steps":["trace[2030213756] 'process raft request' (duration: 224.125146ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:31:11.014394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.582053ms","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-07-08T23:31:11.014567Z","caller":"traceutil/trace.go:171","msg":"trace[1128978392] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:555; }","duration":"201.761009ms","start":"2026-07-08T23:31:10.812757Z","end":"2026-07-08T23:31:11.014518Z","steps":["trace[1128978392] 'agreement among raft nodes before linearized reading' (duration: 201.545881ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:31:11.014881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.146679ms","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-07-08T23:31:11.015051Z","caller":"traceutil/trace.go:171","msg":"trace[1860618246] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:555; }","duration":"199.334596ms","start":"2026-07-08T23:31:10.815705Z","end":"2026-07-08T23:31:11.015039Z","steps":["trace[1860618246] 'agreement among raft nodes before linearized reading' (duration: 199.138898ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:31:11.015378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.344185ms","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-07-08T23:31:11.015442Z","caller":"traceutil/trace.go:171","msg":"trace[563280314] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:555; }","duration":"194.433158ms","start":"2026-07-08T23:31:10.820992Z","end":"2026-07-08T23:31:11.015425Z","steps":["trace[563280314] 'agreement among raft nodes before linearized reading' (duration: 194.311574ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:31:11.016577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.358105ms","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-07-08T23:31:11.0166Z","caller":"traceutil/trace.go:171","msg":"trace[362705043] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:555; }","duration":"194.400457ms","start":"2026-07-08T23:31:10.822194Z","end":"2026-07-08T23:31:11.016594Z","steps":["trace[362705043] 'agreement among raft nodes before linearized reading' (duration: 193.800304ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:31:17.042477Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530394574388621813,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-08T23:31:17.186377Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.060733277s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-08T23:31:17.195857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"805.492623ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T23:31:17.196009Z","caller":"traceutil/trace.go:171","msg":"trace[1424769078] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"1.070460931s","start":"2026-07-08T23:31:16.125514Z","end":"2026-07-08T23:31:17.195975Z","steps":["trace[1424769078] 'process raft request' (duration: 1.061215144s)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:31:17.196035Z","caller":"traceutil/trace.go:171","msg":"trace[754252550] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:592; }","duration":"805.677619ms","start":"2026-07-08T23:31:16.390339Z","end":"2026-07-08T23:31:17.196016Z","steps":["trace[754252550] 'range keys from in-memory index tree' (duration: 805.475552ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:31:17.196151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:31:16.125479Z","time spent":"1.070599735s","remote":"127.0.0.1:53368","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-07-08T23:31:17.197176Z","caller":"traceutil/trace.go:171","msg":"trace[201570893] linearizableReadLoop","detail":"{readStateIndex:618; appliedIndex:616; }","duration":"655.397494ms","start":"2026-07-08T23:31:16.541751Z","end":"2026-07-08T23:31:17.197149Z","steps":["trace[201570893] 'read index received' (duration: 644.891352ms)","trace[201570893] 'applied index is now lower than readState.Index' (duration: 10.505362ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T23:31:17.197339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.56693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-08T23:31:17.197428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.586783ms","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-07-08T23:31:17.197438Z","caller":"traceutil/trace.go:171","msg":"trace[1541410258] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:594; }","duration":"655.705745ms","start":"2026-07-08T23:31:16.541706Z","end":"2026-07-08T23:31:17.197412Z","steps":["trace[1541410258] 'agreement among raft nodes before linearized reading' (duration: 655.55314ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:31:17.197475Z","caller":"traceutil/trace.go:171","msg":"trace[1989052406] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:594; }","duration":"101.665576ms","start":"2026-07-08T23:31:17.095795Z","end":"2026-07-08T23:31:17.197461Z","steps":["trace[1989052406] 'agreement among raft nodes before linearized reading' (duration: 101.533711ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:31:17.197486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:31:16.541684Z","time spent":"655.793948ms","remote":"127.0.0.1:53114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-08T23:31:17.197642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.336355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-07-08T23:31:17.197662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.79488ms","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-07-08T23:31:17.197683Z","caller":"traceutil/trace.go:171","msg":"trace[1059348639] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:594; }","duration":"103.421918ms","start":"2026-07-08T23:31:17.09425Z","end":"2026-07-08T23:31:17.197672Z","steps":["trace[1059348639] 'agreement among raft nodes before linearized reading' (duration: 103.019474ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:31:17.197773Z","caller":"traceutil/trace.go:171","msg":"trace[1999922417] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:594; }","duration":"101.860083ms","start":"2026-07-08T23:31:17.095829Z","end":"2026-07-08T23:31:17.197689Z","steps":["trace[1999922417] 'agreement among raft nodes before linearized reading' (duration: 101.48635ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:31:17.197812Z","caller":"traceutil/trace.go:171","msg":"trace[78803004] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"1.038371593s","start":"2026-07-08T23:31:16.159424Z","end":"2026-07-08T23:31:17.197795Z","steps":["trace[78803004] 'process raft request' (duration: 1.03715711s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:31:17.198054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:31:16.159395Z","time spent":"1.03858183s","remote":"127.0.0.1:53206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T23:31:17.817089Z","caller":"traceutil/trace.go:171","msg":"trace[1867832346] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"301.93923ms","start":"2026-07-08T23:31:17.515119Z","end":"2026-07-08T23:31:17.817058Z","steps":["trace[1867832346] 'process raft request' (duration: 301.748923ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:31:17.817261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:31:17.515074Z","time spent":"302.099535ms","remote":"127.0.0.1:53270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T23:31:18.752219Z","caller":"traceutil/trace.go:171","msg":"trace[1421598697] linearizableReadLoop","detail":"{readStateIndex:628; appliedIndex:627; }","duration":"210.68447ms","start":"2026-07-08T23:31:18.541476Z","end":"2026-07-08T23:31:18.75216Z","steps":["trace[1421598697] 'read index received' (duration: 205.135825ms)","trace[1421598697] 'applied index is now lower than readState.Index' (duration: 5.547615ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:31:18.75235Z","caller":"traceutil/trace.go:171","msg":"trace[1945553223] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"1.090528101s","start":"2026-07-08T23:31:17.661778Z","end":"2026-07-08T23:31:18.752306Z","steps":["trace[1945553223] 'process raft request' (duration: 1.085103141s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:31:18.752525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.018882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-08T23:31:18.752627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:31:17.661744Z","time spent":"1.090726837s","remote":"127.0.0.1:53206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T23:31:18.752662Z","caller":"traceutil/trace.go:171","msg":"trace[488070265] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:604; }","duration":"211.174587ms","start":"2026-07-08T23:31:18.541462Z","end":"2026-07-08T23:31:18.752636Z","steps":["trace[488070265] 'agreement among raft nodes before linearized reading' (duration: 210.933329ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:31:18.917614Z","caller":"traceutil/trace.go:171","msg":"trace[1695161072] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"110.237306ms","start":"2026-07-08T23:31:18.807347Z","end":"2026-07-08T23:31:18.917585Z","steps":["trace[1695161072] 'process raft request' (duration: 110.07108ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:31:21.714637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.713616154s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-08T23:31:21.714943Z","caller":"traceutil/trace.go:171","msg":"trace[953988881] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"2.781883102s","start":"2026-07-08T23:31:18.933032Z","end":"2026-07-08T23:31:21.714915Z","steps":["trace[953988881] 'process raft request' (duration: 67.925076ms)","trace[953988881] 'compare' (duration: 2.713290983s)"],"step_count":2} {"level":"warn","ts":"2026-07-08T23:31:21.715058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:31:18.93301Z","time spent":"2.781997126s","remote":"127.0.0.1:53270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-08T23:31:21.726533Z","caller":"traceutil/trace.go:171","msg":"trace[1637920675] linearizableReadLoop","detail":"{readStateIndex:631; appliedIndex:629; }","duration":"2.519664725s","start":"2026-07-08T23:31:19.206832Z","end":"2026-07-08T23:31:21.726496Z","steps":["trace[1637920675] 'read index received' (duration: 58.562µs)","trace[1637920675] 'applied index is now lower than readState.Index' (duration: 2.519600852s)"],"step_count":2} {"level":"warn","ts":"2026-07-08T23:31:21.726723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.519891273s","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-07-08T23:31:21.726771Z","caller":"traceutil/trace.go:171","msg":"trace[691624108] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:607; }","duration":"2.519983235s","start":"2026-07-08T23:31:19.206777Z","end":"2026-07-08T23:31:21.72676Z","steps":["trace[691624108] 'agreement among raft nodes before linearized reading' (duration: 2.519861711s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:31:21.726795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:31:19.206751Z","time spent":"2.520038297s","remote":"127.0.0.1:53368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-08T23:31:21.742744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.532513257s","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-07-08T23:31:21.742825Z","caller":"traceutil/trace.go:171","msg":"trace[961680003] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:609; }","duration":"2.532602269s","start":"2026-07-08T23:31:19.210194Z","end":"2026-07-08T23:31:21.742797Z","steps":["trace[961680003] 'agreement among raft nodes before linearized reading' (duration: 2.532446744s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:31:21.742859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:31:19.210186Z","time spent":"2.532664931s","remote":"127.0.0.1:53368","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-07-08T23:31:21.742892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.201579677s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T23:31:21.742943Z","caller":"traceutil/trace.go:171","msg":"trace[1600799292] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:609; }","duration":"1.201669169s","start":"2026-07-08T23:31:20.541258Z","end":"2026-07-08T23:31:21.742927Z","steps":["trace[1600799292] 'agreement among raft nodes before linearized reading' (duration: 1.201596877s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:31:21.742981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:31:20.541234Z","time spent":"1.201737512s","remote":"127.0.0.1:53114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-08T23:31:21.7432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.533812281s","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-07-08T23:31:21.743237Z","caller":"traceutil/trace.go:171","msg":"trace[499200189] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:609; }","duration":"2.533895594s","start":"2026-07-08T23:31:19.209331Z","end":"2026-07-08T23:31:21.743227Z","steps":["trace[499200189] 'agreement among raft nodes before linearized reading' (duration: 2.53378646s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:31:21.743265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:31:19.20931Z","time spent":"2.533948837s","remote":"127.0.0.1:53368","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-07-08T23:31:21.742679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.534620778s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-07-08T23:31:21.743558Z","caller":"traceutil/trace.go:171","msg":"trace[2094963093] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:609; }","duration":"2.53551669s","start":"2026-07-08T23:31:19.208017Z","end":"2026-07-08T23:31:21.743534Z","steps":["trace[2094963093] 'agreement among raft nodes before linearized reading' (duration: 2.534547116s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:31:21.743635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:31:19.208008Z","time spent":"2.535609673s","remote":"127.0.0.1:53368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-08T23:31:24.008416Z","caller":"traceutil/trace.go:171","msg":"trace[778341670] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"254.220846ms","start":"2026-07-08T23:31:23.754143Z","end":"2026-07-08T23:31:24.008364Z","steps":["trace[778341670] 'process raft request' (duration: 253.968728ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:31:24.009851Z","caller":"traceutil/trace.go:171","msg":"trace[1327615384] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:647; }","duration":"252.558522ms","start":"2026-07-08T23:31:23.757272Z","end":"2026-07-08T23:31:24.00983Z","steps":["trace[1327615384] 'read index received' (duration: 250.853255ms)","trace[1327615384] 'applied index is now lower than readState.Index' (duration: 1.704717ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:31:24.009946Z","caller":"traceutil/trace.go:171","msg":"trace[1817252423] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"255.171387ms","start":"2026-07-08T23:31:23.754768Z","end":"2026-07-08T23:31:24.009939Z","steps":["trace[1817252423] 'process raft request' (duration: 254.95982ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:31:24.01013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.812491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.74\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-07-08T23:31:24.010154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.866361ms","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-07-08T23:31:24.010184Z","caller":"traceutil/trace.go:171","msg":"trace[700010234] range","detail":"{range_begin:/registry/masterleases/199.204.45.74; range_end:; response_count:1; response_revision:624; }","duration":"211.916505ms","start":"2026-07-08T23:31:23.798255Z","end":"2026-07-08T23:31:24.010171Z","steps":["trace[700010234] 'agreement among raft nodes before linearized reading' (duration: 211.76036ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:31:24.010205Z","caller":"traceutil/trace.go:171","msg":"trace[1739184260] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:624; }","duration":"252.971135ms","start":"2026-07-08T23:31:23.75722Z","end":"2026-07-08T23:31:24.010191Z","steps":["trace[1739184260] 'agreement among raft nodes before linearized reading' (duration: 252.787459ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:31:24.010281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.87073ms","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-07-08T23:31:24.010372Z","caller":"traceutil/trace.go:171","msg":"trace[1067795899] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:624; }","duration":"253.006925ms","start":"2026-07-08T23:31:23.757342Z","end":"2026-07-08T23:31:24.010349Z","steps":["trace[1067795899] 'agreement among raft nodes before linearized reading' (duration: 252.656284ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:31:25.149087Z","caller":"traceutil/trace.go:171","msg":"trace[2057226309] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"383.639777ms","start":"2026-07-08T23:31:24.76542Z","end":"2026-07-08T23:31:25.14906Z","steps":["trace[2057226309] 'process raft request' (duration: 383.478882ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:31:25.149262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:31:24.765394Z","time spent":"383.772591ms","remote":"127.0.0.1:53368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T23:31:25.152627Z","caller":"traceutil/trace.go:171","msg":"trace[1943462029] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"265.373051ms","start":"2026-07-08T23:31:24.887226Z","end":"2026-07-08T23:31:25.152599Z","steps":["trace[1943462029] 'process raft request' (duration: 265.188594ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:31:38.591155Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530394574388622153,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-08T23:31:38.665734Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.040446055s","expected-duration":"1s"} {"level":"info","ts":"2026-07-08T23:31:38.667767Z","caller":"traceutil/trace.go:171","msg":"trace[47489834] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"1.042555994s","start":"2026-07-08T23:31:37.62518Z","end":"2026-07-08T23:31:38.667736Z","steps":["trace[47489834] 'process raft request' (duration: 1.040822874s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:31:38.66797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:31:37.625158Z","time spent":"1.042705138s","remote":"127.0.0.1:53270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4024,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T23:31:38.741804Z","caller":"traceutil/trace.go:171","msg":"trace[79497220] linearizableReadLoop","detail":"{readStateIndex:799; appliedIndex:797; }","duration":"651.59587ms","start":"2026-07-08T23:31:38.090186Z","end":"2026-07-08T23:31:38.741782Z","steps":["trace[79497220] 'read index received' (duration: 575.740361ms)","trace[79497220] 'applied index is now lower than readState.Index' (duration: 75.854439ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:31:38.741918Z","caller":"traceutil/trace.go:171","msg":"trace[277096918] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"1.01871455s","start":"2026-07-08T23:31:37.723194Z","end":"2026-07-08T23:31:38.741908Z","steps":["trace[277096918] 'process raft request' (duration: 1.018455053s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:31:38.742279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.391226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-07-08T23:31:38.742442Z","caller":"traceutil/trace.go:171","msg":"trace[1004594526] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:769; }","duration":"651.620662ms","start":"2026-07-08T23:31:38.090802Z","end":"2026-07-08T23:31:38.742422Z","steps":["trace[1004594526] 'agreement among raft nodes before linearized reading' (duration: 651.213671ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:31:38.742508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.882708ms","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-07-08T23:31:38.742559Z","caller":"traceutil/trace.go:171","msg":"trace[943253893] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:769; }","duration":"646.954031ms","start":"2026-07-08T23:31:38.095592Z","end":"2026-07-08T23:31:38.742546Z","steps":["trace[943253893] 'agreement among raft nodes before linearized reading' (duration: 646.817567ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:31:38.74261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:31:38.095586Z","time spent":"647.016202ms","remote":"127.0.0.1:53368","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-07-08T23:31:38.74258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:31:38.090782Z","time spent":"651.754896ms","remote":"127.0.0.1:53368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-08T23:31:38.742756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.157667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T23:31:38.742792Z","caller":"traceutil/trace.go:171","msg":"trace[1246096001] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:769; }","duration":"202.227389ms","start":"2026-07-08T23:31:38.540555Z","end":"2026-07-08T23:31:38.742783Z","steps":["trace[1246096001] 'agreement among raft nodes before linearized reading' (duration: 202.180878ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:31:38.74286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.105146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T23:31:38.742975Z","caller":"traceutil/trace.go:171","msg":"trace[1464487185] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:769; }","duration":"448.23214ms","start":"2026-07-08T23:31:38.294701Z","end":"2026-07-08T23:31:38.742933Z","steps":["trace[1464487185] 'agreement among raft nodes before linearized reading' (duration: 448.045854ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:31:38.742336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:31:37.723177Z","time spent":"1.018926797s","remote":"127.0.0.1:53368","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-07-08T23:31:38.742481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.406782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-07-08T23:31:38.743051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:31:38.294688Z","time spent":"448.328322ms","remote":"127.0.0.1:53408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"info","ts":"2026-07-08T23:31:38.743068Z","caller":"traceutil/trace.go:171","msg":"trace[243694962] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:769; }","duration":"343.026899ms","start":"2026-07-08T23:31:38.400033Z","end":"2026-07-08T23:31:38.74306Z","steps":["trace[243694962] 'agreement among raft nodes before linearized reading' (duration: 342.35173ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:31:38.743096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:31:38.400015Z","time spent":"343.072861ms","remote":"127.0.0.1:53368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-08T23:31:38.742336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.135197ms","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-07-08T23:31:38.743146Z","caller":"traceutil/trace.go:171","msg":"trace[4715229] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:769; }","duration":"652.97502ms","start":"2026-07-08T23:31:38.090156Z","end":"2026-07-08T23:31:38.743131Z","steps":["trace[4715229] 'agreement among raft nodes before linearized reading' (duration: 652.101876ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:31:38.743201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:31:38.090142Z","time spent":"653.046633ms","remote":"127.0.0.1:53368","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-07-08T23:31:38.743168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.317642ms","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-07-08T23:31:38.743794Z","caller":"traceutil/trace.go:171","msg":"trace[827293818] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:769; }","duration":"652.833726ms","start":"2026-07-08T23:31:38.0908Z","end":"2026-07-08T23:31:38.743634Z","steps":["trace[827293818] 'agreement among raft nodes before linearized reading' (duration: 652.066295ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:31:38.745198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:31:38.090784Z","time spent":"654.394161ms","remote":"127.0.0.1:53368","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-07-08T23:31:38.923224Z","caller":"traceutil/trace.go:171","msg":"trace[1337953973] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"175.973676ms","start":"2026-07-08T23:31:38.747222Z","end":"2026-07-08T23:31:38.923196Z","steps":["trace[1337953973] 'process raft request' (duration: 175.807212ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:31:38.924698Z","caller":"traceutil/trace.go:171","msg":"trace[2035795526] linearizableReadLoop","detail":"{readStateIndex:804; appliedIndex:799; }","duration":"161.002902ms","start":"2026-07-08T23:31:38.763672Z","end":"2026-07-08T23:31:38.924675Z","steps":["trace[2035795526] 'read index received' (duration: 159.396536ms)","trace[2035795526] 'applied index is now lower than readState.Index' (duration: 1.605616ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:31:38.92494Z","caller":"traceutil/trace.go:171","msg":"trace[1802673224] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"175.052671ms","start":"2026-07-08T23:31:38.749838Z","end":"2026-07-08T23:31:38.924891Z","steps":["trace[1802673224] 'process raft request' (duration: 174.806024ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:31:38.925122Z","caller":"traceutil/trace.go:171","msg":"trace[2088905644] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"175.595825ms","start":"2026-07-08T23:31:38.749483Z","end":"2026-07-08T23:31:38.925079Z","steps":["trace[2088905644] 'process raft request' (duration: 175.128802ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:31:38.925117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.410833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T23:31:38.925191Z","caller":"traceutil/trace.go:171","msg":"trace[883655312] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:774; }","duration":"161.522177ms","start":"2026-07-08T23:31:38.763655Z","end":"2026-07-08T23:31:38.925177Z","steps":["trace[883655312] 'agreement among raft nodes before linearized reading' (duration: 161.264009ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:31:38.925152Z","caller":"traceutil/trace.go:171","msg":"trace[1981250352] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"177.242131ms","start":"2026-07-08T23:31:38.74785Z","end":"2026-07-08T23:31:38.925092Z","steps":["trace[1981250352] 'process raft request' (duration: 176.630284ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:31:38.926624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.581837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T23:31:38.926684Z","caller":"traceutil/trace.go:171","msg":"trace[968654167] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:774; }","duration":"162.666918ms","start":"2026-07-08T23:31:38.764Z","end":"2026-07-08T23:31:38.926667Z","steps":["trace[968654167] 'agreement among raft nodes before linearized reading' (duration: 162.565206ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:31:38.926729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.325842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-08T23:31:38.92674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.863282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-08T23:31:38.926859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.614886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T23:31:38.926866Z","caller":"traceutil/trace.go:171","msg":"trace[146883206] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:774; }","duration":"122.080988ms","start":"2026-07-08T23:31:38.804764Z","end":"2026-07-08T23:31:38.926845Z","steps":["trace[146883206] 'agreement among raft nodes before linearized reading' (duration: 121.843642ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:31:38.926936Z","caller":"traceutil/trace.go:171","msg":"trace[692656000] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:774; }","duration":"163.102681ms","start":"2026-07-08T23:31:38.763666Z","end":"2026-07-08T23:31:38.926769Z","steps":["trace[692656000] 'agreement among raft nodes before linearized reading' (duration: 161.291571ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:31:38.926981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.324567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T23:31:38.927004Z","caller":"traceutil/trace.go:171","msg":"trace[318801640] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:774; }","duration":"145.369919ms","start":"2026-07-08T23:31:38.781627Z","end":"2026-07-08T23:31:38.926997Z","steps":["trace[318801640] 'agreement among raft nodes before linearized reading' (duration: 145.334748ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:31:38.927156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.150381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T23:31:38.927241Z","caller":"traceutil/trace.go:171","msg":"trace[663129006] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:774; }","duration":"122.313736ms","start":"2026-07-08T23:31:38.804908Z","end":"2026-07-08T23:31:38.927222Z","steps":["trace[663129006] 'agreement among raft nodes before linearized reading' (duration: 122.175322ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:31:38.926905Z","caller":"traceutil/trace.go:171","msg":"trace[1198079931] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:774; }","duration":"144.67763ms","start":"2026-07-08T23:31:38.782216Z","end":"2026-07-08T23:31:38.926894Z","steps":["trace[1198079931] 'agreement among raft nodes before linearized reading' (duration: 144.608807ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:31:38.926946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.04375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T23:31:38.927513Z","caller":"traceutil/trace.go:171","msg":"trace[737101510] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:774; }","duration":"145.628066ms","start":"2026-07-08T23:31:38.781876Z","end":"2026-07-08T23:31:38.927504Z","steps":["trace[737101510] 'agreement among raft nodes before linearized reading' (duration: 145.04536ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:31:38.927436Z","caller":"traceutil/trace.go:171","msg":"trace[1481488881] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"178.406426ms","start":"2026-07-08T23:31:38.749017Z","end":"2026-07-08T23:31:38.927423Z","steps":["trace[1481488881] 'process raft request' (duration: 175.556645ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:31:38.92742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.972057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T23:31:38.927625Z","caller":"traceutil/trace.go:171","msg":"trace[1659295072] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:774; }","duration":"122.246674ms","start":"2026-07-08T23:31:38.80537Z","end":"2026-07-08T23:31:38.927617Z","steps":["trace[1659295072] 'agreement among raft nodes before linearized reading' (duration: 121.937075ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:32:01.270289Z","caller":"traceutil/trace.go:171","msg":"trace[33421888] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"129.587674ms","start":"2026-07-08T23:32:01.140676Z","end":"2026-07-08T23:32:01.270264Z","steps":["trace[33421888] 'process raft request' (duration: 129.450801ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:32:01.518104Z","caller":"traceutil/trace.go:171","msg":"trace[1649569559] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"150.416022ms","start":"2026-07-08T23:32:01.367661Z","end":"2026-07-08T23:32:01.518077Z","steps":["trace[1649569559] 'process raft request' (duration: 101.551063ms)","trace[1649569559] 'compare' (duration: 48.733676ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:32:09.594534Z","caller":"traceutil/trace.go:171","msg":"trace[1164881813] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"182.129546ms","start":"2026-07-08T23:32:09.412379Z","end":"2026-07-08T23:32:09.594508Z","steps":["trace[1164881813] 'process raft request' (duration: 181.302869ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:32:09.594748Z","caller":"traceutil/trace.go:171","msg":"trace[1444333740] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"182.134147ms","start":"2026-07-08T23:32:09.412579Z","end":"2026-07-08T23:32:09.594713Z","steps":["trace[1444333740] 'process raft request' (duration: 181.781189ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:33:15.72504Z","caller":"traceutil/trace.go:171","msg":"trace[1181171393] linearizableReadLoop","detail":"{readStateIndex:1288; appliedIndex:1287; }","duration":"102.223118ms","start":"2026-07-08T23:33:15.622795Z","end":"2026-07-08T23:33:15.725018Z","steps":["trace[1181171393] 'read index received' (duration: 102.025955ms)","trace[1181171393] 'applied index is now lower than readState.Index' (duration: 196.123µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:33:15.725094Z","caller":"traceutil/trace.go:171","msg":"trace[1232998749] transaction","detail":"{read_only:false; response_revision:1236; number_of_response:1; }","duration":"147.1732ms","start":"2026-07-08T23:33:15.577891Z","end":"2026-07-08T23:33:15.725064Z","steps":["trace[1232998749] 'process raft request' (duration: 146.996587ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:33:15.725369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.558995ms","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-07-08T23:33:15.725472Z","caller":"traceutil/trace.go:171","msg":"trace[1277048021] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1236; }","duration":"102.687116ms","start":"2026-07-08T23:33:15.622748Z","end":"2026-07-08T23:33:15.725435Z","steps":["trace[1277048021] 'agreement among raft nodes before linearized reading' (duration: 102.419331ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:33:21.964714Z","caller":"traceutil/trace.go:171","msg":"trace[1539875141] linearizableReadLoop","detail":"{readStateIndex:1327; appliedIndex:1326; }","duration":"128.248856ms","start":"2026-07-08T23:33:21.836448Z","end":"2026-07-08T23:33:21.964697Z","steps":["trace[1539875141] 'read index received' (duration: 128.104094ms)","trace[1539875141] 'applied index is now lower than readState.Index' (duration: 144.162µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:33:21.964921Z","caller":"traceutil/trace.go:171","msg":"trace[1563715654] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"184.118354ms","start":"2026-07-08T23:33:21.780794Z","end":"2026-07-08T23:33:21.964913Z","steps":["trace[1563715654] 'process raft request' (duration: 183.801229ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:33:21.965114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.652631ms","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-07-08T23:33:21.96514Z","caller":"traceutil/trace.go:171","msg":"trace[584218219] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1273; }","duration":"128.746133ms","start":"2026-07-08T23:33:21.836385Z","end":"2026-07-08T23:33:21.965131Z","steps":["trace[584218219] 'agreement among raft nodes before linearized reading' (duration: 128.644851ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:33:30.472401Z","caller":"traceutil/trace.go:171","msg":"trace[1673543467] linearizableReadLoop","detail":"{readStateIndex:1361; appliedIndex:1360; }","duration":"431.723377ms","start":"2026-07-08T23:33:30.040663Z","end":"2026-07-08T23:33:30.472386Z","steps":["trace[1673543467] 'read index received' (duration: 431.653346ms)","trace[1673543467] 'applied index is now lower than readState.Index' (duration: 69.171µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:33:30.472482Z","caller":"traceutil/trace.go:171","msg":"trace[460058042] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"466.905001ms","start":"2026-07-08T23:33:30.00557Z","end":"2026-07-08T23:33:30.472475Z","steps":["trace[460058042] 'process raft request' (duration: 466.633958ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:33:30.472563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:33:30.005545Z","time spent":"466.952772ms","remote":"127.0.0.1:53368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T23:33:30.472752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.052082ms","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-07-08T23:33:30.472855Z","caller":"traceutil/trace.go:171","msg":"trace[1253045669] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1305; }","duration":"432.207404ms","start":"2026-07-08T23:33:30.040629Z","end":"2026-07-08T23:33:30.472836Z","steps":["trace[1253045669] 'agreement among raft nodes before linearized reading' (duration: 431.89174ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:33:30.472905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:33:30.040617Z","time spent":"432.279025ms","remote":"127.0.0.1:53368","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-07-08T23:33:30.552286Z","caller":"traceutil/trace.go:171","msg":"trace[703141478] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"279.213735ms","start":"2026-07-08T23:33:30.273045Z","end":"2026-07-08T23:33:30.552259Z","steps":["trace[703141478] 'process raft request' (duration: 248.936679ms)","trace[703141478] 'compare' (duration: 30.082184ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T23:33:30.552679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.330395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-07-08T23:33:30.552752Z","caller":"traceutil/trace.go:171","msg":"trace[844800302] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1307; }","duration":"510.431056ms","start":"2026-07-08T23:33:30.042304Z","end":"2026-07-08T23:33:30.552735Z","steps":["trace[844800302] 'agreement among raft nodes before linearized reading' (duration: 509.980541ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:33:30.552799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:33:30.042297Z","time spent":"510.492098ms","remote":"127.0.0.1:53368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-08T23:33:30.552863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.692054ms","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-07-08T23:33:30.552903Z","caller":"traceutil/trace.go:171","msg":"trace[2140064977] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1307; }","duration":"511.771314ms","start":"2026-07-08T23:33:30.04112Z","end":"2026-07-08T23:33:30.552891Z","steps":["trace[2140064977] 'agreement among raft nodes before linearized reading' (duration: 511.178406ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:33:30.552933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:33:30.0411Z","time spent":"511.825556ms","remote":"127.0.0.1:53368","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-07-08T23:33:30.5527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.92773ms","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-07-08T23:33:30.55304Z","caller":"traceutil/trace.go:171","msg":"trace[1980888968] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1307; }","duration":"510.293286ms","start":"2026-07-08T23:33:30.042733Z","end":"2026-07-08T23:33:30.553026Z","steps":["trace[1980888968] 'agreement among raft nodes before linearized reading' (duration: 509.544865ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:33:30.553081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:33:30.042723Z","time spent":"510.346877ms","remote":"127.0.0.1:53368","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\" "}