{"level":"warn","ts":"2026-04-13T21:06:31.112258Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-13T21:06:31.112332Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.75:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.75:2380","--initial-cluster=instance=https://199.19.213.75:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.75:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.75:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-13T21:06:31.112392Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-13T21:06:31.112404Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.75:2380"]} {"level":"info","ts":"2026-04-13T21:06:31.112419Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-13T21:06:31.113389Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.75:2379"]} {"level":"info","ts":"2026-04-13T21:06:31.11346Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.75:2380"],"listen-peer-urls":["https://199.19.213.75:2380"],"advertise-client-urls":["https://199.19.213.75:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.75:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.75:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-13T21:06:31.143029Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"29.342181ms"} {"level":"info","ts":"2026-04-13T21:06:31.151475Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"42c325ad8bcf8012","cluster-id":"1e2ab336c559df5e"} {"level":"info","ts":"2026-04-13T21:06:31.151693Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"42c325ad8bcf8012 switched to configuration voters=()"} {"level":"info","ts":"2026-04-13T21:06:31.151726Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"42c325ad8bcf8012 became follower at term 0"} {"level":"info","ts":"2026-04-13T21:06:31.151767Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 42c325ad8bcf8012 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-13T21:06:31.151793Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"42c325ad8bcf8012 became follower at term 1"} {"level":"info","ts":"2026-04-13T21:06:31.151885Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"42c325ad8bcf8012 switched to configuration voters=(4810730254267023378)"} {"level":"warn","ts":"2026-04-13T21:06:31.170229Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-13T21:06:31.172724Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-13T21:06:31.174095Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-13T21:06:31.178215Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"42c325ad8bcf8012","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-13T21:06:31.178645Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"42c325ad8bcf8012","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-13T21:06:31.178707Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-13T21:06:31.178841Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-13T21:06:31.178854Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-13T21:06:31.184563Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"42c325ad8bcf8012 switched to configuration voters=(4810730254267023378)"} {"level":"info","ts":"2026-04-13T21:06:31.184852Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"1e2ab336c559df5e","local-member-id":"42c325ad8bcf8012","added-peer-id":"42c325ad8bcf8012","added-peer-peer-urls":["https://199.19.213.75:2380"]} {"level":"info","ts":"2026-04-13T21:06:31.186248Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-13T21:06:31.186533Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"42c325ad8bcf8012","initial-advertise-peer-urls":["https://199.19.213.75:2380"],"listen-peer-urls":["https://199.19.213.75:2380"],"advertise-client-urls":["https://199.19.213.75:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.75:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-13T21:06:31.186575Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-13T21:06:31.187826Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.75:2380"} {"level":"info","ts":"2026-04-13T21:06:31.187881Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.75:2380"} {"level":"info","ts":"2026-04-13T21:06:31.652183Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"42c325ad8bcf8012 is starting a new election at term 1"} {"level":"info","ts":"2026-04-13T21:06:31.652255Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"42c325ad8bcf8012 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-13T21:06:31.652303Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"42c325ad8bcf8012 received MsgPreVoteResp from 42c325ad8bcf8012 at term 1"} {"level":"info","ts":"2026-04-13T21:06:31.652322Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"42c325ad8bcf8012 became candidate at term 2"} {"level":"info","ts":"2026-04-13T21:06:31.652333Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"42c325ad8bcf8012 received MsgVoteResp from 42c325ad8bcf8012 at term 2"} {"level":"info","ts":"2026-04-13T21:06:31.652349Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"42c325ad8bcf8012 became leader at term 2"} {"level":"info","ts":"2026-04-13T21:06:31.652407Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 42c325ad8bcf8012 elected leader 42c325ad8bcf8012 at term 2"} {"level":"info","ts":"2026-04-13T21:06:31.673079Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"42c325ad8bcf8012","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.75:2379]}","request-path":"/0/members/42c325ad8bcf8012/attributes","cluster-id":"1e2ab336c559df5e","publish-timeout":"7s"} {"level":"info","ts":"2026-04-13T21:06:31.673236Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-13T21:06:31.673761Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T21:06:31.674401Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-13T21:06:31.674719Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-13T21:06:31.674821Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-13T21:06:31.677126Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"1e2ab336c559df5e","local-member-id":"42c325ad8bcf8012","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T21:06:31.677174Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-13T21:06:31.677371Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T21:06:31.67751Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T21:06:31.683576Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.75:2379"} {"level":"info","ts":"2026-04-13T21:06:33.232687Z","caller":"traceutil/trace.go:171","msg":"trace[1547819624] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"187.828561ms","start":"2026-04-13T21:06:33.044829Z","end":"2026-04-13T21:06:33.232658Z","steps":["trace[1547819624] 'process raft request' (duration: 152.151996ms)","trace[1547819624] 'compare' (duration: 35.337383ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:06:33.232721Z","caller":"traceutil/trace.go:171","msg":"trace[1122482366] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"186.61549ms","start":"2026-04-13T21:06:33.046076Z","end":"2026-04-13T21:06:33.232691Z","steps":["trace[1122482366] 'process raft request' (duration: 186.529717ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:06:33.232682Z","caller":"traceutil/trace.go:171","msg":"trace[261972768] transaction","detail":"{read_only:false; number_of_response:0; response_revision:14; }","duration":"186.943271ms","start":"2026-04-13T21:06:33.045699Z","end":"2026-04-13T21:06:33.232642Z","steps":["trace[261972768] 'process raft request' (duration: 186.838997ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:06:33.232779Z","caller":"traceutil/trace.go:171","msg":"trace[163881134] linearizableReadLoop","detail":"{readStateIndex:16; appliedIndex:14; }","duration":"187.864232ms","start":"2026-04-13T21:06:33.044895Z","end":"2026-04-13T21:06:33.23276Z","steps":["trace[163881134] 'read index received' (duration: 40.615865ms)","trace[163881134] 'applied index is now lower than readState.Index' (duration: 147.247467ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:06:33.232795Z","caller":"traceutil/trace.go:171","msg":"trace[915945311] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"186.663261ms","start":"2026-04-13T21:06:33.046107Z","end":"2026-04-13T21:06:33.23277Z","steps":["trace[915945311] 'process raft request' (duration: 186.555267ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:06:33.232802Z","caller":"traceutil/trace.go:171","msg":"trace[2006885465] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"186.720173ms","start":"2026-04-13T21:06:33.04602Z","end":"2026-04-13T21:06:33.232741Z","steps":["trace[2006885465] 'process raft request' (duration: 186.551087ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:06:33.232884Z","caller":"traceutil/trace.go:171","msg":"trace[2058302263] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"181.865755ms","start":"2026-04-13T21:06:33.051008Z","end":"2026-04-13T21:06:33.232874Z","steps":["trace[2058302263] 'process raft request' (duration: 181.70668ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:06:33.232917Z","caller":"traceutil/trace.go:171","msg":"trace[1660432034] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"186.832187ms","start":"2026-04-13T21:06:33.046075Z","end":"2026-04-13T21:06:33.232907Z","steps":["trace[1660432034] 'process raft request' (duration: 186.558377ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:06:33.232975Z","caller":"traceutil/trace.go:171","msg":"trace[1891186704] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"186.234426ms","start":"2026-04-13T21:06:33.046733Z","end":"2026-04-13T21:06:33.232968Z","steps":["trace[1891186704] 'process raft request' (duration: 185.950486ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:06:33.233116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.038029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-13T21:06:33.233156Z","caller":"traceutil/trace.go:171","msg":"trace[1797824194] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20; }","duration":"188.138623ms","start":"2026-04-13T21:06:33.045012Z","end":"2026-04-13T21:06:33.233151Z","steps":["trace[1797824194] 'agreement among raft nodes before linearized reading' (duration: 188.048159ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:06:33.233143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.223565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-7tfks\" ","response":"range_response_count:1 size:861"} {"level":"warn","ts":"2026-04-13T21:06:33.233214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.808816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-04-13T21:06:33.23322Z","caller":"traceutil/trace.go:171","msg":"trace[1559644532] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-7tfks; range_end:; response_count:1; response_revision:20; }","duration":"188.38144ms","start":"2026-04-13T21:06:33.044821Z","end":"2026-04-13T21:06:33.233203Z","steps":["trace[1559644532] 'agreement among raft nodes before linearized reading' (duration: 188.232565ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:06:33.233255Z","caller":"traceutil/trace.go:171","msg":"trace[1450737703] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:20; }","duration":"186.884039ms","start":"2026-04-13T21:06:33.046359Z","end":"2026-04-13T21:06:33.233244Z","steps":["trace[1450737703] 'agreement among raft nodes before linearized reading' (duration: 186.804916ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:06:33.233393Z","caller":"traceutil/trace.go:171","msg":"trace[1983326699] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"187.712907ms","start":"2026-04-13T21:06:33.045648Z","end":"2026-04-13T21:06:33.233361Z","steps":["trace[1983326699] 'process raft request' (duration: 186.813305ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:06:33.23319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.297631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-13T21:06:33.233683Z","caller":"traceutil/trace.go:171","msg":"trace[1917260533] range","detail":"{range_begin:/registry/resourcequotas/kube-public/; range_end:/registry/resourcequotas/kube-public0; response_count:0; response_revision:20; }","duration":"143.84865ms","start":"2026-04-13T21:06:33.089818Z","end":"2026-04-13T21:06:33.233667Z","steps":["trace[1917260533] 'agreement among raft nodes before linearized reading' (duration: 143.321431ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:06:33.402181Z","caller":"traceutil/trace.go:171","msg":"trace[350401253] transaction","detail":"{read_only:false; response_revision:22; number_of_response:1; }","duration":"164.916858ms","start":"2026-04-13T21:06:33.237232Z","end":"2026-04-13T21:06:33.402149Z","steps":["trace[350401253] 'process raft request' (duration: 140.551644ms)","trace[350401253] 'compare' (duration: 24.140316ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:06:33.402301Z","caller":"traceutil/trace.go:171","msg":"trace[695308199] transaction","detail":"{read_only:false; response_revision:23; number_of_response:1; }","duration":"164.870867ms","start":"2026-04-13T21:06:33.237401Z","end":"2026-04-13T21:06:33.402272Z","steps":["trace[695308199] 'process raft request' (duration: 164.66593ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:06:33.402414Z","caller":"traceutil/trace.go:171","msg":"trace[350376462] transaction","detail":"{read_only:false; response_revision:24; number_of_response:1; }","duration":"163.683236ms","start":"2026-04-13T21:06:33.23871Z","end":"2026-04-13T21:06:33.402393Z","steps":["trace[350376462] 'process raft request' (duration: 163.406057ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:06:33.402472Z","caller":"traceutil/trace.go:171","msg":"trace[2089186651] transaction","detail":"{read_only:false; response_revision:25; number_of_response:1; }","duration":"163.654725ms","start":"2026-04-13T21:06:33.238808Z","end":"2026-04-13T21:06:33.402463Z","steps":["trace[2089186651] 'process raft request' (duration: 163.345814ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:06:33.402427Z","caller":"traceutil/trace.go:171","msg":"trace[2085043501] transaction","detail":"{read_only:false; response_revision:26; number_of_response:1; }","duration":"163.356274ms","start":"2026-04-13T21:06:33.239039Z","end":"2026-04-13T21:06:33.402395Z","steps":["trace[2085043501] 'process raft request' (duration: 163.163118ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:06:33.402577Z","caller":"traceutil/trace.go:171","msg":"trace[1564234367] transaction","detail":"{read_only:false; response_revision:27; number_of_response:1; }","duration":"163.539921ms","start":"2026-04-13T21:06:33.239029Z","end":"2026-04-13T21:06:33.402568Z","steps":["trace[1564234367] 'process raft request' (duration: 163.235511ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:06:33.402357Z","caller":"traceutil/trace.go:171","msg":"trace[1696828163] linearizableReadLoop","detail":"{readStateIndex:28; appliedIndex:24; }","duration":"163.558122ms","start":"2026-04-13T21:06:33.238783Z","end":"2026-04-13T21:06:33.402341Z","steps":["trace[1696828163] 'read index received' (duration: 47.753234ms)","trace[1696828163] 'applied index is now lower than readState.Index' (duration: 115.803698ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:06:33.4026Z","caller":"traceutil/trace.go:171","msg":"trace[2117887104] transaction","detail":"{read_only:false; response_revision:28; number_of_response:1; }","duration":"163.51797ms","start":"2026-04-13T21:06:33.239067Z","end":"2026-04-13T21:06:33.402585Z","steps":["trace[2117887104] 'process raft request' (duration: 163.23748ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:06:33.402817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.013917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-13T21:06:33.402825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.974848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-13T21:06:33.40286Z","caller":"traceutil/trace.go:171","msg":"trace[1720926236] range","detail":"{range_begin:/registry/configmaps/kube-system/extension-apiserver-authentication; range_end:; response_count:0; response_revision:28; }","duration":"164.09143ms","start":"2026-04-13T21:06:33.238756Z","end":"2026-04-13T21:06:33.402847Z","steps":["trace[1720926236] 'agreement among raft nodes before linearized reading' (duration: 163.885863ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:06:33.402883Z","caller":"traceutil/trace.go:171","msg":"trace[1176435406] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:28; }","duration":"158.071852ms","start":"2026-04-13T21:06:33.244794Z","end":"2026-04-13T21:06:33.402866Z","steps":["trace[1176435406] 'agreement among raft nodes before linearized reading' (duration: 157.884695ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:06:35.401219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.732857ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-13T21:06:35.401454Z","caller":"traceutil/trace.go:171","msg":"trace[1635674861] transaction","detail":"{read_only:false; response_revision:174; number_of_response:1; }","duration":"236.531219ms","start":"2026-04-13T21:06:35.164785Z","end":"2026-04-13T21:06:35.401316Z","steps":["trace[1635674861] 'process raft request' (duration: 96.698575ms)","trace[1635674861] 'compare' (duration: 138.572541ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:06:35.409838Z","caller":"traceutil/trace.go:171","msg":"trace[153707671] transaction","detail":"{read_only:false; response_revision:175; number_of_response:1; }","duration":"243.61108ms","start":"2026-04-13T21:06:35.166212Z","end":"2026-04-13T21:06:35.409824Z","steps":["trace[153707671] 'process raft request' (duration: 243.496796ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:06:35.409918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.294925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:06:35.409957Z","caller":"traceutil/trace.go:171","msg":"trace[1679178566] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:175; }","duration":"173.377447ms","start":"2026-04-13T21:06:35.236567Z","end":"2026-04-13T21:06:35.409944Z","steps":["trace[1679178566] 'agreement among raft nodes before linearized reading' (duration: 173.295685ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:06:35.409823Z","caller":"traceutil/trace.go:171","msg":"trace[925639800] linearizableReadLoop","detail":"{readStateIndex:180; appliedIndex:178; }","duration":"173.19083ms","start":"2026-04-13T21:06:35.236608Z","end":"2026-04-13T21:06:35.409799Z","steps":["trace[925639800] 'read index received' (duration: 24.999811ms)","trace[925639800] 'applied index is now lower than readState.Index' (duration: 148.190339ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:06:35.987705Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9228611796742992293,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-13T21:06:36.055147Z","caller":"traceutil/trace.go:171","msg":"trace[1886314084] linearizableReadLoop","detail":"{readStateIndex:182; appliedIndex:181; }","duration":"567.601777ms","start":"2026-04-13T21:06:35.487508Z","end":"2026-04-13T21:06:36.055109Z","steps":["trace[1886314084] 'read index received' (duration: 519.019134ms)","trace[1886314084] 'applied index is now lower than readState.Index' (duration: 48.581233ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:06:36.055167Z","caller":"traceutil/trace.go:171","msg":"trace[120441895] transaction","detail":"{read_only:false; response_revision:177; number_of_response:1; }","duration":"640.309323ms","start":"2026-04-13T21:06:35.414824Z","end":"2026-04-13T21:06:36.055134Z","steps":["trace[120441895] 'process raft request' (duration: 591.762292ms)","trace[120441895] 'compare' (duration: 48.384717ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:06:36.056677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.956671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-04-13T21:06:36.0567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.268879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:namespace-controller\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T21:06:36.056751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.995593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-13T21:06:36.056747Z","caller":"traceutil/trace.go:171","msg":"trace[1385096218] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:177; }","duration":"365.071076ms","start":"2026-04-13T21:06:35.691663Z","end":"2026-04-13T21:06:36.056734Z","steps":["trace[1385096218] 'agreement among raft nodes before linearized reading' (duration: 364.930231ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:06:36.056801Z","caller":"traceutil/trace.go:171","msg":"trace[2132457276] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:177; }","duration":"365.093306ms","start":"2026-04-13T21:06:35.691692Z","end":"2026-04-13T21:06:36.056786Z","steps":["trace[2132457276] 'agreement among raft nodes before linearized reading' (duration: 364.996444ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:06:36.056804Z","caller":"traceutil/trace.go:171","msg":"trace[1273345229] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:namespace-controller; range_end:; response_count:0; response_revision:177; }","duration":"569.316655ms","start":"2026-04-13T21:06:35.487455Z","end":"2026-04-13T21:06:36.056771Z","steps":["trace[1273345229] 'agreement among raft nodes before linearized reading' (duration: 567.800413ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:06:36.056852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:06:35.691622Z","time spent":"365.21255ms","remote":"127.0.0.1:36706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-13T21:06:36.056846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:06:35.691655Z","time spent":"365.182839ms","remote":"127.0.0.1:36706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-13T21:06:36.05692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:06:35.48743Z","time spent":"569.452768ms","remote":"127.0.0.1:36800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":27,"request content":"key:\"/registry/clusterrolebindings/system:controller:namespace-controller\" "} {"level":"warn","ts":"2026-04-13T21:06:36.057642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:06:35.414802Z","time spent":"640.431749ms","remote":"127.0.0.1:48252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":641,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T21:06:36.228599Z","caller":"traceutil/trace.go:171","msg":"trace[485698770] transaction","detail":"{read_only:false; response_revision:185; number_of_response:1; }","duration":"122.73414ms","start":"2026-04-13T21:06:36.105836Z","end":"2026-04-13T21:06:36.22857Z","steps":["trace[485698770] 'process raft request' (duration: 59.561634ms)","trace[485698770] 'compare' (duration: 62.978339ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:06:36.570887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.773166ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-13T21:06:36.571004Z","caller":"traceutil/trace.go:171","msg":"trace[1018334560] transaction","detail":"{read_only:false; response_revision:210; number_of_response:1; }","duration":"113.549969ms","start":"2026-04-13T21:06:36.457434Z","end":"2026-04-13T21:06:36.570984Z","steps":["trace[1018334560] 'compare' (duration: 105.637521ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:06:36.572939Z","caller":"traceutil/trace.go:171","msg":"trace[1762515008] transaction","detail":"{read_only:false; response_revision:211; number_of_response:1; }","duration":"114.727819ms","start":"2026-04-13T21:06:36.458187Z","end":"2026-04-13T21:06:36.572914Z","steps":["trace[1762515008] 'process raft request' (duration: 114.668897ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:06:36.594192Z","caller":"traceutil/trace.go:171","msg":"trace[923031821] transaction","detail":"{read_only:false; response_revision:212; number_of_response:1; }","duration":"114.283003ms","start":"2026-04-13T21:06:36.479884Z","end":"2026-04-13T21:06:36.594167Z","steps":["trace[923031821] 'process raft request' (duration: 113.962702ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:06:36.750357Z","caller":"traceutil/trace.go:171","msg":"trace[1331526507] transaction","detail":"{read_only:false; response_revision:216; number_of_response:1; }","duration":"133.611577ms","start":"2026-04-13T21:06:36.616704Z","end":"2026-04-13T21:06:36.750316Z","steps":["trace[1331526507] 'process raft request' (duration: 60.890728ms)","trace[1331526507] 'compare' (duration: 72.45775ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:06:36.750344Z","caller":"traceutil/trace.go:171","msg":"trace[1318112326] transaction","detail":"{read_only:false; response_revision:217; number_of_response:1; }","duration":"130.926617ms","start":"2026-04-13T21:06:36.619384Z","end":"2026-04-13T21:06:36.750311Z","steps":["trace[1318112326] 'process raft request' (duration: 130.871655ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:06:36.979091Z","caller":"traceutil/trace.go:171","msg":"trace[1471792126] linearizableReadLoop","detail":"{readStateIndex:235; appliedIndex:234; }","duration":"135.975506ms","start":"2026-04-13T21:06:36.843082Z","end":"2026-04-13T21:06:36.979057Z","steps":["trace[1471792126] 'read index received' (duration: 48.83289ms)","trace[1471792126] 'applied index is now lower than readState.Index' (duration: 87.141286ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:06:36.979171Z","caller":"traceutil/trace.go:171","msg":"trace[86994436] transaction","detail":"{read_only:false; response_revision:230; number_of_response:1; }","duration":"146.12657ms","start":"2026-04-13T21:06:36.833014Z","end":"2026-04-13T21:06:36.979141Z","steps":["trace[86994436] 'process raft request' (duration: 58.980724ms)","trace[86994436] 'compare' (duration: 86.872967ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:06:36.979258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.148753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-system/system::leader-locking-kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:06:36.979303Z","caller":"traceutil/trace.go:171","msg":"trace[172268909] range","detail":"{range_begin:/registry/roles/kube-system/system::leader-locking-kube-controller-manager; range_end:; response_count:0; response_revision:230; }","duration":"136.236576ms","start":"2026-04-13T21:06:36.843053Z","end":"2026-04-13T21:06:36.97929Z","steps":["trace[172268909] 'agreement among raft nodes before linearized reading' (duration: 136.123602ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:06:38.248616Z","caller":"traceutil/trace.go:171","msg":"trace[1535240123] transaction","detail":"{read_only:false; response_revision:267; number_of_response:1; }","duration":"123.186792ms","start":"2026-04-13T21:06:38.125399Z","end":"2026-04-13T21:06:38.248586Z","steps":["trace[1535240123] 'process raft request' (duration: 34.447347ms)","trace[1535240123] 'compare' (duration: 88.58836ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:06:39.847047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.401536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-13T21:06:39.847133Z","caller":"traceutil/trace.go:171","msg":"trace[1802226371] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:315; }","duration":"277.533031ms","start":"2026-04-13T21:06:39.569582Z","end":"2026-04-13T21:06:39.847115Z","steps":["trace[1802226371] 'range keys from in-memory index tree' (duration: 277.311683ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:06:39.84709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.631308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-13T21:06:39.847343Z","caller":"traceutil/trace.go:171","msg":"trace[1911603940] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:315; }","duration":"288.950289ms","start":"2026-04-13T21:06:39.55837Z","end":"2026-04-13T21:06:39.84732Z","steps":["trace[1911603940] 'range keys from in-memory index tree' (duration: 288.524184ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:06:44.320613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.928779ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-13T21:06:44.320733Z","caller":"traceutil/trace.go:171","msg":"trace[627258907] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"831.995627ms","start":"2026-04-13T21:06:43.48872Z","end":"2026-04-13T21:06:44.320715Z","steps":["trace[627258907] 'process raft request' (duration: 122.897232ms)","trace[627258907] 'compare' (duration: 708.714172ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:06:44.320792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:06:43.488704Z","time spent":"832.066989ms","remote":"127.0.0.1:48252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":703,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T21:06:44.32118Z","caller":"traceutil/trace.go:171","msg":"trace[206569404] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"831.737238ms","start":"2026-04-13T21:06:43.489432Z","end":"2026-04-13T21:06:44.321169Z","steps":["trace[206569404] 'process raft request' (duration: 831.409718ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:06:44.32124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:06:43.489416Z","time spent":"831.798689ms","remote":"127.0.0.1:48252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":829,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T21:06:44.32136Z","caller":"traceutil/trace.go:171","msg":"trace[857880854] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"831.714407ms","start":"2026-04-13T21:06:43.489597Z","end":"2026-04-13T21:06:44.321311Z","steps":["trace[857880854] 'process raft request' (duration: 831.48363ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:06:44.321417Z","caller":"traceutil/trace.go:171","msg":"trace[16416425] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"825.455538ms","start":"2026-04-13T21:06:43.495913Z","end":"2026-04-13T21:06:44.321368Z","steps":["trace[16416425] 'process raft request' (duration: 825.254671ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:06:44.321632Z","caller":"traceutil/trace.go:171","msg":"trace[1941767840] linearizableReadLoop","detail":"{readStateIndex:398; appliedIndex:394; }","duration":"454.31352ms","start":"2026-04-13T21:06:43.867296Z","end":"2026-04-13T21:06:44.321609Z","steps":["trace[1941767840] 'read index received' (duration: 453.427352ms)","trace[1941767840] 'applied index is now lower than readState.Index' (duration: 884.438µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:06:44.321674Z","caller":"traceutil/trace.go:171","msg":"trace[35681693] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"755.127091ms","start":"2026-04-13T21:06:43.56653Z","end":"2026-04-13T21:06:44.321658Z","steps":["trace[35681693] 'process raft request' (duration: 754.748088ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:06:44.32174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:06:43.495906Z","time spent":"825.658354ms","remote":"127.0.0.1:36624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4001,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T21:06:44.321804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.501797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-13T21:06:44.321838Z","caller":"traceutil/trace.go:171","msg":"trace[678099513] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:384; }","duration":"454.547418ms","start":"2026-04-13T21:06:43.867281Z","end":"2026-04-13T21:06:44.321828Z","steps":["trace[678099513] 'agreement among raft nodes before linearized reading' (duration: 454.449355ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:06:44.321838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:06:43.566505Z","time spent":"755.216162ms","remote":"127.0.0.1:36706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T21:06:44.321879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:06:43.867226Z","time spent":"454.645742ms","remote":"127.0.0.1:36706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-13T21:06:44.321923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.678553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-13T21:06:44.322041Z","caller":"traceutil/trace.go:171","msg":"trace[798878443] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:384; }","duration":"201.789267ms","start":"2026-04-13T21:06:44.120205Z","end":"2026-04-13T21:06:44.321995Z","steps":["trace[798878443] 'agreement among raft nodes before linearized reading' (duration: 201.627561ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:06:44.322064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:06:43.489592Z","time spent":"832.444751ms","remote":"127.0.0.1:36624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3527,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T21:06:44.322041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.636246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-13T21:06:44.322181Z","caller":"traceutil/trace.go:171","msg":"trace[1624764116] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:384; }","duration":"209.864423ms","start":"2026-04-13T21:06:44.112296Z","end":"2026-04-13T21:06:44.322161Z","steps":["trace[1624764116] 'agreement among raft nodes before linearized reading' (duration: 209.475892ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:06:44.524916Z","caller":"traceutil/trace.go:171","msg":"trace[963227040] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"197.854392ms","start":"2026-04-13T21:06:44.327037Z","end":"2026-04-13T21:06:44.524892Z","steps":["trace[963227040] 'process raft request' (duration: 105.275693ms)","trace[963227040] 'compare' (duration: 92.365372ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:06:44.524914Z","caller":"traceutil/trace.go:171","msg":"trace[314190476] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"197.255642ms","start":"2026-04-13T21:06:44.327639Z","end":"2026-04-13T21:06:44.524894Z","steps":["trace[314190476] 'process raft request' (duration: 197.18358ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:06:44.525266Z","caller":"traceutil/trace.go:171","msg":"trace[2107225627] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"197.282543ms","start":"2026-04-13T21:06:44.327976Z","end":"2026-04-13T21:06:44.525259Z","steps":["trace[2107225627] 'process raft request' (duration: 196.88432ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:06:44.525468Z","caller":"traceutil/trace.go:171","msg":"trace[1626870066] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"192.618475ms","start":"2026-04-13T21:06:44.332837Z","end":"2026-04-13T21:06:44.525456Z","steps":["trace[1626870066] 'process raft request' (duration: 192.291994ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:06:44.525492Z","caller":"traceutil/trace.go:171","msg":"trace[1555291970] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"192.058986ms","start":"2026-04-13T21:06:44.333414Z","end":"2026-04-13T21:06:44.525473Z","steps":["trace[1555291970] 'process raft request' (duration: 191.749556ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:06:44.525568Z","caller":"traceutil/trace.go:171","msg":"trace[1568547299] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"193.715599ms","start":"2026-04-13T21:06:44.331826Z","end":"2026-04-13T21:06:44.525542Z","steps":["trace[1568547299] 'process raft request' (duration: 193.252893ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:06:44.525234Z","caller":"traceutil/trace.go:171","msg":"trace[384420295] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"189.832896ms","start":"2026-04-13T21:06:44.335391Z","end":"2026-04-13T21:06:44.525224Z","steps":["trace[384420295] 'process raft request' (duration: 189.800884ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:06:44.851255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.406607ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T21:06:44.85144Z","caller":"traceutil/trace.go:171","msg":"trace[800481908] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"319.748263ms","start":"2026-04-13T21:06:44.531663Z","end":"2026-04-13T21:06:44.851411Z","steps":["trace[800481908] 'process raft request' (duration: 214.042136ms)","trace[800481908] 'compare' (duration: 105.219071ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:06:44.85162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:06:44.531644Z","time spent":"319.862566ms","remote":"127.0.0.1:36910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3804,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T21:06:45.682758Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9228611796742992735,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T21:06:46.687809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.004882569s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T21:06:46.687887Z","caller":"traceutil/trace.go:171","msg":"trace[1333076694] linearizableReadLoop","detail":"{readStateIndex:407; appliedIndex:406; }","duration":"1.505685044s","start":"2026-04-13T21:06:45.18219Z","end":"2026-04-13T21:06:46.687875Z","steps":["trace[1333076694] 'read index received' (duration: 500.64888ms)","trace[1333076694] 'applied index is now lower than readState.Index' (duration: 1.005035384s)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:06:46.687914Z","caller":"etcdserver/v3_server.go:874","msg":"ignored out-of-date read index response; local node read indexes queueing up and waiting to be in sync with leader","sent-request-id":9228611796742992745,"received-request-id":9228611796742992735} {"level":"info","ts":"2026-04-13T21:06:46.687979Z","caller":"traceutil/trace.go:171","msg":"trace[1304901165] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"1.829485905s","start":"2026-04-13T21:06:44.858486Z","end":"2026-04-13T21:06:46.687972Z","steps":["trace[1304901165] 'process raft request' (duration: 824.383419ms)","trace[1304901165] 'compare' (duration: 1.004771816s)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:06:46.688014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:06:44.858459Z","time spent":"1.829536837s","remote":"127.0.0.1:36880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4041,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T21:06:46.688125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.505950082s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-13T21:06:46.688143Z","caller":"traceutil/trace.go:171","msg":"trace[1645680729] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:393; }","duration":"1.506003134s","start":"2026-04-13T21:06:45.182133Z","end":"2026-04-13T21:06:46.688136Z","steps":["trace[1645680729] 'agreement among raft nodes before linearized reading' (duration: 1.505957663s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:06:46.688159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:06:45.182104Z","time spent":"1.506051826s","remote":"127.0.0.1:36706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-13T21:06:46.688313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.489638706s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T21:06:46.688382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.408492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-13T21:06:46.688402Z","caller":"traceutil/trace.go:171","msg":"trace[1390214096] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:393; }","duration":"157.441473ms","start":"2026-04-13T21:06:46.530955Z","end":"2026-04-13T21:06:46.688397Z","steps":["trace[1390214096] 'agreement among raft nodes before linearized reading' (duration: 157.403601ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:06:46.688405Z","caller":"traceutil/trace.go:171","msg":"trace[1731476700] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:393; }","duration":"1.489747079s","start":"2026-04-13T21:06:45.198643Z","end":"2026-04-13T21:06:46.688391Z","steps":["trace[1731476700] 'agreement among raft nodes before linearized reading' (duration: 1.489610754s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:06:46.688389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.430493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-13T21:06:46.688447Z","caller":"traceutil/trace.go:171","msg":"trace[1311171744] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:393; }","duration":"157.515384ms","start":"2026-04-13T21:06:46.530918Z","end":"2026-04-13T21:06:46.688433Z","steps":["trace[1311171744] 'agreement among raft nodes before linearized reading' (duration: 157.415341ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:06:46.688561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.168597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-13T21:06:46.688581Z","caller":"traceutil/trace.go:171","msg":"trace[568172799] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:393; }","duration":"159.213419ms","start":"2026-04-13T21:06:46.529361Z","end":"2026-04-13T21:06:46.688575Z","steps":["trace[568172799] 'agreement among raft nodes before linearized reading' (duration: 159.181718ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:06:47.640352Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9228611796742992747,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T21:06:48.141335Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9228611796742992747,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T21:06:48.189572Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.087389576s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-13T21:06:48.291881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.076392382s","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-04-13T21:06:48.292001Z","caller":"traceutil/trace.go:171","msg":"trace[1458535635] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:393; }","duration":"1.076524135s","start":"2026-04-13T21:06:47.215459Z","end":"2026-04-13T21:06:48.291983Z","steps":["trace[1458535635] 'range keys from in-memory index tree' (duration: 1.07637611s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:06:48.291993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.1898841s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T21:06:48.292314Z","caller":"traceutil/trace.go:171","msg":"trace[502160764] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"1.599341961s","start":"2026-04-13T21:06:46.692921Z","end":"2026-04-13T21:06:48.292263Z","steps":["trace[502160764] 'process raft request' (duration: 1.599250889s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:06:48.292393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:06:46.692906Z","time spent":"1.599447113s","remote":"127.0.0.1:36706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T21:06:48.292474Z","caller":"traceutil/trace.go:171","msg":"trace[1268093183] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"1.600018432s","start":"2026-04-13T21:06:46.692411Z","end":"2026-04-13T21:06:48.292429Z","steps":["trace[1268093183] 'process raft request' (duration: 409.620948ms)","trace[1268093183] 'compare' (duration: 1.189483925s)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:06:48.29267Z","caller":"traceutil/trace.go:171","msg":"trace[376357855] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"1.599793195s","start":"2026-04-13T21:06:46.69286Z","end":"2026-04-13T21:06:48.292653Z","steps":["trace[376357855] 'process raft request' (duration: 1.599216517s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:06:48.292697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:06:46.692399Z","time spent":"1.600193658s","remote":"127.0.0.1:36706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T21:06:48.292765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:06:46.692852Z","time spent":"1.599863098s","remote":"127.0.0.1:36706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T21:06:48.293188Z","caller":"traceutil/trace.go:171","msg":"trace[415208461] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"1.600126595s","start":"2026-04-13T21:06:46.693047Z","end":"2026-04-13T21:06:48.293173Z","steps":["trace[415208461] 'process raft request' (duration: 1.599174726s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:06:48.293267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:06:46.693033Z","time spent":"1.600187957s","remote":"127.0.0.1:36706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T21:06:48.293278Z","caller":"traceutil/trace.go:171","msg":"trace[1593487861] linearizableReadLoop","detail":"{readStateIndex:412; appliedIndex:407; }","duration":"1.153731902s","start":"2026-04-13T21:06:47.139531Z","end":"2026-04-13T21:06:48.293262Z","steps":["trace[1593487861] 'read index received' (duration: 1.05029496s)","trace[1593487861] 'applied index is now lower than readState.Index' (duration: 103.435772ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:06:48.293397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.153861596s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.75\" ","response":"range_response_count:1 size:132"} {"level":"info","ts":"2026-04-13T21:06:48.293429Z","caller":"traceutil/trace.go:171","msg":"trace[1448680801] range","detail":"{range_begin:/registry/masterleases/199.19.213.75; range_end:; response_count:1; response_revision:398; }","duration":"1.153934759s","start":"2026-04-13T21:06:47.139485Z","end":"2026-04-13T21:06:48.29342Z","steps":["trace[1448680801] 'agreement among raft nodes before linearized reading' (duration: 1.153830115s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:06:48.29346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:06:47.139464Z","time spent":"1.153989399s","remote":"127.0.0.1:48202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":154,"request content":"key:\"/registry/masterleases/199.19.213.75\" "} {"level":"info","ts":"2026-04-13T21:06:48.29368Z","caller":"traceutil/trace.go:171","msg":"trace[50376647] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"1.595789739s","start":"2026-04-13T21:06:46.697878Z","end":"2026-04-13T21:06:48.293668Z","steps":["trace[50376647] 'process raft request' (duration: 1.595078828s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:06:48.293745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:06:46.697868Z","time spent":"1.595843811s","remote":"127.0.0.1:36624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5311,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T21:07:02.703261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.833808ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T21:07:02.703495Z","caller":"traceutil/trace.go:171","msg":"trace[529425288] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"218.698544ms","start":"2026-04-13T21:07:02.484775Z","end":"2026-04-13T21:07:02.703473Z","steps":["trace[529425288] 'process raft request' (duration: 78.530137ms)","trace[529425288] 'compare' (duration: 139.682964ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:07:02.703521Z","caller":"traceutil/trace.go:171","msg":"trace[1007019710] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"218.449889ms","start":"2026-04-13T21:07:02.485047Z","end":"2026-04-13T21:07:02.703497Z","steps":["trace[1007019710] 'process raft request' (duration: 218.401847ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:07:02.703693Z","caller":"traceutil/trace.go:171","msg":"trace[1189649756] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"218.787077ms","start":"2026-04-13T21:07:02.484897Z","end":"2026-04-13T21:07:02.703685Z","steps":["trace[1189649756] 'process raft request' (duration: 218.48378ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:07:10.920199Z","caller":"traceutil/trace.go:171","msg":"trace[1886903987] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"615.434766ms","start":"2026-04-13T21:07:10.304735Z","end":"2026-04-13T21:07:10.92017Z","steps":["trace[1886903987] 'process raft request' (duration: 615.264202ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:07:10.920378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:07:10.304719Z","time spent":"615.557148ms","remote":"127.0.0.1:48206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T21:07:10.938673Z","caller":"traceutil/trace.go:171","msg":"trace[143527332] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"626.556056ms","start":"2026-04-13T21:07:10.312089Z","end":"2026-04-13T21:07:10.938645Z","steps":["trace[143527332] 'process raft request' (duration: 626.32355ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:07:10.938809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:07:10.312076Z","time spent":"626.672789ms","remote":"127.0.0.1:48206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T21:07:10.939079Z","caller":"traceutil/trace.go:171","msg":"trace[840069870] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"617.728668ms","start":"2026-04-13T21:07:10.321338Z","end":"2026-04-13T21:07:10.939067Z","steps":["trace[840069870] 'process raft request' (duration: 617.293267ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:07:10.939132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:07:10.321311Z","time spent":"617.798389ms","remote":"127.0.0.1:48206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T21:07:10.939254Z","caller":"traceutil/trace.go:171","msg":"trace[1261958242] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"616.460225ms","start":"2026-04-13T21:07:10.322781Z","end":"2026-04-13T21:07:10.939241Z","steps":["trace[1261958242] 'process raft request' (duration: 615.886938ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:07:10.939296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:07:10.322772Z","time spent":"616.507125ms","remote":"127.0.0.1:48206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T21:07:10.939447Z","caller":"traceutil/trace.go:171","msg":"trace[1053428212] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"627.246704ms","start":"2026-04-13T21:07:10.312179Z","end":"2026-04-13T21:07:10.939426Z","steps":["trace[1053428212] 'process raft request' (duration: 626.33718ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:07:10.939493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:07:10.312173Z","time spent":"627.297616ms","remote":"127.0.0.1:48206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T21:07:10.939582Z","caller":"traceutil/trace.go:171","msg":"trace[1399710379] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"618.602301ms","start":"2026-04-13T21:07:10.320972Z","end":"2026-04-13T21:07:10.939575Z","steps":["trace[1399710379] 'process raft request' (duration: 617.583803ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:07:10.939627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:07:10.320952Z","time spent":"618.656864ms","remote":"127.0.0.1:48206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T21:07:10.939887Z","caller":"traceutil/trace.go:171","msg":"trace[1690498817] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"614.275865ms","start":"2026-04-13T21:07:10.325595Z","end":"2026-04-13T21:07:10.939871Z","steps":["trace[1690498817] 'process raft request' (duration: 613.126934ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:07:10.939953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:07:10.325562Z","time spent":"614.364998ms","remote":"127.0.0.1:48206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T21:07:10.940038Z","caller":"traceutil/trace.go:171","msg":"trace[1729265583] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"603.369942ms","start":"2026-04-13T21:07:10.336653Z","end":"2026-04-13T21:07:10.940023Z","steps":["trace[1729265583] 'process raft request' (duration: 603.142086ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:07:10.940133Z","caller":"traceutil/trace.go:171","msg":"trace[538361612] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"602.821887ms","start":"2026-04-13T21:07:10.337302Z","end":"2026-04-13T21:07:10.940124Z","steps":["trace[538361612] 'process raft request' (duration: 602.588491ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:07:10.940153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:07:10.336624Z","time spent":"603.449473ms","remote":"127.0.0.1:48206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T21:07:10.940188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:07:10.337273Z","time spent":"602.892369ms","remote":"127.0.0.1:48206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T21:07:10.940461Z","caller":"traceutil/trace.go:171","msg":"trace[1071631198] linearizableReadLoop","detail":"{readStateIndex:555; appliedIndex:543; }","duration":"427.062974ms","start":"2026-04-13T21:07:10.513386Z","end":"2026-04-13T21:07:10.940449Z","steps":["trace[1071631198] 'read index received' (duration: 406.753796ms)","trace[1071631198] 'applied index is now lower than readState.Index' (duration: 20.308508ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:07:10.940627Z","caller":"traceutil/trace.go:171","msg":"trace[1013431535] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"463.266937ms","start":"2026-04-13T21:07:10.477352Z","end":"2026-04-13T21:07:10.940619Z","steps":["trace[1013431535] 'process raft request' (duration: 462.99066ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:07:10.940671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:07:10.477332Z","time spent":"463.318039ms","remote":"127.0.0.1:48206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T21:07:10.940929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.547126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-13T21:07:10.940961Z","caller":"traceutil/trace.go:171","msg":"trace[611054353] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:534; }","duration":"427.581597ms","start":"2026-04-13T21:07:10.513372Z","end":"2026-04-13T21:07:10.940953Z","steps":["trace[611054353] 'agreement among raft nodes before linearized reading' (duration: 427.500895ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:07:10.940541Z","caller":"traceutil/trace.go:171","msg":"trace[1024560945] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"580.447884ms","start":"2026-04-13T21:07:10.360085Z","end":"2026-04-13T21:07:10.940533Z","steps":["trace[1024560945] 'process raft request' (duration: 579.849218ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:07:10.941104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:07:10.360057Z","time spent":"580.993908ms","remote":"127.0.0.1:48206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T21:07:10.94098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:07:10.513331Z","time spent":"427.644889ms","remote":"127.0.0.1:36706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-13T21:07:10.939083Z","caller":"traceutil/trace.go:171","msg":"trace[1716202605] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"617.857901ms","start":"2026-04-13T21:07:10.321184Z","end":"2026-04-13T21:07:10.939042Z","steps":["trace[1716202605] 'process raft request' (duration: 617.412599ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:07:10.941636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:07:10.321163Z","time spent":"620.39187ms","remote":"127.0.0.1:48206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T21:07:10.952422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.42689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-13T21:07:10.95249Z","caller":"traceutil/trace.go:171","msg":"trace[932037161] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:536; }","duration":"203.529552ms","start":"2026-04-13T21:07:10.748936Z","end":"2026-04-13T21:07:10.952465Z","steps":["trace[932037161] 'agreement among raft nodes before linearized reading' (duration: 203.387598ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:07:10.95265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.97882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-13T21:07:10.952668Z","caller":"traceutil/trace.go:171","msg":"trace[473087748] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:536; }","duration":"202.027581ms","start":"2026-04-13T21:07:10.750636Z","end":"2026-04-13T21:07:10.952664Z","steps":["trace[473087748] 'agreement among raft nodes before linearized reading' (duration: 201.979861ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:07:10.952765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.477194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-13T21:07:10.953261Z","caller":"traceutil/trace.go:171","msg":"trace[698998663] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:536; }","duration":"202.559276ms","start":"2026-04-13T21:07:10.75025Z","end":"2026-04-13T21:07:10.952809Z","steps":["trace[698998663] 'agreement among raft nodes before linearized reading' (duration: 202.409791ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:07:11.452868Z","caller":"traceutil/trace.go:171","msg":"trace[254399517] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"160.137568ms","start":"2026-04-13T21:07:11.292701Z","end":"2026-04-13T21:07:11.452839Z","steps":["trace[254399517] 'process raft request' (duration: 159.949553ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:07:11.598125Z","caller":"traceutil/trace.go:171","msg":"trace[1204777565] linearizableReadLoop","detail":"{readStateIndex:601; appliedIndex:599; }","duration":"105.158506ms","start":"2026-04-13T21:07:11.49295Z","end":"2026-04-13T21:07:11.598109Z","steps":["trace[1204777565] 'read index received' (duration: 98.58426ms)","trace[1204777565] 'applied index is now lower than readState.Index' (duration: 6.573366ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:07:11.598238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.279229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:07:11.598263Z","caller":"traceutil/trace.go:171","msg":"trace[405350133] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:579; }","duration":"105.349952ms","start":"2026-04-13T21:07:11.492904Z","end":"2026-04-13T21:07:11.598254Z","steps":["trace[405350133] 'agreement among raft nodes before linearized reading' (duration: 105.277639ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:07:11.598224Z","caller":"traceutil/trace.go:171","msg":"trace[892579717] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"285.827375ms","start":"2026-04-13T21:07:11.31236Z","end":"2026-04-13T21:07:11.598188Z","steps":["trace[892579717] 'process raft request' (duration: 279.330811ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:07:11.598457Z","caller":"traceutil/trace.go:171","msg":"trace[1342031513] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"244.013756ms","start":"2026-04-13T21:07:11.354436Z","end":"2026-04-13T21:07:11.598449Z","steps":["trace[1342031513] 'process raft request' (duration: 243.568474ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:07:16.262278Z","caller":"traceutil/trace.go:171","msg":"trace[150574487] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"169.713332ms","start":"2026-04-13T21:07:16.092545Z","end":"2026-04-13T21:07:16.262259Z","steps":["trace[150574487] 'process raft request' (duration: 169.210299ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:07:17.153099Z","caller":"traceutil/trace.go:171","msg":"trace[959750934] linearizableReadLoop","detail":"{readStateIndex:646; appliedIndex:645; }","duration":"149.142476ms","start":"2026-04-13T21:07:17.003931Z","end":"2026-04-13T21:07:17.153074Z","steps":["trace[959750934] 'read index received' (duration: 148.225253ms)","trace[959750934] 'applied index is now lower than readState.Index' (duration: 916.403µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:07:17.153186Z","caller":"traceutil/trace.go:171","msg":"trace[1198205625] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"379.013886ms","start":"2026-04-13T21:07:16.774148Z","end":"2026-04-13T21:07:17.153162Z","steps":["trace[1198205625] 'process raft request' (duration: 378.179444ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:07:17.153311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:07:16.774131Z","time spent":"379.097099ms","remote":"127.0.0.1:48268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036261,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T21:07:17.15346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.030001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-13T21:07:17.153537Z","caller":"traceutil/trace.go:171","msg":"trace[832971988] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:623; }","duration":"140.121102ms","start":"2026-04-13T21:07:17.013389Z","end":"2026-04-13T21:07:17.153511Z","steps":["trace[832971988] 'agreement among raft nodes before linearized reading' (duration: 139.913966ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:07:17.153818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.454541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-13T21:07:17.153861Z","caller":"traceutil/trace.go:171","msg":"trace[1768918369] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:623; }","duration":"140.521752ms","start":"2026-04-13T21:07:17.013323Z","end":"2026-04-13T21:07:17.153845Z","steps":["trace[1768918369] 'agreement among raft nodes before linearized reading' (duration: 140.404829ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:07:17.15423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.193404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-13T21:07:17.154306Z","caller":"traceutil/trace.go:171","msg":"trace[857184481] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:623; }","duration":"145.257925ms","start":"2026-04-13T21:07:17.009023Z","end":"2026-04-13T21:07:17.154281Z","steps":["trace[857184481] 'agreement among raft nodes before linearized reading' (duration: 144.218158ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:07:17.154415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.505115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-13T21:07:17.154581Z","caller":"traceutil/trace.go:171","msg":"trace[1644704454] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:623; }","duration":"150.616843ms","start":"2026-04-13T21:07:17.003888Z","end":"2026-04-13T21:07:17.154504Z","steps":["trace[1644704454] 'agreement among raft nodes before linearized reading' (duration: 149.31875ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:07:18.449776Z","caller":"traceutil/trace.go:171","msg":"trace[2009154909] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"812.0927ms","start":"2026-04-13T21:07:17.637648Z","end":"2026-04-13T21:07:18.449741Z","steps":["trace[2009154909] 'process raft request' (duration: 809.461292ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:07:18.449919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:07:17.637613Z","time spent":"812.229803ms","remote":"127.0.0.1:48268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T21:07:19.66825Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9228611796742993263,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T21:07:19.81692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.324045931s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:07:19.817006Z","caller":"traceutil/trace.go:171","msg":"trace[1276436211] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:634; }","duration":"1.324173305s","start":"2026-04-13T21:07:18.492813Z","end":"2026-04-13T21:07:19.816987Z","steps":["trace[1276436211] 'range keys from in-memory index tree' (duration: 1.32397907s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:07:19.817047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:07:18.4928Z","time spent":"1.324236986s","remote":"127.0.0.1:48168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-13T21:07:20.169475Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9228611796742993263,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T21:07:20.670588Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9228611796742993263,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T21:07:21.032402Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.51967682s","expected-duration":"1s"} {"level":"info","ts":"2026-04-13T21:07:21.03295Z","caller":"traceutil/trace.go:171","msg":"trace[2075330057] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"2.520276065s","start":"2026-04-13T21:07:18.512647Z","end":"2026-04-13T21:07:21.032923Z","steps":["trace[2075330057] 'process raft request' (duration: 2.52009462s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:07:21.033211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:07:18.512636Z","time spent":"2.52041861s","remote":"127.0.0.1:36932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T21:07:21.479237Z","caller":"traceutil/trace.go:171","msg":"trace[785056417] linearizableReadLoop","detail":"{readStateIndex:660; appliedIndex:658; }","duration":"2.311325023s","start":"2026-04-13T21:07:19.167886Z","end":"2026-04-13T21:07:21.479211Z","steps":["trace[785056417] 'read index received' (duration: 1.864896878s)","trace[785056417] 'applied index is now lower than readState.Index' (duration: 446.426795ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:07:21.479516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.31160232s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-13T21:07:21.479576Z","caller":"traceutil/trace.go:171","msg":"trace[541072378] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:636; }","duration":"2.311709403s","start":"2026-04-13T21:07:19.167849Z","end":"2026-04-13T21:07:21.479558Z","steps":["trace[541072378] 'agreement among raft nodes before linearized reading' (duration: 2.311511608s)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:07:21.479396Z","caller":"traceutil/trace.go:171","msg":"trace[1088102507] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"1.105627353s","start":"2026-04-13T21:07:20.373666Z","end":"2026-04-13T21:07:21.479294Z","steps":["trace[1088102507] 'process raft request' (duration: 1.089240278s)","trace[1088102507] 'compare' (duration: 16.15436ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:07:21.479538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.661225216s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T21:07:21.479683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.683221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T21:07:21.479719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.305243806s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-04-13T21:07:21.479755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.310795589s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-13T21:07:21.479781Z","caller":"traceutil/trace.go:171","msg":"trace[2035582257] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:636; }","duration":"2.310848591s","start":"2026-04-13T21:07:19.168924Z","end":"2026-04-13T21:07:21.479772Z","steps":["trace[2035582257] 'agreement among raft nodes before linearized reading' (duration: 2.310801299s)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:07:21.479796Z","caller":"traceutil/trace.go:171","msg":"trace[1197634003] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:636; }","duration":"2.305321438s","start":"2026-04-13T21:07:19.174447Z","end":"2026-04-13T21:07:21.479769Z","steps":["trace[1197634003] 'agreement among raft nodes before linearized reading' (duration: 2.305233796s)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:07:21.479752Z","caller":"traceutil/trace.go:171","msg":"trace[128075127] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:636; }","duration":"1.661461562s","start":"2026-04-13T21:07:19.818243Z","end":"2026-04-13T21:07:21.479704Z","steps":["trace[128075127] 'agreement among raft nodes before linearized reading' (duration: 1.661190396s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:07:21.479815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:07:19.168905Z","time spent":"2.310901672s","remote":"127.0.0.1:36706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-13T21:07:21.479873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.381426472s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T21:07:21.479913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:07:19.174436Z","time spent":"2.305464371s","remote":"127.0.0.1:36706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-13T21:07:21.479948Z","caller":"traceutil/trace.go:171","msg":"trace[1707056541] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:636; }","duration":"1.381640097s","start":"2026-04-13T21:07:20.098288Z","end":"2026-04-13T21:07:21.479928Z","steps":["trace[1707056541] 'agreement among raft nodes before linearized reading' (duration: 1.381478473s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:07:21.479999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:07:19.818223Z","time spent":"1.661683758s","remote":"127.0.0.1:48156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-13T21:07:21.479992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:07:20.098212Z","time spent":"1.381773442s","remote":"127.0.0.1:48168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-13T21:07:21.480191Z","caller":"traceutil/trace.go:171","msg":"trace[1636023830] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:636; }","duration":"433.742372ms","start":"2026-04-13T21:07:21.045967Z","end":"2026-04-13T21:07:21.479709Z","steps":["trace[1636023830] 'agreement among raft nodes before linearized reading' (duration: 433.694671ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:07:21.479614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.311485246s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-04-13T21:07:21.480278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:07:21.045948Z","time spent":"434.307987ms","remote":"127.0.0.1:36672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"info","ts":"2026-04-13T21:07:21.4803Z","caller":"traceutil/trace.go:171","msg":"trace[1489913560] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:636; }","duration":"2.312257676s","start":"2026-04-13T21:07:19.168029Z","end":"2026-04-13T21:07:21.480287Z","steps":["trace[1489913560] 'agreement among raft nodes before linearized reading' (duration: 2.311381723s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:07:21.480335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:07:19.167998Z","time spent":"2.312326468s","remote":"127.0.0.1:36706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-13T21:07:21.479623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:07:19.167827Z","time spent":"2.311786075s","remote":"127.0.0.1:36706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-13T21:07:21.480178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:07:20.373636Z","time spent":"1.106088444s","remote":"127.0.0.1:36706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T21:07:23.941296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.922519ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T21:07:23.941405Z","caller":"traceutil/trace.go:171","msg":"trace[2062258331] linearizableReadLoop","detail":"{readStateIndex:694; appliedIndex:693; }","duration":"449.312184ms","start":"2026-04-13T21:07:23.492077Z","end":"2026-04-13T21:07:23.941389Z","steps":["trace[2062258331] 'read index received' (duration: 293.168989ms)","trace[2062258331] 'applied index is now lower than readState.Index' (duration: 156.142025ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:07:23.941705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.5555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:07:23.942783Z","caller":"traceutil/trace.go:171","msg":"trace[1884462720] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:669; }","duration":"450.72445ms","start":"2026-04-13T21:07:23.492033Z","end":"2026-04-13T21:07:23.942757Z","steps":["trace[1884462720] 'agreement among raft nodes before linearized reading' (duration: 449.5378ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:07:23.942888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:07:23.492014Z","time spent":"450.857574ms","remote":"127.0.0.1:48168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-13T21:07:23.942828Z","caller":"traceutil/trace.go:171","msg":"trace[475249856] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"461.955391ms","start":"2026-04-13T21:07:23.480845Z","end":"2026-04-13T21:07:23.9428Z","steps":["trace[475249856] 'process raft request' (duration: 304.440853ms)","trace[475249856] 'compare' (duration: 155.542309ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:07:23.943818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:07:23.480822Z","time spent":"462.907216ms","remote":"127.0.0.1:48206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5162,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T21:07:23.967692Z","caller":"traceutil/trace.go:171","msg":"trace[45577481] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"458.758491ms","start":"2026-04-13T21:07:23.508905Z","end":"2026-04-13T21:07:23.967664Z","steps":["trace[45577481] 'process raft request' (duration: 458.574306ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:07:23.967801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:07:23.508893Z","time spent":"458.855195ms","remote":"127.0.0.1:48252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":721,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T21:07:23.967878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.356093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-13T21:07:23.967951Z","caller":"traceutil/trace.go:171","msg":"trace[513152330] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:671; }","duration":"468.451616ms","start":"2026-04-13T21:07:23.499483Z","end":"2026-04-13T21:07:23.967934Z","steps":["trace[513152330] 'agreement among raft nodes before linearized reading' (duration: 468.22542ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:07:23.967973Z","caller":"traceutil/trace.go:171","msg":"trace[979420415] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"389.230244ms","start":"2026-04-13T21:07:23.578712Z","end":"2026-04-13T21:07:23.967942Z","steps":["trace[979420415] 'process raft request' (duration: 388.906165ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:07:23.968046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.643351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-04-13T21:07:23.968093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:07:23.578687Z","time spent":"389.357917ms","remote":"127.0.0.1:48206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3202,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T21:07:23.968101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.676131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-04-13T21:07:23.96813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.666499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-13T21:07:23.968125Z","caller":"traceutil/trace.go:171","msg":"trace[14447603] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:671; }","duration":"472.737062ms","start":"2026-04-13T21:07:23.495366Z","end":"2026-04-13T21:07:23.968103Z","steps":["trace[14447603] 'agreement among raft nodes before linearized reading' (duration: 472.534567ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:07:23.968137Z","caller":"traceutil/trace.go:171","msg":"trace[1652174031] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:671; }","duration":"468.738912ms","start":"2026-04-13T21:07:23.499389Z","end":"2026-04-13T21:07:23.968128Z","steps":["trace[1652174031] 'agreement among raft nodes before linearized reading' (duration: 468.64926ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:07:23.968157Z","caller":"traceutil/trace.go:171","msg":"trace[1851103792] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:671; }","duration":"468.722803ms","start":"2026-04-13T21:07:23.499428Z","end":"2026-04-13T21:07:23.968151Z","steps":["trace[1851103792] 'agreement among raft nodes before linearized reading' (duration: 468.60316ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:07:23.968168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:07:23.499376Z","time spent":"468.786323ms","remote":"127.0.0.1:36706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-13T21:07:23.968176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:07:23.49941Z","time spent":"468.762753ms","remote":"127.0.0.1:36706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-13T21:07:23.968177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:07:23.495355Z","time spent":"472.810395ms","remote":"127.0.0.1:36706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-13T21:07:23.968007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:07:23.499477Z","time spent":"468.520657ms","remote":"127.0.0.1:36706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-13T21:07:23.967892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.458772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:07:23.968681Z","caller":"traceutil/trace.go:171","msg":"trace[1110251193] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:671; }","duration":"190.288143ms","start":"2026-04-13T21:07:23.778338Z","end":"2026-04-13T21:07:23.968626Z","steps":["trace[1110251193] 'agreement among raft nodes before linearized reading' (duration: 189.340959ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:07:37.994318Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9228611796742993587,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T21:07:38.494946Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9228611796742993587,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T21:07:38.995531Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9228611796742993587,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T21:07:39.00743Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.513968157s","expected-duration":"1s"} {"level":"info","ts":"2026-04-13T21:07:39.007834Z","caller":"traceutil/trace.go:171","msg":"trace[353857992] linearizableReadLoop","detail":"{readStateIndex:835; appliedIndex:834; }","duration":"1.513637389s","start":"2026-04-13T21:07:37.494172Z","end":"2026-04-13T21:07:39.00781Z","steps":["trace[353857992] 'read index received' (duration: 1.513538667s)","trace[353857992] 'applied index is now lower than readState.Index' (duration: 97.522µs)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:07:39.008044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.513849964s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:07:39.008083Z","caller":"traceutil/trace.go:171","msg":"trace[547870216] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:805; }","duration":"1.513929655s","start":"2026-04-13T21:07:37.494144Z","end":"2026-04-13T21:07:39.008073Z","steps":["trace[547870216] 'agreement among raft nodes before linearized reading' (duration: 1.513793842s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:07:39.008133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:07:37.494135Z","time spent":"1.513991217s","remote":"127.0.0.1:48168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-13T21:07:39.008199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.409220607s","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-04-13T21:07:39.008275Z","caller":"traceutil/trace.go:171","msg":"trace[675743949] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:805; }","duration":"1.409339651s","start":"2026-04-13T21:07:37.59891Z","end":"2026-04-13T21:07:39.00825Z","steps":["trace[675743949] 'agreement among raft nodes before linearized reading' (duration: 1.409081554s)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:07:39.008228Z","caller":"traceutil/trace.go:171","msg":"trace[103975550] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"1.514633873s","start":"2026-04-13T21:07:37.493331Z","end":"2026-04-13T21:07:39.007964Z","steps":["trace[103975550] 'process raft request' (duration: 1.514302445s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:07:39.008346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:07:37.598893Z","time spent":"1.409437083s","remote":"127.0.0.1:36706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-13T21:07:39.008309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"919.674555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-13T21:07:39.009084Z","caller":"traceutil/trace.go:171","msg":"trace[1180742880] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:805; }","duration":"920.429723ms","start":"2026-04-13T21:07:38.088613Z","end":"2026-04-13T21:07:39.009042Z","steps":["trace[1180742880] 'agreement among raft nodes before linearized reading' (duration: 919.44748ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:07:39.008302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"918.252212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-04-13T21:07:39.00917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:07:38.088577Z","time spent":"920.572527ms","remote":"127.0.0.1:36706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-13T21:07:39.009165Z","caller":"traceutil/trace.go:171","msg":"trace[1285785080] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:805; }","duration":"919.135892ms","start":"2026-04-13T21:07:38.090012Z","end":"2026-04-13T21:07:39.009148Z","steps":["trace[1285785080] 'agreement among raft nodes before linearized reading' (duration: 918.138719ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:07:39.009219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:07:38.090002Z","time spent":"919.205235ms","remote":"127.0.0.1:36706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-13T21:07:39.008472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"923.327301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-13T21:07:39.00932Z","caller":"traceutil/trace.go:171","msg":"trace[2053820973] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:805; }","duration":"924.223663ms","start":"2026-04-13T21:07:38.085085Z","end":"2026-04-13T21:07:39.009308Z","steps":["trace[2053820973] 'agreement among raft nodes before linearized reading' (duration: 923.063505ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:07:39.009356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:07:38.085001Z","time spent":"924.346815ms","remote":"127.0.0.1:36706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-13T21:07:39.008629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"920.460754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-13T21:07:39.009451Z","caller":"traceutil/trace.go:171","msg":"trace[143356433] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:805; }","duration":"921.480928ms","start":"2026-04-13T21:07:38.087959Z","end":"2026-04-13T21:07:39.00944Z","steps":["trace[143356433] 'agreement among raft nodes before linearized reading' (duration: 920.136586ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:07:39.009489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:07:38.087948Z","time spent":"921.53013ms","remote":"127.0.0.1:36706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-13T21:07:39.008649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:07:37.493305Z","time spent":"1.515096223s","remote":"127.0.0.1:36624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4027,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T21:07:47.753394Z","caller":"traceutil/trace.go:171","msg":"trace[1579414111] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"150.909417ms","start":"2026-04-13T21:07:47.602468Z","end":"2026-04-13T21:07:47.753378Z","steps":["trace[1579414111] 'process raft request' (duration: 148.409921ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:07:47.753511Z","caller":"traceutil/trace.go:171","msg":"trace[473015144] linearizableReadLoop","detail":"{readStateIndex:943; appliedIndex:941; }","duration":"149.69034ms","start":"2026-04-13T21:07:47.603796Z","end":"2026-04-13T21:07:47.753487Z","steps":["trace[473015144] 'read index received' (duration: 3.49873ms)","trace[473015144] 'applied index is now lower than readState.Index' (duration: 146.19065ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:07:47.753533Z","caller":"traceutil/trace.go:171","msg":"trace[1522978729] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"148.057442ms","start":"2026-04-13T21:07:47.605462Z","end":"2026-04-13T21:07:47.753519Z","steps":["trace[1522978729] 'process raft request' (duration: 147.892489ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:07:47.753658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.851174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-67659f764b\" ","response":"range_response_count:1 size:3903"} {"level":"info","ts":"2026-04-13T21:07:47.753681Z","caller":"traceutil/trace.go:171","msg":"trace[27677171] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-67659f764b; range_end:; response_count:1; response_revision:912; }","duration":"149.908445ms","start":"2026-04-13T21:07:47.603765Z","end":"2026-04-13T21:07:47.753674Z","steps":["trace[27677171] 'agreement among raft nodes before linearized reading' (duration: 149.797562ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:07:47.761057Z","caller":"traceutil/trace.go:171","msg":"trace[73606743] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"136.122813ms","start":"2026-04-13T21:07:47.624919Z","end":"2026-04-13T21:07:47.761042Z","steps":["trace[73606743] 'process raft request' (duration: 135.917249ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:07:53.087328Z","caller":"traceutil/trace.go:171","msg":"trace[398424155] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"167.702543ms","start":"2026-04-13T21:07:52.919603Z","end":"2026-04-13T21:07:53.087305Z","steps":["trace[398424155] 'process raft request' (duration: 167.535339ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:08:12.206276Z","caller":"traceutil/trace.go:171","msg":"trace[822771537] transaction","detail":"{read_only:false; response_revision:1045; number_of_response:1; }","duration":"143.074167ms","start":"2026-04-13T21:08:12.063175Z","end":"2026-04-13T21:08:12.20625Z","steps":["trace[822771537] 'process raft request' (duration: 139.239488ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:08:49.908993Z","caller":"traceutil/trace.go:171","msg":"trace[22258830] transaction","detail":"{read_only:false; response_revision:1172; number_of_response:1; }","duration":"206.370406ms","start":"2026-04-13T21:08:49.702581Z","end":"2026-04-13T21:08:49.908952Z","steps":["trace[22258830] 'process raft request' (duration: 121.48699ms)","trace[22258830] 'compare' (duration: 84.642712ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:08:53.935998Z","caller":"traceutil/trace.go:171","msg":"trace[1536980019] transaction","detail":"{read_only:false; response_revision:1184; number_of_response:1; }","duration":"115.353499ms","start":"2026-04-13T21:08:53.820613Z","end":"2026-04-13T21:08:53.935966Z","steps":["trace[1536980019] 'process raft request' (duration: 115.086954ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:08:59.881121Z","caller":"traceutil/trace.go:171","msg":"trace[1205734424] transaction","detail":"{read_only:false; response_revision:1203; number_of_response:1; }","duration":"116.203724ms","start":"2026-04-13T21:08:59.76488Z","end":"2026-04-13T21:08:59.881083Z","steps":["trace[1205734424] 'process raft request' (duration: 30.670772ms)","trace[1205734424] 'compare' (duration: 85.443661ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:09:00.301958Z","caller":"traceutil/trace.go:171","msg":"trace[872497890] transaction","detail":"{read_only:false; response_revision:1205; number_of_response:1; }","duration":"256.724913ms","start":"2026-04-13T21:09:00.045203Z","end":"2026-04-13T21:09:00.301928Z","steps":["trace[872497890] 'process raft request' (duration: 166.888291ms)","trace[872497890] 'compare' (duration: 89.670499ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:09:00.599362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.221103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:09:00.599503Z","caller":"traceutil/trace.go:171","msg":"trace[835155310] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1206; }","duration":"106.373995ms","start":"2026-04-13T21:09:00.493073Z","end":"2026-04-13T21:09:00.599446Z","steps":["trace[835155310] 'range keys from in-memory index tree' (duration: 106.137759ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:09:18.334774Z","caller":"traceutil/trace.go:171","msg":"trace[1946935409] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"211.896314ms","start":"2026-04-13T21:09:18.122832Z","end":"2026-04-13T21:09:18.334729Z","steps":["trace[1946935409] 'process raft request' (duration: 161.096353ms)","trace[1946935409] 'compare' (duration: 49.776362ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:09:25.965392Z","caller":"traceutil/trace.go:171","msg":"trace[586082669] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"135.878268ms","start":"2026-04-13T21:09:25.829487Z","end":"2026-04-13T21:09:25.965365Z","steps":["trace[586082669] 'process raft request' (duration: 135.647634ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:09:26.377102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.124106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-13T21:09:26.37723Z","caller":"traceutil/trace.go:171","msg":"trace[241364344] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1319; }","duration":"322.31629ms","start":"2026-04-13T21:09:26.054889Z","end":"2026-04-13T21:09:26.377205Z","steps":["trace[241364344] 'range keys from in-memory index tree' (duration: 321.919063ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:09:26.37729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:09:26.054866Z","time spent":"322.411112ms","remote":"127.0.0.1:36706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-13T21:09:26.37729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.610762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2475"} {"level":"info","ts":"2026-04-13T21:09:26.377571Z","caller":"traceutil/trace.go:171","msg":"trace[1904477031] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1319; }","duration":"153.913776ms","start":"2026-04-13T21:09:26.223608Z","end":"2026-04-13T21:09:26.377522Z","steps":["trace[1904477031] 'range keys from in-memory index tree' (duration: 153.401998ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:09:32.66056Z","caller":"traceutil/trace.go:171","msg":"trace[1876881366] transaction","detail":"{read_only:false; response_revision:1347; number_of_response:1; }","duration":"187.408833ms","start":"2026-04-13T21:09:32.473123Z","end":"2026-04-13T21:09:32.660532Z","steps":["trace[1876881366] 'process raft request' (duration: 187.21897ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:09:32.660574Z","caller":"traceutil/trace.go:171","msg":"trace[147831950] linearizableReadLoop","detail":"{readStateIndex:1403; appliedIndex:1403; }","duration":"169.581277ms","start":"2026-04-13T21:09:32.490973Z","end":"2026-04-13T21:09:32.660554Z","steps":["trace[147831950] 'read index received' (duration: 169.572247ms)","trace[147831950] 'applied index is now lower than readState.Index' (duration: 7.36µs)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:09:32.660802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.818391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:09:32.660854Z","caller":"traceutil/trace.go:171","msg":"trace[283364655] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1347; }","duration":"169.922202ms","start":"2026-04-13T21:09:32.490919Z","end":"2026-04-13T21:09:32.660841Z","steps":["trace[283364655] 'agreement among raft nodes before linearized reading' (duration: 169.80462ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:09:32.672415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.211693ms","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-04-13T21:09:32.672511Z","caller":"traceutil/trace.go:171","msg":"trace[1544650134] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1348; }","duration":"116.321844ms","start":"2026-04-13T21:09:32.556147Z","end":"2026-04-13T21:09:32.672469Z","steps":["trace[1544650134] 'agreement among raft nodes before linearized reading' (duration: 116.142112ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:09:32.672865Z","caller":"traceutil/trace.go:171","msg":"trace[2124983832] transaction","detail":"{read_only:false; response_revision:1348; number_of_response:1; }","duration":"162.990737ms","start":"2026-04-13T21:09:32.509858Z","end":"2026-04-13T21:09:32.672849Z","steps":["trace[2124983832] 'process raft request' (duration: 162.336325ms)"],"step_count":1}