{"level":"warn","ts":"2026-07-03T18:59:19.282842Z","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-03T18:59:19.282931Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.144: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.144:2380","--initial-cluster=instance=https://199.204.45.144:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.144:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.144: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-03T18:59:19.28301Z","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-03T18:59:19.283022Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.144:2380"]} {"level":"info","ts":"2026-07-03T18:59:19.283041Z","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-03T18:59:19.283774Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.144:2379"]} {"level":"info","ts":"2026-07-03T18:59:19.28385Z","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.144:2380"],"listen-peer-urls":["https://199.204.45.144:2380"],"advertise-client-urls":["https://199.204.45.144:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.144:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.144: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-03T18:59:19.30597Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"21.882098ms"} {"level":"info","ts":"2026-07-03T18:59:19.327411Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4594e5f716bad646","cluster-id":"ff2e51b364668606"} {"level":"info","ts":"2026-07-03T18:59:19.327571Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 switched to configuration voters=()"} {"level":"info","ts":"2026-07-03T18:59:19.327594Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became follower at term 0"} {"level":"info","ts":"2026-07-03T18:59:19.327604Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4594e5f716bad646 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-03T18:59:19.327618Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became follower at term 1"} {"level":"info","ts":"2026-07-03T18:59:19.327659Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 switched to configuration voters=(5013885134571230790)"} {"level":"warn","ts":"2026-07-03T18:59:19.387879Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-03T18:59:19.396421Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-03T18:59:19.403021Z","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-03T18:59:19.409362Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4594e5f716bad646","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-03T18:59:19.412614Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4594e5f716bad646","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-03T18:59:19.413742Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 switched to configuration voters=(5013885134571230790)"} {"level":"info","ts":"2026-07-03T18:59:19.411681Z","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-03T18:59:19.414239Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ff2e51b364668606","local-member-id":"4594e5f716bad646","added-peer-id":"4594e5f716bad646","added-peer-peer-urls":["https://199.204.45.144:2380"]} {"level":"info","ts":"2026-07-03T18:59:19.414341Z","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-03T18:59:19.414884Z","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-03T18:59:19.41718Z","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-03T18:59:19.417323Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.144:2380"} {"level":"info","ts":"2026-07-03T18:59:19.417366Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.144:2380"} {"level":"info","ts":"2026-07-03T18:59:19.417509Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4594e5f716bad646","initial-advertise-peer-urls":["https://199.204.45.144:2380"],"listen-peer-urls":["https://199.204.45.144:2380"],"advertise-client-urls":["https://199.204.45.144:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.144:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-03T18:59:19.417559Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-03T18:59:19.532552Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 is starting a new election at term 1"} {"level":"info","ts":"2026-07-03T18:59:19.53266Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-03T18:59:19.53271Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 received MsgPreVoteResp from 4594e5f716bad646 at term 1"} {"level":"info","ts":"2026-07-03T18:59:19.532735Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became candidate at term 2"} {"level":"info","ts":"2026-07-03T18:59:19.532749Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 received MsgVoteResp from 4594e5f716bad646 at term 2"} {"level":"info","ts":"2026-07-03T18:59:19.532779Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became leader at term 2"} {"level":"info","ts":"2026-07-03T18:59:19.532816Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4594e5f716bad646 elected leader 4594e5f716bad646 at term 2"} {"level":"info","ts":"2026-07-03T18:59:19.534536Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4594e5f716bad646","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.144:2379]}","request-path":"/0/members/4594e5f716bad646/attributes","cluster-id":"ff2e51b364668606","publish-timeout":"7s"} {"level":"info","ts":"2026-07-03T18:59:19.534543Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-03T18:59:19.534641Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-03T18:59:19.535287Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-03T18:59:19.535759Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-03T18:59:19.539094Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-03T18:59:19.539109Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.144:2379"} {"level":"info","ts":"2026-07-03T18:59:19.539309Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-03T18:59:19.5536Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ff2e51b364668606","local-member-id":"4594e5f716bad646","cluster-version":"3.5"} {"level":"info","ts":"2026-07-03T18:59:19.553709Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-03T18:59:19.553731Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-03T18:59:21.352635Z","caller":"traceutil/trace.go:171","msg":"trace[1305145388] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:4; }","duration":"172.873316ms","start":"2026-07-03T18:59:21.179727Z","end":"2026-07-03T18:59:21.3526Z","steps":["trace[1305145388] 'read index received' (duration: 74.963215ms)","trace[1305145388] 'applied index is now lower than readState.Index' (duration: 97.908401ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T18:59:21.352682Z","caller":"traceutil/trace.go:171","msg":"trace[813197322] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"176.728674ms","start":"2026-07-03T18:59:21.175896Z","end":"2026-07-03T18:59:21.352624Z","steps":["trace[813197322] 'process raft request' (duration: 154.539768ms)","trace[813197322] 'compare' (duration: 22.001574ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T18:59:21.352925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.147829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-03T18:59:21.352999Z","caller":"traceutil/trace.go:171","msg":"trace[731111096] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:3; }","duration":"173.294102ms","start":"2026-07-03T18:59:21.179691Z","end":"2026-07-03T18:59:21.352985Z","steps":["trace[731111096] 'agreement among raft nodes before linearized reading' (duration: 173.065669ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T18:59:21.417185Z","caller":"traceutil/trace.go:171","msg":"trace[638847368] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"160.243109ms","start":"2026-07-03T18:59:21.256911Z","end":"2026-07-03T18:59:21.417154Z","steps":["trace[638847368] 'process raft request' (duration: 159.978106ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T18:59:21.417472Z","caller":"traceutil/trace.go:171","msg":"trace[1422485454] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"160.504612ms","start":"2026-07-03T18:59:21.25693Z","end":"2026-07-03T18:59:21.417434Z","steps":["trace[1422485454] 'process raft request' (duration: 160.096207ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T18:59:21.417953Z","caller":"traceutil/trace.go:171","msg":"trace[686265787] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"160.745405ms","start":"2026-07-03T18:59:21.25719Z","end":"2026-07-03T18:59:21.417935Z","steps":["trace[686265787] 'process raft request' (duration: 159.907855ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T18:59:21.418086Z","caller":"traceutil/trace.go:171","msg":"trace[1419707717] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"160.811386ms","start":"2026-07-03T18:59:21.257265Z","end":"2026-07-03T18:59:21.418076Z","steps":["trace[1419707717] 'process raft request' (duration: 160.535552ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T18:59:21.418333Z","caller":"traceutil/trace.go:171","msg":"trace[479683094] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"160.776396ms","start":"2026-07-03T18:59:21.257288Z","end":"2026-07-03T18:59:21.418065Z","steps":["trace[479683094] 'process raft request' (duration: 160.467892ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T18:59:21.41853Z","caller":"traceutil/trace.go:171","msg":"trace[848331241] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"161.119959ms","start":"2026-07-03T18:59:21.257393Z","end":"2026-07-03T18:59:21.418513Z","steps":["trace[848331241] 'process raft request' (duration: 160.433471ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T18:59:21.418375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.07026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-03T18:59:21.418618Z","caller":"traceutil/trace.go:171","msg":"trace[603585432] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"113.363424ms","start":"2026-07-03T18:59:21.30524Z","end":"2026-07-03T18:59:21.418604Z","steps":["trace[603585432] 'agreement among raft nodes before linearized reading' (duration: 113.06021ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T18:59:21.41841Z","caller":"traceutil/trace.go:171","msg":"trace[1837380009] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"144.968868ms","start":"2026-07-03T18:59:21.273432Z","end":"2026-07-03T18:59:21.418401Z","steps":["trace[1837380009] 'process raft request' (duration: 144.598853ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T18:59:21.418896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.038021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.144\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-03T18:59:21.418925Z","caller":"traceutil/trace.go:171","msg":"trace[1589562747] range","detail":"{range_begin:/registry/masterleases/199.204.45.144; range_end:; response_count:0; response_revision:10; }","duration":"166.103602ms","start":"2026-07-03T18:59:21.252814Z","end":"2026-07-03T18:59:21.418918Z","steps":["trace[1589562747] 'agreement among raft nodes before linearized reading' (duration: 166.060732ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T18:59:21.418958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.094721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-03T18:59:21.419016Z","caller":"traceutil/trace.go:171","msg":"trace[200459072] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"162.173343ms","start":"2026-07-03T18:59:21.256828Z","end":"2026-07-03T18:59:21.419001Z","steps":["trace[200459072] 'agreement among raft nodes before linearized reading' (duration: 162.082592ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T18:59:21.419053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.390346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-03T18:59:21.419074Z","caller":"traceutil/trace.go:171","msg":"trace[1928410440] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:10; }","duration":"162.442036ms","start":"2026-07-03T18:59:21.256625Z","end":"2026-07-03T18:59:21.419067Z","steps":["trace[1928410440] 'agreement among raft nodes before linearized reading' (duration: 162.398165ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T18:59:21.419062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.769214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-03T18:59:21.419161Z","caller":"traceutil/trace.go:171","msg":"trace[224030218] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"188.909876ms","start":"2026-07-03T18:59:21.230193Z","end":"2026-07-03T18:59:21.419103Z","steps":["trace[224030218] 'agreement among raft nodes before linearized reading' (duration: 188.776685ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T18:59:21.419405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.762887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-03T18:59:21.419468Z","caller":"traceutil/trace.go:171","msg":"trace[187431820] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"189.826298ms","start":"2026-07-03T18:59:21.229632Z","end":"2026-07-03T18:59:21.419458Z","steps":["trace[187431820] 'agreement among raft nodes before linearized reading' (duration: 189.747397ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T18:59:35.876377Z","caller":"traceutil/trace.go:171","msg":"trace[890635433] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"183.467822ms","start":"2026-07-03T18:59:35.692877Z","end":"2026-07-03T18:59:35.876345Z","steps":["trace[890635433] 'process raft request' (duration: 145.835853ms)","trace[890635433] 'compare' (duration: 37.487207ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T18:59:36.043744Z","caller":"traceutil/trace.go:171","msg":"trace[2104117932] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"154.376825ms","start":"2026-07-03T18:59:35.889324Z","end":"2026-07-03T18:59:36.043701Z","steps":["trace[2104117932] 'process raft request' (duration: 137.63964ms)","trace[2104117932] 'compare' (duration: 16.403981ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T18:59:36.043999Z","caller":"traceutil/trace.go:171","msg":"trace[929025516] linearizableReadLoop","detail":"{readStateIndex:385; appliedIndex:384; }","duration":"131.025594ms","start":"2026-07-03T18:59:35.912583Z","end":"2026-07-03T18:59:36.043609Z","steps":["trace[929025516] 'read index received' (duration: 114.3113ms)","trace[929025516] 'applied index is now lower than readState.Index' (duration: 16.713174ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T18:59:36.04415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.54731ms","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-03T18:59:36.044193Z","caller":"traceutil/trace.go:171","msg":"trace[1040589438] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:370; }","duration":"131.647752ms","start":"2026-07-03T18:59:35.912532Z","end":"2026-07-03T18:59:36.04418Z","steps":["trace[1040589438] 'agreement among raft nodes before linearized reading' (duration: 131.555701ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T18:59:36.481071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.349954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T18:59:36.481155Z","caller":"traceutil/trace.go:171","msg":"trace[1587255356] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:371; }","duration":"191.495915ms","start":"2026-07-03T18:59:36.28964Z","end":"2026-07-03T18:59:36.481136Z","steps":["trace[1587255356] 'range keys from in-memory index tree' (duration: 191.258193ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T18:59:40.049908Z","caller":"traceutil/trace.go:171","msg":"trace[574913908] linearizableReadLoop","detail":"{readStateIndex:393; appliedIndex:392; }","duration":"316.726479ms","start":"2026-07-03T18:59:39.733151Z","end":"2026-07-03T18:59:40.049878Z","steps":["trace[574913908] 'read index received' (duration: 316.469137ms)","trace[574913908] 'applied index is now lower than readState.Index' (duration: 256.182µs)"],"step_count":2} {"level":"warn","ts":"2026-07-03T18:59:40.050362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.187274ms","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-03T18:59:40.050466Z","caller":"traceutil/trace.go:171","msg":"trace[210400497] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:377; }","duration":"317.358986ms","start":"2026-07-03T18:59:39.73309Z","end":"2026-07-03T18:59:40.050449Z","steps":["trace[210400497] 'agreement among raft nodes before linearized reading' (duration: 317.060213ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T18:59:40.050529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T18:59:39.733064Z","time spent":"317.436067ms","remote":"127.0.0.1:50516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-03T18:59:40.050617Z","caller":"traceutil/trace.go:171","msg":"trace[358826589] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"350.379774ms","start":"2026-07-03T18:59:39.700217Z","end":"2026-07-03T18:59:40.050597Z","steps":["trace[358826589] 'process raft request' (duration: 349.486164ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T18:59:40.051309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T18:59:39.700203Z","time spent":"350.476815ms","remote":"127.0.0.1:50516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T18:59:42.816179Z","caller":"traceutil/trace.go:171","msg":"trace[1684318593] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"213.879977ms","start":"2026-07-03T18:59:42.60227Z","end":"2026-07-03T18:59:42.81615Z","steps":["trace[1684318593] 'process raft request' (duration: 213.612914ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T18:59:42.817327Z","caller":"traceutil/trace.go:171","msg":"trace[324453440] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"212.692765ms","start":"2026-07-03T18:59:42.604601Z","end":"2026-07-03T18:59:42.817294Z","steps":["trace[324453440] 'process raft request' (duration: 212.530143ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T18:59:45.326056Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15440203372430403481,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-03T18:59:45.826543Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15440203372430403481,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-03T18:59:46.327016Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15440203372430403481,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-03T18:59:46.370202Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.970955891s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-03T18:59:46.997652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"626.92371ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-03T18:59:46.998066Z","caller":"traceutil/trace.go:171","msg":"trace[1908518657] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"2.605634333s","start":"2026-07-03T18:59:44.39241Z","end":"2026-07-03T18:59:46.998044Z","steps":["trace[1908518657] 'process raft request' (duration: 2.605523322s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T18:59:46.998188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T18:59:44.392392Z","time spent":"2.605732235s","remote":"127.0.0.1:50436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4037,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T18:59:46.998422Z","caller":"traceutil/trace.go:171","msg":"trace[283726687] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"2.607638435s","start":"2026-07-03T18:59:44.390766Z","end":"2026-07-03T18:59:46.998404Z","steps":["trace[283726687] 'process raft request' (duration: 1.979673694s)","trace[283726687] 'compare' (duration: 626.640177ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T18:59:46.998494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T18:59:44.390751Z","time spent":"2.607707796s","remote":"127.0.0.1:50358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-03T18:59:46.998524Z","caller":"traceutil/trace.go:171","msg":"trace[1144735249] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"2.607438222s","start":"2026-07-03T18:59:44.391066Z","end":"2026-07-03T18:59:46.998504Z","steps":["trace[1144735249] 'process raft request' (duration: 2.606802055s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T18:59:46.998628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T18:59:44.391057Z","time spent":"2.607507824s","remote":"127.0.0.1:35616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3795,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T18:59:46.998873Z","caller":"traceutil/trace.go:171","msg":"trace[2070740118] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"2.608043549s","start":"2026-07-03T18:59:44.390812Z","end":"2026-07-03T18:59:46.998856Z","steps":["trace[2070740118] 'process raft request' (duration: 2.606964197s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T18:59:46.998967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T18:59:44.390801Z","time spent":"2.608120719s","remote":"127.0.0.1:35616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3804,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T18:59:47.289367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000178247s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-03T18:59:47.289482Z","caller":"traceutil/trace.go:171","msg":"trace[1618503906] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000344019s","start":"2026-07-03T18:59:45.289118Z","end":"2026-07-03T18:59:47.289462Z","steps":["trace[1618503906] 'agreement among raft nodes before linearized reading' (duration: 2.000215608s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T18:59:47.28953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T18:59:45.289086Z","time spent":"2.00043552s","remote":"127.0.0.1:50284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/03 18:59:47 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-03T18:59:48.475786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.377966296s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-03T18:59:48.475885Z","caller":"traceutil/trace.go:171","msg":"trace[2100698364] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"3.47231272s","start":"2026-07-03T18:59:45.003552Z","end":"2026-07-03T18:59:48.475865Z","steps":["trace[2100698364] 'process raft request' (duration: 2.094168092s)","trace[2100698364] 'compare' (duration: 1.377768594s)"],"step_count":2} {"level":"warn","ts":"2026-07-03T18:59:48.475981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T18:59:45.003526Z","time spent":"3.472400981s","remote":"127.0.0.1:50516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":519,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T18:59:48.476294Z","caller":"traceutil/trace.go:171","msg":"trace[728537816] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"1.472139291s","start":"2026-07-03T18:59:47.004125Z","end":"2026-07-03T18:59:48.476264Z","steps":["trace[728537816] 'process raft request' (duration: 1.471758137s)"],"step_count":1} {"level":"info","ts":"2026-07-03T18:59:48.484279Z","caller":"traceutil/trace.go:171","msg":"trace[868242654] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"1.478667208s","start":"2026-07-03T18:59:47.005577Z","end":"2026-07-03T18:59:48.484244Z","steps":["trace[868242654] 'process raft request' (duration: 1.470380853s)"],"step_count":1} {"level":"info","ts":"2026-07-03T18:59:48.476025Z","caller":"traceutil/trace.go:171","msg":"trace[1214839576] linearizableReadLoop","detail":"{readStateIndex:418; appliedIndex:413; }","duration":"3.650363405s","start":"2026-07-03T18:59:44.825657Z","end":"2026-07-03T18:59:48.47602Z","steps":["trace[1214839576] 'read index received' (duration: 1.544838597s)","trace[1214839576] 'applied index is now lower than readState.Index' (duration: 2.105524368s)"],"step_count":2} {"level":"warn","ts":"2026-07-03T18:59:48.484299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T18:59:47.00411Z","time spent":"1.480117013s","remote":"127.0.0.1:50358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":689,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-03T18:59:48.484573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T18:59:47.005567Z","time spent":"1.47878294s","remote":"127.0.0.1:35616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3840,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T18:59:48.484827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.659146195s","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-03T18:59:48.484904Z","caller":"traceutil/trace.go:171","msg":"trace[220872737] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:403; }","duration":"3.659294356s","start":"2026-07-03T18:59:44.825582Z","end":"2026-07-03T18:59:48.484877Z","steps":["trace[220872737] 'agreement among raft nodes before linearized reading' (duration: 3.658631009s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T18:59:48.485795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T18:59:44.825566Z","time spent":"3.660201356s","remote":"127.0.0.1:50516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-03T18:59:48.64025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.707965ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-03T18:59:48.640484Z","caller":"traceutil/trace.go:171","msg":"trace[388570613] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"151.617033ms","start":"2026-07-03T18:59:48.488851Z","end":"2026-07-03T18:59:48.640468Z","steps":["trace[388570613] 'process raft request' (duration: 151.500732ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T18:59:48.640593Z","caller":"traceutil/trace.go:171","msg":"trace[1629617528] linearizableReadLoop","detail":"{readStateIndex:421; appliedIndex:420; }","duration":"156.028408ms","start":"2026-07-03T18:59:48.484456Z","end":"2026-07-03T18:59:48.640484Z","steps":["trace[1629617528] 'read index received' (duration: 35.103731ms)","trace[1629617528] 'applied index is now lower than readState.Index' (duration: 120.921877ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T18:59:48.640886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.637294623s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-68hsn\" ","response":"range_response_count:1 size:4016"} {"level":"info","ts":"2026-07-03T18:59:48.641015Z","caller":"traceutil/trace.go:171","msg":"trace[1075237820] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-68hsn; range_end:; response_count:1; response_revision:404; }","duration":"1.637460656s","start":"2026-07-03T18:59:47.003534Z","end":"2026-07-03T18:59:48.640994Z","steps":["trace[1075237820] 'agreement among raft nodes before linearized reading' (duration: 1.637294284s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T18:59:48.641123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T18:59:47.003511Z","time spent":"1.637593467s","remote":"127.0.0.1:50436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":4039,"request content":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-68hsn\" "} {"level":"warn","ts":"2026-07-03T18:59:48.641307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.350864006s","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-03T18:59:48.641357Z","caller":"traceutil/trace.go:171","msg":"trace[1693680289] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:404; }","duration":"2.350941827s","start":"2026-07-03T18:59:46.290401Z","end":"2026-07-03T18:59:48.641343Z","steps":["trace[1693680289] 'agreement among raft nodes before linearized reading' (duration: 2.350716875s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T18:59:48.64149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T18:59:46.290386Z","time spent":"2.350994558s","remote":"127.0.0.1:50516","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-03T18:59:48.641495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.351808087s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T18:59:48.641642Z","caller":"traceutil/trace.go:171","msg":"trace[280772648] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:404; }","duration":"1.351985088s","start":"2026-07-03T18:59:47.289639Z","end":"2026-07-03T18:59:48.641624Z","steps":["trace[280772648] 'agreement among raft nodes before linearized reading' (duration: 1.351815196s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T18:59:48.641735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T18:59:47.289619Z","time spent":"1.352100589s","remote":"127.0.0.1:50276","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-03T18:59:48.641831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.635313033s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4195"} {"level":"info","ts":"2026-07-03T18:59:48.641874Z","caller":"traceutil/trace.go:171","msg":"trace[672946414] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:404; }","duration":"1.635369654s","start":"2026-07-03T18:59:47.006493Z","end":"2026-07-03T18:59:48.641863Z","steps":["trace[672946414] 'agreement among raft nodes before linearized reading' (duration: 1.635295513s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T18:59:48.641902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T18:59:47.006483Z","time spent":"1.635412904s","remote":"127.0.0.1:35594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":4218,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"warn","ts":"2026-07-03T18:59:48.642063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.566744995s","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-03T18:59:48.642169Z","caller":"traceutil/trace.go:171","msg":"trace[2097393511] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:404; }","duration":"2.566884526s","start":"2026-07-03T18:59:46.075227Z","end":"2026-07-03T18:59:48.642111Z","steps":["trace[2097393511] 'agreement among raft nodes before linearized reading' (duration: 2.565463082s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T18:59:48.642227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.42710034s","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-03T18:59:48.642272Z","caller":"traceutil/trace.go:171","msg":"trace[1410829371] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:404; }","duration":"2.427377713s","start":"2026-07-03T18:59:46.214885Z","end":"2026-07-03T18:59:48.642262Z","steps":["trace[1410829371] 'agreement among raft nodes before linearized reading' (duration: 2.427293162s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T18:59:48.642273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T18:59:46.075204Z","time spent":"2.567052718s","remote":"127.0.0.1:50516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-03T18:59:48.642302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T18:59:46.214862Z","time spent":"2.427432363s","remote":"127.0.0.1:50516","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-03T18:59:48.898364Z","caller":"traceutil/trace.go:171","msg":"trace[692691694] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"251.14685ms","start":"2026-07-03T18:59:48.647177Z","end":"2026-07-03T18:59:48.898323Z","steps":["trace[692691694] 'process raft request' (duration: 169.086304ms)","trace[692691694] 'compare' (duration: 81.851554ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T18:59:48.899103Z","caller":"traceutil/trace.go:171","msg":"trace[226039515] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"251.908298ms","start":"2026-07-03T18:59:48.647181Z","end":"2026-07-03T18:59:48.899089Z","steps":["trace[226039515] 'process raft request' (duration: 251.08982ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T18:59:48.89929Z","caller":"traceutil/trace.go:171","msg":"trace[1599223159] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"251.932728ms","start":"2026-07-03T18:59:48.647341Z","end":"2026-07-03T18:59:48.899274Z","steps":["trace[1599223159] 'process raft request' (duration: 251.709706ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T18:59:48.89981Z","caller":"traceutil/trace.go:171","msg":"trace[1608716293] transaction","detail":"{read_only:false; number_of_response:1; response_revision:410; }","duration":"177.564781ms","start":"2026-07-03T18:59:48.722228Z","end":"2026-07-03T18:59:48.899793Z","steps":["trace[1608716293] 'process raft request' (duration: 177.46836ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T18:59:48.899914Z","caller":"traceutil/trace.go:171","msg":"trace[1537248858] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"252.402612ms","start":"2026-07-03T18:59:48.647498Z","end":"2026-07-03T18:59:48.8999Z","steps":["trace[1537248858] 'process raft request' (duration: 251.725496ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T18:59:48.900036Z","caller":"traceutil/trace.go:171","msg":"trace[881125451] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"248.444702ms","start":"2026-07-03T18:59:48.651577Z","end":"2026-07-03T18:59:48.900022Z","steps":["trace[881125451] 'process raft request' (duration: 247.981867ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T18:59:48.900192Z","caller":"traceutil/trace.go:171","msg":"trace[1248222296] linearizableReadLoop","detail":"{readStateIndex:427; appliedIndex:422; }","duration":"249.634874ms","start":"2026-07-03T18:59:48.650544Z","end":"2026-07-03T18:59:48.900178Z","steps":["trace[1248222296] 'read index received' (duration: 69.990392ms)","trace[1248222296] 'applied index is now lower than readState.Index' (duration: 179.642992ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T18:59:48.90045Z","caller":"traceutil/trace.go:171","msg":"trace[1003170998] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"136.939792ms","start":"2026-07-03T18:59:48.763489Z","end":"2026-07-03T18:59:48.900429Z","steps":["trace[1003170998] 'process raft request' (duration: 136.254205ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T18:59:48.900591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.025728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T18:59:48.900648Z","caller":"traceutil/trace.go:171","msg":"trace[1564935988] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:411; }","duration":"250.115419ms","start":"2026-07-03T18:59:48.650519Z","end":"2026-07-03T18:59:48.900634Z","steps":["trace[1564935988] 'agreement among raft nodes before linearized reading' (duration: 249.702935ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T18:59:49.078739Z","caller":"traceutil/trace.go:171","msg":"trace[1113085031] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"170.96112ms","start":"2026-07-03T18:59:48.90774Z","end":"2026-07-03T18:59:49.078701Z","steps":["trace[1113085031] 'process raft request' (duration: 124.166899ms)","trace[1113085031] 'compare' (duration: 46.579618ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T18:59:49.078833Z","caller":"traceutil/trace.go:171","msg":"trace[416230725] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"171.061921ms","start":"2026-07-03T18:59:48.90773Z","end":"2026-07-03T18:59:49.078792Z","steps":["trace[416230725] 'process raft request' (duration: 170.893679ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T18:59:49.078756Z","caller":"traceutil/trace.go:171","msg":"trace[1588227702] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"169.181151ms","start":"2026-07-03T18:59:48.909552Z","end":"2026-07-03T18:59:49.078733Z","steps":["trace[1588227702] 'process raft request' (duration: 169.12858ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T18:59:49.091808Z","caller":"traceutil/trace.go:171","msg":"trace[1011232911] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"166.642474ms","start":"2026-07-03T18:59:48.925153Z","end":"2026-07-03T18:59:49.091795Z","steps":["trace[1011232911] 'process raft request' (duration: 166.590463ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T18:59:49.091841Z","caller":"traceutil/trace.go:171","msg":"trace[1664980006] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"169.232811ms","start":"2026-07-03T18:59:48.922586Z","end":"2026-07-03T18:59:49.091819Z","steps":["trace[1664980006] 'process raft request' (duration: 169.07952ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T18:59:49.376778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.839573ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-03T18:59:49.37698Z","caller":"traceutil/trace.go:171","msg":"trace[1440657820] linearizableReadLoop","detail":"{readStateIndex:441; appliedIndex:439; }","duration":"251.158827ms","start":"2026-07-03T18:59:49.125805Z","end":"2026-07-03T18:59:49.376964Z","steps":["trace[1440657820] 'read index received' (duration: 119.085682ms)","trace[1440657820] 'applied index is now lower than readState.Index' (duration: 132.072185ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T18:59:49.377009Z","caller":"traceutil/trace.go:171","msg":"trace[931044523] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"281.467968ms","start":"2026-07-03T18:59:49.095503Z","end":"2026-07-03T18:59:49.376971Z","steps":["trace[931044523] 'process raft request' (duration: 149.372993ms)","trace[931044523] 'compare' (duration: 131.685591ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T18:59:49.377077Z","caller":"traceutil/trace.go:171","msg":"trace[775692808] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"280.436528ms","start":"2026-07-03T18:59:49.096618Z","end":"2026-07-03T18:59:49.377054Z","steps":["trace[775692808] 'process raft request' (duration: 280.276947ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T18:59:49.377106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.298839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-67659f764b\" ","response":"range_response_count:1 size:3855"} {"level":"info","ts":"2026-07-03T18:59:49.377146Z","caller":"traceutil/trace.go:171","msg":"trace[871493635] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-67659f764b; range_end:; response_count:1; response_revision:422; }","duration":"251.369299ms","start":"2026-07-03T18:59:49.125768Z","end":"2026-07-03T18:59:49.377137Z","steps":["trace[871493635] 'agreement among raft nodes before linearized reading' (duration: 251.249138ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T18:59:49.417143Z","caller":"traceutil/trace.go:171","msg":"trace[1924937024] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"289.066726ms","start":"2026-07-03T18:59:49.128044Z","end":"2026-07-03T18:59:49.417111Z","steps":["trace[1924937024] 'process raft request' (duration: 288.912945ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T18:59:49.531678Z","caller":"traceutil/trace.go:171","msg":"trace[1560177106] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"104.913417ms","start":"2026-07-03T18:59:49.426741Z","end":"2026-07-03T18:59:49.531654Z","steps":["trace[1560177106] 'process raft request' (duration: 104.786575ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T18:59:49.694609Z","caller":"traceutil/trace.go:171","msg":"trace[1886106775] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"153.308684ms","start":"2026-07-03T18:59:49.541277Z","end":"2026-07-03T18:59:49.694585Z","steps":["trace[1886106775] 'process raft request' (duration: 95.881184ms)","trace[1886106775] 'compare' (duration: 57.319919ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:00:00.894124Z","caller":"traceutil/trace.go:171","msg":"trace[1776031384] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"379.164163ms","start":"2026-07-03T19:00:00.514944Z","end":"2026-07-03T19:00:00.894108Z","steps":["trace[1776031384] 'process raft request' (duration: 379.060287ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:00.894314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:00.514908Z","time spent":"379.328883ms","remote":"127.0.0.1:50320","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-03T19:00:00.902045Z","caller":"traceutil/trace.go:171","msg":"trace[1367782275] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"383.398798ms","start":"2026-07-03T19:00:00.518631Z","end":"2026-07-03T19:00:00.90203Z","steps":["trace[1367782275] 'process raft request' (duration: 383.222237ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:00:00.902125Z","caller":"traceutil/trace.go:171","msg":"trace[203909683] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"371.298162ms","start":"2026-07-03T19:00:00.530798Z","end":"2026-07-03T19:00:00.902096Z","steps":["trace[203909683] 'process raft request' (duration: 371.081619ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:00.902247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:00.518593Z","time spent":"383.494873ms","remote":"127.0.0.1:50320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-03T19:00:00.902263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:00.530781Z","time spent":"371.415379ms","remote":"127.0.0.1:50320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-03T19:00:00.902373Z","caller":"traceutil/trace.go:171","msg":"trace[1907912711] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"368.676474ms","start":"2026-07-03T19:00:00.533686Z","end":"2026-07-03T19:00:00.902363Z","steps":["trace[1907912711] 'process raft request' (duration: 368.272039ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:00:00.902396Z","caller":"traceutil/trace.go:171","msg":"trace[2030920286] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"367.356855ms","start":"2026-07-03T19:00:00.535024Z","end":"2026-07-03T19:00:00.902381Z","steps":["trace[2030920286] 'process raft request' (duration: 366.971662ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:00.902466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:00.535005Z","time spent":"367.438609ms","remote":"127.0.0.1:50320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-03T19:00:00.902479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:00.533666Z","time spent":"368.788141ms","remote":"127.0.0.1:50320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-03T19:00:00.902255Z","caller":"traceutil/trace.go:171","msg":"trace[1527169584] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"371.377997ms","start":"2026-07-03T19:00:00.530866Z","end":"2026-07-03T19:00:00.902244Z","steps":["trace[1527169584] 'process raft request' (duration: 371.036216ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:00.902754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:00.530847Z","time spent":"371.885017ms","remote":"127.0.0.1:50320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-03T19:00:00.902365Z","caller":"traceutil/trace.go:171","msg":"trace[1787286118] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"384.792092ms","start":"2026-07-03T19:00:00.517556Z","end":"2026-07-03T19:00:00.902348Z","steps":["trace[1787286118] 'process raft request' (duration: 384.066668ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:00.903038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:00.517545Z","time spent":"385.472812ms","remote":"127.0.0.1:50320","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-03T19:00:00.902824Z","caller":"traceutil/trace.go:171","msg":"trace[1831451571] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"358.932839ms","start":"2026-07-03T19:00:00.543881Z","end":"2026-07-03T19:00:00.902814Z","steps":["trace[1831451571] 'process raft request' (duration: 358.420518ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:00.903175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:00.543863Z","time spent":"359.28448ms","remote":"127.0.0.1:50320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-03T19:00:00.904118Z","caller":"traceutil/trace.go:171","msg":"trace[2098949419] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"348.034684ms","start":"2026-07-03T19:00:00.556073Z","end":"2026-07-03T19:00:00.904107Z","steps":["trace[2098949419] 'process raft request' (duration: 347.398606ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:00.904186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:00.556046Z","time spent":"348.096768ms","remote":"127.0.0.1:50320","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-03T19:00:00.904268Z","caller":"traceutil/trace.go:171","msg":"trace[1065318662] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"348.112869ms","start":"2026-07-03T19:00:00.556142Z","end":"2026-07-03T19:00:00.904255Z","steps":["trace[1065318662] 'process raft request' (duration: 347.885235ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:00:00.904718Z","caller":"traceutil/trace.go:171","msg":"trace[1255640216] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"323.397156ms","start":"2026-07-03T19:00:00.581303Z","end":"2026-07-03T19:00:00.9047Z","steps":["trace[1255640216] 'process raft request' (duration: 322.850323ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:00.904792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:00.581281Z","time spent":"323.47714ms","remote":"127.0.0.1:50320","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-03T19:00:00.904905Z","caller":"traceutil/trace.go:171","msg":"trace[1820310622] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"236.335929ms","start":"2026-07-03T19:00:00.66856Z","end":"2026-07-03T19:00:00.904896Z","steps":["trace[1820310622] 'process raft request' (duration: 235.752414ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:00.905104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:00.556138Z","time spent":"348.152471ms","remote":"127.0.0.1:50320","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-03T19:00:00.905105Z","caller":"traceutil/trace.go:171","msg":"trace[993409378] linearizableReadLoop","detail":"{readStateIndex:517; appliedIndex:506; }","duration":"205.843068ms","start":"2026-07-03T19:00:00.699237Z","end":"2026-07-03T19:00:00.90508Z","steps":["trace[993409378] 'read index received' (duration: 194.912392ms)","trace[993409378] 'applied index is now lower than readState.Index' (duration: 10.928736ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:00:00.905302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.055561ms","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-03T19:00:00.905337Z","caller":"traceutil/trace.go:171","msg":"trace[1047394397] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:498; }","duration":"206.128355ms","start":"2026-07-03T19:00:00.699199Z","end":"2026-07-03T19:00:00.905327Z","steps":["trace[1047394397] 'agreement among raft nodes before linearized reading' (duration: 205.956994ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:01.478653Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15440203372430403692,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-03T19:00:01.62857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.528512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1alpha2.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:1013"} {"level":"info","ts":"2026-07-03T19:00:01.628632Z","caller":"traceutil/trace.go:171","msg":"trace[283776832] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1alpha2.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:509; }","duration":"656.609036ms","start":"2026-07-03T19:00:00.972008Z","end":"2026-07-03T19:00:01.628617Z","steps":["trace[283776832] 'range keys from in-memory index tree' (duration: 656.466118ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:01.628663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:00.971994Z","time spent":"656.66139ms","remote":"127.0.0.1:35648","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":1,"response size":1036,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/v1alpha2.gateway.networking.k8s.io\" "} {"level":"info","ts":"2026-07-03T19:00:01.629342Z","caller":"traceutil/trace.go:171","msg":"trace[1897577134] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"645.824709ms","start":"2026-07-03T19:00:00.983488Z","end":"2026-07-03T19:00:01.629312Z","steps":["trace[1897577134] 'process raft request' (duration: 645.424435ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:00:01.629469Z","caller":"traceutil/trace.go:171","msg":"trace[1275097013] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"657.277946ms","start":"2026-07-03T19:00:00.972176Z","end":"2026-07-03T19:00:01.629454Z","steps":["trace[1275097013] 'process raft request' (duration: 644.111285ms)","trace[1275097013] 'compare' (duration: 12.208314ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:00:01.629492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:00.983457Z","time spent":"645.961757ms","remote":"127.0.0.1:50320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T19:00:01.629485Z","caller":"traceutil/trace.go:171","msg":"trace[1628271496] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"633.247954ms","start":"2026-07-03T19:00:00.996114Z","end":"2026-07-03T19:00:01.629362Z","steps":["trace[1628271496] 'process raft request' (duration: 633.126596ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:01.62955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:00.972164Z","time spent":"657.33538ms","remote":"127.0.0.1:50516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T19:00:01.629634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:00.996098Z","time spent":"633.467467ms","remote":"127.0.0.1:50320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-03T19:00:01.629658Z","caller":"traceutil/trace.go:171","msg":"trace[1894879962] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"637.741663ms","start":"2026-07-03T19:00:00.991908Z","end":"2026-07-03T19:00:01.629649Z","steps":["trace[1894879962] 'process raft request' (duration: 637.231222ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:01.629716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:00.991886Z","time spent":"637.805356ms","remote":"127.0.0.1:50320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79947,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T19:00:01.62988Z","caller":"traceutil/trace.go:171","msg":"trace[2116762071] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"632.965537ms","start":"2026-07-03T19:00:00.996901Z","end":"2026-07-03T19:00:01.629867Z","steps":["trace[2116762071] 'process raft request' (duration: 632.393673ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:01.629952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:00.996891Z","time spent":"633.02962ms","remote":"127.0.0.1:50320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58844,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T19:00:01.630154Z","caller":"traceutil/trace.go:171","msg":"trace[1825986018] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"633.123306ms","start":"2026-07-03T19:00:00.996947Z","end":"2026-07-03T19:00:01.630071Z","steps":["trace[1825986018] 'process raft request' (duration: 632.750604ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:00:01.630267Z","caller":"traceutil/trace.go:171","msg":"trace[378063763] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"590.025824ms","start":"2026-07-03T19:00:01.040224Z","end":"2026-07-03T19:00:01.63025Z","steps":["trace[378063763] 'process raft request' (duration: 589.593649ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:00:01.630172Z","caller":"traceutil/trace.go:171","msg":"trace[1006376277] linearizableReadLoop","detail":"{readStateIndex:542; appliedIndex:535; }","duration":"652.051801ms","start":"2026-07-03T19:00:00.978096Z","end":"2026-07-03T19:00:01.630148Z","steps":["trace[1006376277] 'read index received' (duration: 638.20614ms)","trace[1006376277] 'applied index is now lower than readState.Index' (duration: 13.844671ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:00:01.630307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:00.99694Z","time spent":"633.298336ms","remote":"127.0.0.1:50320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31121,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T19:00:01.630333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:01.040206Z","time spent":"590.093488ms","remote":"127.0.0.1:50320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T19:00:01.630378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.255634ms","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-03T19:00:01.630421Z","caller":"traceutil/trace.go:171","msg":"trace[2081575339] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:516; }","duration":"652.334359ms","start":"2026-07-03T19:00:00.978075Z","end":"2026-07-03T19:00:01.630409Z","steps":["trace[2081575339] 'agreement among raft nodes before linearized reading' (duration: 652.149098ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:01.630455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.617986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-03T19:00:01.630457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:00.978066Z","time spent":"652.382341ms","remote":"127.0.0.1:50516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-03T19:00:01.630458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.963606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T19:00:01.630484Z","caller":"traceutil/trace.go:171","msg":"trace[2088748117] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:516; }","duration":"649.657858ms","start":"2026-07-03T19:00:00.980815Z","end":"2026-07-03T19:00:01.630473Z","steps":["trace[2088748117] 'agreement among raft nodes before linearized reading' (duration: 649.552111ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:00:01.630503Z","caller":"traceutil/trace.go:171","msg":"trace[1537923134] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:516; }","duration":"343.010649ms","start":"2026-07-03T19:00:01.287484Z","end":"2026-07-03T19:00:01.630494Z","steps":["trace[1537923134] 'agreement among raft nodes before linearized reading' (duration: 342.939355ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:01.630516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:00.98081Z","time spent":"649.700941ms","remote":"127.0.0.1:50516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-03T19:00:01.630526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:01.287394Z","time spent":"343.125605ms","remote":"127.0.0.1:50284","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-03T19:00:08.115649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.294976ms","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-03T19:00:08.115785Z","caller":"traceutil/trace.go:171","msg":"trace[1377530939] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:591; }","duration":"417.514197ms","start":"2026-07-03T19:00:07.698242Z","end":"2026-07-03T19:00:08.115757Z","steps":["trace[1377530939] 'range keys from in-memory index tree' (duration: 417.036911ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:08.115851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:07.698221Z","time spent":"417.614344ms","remote":"127.0.0.1:50516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-03T19:00:08.116027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.196235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-03T19:00:08.116119Z","caller":"traceutil/trace.go:171","msg":"trace[316320012] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:591; }","duration":"437.321143ms","start":"2026-07-03T19:00:07.678787Z","end":"2026-07-03T19:00:08.116108Z","steps":["trace[316320012] 'range keys from in-memory index tree' (duration: 436.54262ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:08.116239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:07.678768Z","time spent":"437.46101ms","remote":"127.0.0.1:50516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-03T19:00:08.116012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.469784ms","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-03T19:00:08.11643Z","caller":"traceutil/trace.go:171","msg":"trace[974917880] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:591; }","duration":"428.972431ms","start":"2026-07-03T19:00:07.68744Z","end":"2026-07-03T19:00:08.116412Z","steps":["trace[974917880] 'range keys from in-memory index tree' (duration: 428.088973ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:08.116494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:07.687433Z","time spent":"429.042225ms","remote":"127.0.0.1:50516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-03T19:00:08.31744Z","caller":"traceutil/trace.go:171","msg":"trace[1308767137] linearizableReadLoop","detail":"{readStateIndex:619; appliedIndex:618; }","duration":"447.637396ms","start":"2026-07-03T19:00:07.869769Z","end":"2026-07-03T19:00:08.317406Z","steps":["trace[1308767137] 'read index received' (duration: 445.15157ms)","trace[1308767137] 'applied index is now lower than readState.Index' (duration: 2.484696ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:00:08.31754Z","caller":"traceutil/trace.go:171","msg":"trace[1577131005] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"519.807436ms","start":"2026-07-03T19:00:07.797662Z","end":"2026-07-03T19:00:08.31747Z","steps":["trace[1577131005] 'process raft request' (duration: 517.981316ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:08.317751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.894679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-blzfl\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-03T19:00:08.317851Z","caller":"traceutil/trace.go:171","msg":"trace[844888284] range","detail":"{range_begin:/registry/pods/kube-system/cilium-blzfl; range_end:; response_count:1; response_revision:592; }","duration":"448.102041ms","start":"2026-07-03T19:00:07.86973Z","end":"2026-07-03T19:00:08.317832Z","steps":["trace[844888284] 'agreement among raft nodes before linearized reading' (duration: 447.784404ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:08.317928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:07.869712Z","time spent":"448.204367ms","remote":"127.0.0.1:50436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-blzfl\" "} {"level":"warn","ts":"2026-07-03T19:00:08.318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:07.797632Z","time spent":"520.182987ms","remote":"127.0.0.1:50374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T19:00:09.806386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.335921196s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-07-03T19:00:09.806452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.644838ms","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-03T19:00:09.806858Z","caller":"traceutil/trace.go:171","msg":"trace[1359324388] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"1.685653604s","start":"2026-07-03T19:00:08.121164Z","end":"2026-07-03T19:00:09.806817Z","steps":["trace[1359324388] 'process raft request' (duration: 1.68539738s)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:00:09.806899Z","caller":"traceutil/trace.go:171","msg":"trace[1961340909] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"1.685146216s","start":"2026-07-03T19:00:08.121726Z","end":"2026-07-03T19:00:09.806872Z","steps":["trace[1961340909] 'process raft request' (duration: 1.685017999s)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:00:09.806888Z","caller":"traceutil/trace.go:171","msg":"trace[552046664] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:592; }","duration":"237.113724ms","start":"2026-07-03T19:00:09.569742Z","end":"2026-07-03T19:00:09.806856Z","steps":["trace[552046664] 'range keys from in-memory index tree' (duration: 236.612026ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:09.807026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:08.121144Z","time spent":"1.685799072s","remote":"127.0.0.1:50516","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-03T19:00:09.807071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:08.121712Z","time spent":"1.685244972s","remote":"127.0.0.1:50516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T19:00:09.807181Z","caller":"traceutil/trace.go:171","msg":"trace[1964229659] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"1.430806772s","start":"2026-07-03T19:00:08.376346Z","end":"2026-07-03T19:00:09.807153Z","steps":["trace[1964229659] 'process raft request' (duration: 1.43076547s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:09.807311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:08.376326Z","time spent":"1.430925338s","remote":"127.0.0.1:35638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-03T19:00:09.807327Z","caller":"traceutil/trace.go:171","msg":"trace[1658310978] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"1.472265559s","start":"2026-07-03T19:00:08.335042Z","end":"2026-07-03T19:00:09.807308Z","steps":["trace[1658310978] 'process raft request' (duration: 1.471962612s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:09.80741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:08.335025Z","time spent":"1.472347223s","remote":"127.0.0.1:50436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T19:00:09.807546Z","caller":"traceutil/trace.go:171","msg":"trace[1492817296] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"1.685708486s","start":"2026-07-03T19:00:08.121821Z","end":"2026-07-03T19:00:09.80753Z","steps":["trace[1492817296] 'process raft request' (duration: 1.684983287s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:09.807646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:08.121808Z","time spent":"1.685790321s","remote":"127.0.0.1:50516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T19:00:09.807736Z","caller":"traceutil/trace.go:171","msg":"trace[1071544011] linearizableReadLoop","detail":"{readStateIndex:623; appliedIndex:619; }","duration":"1.49021315s","start":"2026-07-03T19:00:08.317511Z","end":"2026-07-03T19:00:09.807724Z","steps":["trace[1071544011] 'read index received' (duration: 152.941491ms)","trace[1071544011] 'applied index is now lower than readState.Index' (duration: 1.337270599s)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:00:09.808022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.520013268s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T19:00:09.808066Z","caller":"traceutil/trace.go:171","msg":"trace[1571832610] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:597; }","duration":"1.520066181s","start":"2026-07-03T19:00:08.287987Z","end":"2026-07-03T19:00:09.808054Z","steps":["trace[1571832610] 'agreement among raft nodes before linearized reading' (duration: 1.519978346s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:09.808096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:08.287917Z","time spent":"1.520172667s","remote":"127.0.0.1:50284","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-03T19:00:09.864248Z","caller":"traceutil/trace.go:171","msg":"trace[1988578110] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"1.002386188s","start":"2026-07-03T19:00:08.861828Z","end":"2026-07-03T19:00:09.864214Z","steps":["trace[1988578110] 'process raft request' (duration: 1.002187627s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:09.864474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:08.861793Z","time spent":"1.002551227s","remote":"127.0.0.1:50516","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-03T19:00:09.864856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"887.768722ms","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-03T19:00:09.864922Z","caller":"traceutil/trace.go:171","msg":"trace[1546164040] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:598; }","duration":"887.864537ms","start":"2026-07-03T19:00:08.977041Z","end":"2026-07-03T19:00:09.864905Z","steps":["trace[1546164040] 'agreement among raft nodes before linearized reading' (duration: 887.647155ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:09.864964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:08.977019Z","time spent":"887.934411ms","remote":"127.0.0.1:50516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-03T19:00:11.387636Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.138378429s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-03T19:00:11.427348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.365435165s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-03T19:00:11.42755Z","caller":"traceutil/trace.go:171","msg":"trace[716960012] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"1.555897072s","start":"2026-07-03T19:00:09.871615Z","end":"2026-07-03T19:00:11.427512Z","steps":["trace[716960012] 'process raft request' (duration: 190.228784ms)","trace[716960012] 'compare' (duration: 1.36533043s)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:00:11.427729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:09.871586Z","time spent":"1.55605094s","remote":"127.0.0.1:50516","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-03T19:00:11.427933Z","caller":"traceutil/trace.go:171","msg":"trace[567742270] linearizableReadLoop","detail":"{readStateIndex:627; appliedIndex:626; }","duration":"1.5538463s","start":"2026-07-03T19:00:09.874059Z","end":"2026-07-03T19:00:11.427905Z","steps":["trace[567742270] 'read index received' (duration: 187.74126ms)","trace[567742270] 'applied index is now lower than readState.Index' (duration: 1.36610285s)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:00:11.428139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.55402099s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T19:00:11.428237Z","caller":"traceutil/trace.go:171","msg":"trace[162903406] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:599; }","duration":"1.55421605s","start":"2026-07-03T19:00:09.874002Z","end":"2026-07-03T19:00:11.428218Z","steps":["trace[162903406] 'agreement among raft nodes before linearized reading' (duration: 1.55403428s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:11.428381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:09.873969Z","time spent":"1.55439959s","remote":"127.0.0.1:50284","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-03T19:00:11.428382Z","caller":"traceutil/trace.go:171","msg":"trace[1635965112] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"1.180632942s","start":"2026-07-03T19:00:10.247736Z","end":"2026-07-03T19:00:11.428368Z","steps":["trace[1635965112] 'process raft request' (duration: 1.179914754s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:11.428617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:10.247686Z","time spent":"1.180886145s","remote":"127.0.0.1:50374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T19:00:12.313335Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15440203372430403859,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-03T19:00:12.814485Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15440203372430403859,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-03T19:00:13.3152Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15440203372430403859,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-03T19:00:13.815949Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15440203372430403859,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-03T19:00:14.281664Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.79040709s","expected-duration":"1s"} {"level":"info","ts":"2026-07-03T19:00:14.282016Z","caller":"traceutil/trace.go:171","msg":"trace[444944395] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"2.790851332s","start":"2026-07-03T19:00:11.491145Z","end":"2026-07-03T19:00:14.281997Z","steps":["trace[444944395] 'process raft request' (duration: 2.790717516s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:14.282121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:11.491113Z","time spent":"2.790958998s","remote":"127.0.0.1:50490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-03T19:00:14.459122Z","caller":"traceutil/trace.go:171","msg":"trace[946749798] linearizableReadLoop","detail":"{readStateIndex:630; appliedIndex:628; }","duration":"2.646665446s","start":"2026-07-03T19:00:11.812429Z","end":"2026-07-03T19:00:14.459095Z","steps":["trace[946749798] 'read index received' (duration: 2.469474958s)","trace[946749798] 'applied index is now lower than readState.Index' (duration: 177.189578ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:00:14.459175Z","caller":"traceutil/trace.go:171","msg":"trace[413057547] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"1.828169074s","start":"2026-07-03T19:00:12.630981Z","end":"2026-07-03T19:00:14.459151Z","steps":["trace[413057547] 'process raft request' (duration: 1.803907985s)","trace[413057547] 'compare' (duration: 23.931533ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:00:14.459316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:12.630961Z","time spent":"1.828266589s","remote":"127.0.0.1:50516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T19:00:14.459399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.646840244s","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-03T19:00:14.459521Z","caller":"traceutil/trace.go:171","msg":"trace[453155950] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:602; }","duration":"2.646964961s","start":"2026-07-03T19:00:11.812543Z","end":"2026-07-03T19:00:14.459508Z","steps":["trace[453155950] 'agreement among raft nodes before linearized reading' (duration: 2.646685916s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:14.459558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:11.812468Z","time spent":"2.647082237s","remote":"127.0.0.1:50516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-03T19:00:14.459548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.647084847s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-03T19:00:14.459634Z","caller":"traceutil/trace.go:171","msg":"trace[120142521] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:602; }","duration":"2.647229264s","start":"2026-07-03T19:00:11.812386Z","end":"2026-07-03T19:00:14.459615Z","steps":["trace[120142521] 'agreement among raft nodes before linearized reading' (duration: 2.646838824s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:14.459636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.02810842s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-03T19:00:14.459706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:11.812365Z","time spent":"2.647332349s","remote":"127.0.0.1:50516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-03T19:00:14.459731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.528810669s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.144\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-03T19:00:14.459725Z","caller":"traceutil/trace.go:171","msg":"trace[1596929107] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:602; }","duration":"1.028207735s","start":"2026-07-03T19:00:13.431495Z","end":"2026-07-03T19:00:14.459703Z","steps":["trace[1596929107] 'agreement among raft nodes before linearized reading' (duration: 1.027902689s)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:00:14.459761Z","caller":"traceutil/trace.go:171","msg":"trace[2082560317] range","detail":"{range_begin:/registry/masterleases/199.204.45.144; range_end:; response_count:1; response_revision:602; }","duration":"1.528875002s","start":"2026-07-03T19:00:12.930878Z","end":"2026-07-03T19:00:14.459753Z","steps":["trace[2082560317] 'agreement among raft nodes before linearized reading' (duration: 1.528796588s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:14.459779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:13.431418Z","time spent":"1.028349292s","remote":"127.0.0.1:50516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-03T19:00:14.459795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:12.930852Z","time spent":"1.528937706s","remote":"127.0.0.1:50302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.144\" "} {"level":"warn","ts":"2026-07-03T19:00:14.459658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.169787606s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T19:00:14.459862Z","caller":"traceutil/trace.go:171","msg":"trace[1977138044] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:602; }","duration":"1.170022298s","start":"2026-07-03T19:00:13.289825Z","end":"2026-07-03T19:00:14.459848Z","steps":["trace[1977138044] 'agreement among raft nodes before linearized reading' (duration: 1.169811367s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:14.459915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:13.289805Z","time spent":"1.170098422s","remote":"127.0.0.1:50284","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-03T19:00:14.459951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.556611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-07-03T19:00:14.459992Z","caller":"traceutil/trace.go:171","msg":"trace[1546213886] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:602; }","duration":"172.630225ms","start":"2026-07-03T19:00:14.287349Z","end":"2026-07-03T19:00:14.459979Z","steps":["trace[1546213886] 'agreement among raft nodes before linearized reading' (duration: 172.558011ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:14.460007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.646033881s","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-03T19:00:14.460146Z","caller":"traceutil/trace.go:171","msg":"trace[1265209157] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:602; }","duration":"2.64621687s","start":"2026-07-03T19:00:11.813919Z","end":"2026-07-03T19:00:14.460136Z","steps":["trace[1265209157] 'agreement among raft nodes before linearized reading' (duration: 2.64602708s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:14.460183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:11.813899Z","time spent":"2.646272153s","remote":"127.0.0.1:50516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-03T19:00:16.387276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.73947ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-03T19:00:16.387378Z","caller":"traceutil/trace.go:171","msg":"trace[677774788] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"361.039177ms","start":"2026-07-03T19:00:16.02632Z","end":"2026-07-03T19:00:16.387359Z","steps":["trace[677774788] 'process raft request' (duration: 255.15385ms)","trace[677774788] 'compare' (duration: 105.360981ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:00:16.38746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:16.026298Z","time spent":"361.111231ms","remote":"127.0.0.1:50320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4580,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-03T19:00:16.390338Z","caller":"traceutil/trace.go:171","msg":"trace[920731761] linearizableReadLoop","detail":"{readStateIndex:667; appliedIndex:666; }","duration":"101.332069ms","start":"2026-07-03T19:00:16.288988Z","end":"2026-07-03T19:00:16.39032Z","steps":["trace[920731761] 'read index received' (duration: 101.223124ms)","trace[920731761] 'applied index is now lower than readState.Index' (duration: 108.345µs)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:00:16.390391Z","caller":"traceutil/trace.go:171","msg":"trace[934758585] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"164.194079ms","start":"2026-07-03T19:00:16.226163Z","end":"2026-07-03T19:00:16.390357Z","steps":["trace[934758585] 'process raft request' (duration: 164.081144ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:16.390455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.461166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T19:00:16.390581Z","caller":"traceutil/trace.go:171","msg":"trace[142940653] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:637; }","duration":"101.621854ms","start":"2026-07-03T19:00:16.288946Z","end":"2026-07-03T19:00:16.390568Z","steps":["trace[142940653] 'agreement among raft nodes before linearized reading' (duration: 101.440515ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:19.340043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.277096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-07-03T19:00:19.340133Z","caller":"traceutil/trace.go:171","msg":"trace[864251805] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:678; }","duration":"116.420843ms","start":"2026-07-03T19:00:19.223692Z","end":"2026-07-03T19:00:19.340112Z","steps":["trace[864251805] 'range keys from in-memory index tree' (duration: 116.038624ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:27.110025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.810623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-03T19:00:27.110145Z","caller":"traceutil/trace.go:171","msg":"trace[416615573] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:745; }","duration":"236.008351ms","start":"2026-07-03T19:00:26.874113Z","end":"2026-07-03T19:00:27.110121Z","steps":["trace[416615573] 'count revisions from in-memory index tree' (duration: 235.679157ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:30.398334Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15440203372430404254,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-03T19:00:30.898836Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15440203372430404254,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-03T19:00:31.211571Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.370499088s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-03T19:00:31.567554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.997730604s","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-03T19:00:31.588967Z","caller":"traceutil/trace.go:171","msg":"trace[698718899] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:759; }","duration":"2.019159344s","start":"2026-07-03T19:00:29.569777Z","end":"2026-07-03T19:00:31.588936Z","steps":["trace[698718899] 'range keys from in-memory index tree' (duration: 1.997703703s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:31.646716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.823814ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-03T19:00:31.652097Z","caller":"traceutil/trace.go:171","msg":"trace[1591622369] linearizableReadLoop","detail":"{readStateIndex:797; appliedIndex:794; }","duration":"1.754590945s","start":"2026-07-03T19:00:29.89748Z","end":"2026-07-03T19:00:31.652071Z","steps":["trace[1591622369] 'read index received' (duration: 1.314377512s)","trace[1591622369] 'applied index is now lower than readState.Index' (duration: 440.212283ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:00:31.652211Z","caller":"traceutil/trace.go:171","msg":"trace[1563134774] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"1.286098605s","start":"2026-07-03T19:00:30.366106Z","end":"2026-07-03T19:00:31.652204Z","steps":["trace[1563134774] 'process raft request' (duration: 1.285915047s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:31.65233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:30.366079Z","time spent":"1.286159698s","remote":"127.0.0.1:50516","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-03T19:00:31.652539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.092571503s","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-03T19:00:31.652576Z","caller":"traceutil/trace.go:171","msg":"trace[851578164] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:761; }","duration":"1.092634735s","start":"2026-07-03T19:00:30.559932Z","end":"2026-07-03T19:00:31.652567Z","steps":["trace[851578164] 'agreement among raft nodes before linearized reading' (duration: 1.09251207s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:31.6526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:30.559909Z","time spent":"1.092686407s","remote":"127.0.0.1:50516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-03T19:00:31.652633Z","caller":"traceutil/trace.go:171","msg":"trace[367117891] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"1.61566715s","start":"2026-07-03T19:00:30.03694Z","end":"2026-07-03T19:00:31.652607Z","steps":["trace[367117891] 'process raft request' (duration: 1.614376545s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:31.652502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.082393125s","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-03T19:00:31.652764Z","caller":"traceutil/trace.go:171","msg":"trace[1121717809] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:761; }","duration":"1.082658177s","start":"2026-07-03T19:00:30.570055Z","end":"2026-07-03T19:00:31.652713Z","steps":["trace[1121717809] 'agreement among raft nodes before linearized reading' (duration: 1.082274341s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:31.652809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:30.036899Z","time spent":"1.615800386s","remote":"127.0.0.1:50490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T19:00:31.652879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:30.570041Z","time spent":"1.082821454s","remote":"127.0.0.1:50516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-03T19:00:31.652998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.123864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-03T19:00:31.652829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.09134342s","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-03T19:00:31.653023Z","caller":"traceutil/trace.go:171","msg":"trace[261928766] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:761; }","duration":"577.173046ms","start":"2026-07-03T19:00:31.075843Z","end":"2026-07-03T19:00:31.653016Z","steps":["trace[261928766] 'agreement among raft nodes before linearized reading' (duration: 577.125974ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:31.653019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.755540996s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-03T19:00:31.653046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:31.075825Z","time spent":"577.215518ms","remote":"127.0.0.1:50436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":12,"response size":30,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"info","ts":"2026-07-03T19:00:31.653081Z","caller":"traceutil/trace.go:171","msg":"trace[1497924857] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:761; }","duration":"1.75564338s","start":"2026-07-03T19:00:29.897417Z","end":"2026-07-03T19:00:31.65306Z","steps":["trace[1497924857] 'agreement among raft nodes before linearized reading' (duration: 1.755143909s)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:00:31.653045Z","caller":"traceutil/trace.go:171","msg":"trace[1348995956] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:761; }","duration":"1.09158896s","start":"2026-07-03T19:00:30.561446Z","end":"2026-07-03T19:00:31.653035Z","steps":["trace[1348995956] 'agreement among raft nodes before linearized reading' (duration: 1.09135022s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:31.653178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:30.561422Z","time spent":"1.091746567s","remote":"127.0.0.1:50516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-03T19:00:31.653226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"637.188977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-zrm8b\" ","response":"range_response_count:1 size:5050"} {"level":"warn","ts":"2026-07-03T19:00:31.653236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.330654828s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-03T19:00:31.65239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"809.022784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-03T19:00:31.653257Z","caller":"traceutil/trace.go:171","msg":"trace[2018253012] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-zrm8b; range_end:; response_count:1; response_revision:761; }","duration":"637.238419ms","start":"2026-07-03T19:00:31.016009Z","end":"2026-07-03T19:00:31.653247Z","steps":["trace[2018253012] 'agreement among raft nodes before linearized reading' (duration: 637.153356ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:00:31.653281Z","caller":"traceutil/trace.go:171","msg":"trace[398373605] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:761; }","duration":"809.986134ms","start":"2026-07-03T19:00:30.843286Z","end":"2026-07-03T19:00:31.653272Z","steps":["trace[398373605] 'agreement among raft nodes before linearized reading' (duration: 809.000242ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:00:31.65328Z","caller":"traceutil/trace.go:171","msg":"trace[501311466] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:761; }","duration":"1.330746583s","start":"2026-07-03T19:00:30.322519Z","end":"2026-07-03T19:00:31.653265Z","steps":["trace[501311466] 'agreement among raft nodes before linearized reading' (duration: 1.33067758s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:31.653285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:31.01599Z","time spent":"637.289091ms","remote":"127.0.0.1:50436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":5073,"request content":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-zrm8b\" "} {"level":"warn","ts":"2026-07-03T19:00:31.653306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:30.843264Z","time spent":"810.033416ms","remote":"127.0.0.1:35594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":2,"response size":30,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-07-03T19:00:31.653317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:30.322481Z","time spent":"1.330830746s","remote":"127.0.0.1:50284","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-03T19:00:31.653139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:29.897389Z","time spent":"1.755733224s","remote":"127.0.0.1:50516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-03T19:00:31.652782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.091239455s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-03T19:00:31.653667Z","caller":"traceutil/trace.go:171","msg":"trace[842749790] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:761; }","duration":"1.092136834s","start":"2026-07-03T19:00:30.561522Z","end":"2026-07-03T19:00:31.653659Z","steps":["trace[842749790] 'agreement among raft nodes before linearized reading' (duration: 1.091207674s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:31.653694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:30.561515Z","time spent":"1.092171565s","remote":"127.0.0.1:50516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-03T19:00:44.516593Z","caller":"traceutil/trace.go:171","msg":"trace[1553700175] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"165.72633ms","start":"2026-07-03T19:00:44.350837Z","end":"2026-07-03T19:00:44.516563Z","steps":["trace[1553700175] 'process raft request' (duration: 165.512852ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:00:46.630749Z","caller":"traceutil/trace.go:171","msg":"trace[2070516850] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"106.177782ms","start":"2026-07-03T19:00:46.524547Z","end":"2026-07-03T19:00:46.630724Z","steps":["trace[2070516850] 'process raft request' (duration: 106.021516ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:48.313358Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15440203372430404583,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-03T19:00:48.526356Z","caller":"traceutil/trace.go:171","msg":"trace[979745639] linearizableReadLoop","detail":"{readStateIndex:980; appliedIndex:979; }","duration":"713.293881ms","start":"2026-07-03T19:00:47.813027Z","end":"2026-07-03T19:00:48.526321Z","steps":["trace[979745639] 'read index received' (duration: 713.248669ms)","trace[979745639] 'applied index is now lower than readState.Index' (duration: 43.742µs)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:00:48.526536Z","caller":"traceutil/trace.go:171","msg":"trace[1330794558] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"748.570811ms","start":"2026-07-03T19:00:47.777822Z","end":"2026-07-03T19:00:48.526393Z","steps":["trace[1330794558] 'process raft request' (duration: 748.331243ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:48.526746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:47.777801Z","time spent":"748.852662ms","remote":"127.0.0.1:50516","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-03T19:00:48.526578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"713.530829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-03T19:00:48.52715Z","caller":"traceutil/trace.go:171","msg":"trace[1803375680] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:941; }","duration":"714.11693ms","start":"2026-07-03T19:00:47.813017Z","end":"2026-07-03T19:00:48.527134Z","steps":["trace[1803375680] 'agreement among raft nodes before linearized reading' (duration: 713.441045ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:48.527243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:47.81296Z","time spent":"714.267855ms","remote":"127.0.0.1:50516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-03T19:00:48.680681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"861.824571ms","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-03T19:00:48.680757Z","caller":"traceutil/trace.go:171","msg":"trace[899856245] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:941; }","duration":"861.942725ms","start":"2026-07-03T19:00:47.818798Z","end":"2026-07-03T19:00:48.680741Z","steps":["trace[899856245] 'agreement among raft nodes before linearized reading' (duration: 861.705177ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:00:48.680687Z","caller":"traceutil/trace.go:171","msg":"trace[1888994856] linearizableReadLoop","detail":"{readStateIndex:981; appliedIndex:980; }","duration":"154.073833ms","start":"2026-07-03T19:00:48.526426Z","end":"2026-07-03T19:00:48.6805Z","steps":["trace[1888994856] 'read index received' (duration: 88.261665ms)","trace[1888994856] 'applied index is now lower than readState.Index' (duration: 65.809737ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:00:48.680798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:47.818786Z","time spent":"862.005718ms","remote":"127.0.0.1:50516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-03T19:00:48.680798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.30697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-03T19:00:48.680807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"867.331831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-03T19:00:48.6809Z","caller":"traceutil/trace.go:171","msg":"trace[890648360] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:941; }","duration":"390.481906ms","start":"2026-07-03T19:00:48.290392Z","end":"2026-07-03T19:00:48.680874Z","steps":["trace[890648360] 'agreement among raft nodes before linearized reading' (duration: 390.30802ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:00:48.680916Z","caller":"traceutil/trace.go:171","msg":"trace[219875383] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:941; }","duration":"867.483677ms","start":"2026-07-03T19:00:47.813412Z","end":"2026-07-03T19:00:48.680896Z","steps":["trace[219875383] 'agreement among raft nodes before linearized reading' (duration: 867.167435ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:48.680957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:48.290357Z","time spent":"390.59018ms","remote":"127.0.0.1:50284","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-03T19:00:48.680965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:47.813404Z","time spent":"867.551209ms","remote":"127.0.0.1:50516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-03T19:00:48.681416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"863.605016ms","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-03T19:00:48.681461Z","caller":"traceutil/trace.go:171","msg":"trace[1164282519] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:941; }","duration":"863.685789ms","start":"2026-07-03T19:00:47.817765Z","end":"2026-07-03T19:00:48.681451Z","steps":["trace[1164282519] 'agreement among raft nodes before linearized reading' (duration: 862.789377ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:48.681497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:47.817746Z","time spent":"863.744861ms","remote":"127.0.0.1:50516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-03T19:00:48.681765Z","caller":"traceutil/trace.go:171","msg":"trace[1102213464] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"150.240264ms","start":"2026-07-03T19:00:48.53151Z","end":"2026-07-03T19:00:48.68175Z","steps":["trace[1102213464] 'process raft request' (duration: 148.958838ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:00:49.231963Z","caller":"traceutil/trace.go:171","msg":"trace[1583732083] linearizableReadLoop","detail":"{readStateIndex:987; appliedIndex:986; }","duration":"269.636012ms","start":"2026-07-03T19:00:48.962293Z","end":"2026-07-03T19:00:49.231929Z","steps":["trace[1583732083] 'read index received' (duration: 269.376073ms)","trace[1583732083] 'applied index is now lower than readState.Index' (duration: 258.639µs)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:00:49.232014Z","caller":"traceutil/trace.go:171","msg":"trace[1221670339] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"330.254623ms","start":"2026-07-03T19:00:48.901712Z","end":"2026-07-03T19:00:49.231966Z","steps":["trace[1221670339] 'process raft request' (duration: 330.037795ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:49.232247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:48.901677Z","time spent":"330.470601ms","remote":"127.0.0.1:50358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":816,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-03T19:00:49.232301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.987855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/local-path-storage/local-path-config\" ","response":"range_response_count:1 size:1464"} {"level":"info","ts":"2026-07-03T19:00:49.232404Z","caller":"traceutil/trace.go:171","msg":"trace[1532142120] range","detail":"{range_begin:/registry/configmaps/local-path-storage/local-path-config; range_end:; response_count:1; response_revision:947; }","duration":"270.14111ms","start":"2026-07-03T19:00:48.962235Z","end":"2026-07-03T19:00:49.232376Z","steps":["trace[1532142120] 'agreement among raft nodes before linearized reading' (duration: 269.802278ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:01:12.851379Z","caller":"traceutil/trace.go:171","msg":"trace[1053767636] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"127.737698ms","start":"2026-07-03T19:01:12.723608Z","end":"2026-07-03T19:01:12.851345Z","steps":["trace[1053767636] 'process raft request' (duration: 127.480131ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:01:13.093518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.630451ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-03T19:01:13.093752Z","caller":"traceutil/trace.go:171","msg":"trace[387152538] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"214.622769ms","start":"2026-07-03T19:01:12.879055Z","end":"2026-07-03T19:01:13.093678Z","steps":["trace[387152538] 'process raft request' (duration: 97.73189ms)","trace[387152538] 'compare' (duration: 116.536339ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:01:13.10262Z","caller":"traceutil/trace.go:171","msg":"trace[1911512807] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"221.843778ms","start":"2026-07-03T19:01:12.880763Z","end":"2026-07-03T19:01:13.102607Z","steps":["trace[1911512807] 'process raft request' (duration: 221.762096ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:01:13.102616Z","caller":"traceutil/trace.go:171","msg":"trace[1364880426] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"222.033414ms","start":"2026-07-03T19:01:12.880549Z","end":"2026-07-03T19:01:13.102582Z","steps":["trace[1364880426] 'process raft request' (duration: 221.833508ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:01:13.102595Z","caller":"traceutil/trace.go:171","msg":"trace[204517591] linearizableReadLoop","detail":"{readStateIndex:1078; appliedIndex:1075; }","duration":"178.799488ms","start":"2026-07-03T19:01:12.92377Z","end":"2026-07-03T19:01:13.102569Z","steps":["trace[204517591] 'read index received' (duration: 53.118874ms)","trace[204517591] 'applied index is now lower than readState.Index' (duration: 125.679554ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:01:13.102783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.002815ms","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-03T19:01:13.102842Z","caller":"traceutil/trace.go:171","msg":"trace[879147706] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1034; }","duration":"179.118138ms","start":"2026-07-03T19:01:12.92371Z","end":"2026-07-03T19:01:13.102828Z","steps":["trace[879147706] 'agreement among raft nodes before linearized reading' (duration: 178.940703ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:01:13.10296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.962992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.144\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-03T19:01:13.103005Z","caller":"traceutil/trace.go:171","msg":"trace[692991630] range","detail":"{range_begin:/registry/masterleases/199.204.45.144; range_end:; response_count:1; response_revision:1034; }","duration":"168.038874ms","start":"2026-07-03T19:01:12.934954Z","end":"2026-07-03T19:01:13.102993Z","steps":["trace[692991630] 'agreement among raft nodes before linearized reading' (duration: 167.854858ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:01:17.260467Z","caller":"traceutil/trace.go:171","msg":"trace[684415843] linearizableReadLoop","detail":"{readStateIndex:1091; appliedIndex:1090; }","duration":"133.750286ms","start":"2026-07-03T19:01:17.126698Z","end":"2026-07-03T19:01:17.260448Z","steps":["trace[684415843] 'read index received' (duration: 133.55349ms)","trace[684415843] 'applied index is now lower than readState.Index' (duration: 196.186µs)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:01:17.260584Z","caller":"traceutil/trace.go:171","msg":"trace[1981874891] transaction","detail":"{read_only:false; response_revision:1046; number_of_response:1; }","duration":"134.57114ms","start":"2026-07-03T19:01:17.12597Z","end":"2026-07-03T19:01:17.260541Z","steps":["trace[1981874891] 'process raft request' (duration: 134.340803ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:01:17.260735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.027984ms","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-03T19:01:17.26093Z","caller":"traceutil/trace.go:171","msg":"trace[1684418575] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1046; }","duration":"134.25616ms","start":"2026-07-03T19:01:17.126661Z","end":"2026-07-03T19:01:17.260917Z","steps":["trace[1684418575] 'agreement among raft nodes before linearized reading' (duration: 133.88499ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:01:17.260818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.788129ms","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-03T19:01:17.261048Z","caller":"traceutil/trace.go:171","msg":"trace[652428612] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1046; }","duration":"133.075767ms","start":"2026-07-03T19:01:17.127953Z","end":"2026-07-03T19:01:17.261029Z","steps":["trace[652428612] 'agreement among raft nodes before linearized reading' (duration: 132.652475ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:01:17.261086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.539141ms","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-03T19:01:17.261154Z","caller":"traceutil/trace.go:171","msg":"trace[210326526] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1046; }","duration":"127.651744ms","start":"2026-07-03T19:01:17.133487Z","end":"2026-07-03T19:01:17.261139Z","steps":["trace[210326526] 'agreement among raft nodes before linearized reading' (duration: 127.398767ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:01:43.304223Z","caller":"traceutil/trace.go:171","msg":"trace[1516252494] transaction","detail":"{read_only:false; response_revision:1130; number_of_response:1; }","duration":"165.067299ms","start":"2026-07-03T19:01:43.139122Z","end":"2026-07-03T19:01:43.304189Z","steps":["trace[1516252494] 'process raft request' (duration: 92.541186ms)","trace[1516252494] 'compare' (duration: 72.38988ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:02:01.420363Z","caller":"traceutil/trace.go:171","msg":"trace[1426558147] linearizableReadLoop","detail":"{readStateIndex:1242; appliedIndex:1241; }","duration":"213.960012ms","start":"2026-07-03T19:02:01.206377Z","end":"2026-07-03T19:02:01.420337Z","steps":["trace[1426558147] 'read index received' (duration: 213.919081ms)","trace[1426558147] 'applied index is now lower than readState.Index' (duration: 39.731µs)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:02:01.4205Z","caller":"traceutil/trace.go:171","msg":"trace[1348179133] transaction","detail":"{read_only:false; response_revision:1188; number_of_response:1; }","duration":"266.931485ms","start":"2026-07-03T19:02:01.153534Z","end":"2026-07-03T19:02:01.420465Z","steps":["trace[1348179133] 'process raft request' (duration: 266.66097ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:02:01.420841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.439142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329952"} {"level":"info","ts":"2026-07-03T19:02:01.420943Z","caller":"traceutil/trace.go:171","msg":"trace[1918197081] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:1188; }","duration":"214.592945ms","start":"2026-07-03T19:02:01.206332Z","end":"2026-07-03T19:02:01.420925Z","steps":["trace[1918197081] 'agreement among raft nodes before linearized reading' (duration: 214.209357ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:02:01.504291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.101837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T19:02:01.504389Z","caller":"traceutil/trace.go:171","msg":"trace[1118284142] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1189; }","duration":"216.24447ms","start":"2026-07-03T19:02:01.288125Z","end":"2026-07-03T19:02:01.504369Z","steps":["trace[1118284142] 'agreement among raft nodes before linearized reading' (duration: 216.084926ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:02:01.504509Z","caller":"traceutil/trace.go:171","msg":"trace[716448949] transaction","detail":"{read_only:false; response_revision:1189; number_of_response:1; }","duration":"138.567457ms","start":"2026-07-03T19:02:01.365779Z","end":"2026-07-03T19:02:01.504347Z","steps":["trace[716448949] 'process raft request' (duration: 132.522598ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:02:24.110972Z","caller":"traceutil/trace.go:171","msg":"trace[2050253481] linearizableReadLoop","detail":"{readStateIndex:1358; appliedIndex:1357; }","duration":"188.25998ms","start":"2026-07-03T19:02:23.922676Z","end":"2026-07-03T19:02:24.110936Z","steps":["trace[2050253481] 'read index received' (duration: 187.302341ms)","trace[2050253481] 'applied index is now lower than readState.Index' (duration: 956.319µs)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:02:24.111305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.600947ms","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-03T19:02:24.111359Z","caller":"traceutil/trace.go:171","msg":"trace[2128899080] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1297; }","duration":"188.674178ms","start":"2026-07-03T19:02:23.922666Z","end":"2026-07-03T19:02:24.11134Z","steps":["trace[2128899080] 'agreement among raft nodes before linearized reading' (duration: 188.411993ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:02:24.111415Z","caller":"traceutil/trace.go:171","msg":"trace[1480377657] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"192.528053ms","start":"2026-07-03T19:02:23.91885Z","end":"2026-07-03T19:02:24.111378Z","steps":["trace[1480377657] 'process raft request' (duration: 191.242338ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:02:32.050045Z","caller":"traceutil/trace.go:171","msg":"trace[1045338156] transaction","detail":"{read_only:false; response_revision:1329; number_of_response:1; }","duration":"105.030919ms","start":"2026-07-03T19:02:31.944985Z","end":"2026-07-03T19:02:32.050015Z","steps":["trace[1045338156] 'process raft request' (duration: 104.912347ms)"],"step_count":1}