{"level":"warn","ts":"2026-08-26T00:15:31.613065Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-26T00:15:31.613173Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.119: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://162.253.55.119:2380","--initial-cluster=instance=https://162.253.55.119:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.119:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.119:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-08-26T00:15:31.613253Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-26T00:15:31.613273Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.119:2380"]} {"level":"info","ts":"2026-08-26T00:15:31.613308Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-26T00:15:31.61399Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.119:2379"]} {"level":"info","ts":"2026-08-26T00:15:31.614067Z","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://162.253.55.119:2380"],"listen-peer-urls":["https://162.253.55.119:2380"],"advertise-client-urls":["https://162.253.55.119:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.119:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.119:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-08-26T00:15:31.620512Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.235067ms"} {"level":"info","ts":"2026-08-26T00:15:31.631267Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2f0e00eb3c3ee1b","cluster-id":"8e467f099f65c603"} {"level":"info","ts":"2026-08-26T00:15:31.631357Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2f0e00eb3c3ee1b switched to configuration voters=()"} {"level":"info","ts":"2026-08-26T00:15:31.631391Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2f0e00eb3c3ee1b became follower at term 0"} {"level":"info","ts":"2026-08-26T00:15:31.632471Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2f0e00eb3c3ee1b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-26T00:15:31.632506Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2f0e00eb3c3ee1b became follower at term 1"} {"level":"info","ts":"2026-08-26T00:15:31.632548Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2f0e00eb3c3ee1b switched to configuration voters=(211915536236539419)"} {"level":"warn","ts":"2026-08-26T00:15:31.639114Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-26T00:15:31.640645Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-26T00:15:31.642048Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-08-26T00:15:31.646561Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2f0e00eb3c3ee1b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-26T00:15:31.646884Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-26T00:15:31.646936Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-26T00:15:31.646947Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-26T00:15:31.646956Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2f0e00eb3c3ee1b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-26T00:15:31.650643Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2f0e00eb3c3ee1b switched to configuration voters=(211915536236539419)"} {"level":"info","ts":"2026-08-26T00:15:31.650944Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8e467f099f65c603","local-member-id":"2f0e00eb3c3ee1b","added-peer-id":"2f0e00eb3c3ee1b","added-peer-peer-urls":["https://162.253.55.119:2380"]} {"level":"info","ts":"2026-08-26T00:15:31.656352Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-26T00:15:31.656507Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.119:2380"} {"level":"info","ts":"2026-08-26T00:15:31.656555Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.119:2380"} {"level":"info","ts":"2026-08-26T00:15:31.65675Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2f0e00eb3c3ee1b","initial-advertise-peer-urls":["https://162.253.55.119:2380"],"listen-peer-urls":["https://162.253.55.119:2380"],"advertise-client-urls":["https://162.253.55.119:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.119:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-26T00:15:31.656784Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-26T00:15:32.633519Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2f0e00eb3c3ee1b is starting a new election at term 1"} {"level":"info","ts":"2026-08-26T00:15:32.633562Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2f0e00eb3c3ee1b became pre-candidate at term 1"} {"level":"info","ts":"2026-08-26T00:15:32.633587Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2f0e00eb3c3ee1b received MsgPreVoteResp from 2f0e00eb3c3ee1b at term 1"} {"level":"info","ts":"2026-08-26T00:15:32.633598Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2f0e00eb3c3ee1b became candidate at term 2"} {"level":"info","ts":"2026-08-26T00:15:32.633603Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2f0e00eb3c3ee1b received MsgVoteResp from 2f0e00eb3c3ee1b at term 2"} {"level":"info","ts":"2026-08-26T00:15:32.633611Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2f0e00eb3c3ee1b became leader at term 2"} {"level":"info","ts":"2026-08-26T00:15:32.633618Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2f0e00eb3c3ee1b elected leader 2f0e00eb3c3ee1b at term 2"} {"level":"info","ts":"2026-08-26T00:15:32.636893Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-26T00:15:32.637722Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-26T00:15:32.637722Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2f0e00eb3c3ee1b","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.119:2379]}","request-path":"/0/members/2f0e00eb3c3ee1b/attributes","cluster-id":"8e467f099f65c603","publish-timeout":"7s"} {"level":"info","ts":"2026-08-26T00:15:32.637761Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-26T00:15:32.638022Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-26T00:15:32.638061Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-26T00:15:32.63811Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8e467f099f65c603","local-member-id":"2f0e00eb3c3ee1b","cluster-version":"3.5"} {"level":"info","ts":"2026-08-26T00:15:32.638247Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-26T00:15:32.638292Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-26T00:15:32.639583Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.119:2379"} {"level":"info","ts":"2026-08-26T00:15:32.640616Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-26T00:16:08.345065Z","caller":"traceutil/trace.go:171","msg":"trace[1438796671] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"123.490738ms","start":"2026-08-26T00:16:08.221535Z","end":"2026-08-26T00:16:08.345026Z","steps":["trace[1438796671] 'process raft request' (duration: 123.198458ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:16:08.345124Z","caller":"traceutil/trace.go:171","msg":"trace[633698103] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"118.53228ms","start":"2026-08-26T00:16:08.226571Z","end":"2026-08-26T00:16:08.345103Z","steps":["trace[633698103] 'process raft request' (duration: 118.20326ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:16:08.345068Z","caller":"traceutil/trace.go:171","msg":"trace[604549246] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"134.820278ms","start":"2026-08-26T00:16:08.210145Z","end":"2026-08-26T00:16:08.344965Z","steps":["trace[604549246] 'process raft request' (duration: 131.13085ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:16:08.345235Z","caller":"traceutil/trace.go:171","msg":"trace[2002544167] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"111.91127ms","start":"2026-08-26T00:16:08.233309Z","end":"2026-08-26T00:16:08.345221Z","steps":["trace[2002544167] 'process raft request' (duration: 111.536958ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:16:08.491371Z","caller":"traceutil/trace.go:171","msg":"trace[36626472] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"118.284652ms","start":"2026-08-26T00:16:08.373063Z","end":"2026-08-26T00:16:08.491347Z","steps":["trace[36626472] 'process raft request' (duration: 113.450339ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:16:08.491483Z","caller":"traceutil/trace.go:171","msg":"trace[381825318] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"100.346822ms","start":"2026-08-26T00:16:08.391107Z","end":"2026-08-26T00:16:08.491454Z","steps":["trace[381825318] 'process raft request' (duration: 100.190347ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:14.348669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.670604ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-26T00:16:14.348889Z","caller":"traceutil/trace.go:171","msg":"trace[936543148] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:647; }","duration":"115.271516ms","start":"2026-08-26T00:16:14.233592Z","end":"2026-08-26T00:16:14.348863Z","steps":["trace[936543148] 'read index received' (duration: 68.462µs)","trace[936543148] 'applied index is now lower than readState.Index' (duration: 115.201464ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:16:14.349019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.420151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:16:14.349048Z","caller":"traceutil/trace.go:171","msg":"trace[1258396101] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:630; }","duration":"115.504714ms","start":"2026-08-26T00:16:14.233533Z","end":"2026-08-26T00:16:14.349037Z","steps":["trace[1258396101] 'agreement among raft nodes before linearized reading' (duration: 115.39438ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:16:14.349377Z","caller":"traceutil/trace.go:171","msg":"trace[1153371327] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"176.383585ms","start":"2026-08-26T00:16:14.172956Z","end":"2026-08-26T00:16:14.34934Z","steps":["trace[1153371327] 'compare' (duration: 169.394926ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:14.70473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.557249ms","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-08-26T00:16:14.704796Z","caller":"traceutil/trace.go:171","msg":"trace[511012536] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:631; }","duration":"179.672182ms","start":"2026-08-26T00:16:14.525109Z","end":"2026-08-26T00:16:14.704781Z","steps":["trace[511012536] 'range keys from in-memory index tree' (duration: 179.336093ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:15.315895Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17157483382474225005,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-26T00:16:15.355669Z","caller":"traceutil/trace.go:171","msg":"trace[231681206] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"604.172741ms","start":"2026-08-26T00:16:14.751474Z","end":"2026-08-26T00:16:15.355647Z","steps":["trace[231681206] 'process raft request' (duration: 603.673346ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:15.355963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:14.751451Z","time spent":"604.288715ms","remote":"127.0.0.1:36258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T00:16:15.38008Z","caller":"traceutil/trace.go:171","msg":"trace[688933239] linearizableReadLoop","detail":"{readStateIndex:653; appliedIndex:652; }","duration":"564.530098ms","start":"2026-08-26T00:16:14.815526Z","end":"2026-08-26T00:16:15.380056Z","steps":["trace[688933239] 'read index received' (duration: 540.047654ms)","trace[688933239] 'applied index is now lower than readState.Index' (duration: 24.481284ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:16:15.380389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.482556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:16:15.380455Z","caller":"traceutil/trace.go:171","msg":"trace[175820895] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:635; }","duration":"147.593839ms","start":"2026-08-26T00:16:15.232846Z","end":"2026-08-26T00:16:15.38044Z","steps":["trace[175820895] 'agreement among raft nodes before linearized reading' (duration: 147.468815ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:15.380737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.195848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-26T00:16:15.380786Z","caller":"traceutil/trace.go:171","msg":"trace[610014892] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:635; }","duration":"565.280721ms","start":"2026-08-26T00:16:14.815492Z","end":"2026-08-26T00:16:15.380773Z","steps":["trace[610014892] 'agreement among raft nodes before linearized reading' (duration: 565.133486ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:15.380813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:14.815475Z","time spent":"565.330673ms","remote":"127.0.0.1:36450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-26T00:16:15.380778Z","caller":"traceutil/trace.go:171","msg":"trace[916366893] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"440.909442ms","start":"2026-08-26T00:16:14.939842Z","end":"2026-08-26T00:16:15.380751Z","steps":["trace[916366893] 'process raft request' (duration: 440.098968ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:15.380932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:14.939817Z","time spent":"441.032905ms","remote":"127.0.0.1:36450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T00:16:35.278674Z","caller":"traceutil/trace.go:171","msg":"trace[1601471070] linearizableReadLoop","detail":"{readStateIndex:831; appliedIndex:830; }","duration":"464.285973ms","start":"2026-08-26T00:16:34.814364Z","end":"2026-08-26T00:16:35.27865Z","steps":["trace[1601471070] 'read index received' (duration: 464.131699ms)","trace[1601471070] 'applied index is now lower than readState.Index' (duration: 153.154µs)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:16:35.278777Z","caller":"traceutil/trace.go:171","msg":"trace[1999456441] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"501.811501ms","start":"2026-08-26T00:16:34.776958Z","end":"2026-08-26T00:16:35.27877Z","steps":["trace[1999456441] 'process raft request' (duration: 501.568065ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:35.278857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:34.776932Z","time spent":"501.862262ms","remote":"127.0.0.1:36450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-26T00:16:35.279127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.707664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-26T00:16:35.279215Z","caller":"traceutil/trace.go:171","msg":"trace[1382450772] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:808; }","duration":"413.904318ms","start":"2026-08-26T00:16:34.865289Z","end":"2026-08-26T00:16:35.279193Z","steps":["trace[1382450772] 'agreement among raft nodes before linearized reading' (duration: 413.638772ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:35.279275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:34.865268Z","time spent":"413.99322ms","remote":"127.0.0.1:36450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-26T00:16:35.983982Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17157483382474225352,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:16:36.351494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.419110862s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-26T00:16:36.351564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.118212726s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:16:36.351614Z","caller":"traceutil/trace.go:171","msg":"trace[1619756218] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:808; }","duration":"1.118305738s","start":"2026-08-26T00:16:35.233291Z","end":"2026-08-26T00:16:36.351597Z","steps":["trace[1619756218] 'agreement among raft nodes before linearized reading' (duration: 45.839643ms)","trace[1619756218] 'range keys from in-memory index tree' (duration: 1.072407894s)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:16:36.351654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:35.233263Z","time spent":"1.11838003s","remote":"127.0.0.1:55054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-26T00:16:36.352037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.127401051s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:16:36.352073Z","caller":"traceutil/trace.go:171","msg":"trace[1801516327] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:808; }","duration":"1.127485273s","start":"2026-08-26T00:16:35.224579Z","end":"2026-08-26T00:16:36.352064Z","steps":["trace[1801516327] 'agreement among raft nodes before linearized reading' (duration: 54.576027ms)","trace[1801516327] 'count revisions from in-memory index tree' (duration: 1.072859644s)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:16:36.352097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:35.224551Z","time spent":"1.127540394s","remote":"127.0.0.1:36244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-08-26T00:16:36.352119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.537264811s","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-08-26T00:16:36.352211Z","caller":"traceutil/trace.go:171","msg":"trace[700202003] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:808; }","duration":"1.537887506s","start":"2026-08-26T00:16:34.814298Z","end":"2026-08-26T00:16:36.352186Z","steps":["trace[700202003] 'agreement among raft nodes before linearized reading' (duration: 464.847477ms)","trace[700202003] 'range keys from in-memory index tree' (duration: 1.072333572s)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:16:36.351605Z","caller":"traceutil/trace.go:171","msg":"trace[1291040907] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:808; }","duration":"1.419289526s","start":"2026-08-26T00:16:34.932283Z","end":"2026-08-26T00:16:36.351573Z","steps":["trace[1291040907] 'agreement among raft nodes before linearized reading' (duration: 346.888463ms)","trace[1291040907] 'count revisions from in-memory index tree' (duration: 1.0722377s)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:16:36.352263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:34.814275Z","time spent":"1.537978289s","remote":"127.0.0.1:36450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-26T00:16:36.352342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:34.932254Z","time spent":"1.420044185s","remote":"127.0.0.1:36358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":12,"response size":30,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-08-26T00:16:36.356863Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.07311667s","expected-duration":"1s"} {"level":"info","ts":"2026-08-26T00:16:36.357314Z","caller":"traceutil/trace.go:171","msg":"trace[1542191925] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"1.073639533s","start":"2026-08-26T00:16:35.283651Z","end":"2026-08-26T00:16:36.35729Z","steps":["trace[1542191925] 'process raft request' (duration: 1.0735106s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:36.357436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:35.283608Z","time spent":"1.073768706s","remote":"127.0.0.1:36450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T00:16:36.371234Z","caller":"traceutil/trace.go:171","msg":"trace[579318281] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"618.4614ms","start":"2026-08-26T00:16:35.752751Z","end":"2026-08-26T00:16:36.371212Z","steps":["trace[579318281] 'process raft request' (duration: 618.402779ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:16:36.371246Z","caller":"traceutil/trace.go:171","msg":"trace[225357190] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"867.084281ms","start":"2026-08-26T00:16:35.504138Z","end":"2026-08-26T00:16:36.371222Z","steps":["trace[225357190] 'process raft request' (duration: 866.884446ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:36.371361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:35.504118Z","time spent":"867.175792ms","remote":"127.0.0.1:36424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T00:16:36.371405Z","caller":"traceutil/trace.go:171","msg":"trace[438938938] linearizableReadLoop","detail":"{readStateIndex:834; appliedIndex:831; }","duration":"888.222458ms","start":"2026-08-26T00:16:35.483158Z","end":"2026-08-26T00:16:36.37138Z","steps":["trace[438938938] 'read index received' (duration: 873.998193ms)","trace[438938938] 'applied index is now lower than readState.Index' (duration: 14.222945ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:16:36.371366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:35.752726Z","time spent":"618.554933ms","remote":"127.0.0.1:36450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-26T00:16:36.371651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.923876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-26T00:16:36.371699Z","caller":"traceutil/trace.go:171","msg":"trace[1300327362] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:812; }","duration":"335.973768ms","start":"2026-08-26T00:16:36.035714Z","end":"2026-08-26T00:16:36.371688Z","steps":["trace[1300327362] 'agreement among raft nodes before linearized reading' (duration: 335.893166ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:36.371711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.443245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-26T00:16:36.371726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:36.035659Z","time spent":"336.0596ms","remote":"127.0.0.1:36258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":9,"response size":30,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"info","ts":"2026-08-26T00:16:36.371746Z","caller":"traceutil/trace.go:171","msg":"trace[109784613] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:812; }","duration":"608.513137ms","start":"2026-08-26T00:16:35.76322Z","end":"2026-08-26T00:16:36.371733Z","steps":["trace[109784613] 'agreement among raft nodes before linearized reading' (duration: 608.464666ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:36.372172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:35.763201Z","time spent":"608.947757ms","remote":"127.0.0.1:36578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-08-26T00:16:36.371661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"887.969932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-26T00:16:36.372377Z","caller":"traceutil/trace.go:171","msg":"trace[1039136566] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:812; }","duration":"888.71997ms","start":"2026-08-26T00:16:35.483644Z","end":"2026-08-26T00:16:36.372364Z","steps":["trace[1039136566] 'agreement among raft nodes before linearized reading' (duration: 887.90404ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:36.372422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:35.483623Z","time spent":"888.787961ms","remote":"127.0.0.1:36450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-26T00:16:36.371972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"888.811743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-26T00:16:36.372641Z","caller":"traceutil/trace.go:171","msg":"trace[817740884] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:812; }","duration":"889.5121ms","start":"2026-08-26T00:16:35.483109Z","end":"2026-08-26T00:16:36.372621Z","steps":["trace[817740884] 'agreement among raft nodes before linearized reading' (duration: 888.793842ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:36.372697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:35.483085Z","time spent":"889.596542ms","remote":"127.0.0.1:36450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-26T00:16:36.371987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"681.464564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.119\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-26T00:16:36.37281Z","caller":"traceutil/trace.go:171","msg":"trace[1419792258] range","detail":"{range_begin:/registry/masterleases/162.253.55.119; range_end:; response_count:1; response_revision:812; }","duration":"682.332145ms","start":"2026-08-26T00:16:35.690468Z","end":"2026-08-26T00:16:36.3728Z","steps":["trace[1419792258] 'agreement among raft nodes before linearized reading' (duration: 681.392712ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:36.372843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:35.690446Z","time spent":"682.389166ms","remote":"127.0.0.1:36202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/162.253.55.119\" "} {"level":"info","ts":"2026-08-26T00:16:48.602071Z","caller":"traceutil/trace.go:171","msg":"trace[503137729] linearizableReadLoop","detail":"{readStateIndex:988; appliedIndex:987; }","duration":"173.757682ms","start":"2026-08-26T00:16:48.428288Z","end":"2026-08-26T00:16:48.602046Z","steps":["trace[503137729] 'read index received' (duration: 173.579978ms)","trace[503137729] 'applied index is now lower than readState.Index' (duration: 176.814µs)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:16:48.602182Z","caller":"traceutil/trace.go:171","msg":"trace[1235091324] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"175.487881ms","start":"2026-08-26T00:16:48.42667Z","end":"2026-08-26T00:16:48.602158Z","steps":["trace[1235091324] 'process raft request' (duration: 175.226355ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:48.60259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.285163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-26T00:16:48.60289Z","caller":"traceutil/trace.go:171","msg":"trace[1772690834] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:961; }","duration":"174.381416ms","start":"2026-08-26T00:16:48.428247Z","end":"2026-08-26T00:16:48.602629Z","steps":["trace[1772690834] 'agreement among raft nodes before linearized reading' (duration: 173.928636ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:48.61754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.201801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-26T00:16:48.617709Z","caller":"traceutil/trace.go:171","msg":"trace[855719801] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:962; }","duration":"189.373594ms","start":"2026-08-26T00:16:48.42827Z","end":"2026-08-26T00:16:48.617644Z","steps":["trace[855719801] 'agreement among raft nodes before linearized reading' (duration: 189.098708ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:48.617711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.869028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-26T00:16:48.617888Z","caller":"traceutil/trace.go:171","msg":"trace[2024535751] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:962; }","duration":"186.115553ms","start":"2026-08-26T00:16:48.431759Z","end":"2026-08-26T00:16:48.617874Z","steps":["trace[2024535751] 'agreement among raft nodes before linearized reading' (duration: 185.764796ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:16:54.761628Z","caller":"traceutil/trace.go:171","msg":"trace[1218078675] linearizableReadLoop","detail":"{readStateIndex:1026; appliedIndex:1024; }","duration":"120.381169ms","start":"2026-08-26T00:16:54.641222Z","end":"2026-08-26T00:16:54.761603Z","steps":["trace[1218078675] 'read index received' (duration: 53.434748ms)","trace[1218078675] 'applied index is now lower than readState.Index' (duration: 66.945281ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:16:54.761705Z","caller":"traceutil/trace.go:171","msg":"trace[817494837] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"129.098322ms","start":"2026-08-26T00:16:54.632559Z","end":"2026-08-26T00:16:54.761658Z","steps":["trace[817494837] 'process raft request' (duration: 128.827937ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:54.761949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.724306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-26T00:16:54.762029Z","caller":"traceutil/trace.go:171","msg":"trace[646960380] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:997; }","duration":"119.831968ms","start":"2026-08-26T00:16:54.64218Z","end":"2026-08-26T00:16:54.762011Z","steps":["trace[646960380] 'agreement among raft nodes before linearized reading' (duration: 119.603073ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:54.762148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.425988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-26T00:16:54.762256Z","caller":"traceutil/trace.go:171","msg":"trace[189544009] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:997; }","duration":"107.648312ms","start":"2026-08-26T00:16:54.654588Z","end":"2026-08-26T00:16:54.762236Z","steps":["trace[189544009] 'agreement among raft nodes before linearized reading' (duration: 107.297745ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:54.762447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.629314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-26T00:16:54.762531Z","caller":"traceutil/trace.go:171","msg":"trace[695436286] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:997; }","duration":"121.312069ms","start":"2026-08-26T00:16:54.641199Z","end":"2026-08-26T00:16:54.762511Z","steps":["trace[695436286] 'agreement among raft nodes before linearized reading' (duration: 120.488092ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:55.145068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.804445ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-26T00:16:55.145182Z","caller":"traceutil/trace.go:171","msg":"trace[1806950462] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"378.574721ms","start":"2026-08-26T00:16:54.766585Z","end":"2026-08-26T00:16:55.14516Z","steps":["trace[1806950462] 'process raft request' (duration: 97.595093ms)","trace[1806950462] 'compare' (duration: 280.629781ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:16:55.145258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:54.766562Z","time spent":"378.659792ms","remote":"127.0.0.1:36450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T00:16:55.155637Z","caller":"traceutil/trace.go:171","msg":"trace[1788185021] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"388.293572ms","start":"2026-08-26T00:16:54.767334Z","end":"2026-08-26T00:16:55.155627Z","steps":["trace[1788185021] 'process raft request' (duration: 388.266731ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:55.155694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:54.767308Z","time spent":"388.358593ms","remote":"127.0.0.1:36450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T00:16:55.155733Z","caller":"traceutil/trace.go:171","msg":"trace[566462656] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"388.384003ms","start":"2026-08-26T00:16:54.76731Z","end":"2026-08-26T00:16:55.155694Z","steps":["trace[566462656] 'process raft request' (duration: 388.24348ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:55.155885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:54.767247Z","time spent":"388.557238ms","remote":"127.0.0.1:36450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T00:16:55.567458Z","caller":"traceutil/trace.go:171","msg":"trace[1980175529] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"107.007452ms","start":"2026-08-26T00:16:55.460419Z","end":"2026-08-26T00:16:55.567427Z","steps":["trace[1980175529] 'process raft request' (duration: 106.859379ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:55.804935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.090929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.119\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-26T00:16:55.805027Z","caller":"traceutil/trace.go:171","msg":"trace[843297288] range","detail":"{range_begin:/registry/masterleases/162.253.55.119; range_end:; response_count:1; response_revision:1001; }","duration":"113.241592ms","start":"2026-08-26T00:16:55.691762Z","end":"2026-08-26T00:16:55.805004Z","steps":["trace[843297288] 'range keys from in-memory index tree' (duration: 112.835173ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:16:56.108437Z","caller":"traceutil/trace.go:171","msg":"trace[1890401687] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"215.802985ms","start":"2026-08-26T00:16:55.892604Z","end":"2026-08-26T00:16:56.108407Z","steps":["trace[1890401687] 'process raft request' (duration: 189.975643ms)","trace[1890401687] 'compare' (duration: 25.655529ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:16:56.351639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.109035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:16:56.351719Z","caller":"traceutil/trace.go:171","msg":"trace[331626730] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1002; }","duration":"119.244748ms","start":"2026-08-26T00:16:56.232457Z","end":"2026-08-26T00:16:56.351702Z","steps":["trace[331626730] 'range keys from in-memory index tree' (duration: 118.987603ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:16:56.820596Z","caller":"traceutil/trace.go:171","msg":"trace[1732762046] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"118.325838ms","start":"2026-08-26T00:16:56.70225Z","end":"2026-08-26T00:16:56.820576Z","steps":["trace[1732762046] 'process raft request' (duration: 118.197005ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:57.051312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.662929ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-26T00:16:57.051467Z","caller":"traceutil/trace.go:171","msg":"trace[185773491] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"283.363393ms","start":"2026-08-26T00:16:56.768065Z","end":"2026-08-26T00:16:57.051429Z","steps":["trace[185773491] 'process raft request' (duration: 146.50715ms)","trace[185773491] 'compare' (duration: 136.561827ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:16:57.556307Z","caller":"traceutil/trace.go:171","msg":"trace[1255275897] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"403.568305ms","start":"2026-08-26T00:16:57.152724Z","end":"2026-08-26T00:16:57.556292Z","steps":["trace[1255275897] 'process raft request' (duration: 403.32802ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:57.556443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:57.152699Z","time spent":"403.662527ms","remote":"127.0.0.1:36450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-26T00:16:57.556676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.686825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-26T00:16:57.556217Z","caller":"traceutil/trace.go:171","msg":"trace[1735345645] linearizableReadLoop","detail":"{readStateIndex:1036; appliedIndex:1035; }","duration":"397.220885ms","start":"2026-08-26T00:16:57.158971Z","end":"2026-08-26T00:16:57.556192Z","steps":["trace[1735345645] 'read index received' (duration: 397.008231ms)","trace[1735345645] 'applied index is now lower than readState.Index' (duration: 211.504µs)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:16:57.556839Z","caller":"traceutil/trace.go:171","msg":"trace[2129074132] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1006; }","duration":"397.796997ms","start":"2026-08-26T00:16:57.158935Z","end":"2026-08-26T00:16:57.556732Z","steps":["trace[2129074132] 'agreement among raft nodes before linearized reading' (duration: 397.482571ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:57.556881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.377205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:16:57.55692Z","caller":"traceutil/trace.go:171","msg":"trace[924452356] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1006; }","duration":"324.450267ms","start":"2026-08-26T00:16:57.232459Z","end":"2026-08-26T00:16:57.55691Z","steps":["trace[924452356] 'agreement among raft nodes before linearized reading' (duration: 324.385006ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:57.556951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:57.23244Z","time spent":"324.504578ms","remote":"127.0.0.1:55054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-26T00:16:57.556952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.354718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-26T00:16:57.557012Z","caller":"traceutil/trace.go:171","msg":"trace[1050670475] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1006; }","duration":"397.43915ms","start":"2026-08-26T00:16:57.159557Z","end":"2026-08-26T00:16:57.556997Z","steps":["trace[1050670475] 'agreement among raft nodes before linearized reading' (duration: 397.287767ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:57.557052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:57.159548Z","time spent":"397.491041ms","remote":"127.0.0.1:36450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-26T00:16:57.557258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:57.158922Z","time spent":"398.324648ms","remote":"127.0.0.1:36450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-26T00:16:57.857826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.150576ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-26T00:16:57.858001Z","caller":"traceutil/trace.go:171","msg":"trace[1073522556] linearizableReadLoop","detail":"{readStateIndex:1038; appliedIndex:1036; }","duration":"287.310767ms","start":"2026-08-26T00:16:57.570674Z","end":"2026-08-26T00:16:57.857985Z","steps":["trace[1073522556] 'read index received' (duration: 39.949507ms)","trace[1073522556] 'applied index is now lower than readState.Index' (duration: 247.36001ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:16:57.85806Z","caller":"traceutil/trace.go:171","msg":"trace[1231076589] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"297.295982ms","start":"2026-08-26T00:16:57.560749Z","end":"2026-08-26T00:16:57.858045Z","steps":["trace[1231076589] 'process raft request' (duration: 49.84736ms)","trace[1231076589] 'compare' (duration: 247.057204ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:16:57.858123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.443349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-26T00:16:57.858158Z","caller":"traceutil/trace.go:171","msg":"trace[931679631] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1008; }","duration":"287.499131ms","start":"2026-08-26T00:16:57.57065Z","end":"2026-08-26T00:16:57.858149Z","steps":["trace[931679631] 'agreement among raft nodes before linearized reading' (duration: 287.382019ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:16:57.85817Z","caller":"traceutil/trace.go:171","msg":"trace[922066293] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"296.24452ms","start":"2026-08-26T00:16:57.561919Z","end":"2026-08-26T00:16:57.858164Z","steps":["trace[922066293] 'process raft request' (duration: 296.014626ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:16:59.25779Z","caller":"traceutil/trace.go:171","msg":"trace[1920746041] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"201.405869ms","start":"2026-08-26T00:16:59.056362Z","end":"2026-08-26T00:16:59.257768Z","steps":["trace[1920746041] 'process raft request' (duration: 201.246316ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:59.449295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.243568ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-26T00:16:59.449484Z","caller":"traceutil/trace.go:171","msg":"trace[473170077] linearizableReadLoop","detail":"{readStateIndex:1042; appliedIndex:1040; }","duration":"216.993233ms","start":"2026-08-26T00:16:59.232427Z","end":"2026-08-26T00:16:59.44942Z","steps":["trace[473170077] 'read index received' (duration: 25.176758ms)","trace[473170077] 'applied index is now lower than readState.Index' (duration: 191.815275ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:16:59.449548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.127276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:16:59.449572Z","caller":"traceutil/trace.go:171","msg":"trace[76418379] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1012; }","duration":"217.191367ms","start":"2026-08-26T00:16:59.232372Z","end":"2026-08-26T00:16:59.449563Z","steps":["trace[76418379] 'agreement among raft nodes before linearized reading' (duration: 217.139866ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:16:59.449569Z","caller":"traceutil/trace.go:171","msg":"trace[1403554922] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"261.094051ms","start":"2026-08-26T00:16:59.188414Z","end":"2026-08-26T00:16:59.449508Z","steps":["trace[1403554922] 'process raft request' (duration: 109.353363ms)","trace[1403554922] 'compare' (duration: 151.089945ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:16:59.671303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.843924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-08-26T00:16:59.671392Z","caller":"traceutil/trace.go:171","msg":"trace[1658855058] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1012; }","duration":"109.976476ms","start":"2026-08-26T00:16:59.561394Z","end":"2026-08-26T00:16:59.67137Z","steps":["trace[1658855058] 'range keys from in-memory index tree' (duration: 109.070188ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:16:59.785916Z","caller":"traceutil/trace.go:171","msg":"trace[1634942833] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"109.811873ms","start":"2026-08-26T00:16:59.675807Z","end":"2026-08-26T00:16:59.785619Z","steps":["trace[1634942833] 'process raft request' (duration: 109.632439ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:16:59.99173Z","caller":"traceutil/trace.go:171","msg":"trace[1485934398] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"126.797595ms","start":"2026-08-26T00:16:59.864899Z","end":"2026-08-26T00:16:59.991697Z","steps":["trace[1485934398] 'process raft request' (duration: 71.535342ms)","trace[1485934398] 'compare' (duration: 55.090089ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:17:00.017833Z","caller":"traceutil/trace.go:171","msg":"trace[1962812061] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"150.406128ms","start":"2026-08-26T00:16:59.86734Z","end":"2026-08-26T00:17:00.017746Z","steps":["trace[1962812061] 'process raft request' (duration: 150.200374ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:00.708455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.451263ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-26T00:17:00.708555Z","caller":"traceutil/trace.go:171","msg":"trace[51099193] linearizableReadLoop","detail":"{readStateIndex:1046; appliedIndex:1045; }","duration":"609.548204ms","start":"2026-08-26T00:17:00.09899Z","end":"2026-08-26T00:17:00.708538Z","steps":["trace[51099193] 'read index received' (duration: 23.823816ms)","trace[51099193] 'applied index is now lower than readState.Index' (duration: 585.721748ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:17:00.708765Z","caller":"traceutil/trace.go:171","msg":"trace[504259784] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"686.406591ms","start":"2026-08-26T00:17:00.022345Z","end":"2026-08-26T00:17:00.708751Z","steps":["trace[504259784] 'process raft request' (duration: 100.531771ms)","trace[504259784] 'compare' (duration: 585.272318ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:17:00.708826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:00.022323Z","time spent":"686.468983ms","remote":"127.0.0.1:36450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-26T00:17:00.708824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.772299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-26T00:17:00.708867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.865531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-26T00:17:00.708894Z","caller":"traceutil/trace.go:171","msg":"trace[420801864] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1016; }","duration":"475.890751ms","start":"2026-08-26T00:17:00.232989Z","end":"2026-08-26T00:17:00.70888Z","steps":["trace[420801864] 'agreement among raft nodes before linearized reading' (duration: 475.795939ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:00.708931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:00.232951Z","time spent":"475.972782ms","remote":"127.0.0.1:55054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-26T00:17:00.70894Z","caller":"traceutil/trace.go:171","msg":"trace[990557235] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1016; }","duration":"609.983393ms","start":"2026-08-26T00:17:00.098933Z","end":"2026-08-26T00:17:00.708916Z","steps":["trace[990557235] 'agreement among raft nodes before linearized reading' (duration: 609.85125ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:00.70933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:00.098911Z","time spent":"610.400792ms","remote":"127.0.0.1:36686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":29,"response size":30,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-08-26T00:17:00.981808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.519701ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-26T00:17:01.349407Z","caller":"traceutil/trace.go:171","msg":"trace[250293833] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"363.611624ms","start":"2026-08-26T00:17:00.985771Z","end":"2026-08-26T00:17:01.349382Z","steps":["trace[250293833] 'process raft request' (duration: 363.465731ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:17:01.349451Z","caller":"traceutil/trace.go:171","msg":"trace[2037997120] linearizableReadLoop","detail":"{readStateIndex:1048; appliedIndex:1048; }","duration":"339.040993ms","start":"2026-08-26T00:17:01.010354Z","end":"2026-08-26T00:17:01.349395Z","steps":["trace[2037997120] 'read index received' (duration: 339.023413ms)","trace[2037997120] 'applied index is now lower than readState.Index' (duration: 14.04µs)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:17:01.349596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:00.985756Z","time spent":"363.763187ms","remote":"127.0.0.1:36450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-26T00:17:01.349598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.218827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-26T00:17:01.349679Z","caller":"traceutil/trace.go:171","msg":"trace[730294655] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1017; }","duration":"339.35215ms","start":"2026-08-26T00:17:01.010307Z","end":"2026-08-26T00:17:01.349659Z","steps":["trace[730294655] 'agreement among raft nodes before linearized reading' (duration: 339.189676ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:01.349715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:01.010286Z","time spent":"339.422621ms","remote":"127.0.0.1:36644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":2,"response size":30,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-08-26T00:17:01.381235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.283083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:17:01.381326Z","caller":"traceutil/trace.go:171","msg":"trace[1381239050] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1018; }","duration":"232.426756ms","start":"2026-08-26T00:17:01.148879Z","end":"2026-08-26T00:17:01.381306Z","steps":["trace[1381239050] 'agreement among raft nodes before linearized reading' (duration: 232.275623ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:17:01.381313Z","caller":"traceutil/trace.go:171","msg":"trace[2067589469] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"115.3337ms","start":"2026-08-26T00:17:01.265932Z","end":"2026-08-26T00:17:01.381265Z","steps":["trace[2067589469] 'process raft request' (duration: 115.059755ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:17:01.920822Z","caller":"traceutil/trace.go:171","msg":"trace[1307037773] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"126.946631ms","start":"2026-08-26T00:17:01.793851Z","end":"2026-08-26T00:17:01.920797Z","steps":["trace[1307037773] 'process raft request' (duration: 126.803018ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:02.137503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.405404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-26T00:17:02.137604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.815047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-26T00:17:02.137645Z","caller":"traceutil/trace.go:171","msg":"trace[1639581918] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1019; }","duration":"119.642519ms","start":"2026-08-26T00:17:02.017976Z","end":"2026-08-26T00:17:02.137619Z","steps":["trace[1639581918] 'range keys from in-memory index tree' (duration: 119.322312ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:02.137779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.589309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-26T00:17:02.137839Z","caller":"traceutil/trace.go:171","msg":"trace[2066714607] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1019; }","duration":"141.93781ms","start":"2026-08-26T00:17:01.995714Z","end":"2026-08-26T00:17:02.137651Z","steps":["trace[2066714607] 'range keys from in-memory index tree' (duration: 141.591593ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:17:02.137874Z","caller":"traceutil/trace.go:171","msg":"trace[909108626] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1019; }","duration":"116.719731ms","start":"2026-08-26T00:17:02.021134Z","end":"2026-08-26T00:17:02.137853Z","steps":["trace[909108626] 'range keys from in-memory index tree' (duration: 116.155539ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:02.43558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.612712ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-26T00:17:02.435653Z","caller":"traceutil/trace.go:171","msg":"trace[413273686] linearizableReadLoop","detail":"{readStateIndex:1052; appliedIndex:1051; }","duration":"202.688646ms","start":"2026-08-26T00:17:02.232953Z","end":"2026-08-26T00:17:02.435642Z","steps":["trace[413273686] 'read index received' (duration: 58.927652ms)","trace[413273686] 'applied index is now lower than readState.Index' (duration: 143.759944ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:17:02.435717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.766658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:17:02.435746Z","caller":"traceutil/trace.go:171","msg":"trace[1373865730] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1021; }","duration":"202.829989ms","start":"2026-08-26T00:17:02.232904Z","end":"2026-08-26T00:17:02.435734Z","steps":["trace[1373865730] 'agreement among raft nodes before linearized reading' (duration: 202.789368ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:17:02.435757Z","caller":"traceutil/trace.go:171","msg":"trace[1788173514] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"293.586499ms","start":"2026-08-26T00:17:02.142126Z","end":"2026-08-26T00:17:02.435712Z","steps":["trace[1788173514] 'process raft request' (duration: 149.793244ms)","trace[1788173514] 'compare' (duration: 143.514079ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:17:02.674202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.869396ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:17:02.674318Z","caller":"traceutil/trace.go:171","msg":"trace[602741731] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1021; }","duration":"112.003569ms","start":"2026-08-26T00:17:02.562297Z","end":"2026-08-26T00:17:02.674301Z","steps":["trace[602741731] 'range keys from in-memory index tree' (duration: 111.855385ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:17:03.602242Z","caller":"traceutil/trace.go:171","msg":"trace[1968731249] transaction","detail":"{read_only:false; response_revision:1023; number_of_response:1; }","duration":"244.547386ms","start":"2026-08-26T00:17:03.357671Z","end":"2026-08-26T00:17:03.602218Z","steps":["trace[1968731249] 'process raft request' (duration: 244.418164ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:03.849803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.034639ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-26T00:17:03.849897Z","caller":"traceutil/trace.go:171","msg":"trace[1653158277] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"463.282998ms","start":"2026-08-26T00:17:03.386595Z","end":"2026-08-26T00:17:03.849878Z","steps":["trace[1653158277] 'process raft request' (duration: 271.003384ms)","trace[1653158277] 'compare' (duration: 191.936748ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:17:03.849959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:03.386564Z","time spent":"463.363819ms","remote":"127.0.0.1:36450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-26T00:17:04.124891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.377959ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-26T00:17:04.125253Z","caller":"traceutil/trace.go:171","msg":"trace[490654645] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"196.672604ms","start":"2026-08-26T00:17:03.928561Z","end":"2026-08-26T00:17:04.125233Z","steps":["trace[490654645] 'process raft request' (duration: 73.791645ms)","trace[490654645] 'compare' (duration: 122.190285ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:17:04.489073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.181348ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-26T00:17:04.48932Z","caller":"traceutil/trace.go:171","msg":"trace[1565552271] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"283.98827ms","start":"2026-08-26T00:17:04.20531Z","end":"2026-08-26T00:17:04.489298Z","steps":["trace[1565552271] 'process raft request' (duration: 113.495157ms)","trace[1565552271] 'compare' (duration: 170.038645ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:17:05.854763Z","caller":"traceutil/trace.go:171","msg":"trace[1314148035] linearizableReadLoop","detail":"{readStateIndex:1060; appliedIndex:1059; }","duration":"162.302623ms","start":"2026-08-26T00:17:05.692432Z","end":"2026-08-26T00:17:05.854735Z","steps":["trace[1314148035] 'read index received' (duration: 162.16091ms)","trace[1314148035] 'applied index is now lower than readState.Index' (duration: 140.653µs)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:17:05.854864Z","caller":"traceutil/trace.go:171","msg":"trace[294763430] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"246.012978ms","start":"2026-08-26T00:17:05.60882Z","end":"2026-08-26T00:17:05.854833Z","steps":["trace[294763430] 'process raft request' (duration: 245.658291ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:05.855018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.567578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.119\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-26T00:17:05.855238Z","caller":"traceutil/trace.go:171","msg":"trace[292580331] range","detail":"{range_begin:/registry/masterleases/162.253.55.119; range_end:; response_count:1; response_revision:1029; }","duration":"162.66232ms","start":"2026-08-26T00:17:05.692381Z","end":"2026-08-26T00:17:05.855044Z","steps":["trace[292580331] 'agreement among raft nodes before linearized reading' (duration: 162.496117ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:06.188086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.900968ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-26T00:17:06.188183Z","caller":"traceutil/trace.go:171","msg":"trace[395600964] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"332.570764ms","start":"2026-08-26T00:17:05.855601Z","end":"2026-08-26T00:17:06.188171Z","steps":["trace[395600964] 'process raft request' (duration: 81.526043ms)","trace[395600964] 'compare' (duration: 250.826577ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:17:06.188219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:05.855577Z","time spent":"332.624755ms","remote":"127.0.0.1:36450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-26T00:17:06.188494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:05.856282Z","time spent":"332.206937ms","remote":"127.0.0.1:36202","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-08-26T00:17:06.557561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.61445ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-26T00:17:06.557812Z","caller":"traceutil/trace.go:171","msg":"trace[1641588499] linearizableReadLoop","detail":"{readStateIndex:1064; appliedIndex:1062; }","duration":"323.176894ms","start":"2026-08-26T00:17:06.234612Z","end":"2026-08-26T00:17:06.557789Z","steps":["trace[1641588499] 'read index received' (duration: 188.188447ms)","trace[1641588499] 'applied index is now lower than readState.Index' (duration: 134.987227ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:17:06.557964Z","caller":"traceutil/trace.go:171","msg":"trace[645846600] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"365.324022ms","start":"2026-08-26T00:17:06.192627Z","end":"2026-08-26T00:17:06.557951Z","steps":["trace[645846600] 'process raft request' (duration: 365.082597ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:17:06.557986Z","caller":"traceutil/trace.go:171","msg":"trace[1305016442] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"368.034984ms","start":"2026-08-26T00:17:06.189918Z","end":"2026-08-26T00:17:06.557953Z","steps":["trace[1305016442] 'process raft request' (duration: 232.861383ms)","trace[1305016442] 'compare' (duration: 134.316965ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:17:06.558033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:06.192577Z","time spent":"365.419784ms","remote":"127.0.0.1:36450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-26T00:17:06.558068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.436229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-26T00:17:06.558145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:06.189897Z","time spent":"368.152416ms","remote":"127.0.0.1:36202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T00:17:06.558172Z","caller":"traceutil/trace.go:171","msg":"trace[784915829] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1032; }","duration":"323.594472ms","start":"2026-08-26T00:17:06.234553Z","end":"2026-08-26T00:17:06.558148Z","steps":["trace[784915829] 'agreement among raft nodes before linearized reading' (duration: 323.424688ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:06.558239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:06.234535Z","time spent":"323.689934ms","remote":"127.0.0.1:55054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-26T00:17:06.789158Z","caller":"traceutil/trace.go:171","msg":"trace[286662369] linearizableReadLoop","detail":"{readStateIndex:1066; appliedIndex:1064; }","duration":"226.176995ms","start":"2026-08-26T00:17:06.562953Z","end":"2026-08-26T00:17:06.78913Z","steps":["trace[286662369] 'read index received' (duration: 88.060888ms)","trace[286662369] 'applied index is now lower than readState.Index' (duration: 138.115117ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:17:06.789376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.395849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-26T00:17:06.789417Z","caller":"traceutil/trace.go:171","msg":"trace[1426641670] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:1034; }","duration":"226.489471ms","start":"2026-08-26T00:17:06.562918Z","end":"2026-08-26T00:17:06.789407Z","steps":["trace[1426641670] 'agreement among raft nodes before linearized reading' (duration: 226.319518ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:17:06.789413Z","caller":"traceutil/trace.go:171","msg":"trace[1073117373] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"226.682915ms","start":"2026-08-26T00:17:06.562702Z","end":"2026-08-26T00:17:06.789385Z","steps":["trace[1073117373] 'process raft request' (duration: 153.577654ms)","trace[1073117373] 'compare' (duration: 72.734183ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:17:07.290446Z","caller":"traceutil/trace.go:171","msg":"trace[98343230] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"443.575173ms","start":"2026-08-26T00:17:06.846601Z","end":"2026-08-26T00:17:07.290176Z","steps":["trace[98343230] 'process raft request' (duration: 374.58361ms)","trace[98343230] 'compare' (duration: 68.576215ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:17:07.290615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:06.846577Z","time spent":"443.947149ms","remote":"127.0.0.1:36450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T00:17:07.345056Z","caller":"traceutil/trace.go:171","msg":"trace[1732122078] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"222.919433ms","start":"2026-08-26T00:17:07.12211Z","end":"2026-08-26T00:17:07.34503Z","steps":["trace[1732122078] 'process raft request' (duration: 222.77143ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:17:09.034251Z","caller":"traceutil/trace.go:171","msg":"trace[1035841443] linearizableReadLoop","detail":"{readStateIndex:1071; appliedIndex:1070; }","duration":"472.303964ms","start":"2026-08-26T00:17:08.561925Z","end":"2026-08-26T00:17:09.034229Z","steps":["trace[1035841443] 'read index received' (duration: 472.10489ms)","trace[1035841443] 'applied index is now lower than readState.Index' (duration: 198.204µs)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:17:09.034316Z","caller":"traceutil/trace.go:171","msg":"trace[1613644950] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"478.97073ms","start":"2026-08-26T00:17:08.555305Z","end":"2026-08-26T00:17:09.034276Z","steps":["trace[1613644950] 'process raft request' (duration: 478.778826ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:09.034467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:08.555273Z","time spent":"479.107963ms","remote":"127.0.0.1:36354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-26T00:17:09.034483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.537108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-08-26T00:17:09.034519Z","caller":"traceutil/trace.go:171","msg":"trace[44423825] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1039; }","duration":"472.64895ms","start":"2026-08-26T00:17:08.56186Z","end":"2026-08-26T00:17:09.034509Z","steps":["trace[44423825] 'agreement among raft nodes before linearized reading' (duration: 472.502228ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:09.034555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:08.561834Z","time spent":"472.713522ms","remote":"127.0.0.1:36450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-26T00:17:09.034695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.241115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-26T00:17:09.034737Z","caller":"traceutil/trace.go:171","msg":"trace[907188552] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1039; }","duration":"379.319656ms","start":"2026-08-26T00:17:08.655405Z","end":"2026-08-26T00:17:09.034725Z","steps":["trace[907188552] 'agreement among raft nodes before linearized reading' (duration: 379.191034ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:09.034766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:08.655381Z","time spent":"379.378177ms","remote":"127.0.0.1:36450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-26T00:17:09.035077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.179894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-26T00:17:09.035163Z","caller":"traceutil/trace.go:171","msg":"trace[2030839924] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1039; }","duration":"242.267756ms","start":"2026-08-26T00:17:08.79287Z","end":"2026-08-26T00:17:09.035138Z","steps":["trace[2030839924] 'agreement among raft nodes before linearized reading' (duration: 242.069832ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:09.264697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.145905ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-26T00:17:09.264964Z","caller":"traceutil/trace.go:171","msg":"trace[879548198] transaction","detail":"{read_only:false; response_revision:1040; number_of_response:1; }","duration":"224.848892ms","start":"2026-08-26T00:17:09.040081Z","end":"2026-08-26T00:17:09.26493Z","steps":["trace[879548198] 'process raft request' (duration: 79.3732ms)","trace[879548198] 'compare' (duration: 145.014573ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:17:09.265011Z","caller":"traceutil/trace.go:171","msg":"trace[1034381650] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"224.323192ms","start":"2026-08-26T00:17:09.040668Z","end":"2026-08-26T00:17:09.264992Z","steps":["trace[1034381650] 'process raft request' (duration: 224.269711ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:17:09.265163Z","caller":"traceutil/trace.go:171","msg":"trace[565879192] linearizableReadLoop","detail":"{readStateIndex:1072; appliedIndex:1071; }","duration":"225.042506ms","start":"2026-08-26T00:17:09.04011Z","end":"2026-08-26T00:17:09.265152Z","steps":["trace[565879192] 'read index received' (duration: 110.173µs)","trace[565879192] 'applied index is now lower than readState.Index' (duration: 224.931493ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:17:09.267097Z","caller":"traceutil/trace.go:171","msg":"trace[1686108239] transaction","detail":"{read_only:false; response_revision:1041; number_of_response:1; }","duration":"226.627835ms","start":"2026-08-26T00:17:09.040454Z","end":"2026-08-26T00:17:09.267081Z","steps":["trace[1686108239] 'process raft request' (duration: 224.418423ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:09.267159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.037603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-after-finished-controller\" ","response":"range_response_count:1 size:224"} {"level":"info","ts":"2026-08-26T00:17:09.26734Z","caller":"traceutil/trace.go:171","msg":"trace[428813505] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-after-finished-controller; range_end:; response_count:1; response_revision:1042; }","duration":"227.239446ms","start":"2026-08-26T00:17:09.040079Z","end":"2026-08-26T00:17:09.267318Z","steps":["trace[428813505] 'agreement among raft nodes before linearized reading' (duration: 225.115577ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:17:09.710482Z","caller":"traceutil/trace.go:171","msg":"trace[2119961265] linearizableReadLoop","detail":"{readStateIndex:1075; appliedIndex:1074; }","duration":"414.513862ms","start":"2026-08-26T00:17:09.295948Z","end":"2026-08-26T00:17:09.710461Z","steps":["trace[2119961265] 'read index received' (duration: 370.316253ms)","trace[2119961265] 'applied index is now lower than readState.Index' (duration: 44.196639ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:17:09.710599Z","caller":"traceutil/trace.go:171","msg":"trace[64146635] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"435.68552ms","start":"2026-08-26T00:17:09.274874Z","end":"2026-08-26T00:17:09.71056Z","steps":["trace[64146635] 'process raft request' (duration: 391.375388ms)","trace[64146635] 'compare' (duration: 43.424325ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:17:09.710652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.672865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-26T00:17:09.710679Z","caller":"traceutil/trace.go:171","msg":"trace[2136103862] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1043; }","duration":"414.748747ms","start":"2026-08-26T00:17:09.295923Z","end":"2026-08-26T00:17:09.710672Z","steps":["trace[2136103862] 'agreement among raft nodes before linearized reading' (duration: 414.618485ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:09.710703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:09.295913Z","time spent":"414.784498ms","remote":"127.0.0.1:36450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-26T00:17:09.710781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:09.274865Z","time spent":"435.816032ms","remote":"127.0.0.1:36424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2914,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-26T00:17:09.995037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.905911ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-26T00:17:09.995315Z","caller":"traceutil/trace.go:171","msg":"trace[211697660] transaction","detail":"{read_only:false; response_revision:1045; number_of_response:1; }","duration":"256.918353ms","start":"2026-08-26T00:17:09.738383Z","end":"2026-08-26T00:17:09.995302Z","steps":["trace[211697660] 'process raft request' (duration: 256.859502ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:17:09.995573Z","caller":"traceutil/trace.go:171","msg":"trace[1495889174] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"280.389815ms","start":"2026-08-26T00:17:09.715143Z","end":"2026-08-26T00:17:09.995532Z","steps":["trace[1495889174] 'process raft request' (duration: 118.920723ms)","trace[1495889174] 'compare' (duration: 160.801559ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:17:09.995792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.168875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:1 size:2929"} {"level":"info","ts":"2026-08-26T00:17:09.99564Z","caller":"traceutil/trace.go:171","msg":"trace[329428142] linearizableReadLoop","detail":"{readStateIndex:1076; appliedIndex:1075; }","duration":"276.030162ms","start":"2026-08-26T00:17:09.719598Z","end":"2026-08-26T00:17:09.995628Z","steps":["trace[329428142] 'read index received' (duration: 114.480489ms)","trace[329428142] 'applied index is now lower than readState.Index' (duration: 161.547983ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:17:09.995835Z","caller":"traceutil/trace.go:171","msg":"trace[1451786474] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:1; response_revision:1045; }","duration":"276.256497ms","start":"2026-08-26T00:17:09.719567Z","end":"2026-08-26T00:17:09.995823Z","steps":["trace[1451786474] 'agreement among raft nodes before linearized reading' (duration: 276.095194ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:09.996162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.228686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-77ch6\" ","response":"range_response_count:1 size:4129"} {"level":"info","ts":"2026-08-26T00:17:09.996245Z","caller":"traceutil/trace.go:171","msg":"trace[848987154] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-77ch6; range_end:; response_count:1; response_revision:1045; }","duration":"276.335168ms","start":"2026-08-26T00:17:09.719883Z","end":"2026-08-26T00:17:09.996218Z","steps":["trace[848987154] 'agreement among raft nodes before linearized reading' (duration: 276.082144ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:11.110295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.015370261s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-26T00:17:11.110489Z","caller":"traceutil/trace.go:171","msg":"trace[1527557682] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"830.117286ms","start":"2026-08-26T00:17:10.280353Z","end":"2026-08-26T00:17:11.11047Z","steps":["trace[1527557682] 'process raft request' (duration: 830.048265ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:17:11.110478Z","caller":"traceutil/trace.go:171","msg":"trace[1264603206] transaction","detail":"{read_only:false; response_revision:1046; number_of_response:1; }","duration":"1.109853113s","start":"2026-08-26T00:17:10.00059Z","end":"2026-08-26T00:17:11.110443Z","steps":["trace[1264603206] 'process raft request' (duration: 94.254878ms)","trace[1264603206] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/plndr-svcs-lock; req_size:419; } (duration: 1.014341302s)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:17:11.110565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:10.280272Z","time spent":"830.251058ms","remote":"127.0.0.1:36450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-26T00:17:11.110608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:10.000574Z","time spent":"1.109965336s","remote":"127.0.0.1:36450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T00:17:11.110725Z","caller":"traceutil/trace.go:171","msg":"trace[645646028] linearizableReadLoop","detail":"{readStateIndex:1078; appliedIndex:1077; }","duration":"1.109565888s","start":"2026-08-26T00:17:10.001148Z","end":"2026-08-26T00:17:11.110714Z","steps":["trace[645646028] 'read index received' (duration: 94.041754ms)","trace[645646028] 'applied index is now lower than readState.Index' (duration: 1.015523164s)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:17:11.110903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.109747691s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-77ch6\" ","response":"range_response_count:1 size:4129"} {"level":"info","ts":"2026-08-26T00:17:11.110937Z","caller":"traceutil/trace.go:171","msg":"trace[968929904] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-77ch6; range_end:; response_count:1; response_revision:1047; }","duration":"1.109792022s","start":"2026-08-26T00:17:10.001132Z","end":"2026-08-26T00:17:11.110924Z","steps":["trace[968929904] 'agreement among raft nodes before linearized reading' (duration: 1.109652549s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:11.110969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:10.001125Z","time spent":"1.109837942s","remote":"127.0.0.1:36358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":4152,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-77ch6\" "} {"level":"warn","ts":"2026-08-26T00:17:11.11115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"971.069295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:17:11.111192Z","caller":"traceutil/trace.go:171","msg":"trace[641841327] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1047; }","duration":"971.112765ms","start":"2026-08-26T00:17:10.140066Z","end":"2026-08-26T00:17:11.111178Z","steps":["trace[641841327] 'agreement among raft nodes before linearized reading' (duration: 971.048534ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:11.111225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:10.140022Z","time spent":"971.194897ms","remote":"127.0.0.1:36468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-08-26T00:17:11.111258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"877.898677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:17:11.111289Z","caller":"traceutil/trace.go:171","msg":"trace[1148580000] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1047; }","duration":"877.930067ms","start":"2026-08-26T00:17:10.233349Z","end":"2026-08-26T00:17:11.111279Z","steps":["trace[1148580000] 'agreement among raft nodes before linearized reading' (duration: 877.881856ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:11.111314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:10.233266Z","time spent":"878.04304ms","remote":"127.0.0.1:55054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-26T00:17:11.734541Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17157483382474225840,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-26T00:17:11.827381Z","caller":"traceutil/trace.go:171","msg":"trace[1652567473] transaction","detail":"{read_only:false; response_revision:1048; number_of_response:1; }","duration":"713.875455ms","start":"2026-08-26T00:17:11.11348Z","end":"2026-08-26T00:17:11.827356Z","steps":["trace[1652567473] 'process raft request' (duration: 713.61671ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:11.827566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:11.113439Z","time spent":"714.028017ms","remote":"127.0.0.1:36358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4126,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-26T00:17:12.24568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.177603ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-26T00:17:12.246524Z","caller":"traceutil/trace.go:171","msg":"trace[1270703706] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1049; }","duration":"416.897133ms","start":"2026-08-26T00:17:11.829606Z","end":"2026-08-26T00:17:12.246503Z","steps":["trace[1270703706] 'process raft request' (duration: 416.713579ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:17:12.246561Z","caller":"traceutil/trace.go:171","msg":"trace[129430067] linearizableReadLoop","detail":"{readStateIndex:1081; appliedIndex:1080; }","duration":"1.012478706s","start":"2026-08-26T00:17:11.234052Z","end":"2026-08-26T00:17:12.246531Z","steps":["trace[129430067] 'read index received' (duration: 593.984133ms)","trace[129430067] 'applied index is now lower than readState.Index' (duration: 418.491693ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:17:12.246698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.012637319s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:17:12.247577Z","caller":"traceutil/trace.go:171","msg":"trace[681308670] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1049; }","duration":"1.013570076s","start":"2026-08-26T00:17:11.233985Z","end":"2026-08-26T00:17:12.247555Z","steps":["trace[681308670] 'agreement among raft nodes before linearized reading' (duration: 1.012623888s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:12.247009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"977.720902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-26T00:17:12.247769Z","caller":"traceutil/trace.go:171","msg":"trace[894422562] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1049; }","duration":"978.502436ms","start":"2026-08-26T00:17:11.269248Z","end":"2026-08-26T00:17:12.247751Z","steps":["trace[894422562] 'agreement among raft nodes before linearized reading' (duration: 977.669251ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:12.247848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:11.269228Z","time spent":"978.605728ms","remote":"127.0.0.1:36450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-26T00:17:12.247045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:11.829581Z","time spent":"417.207659ms","remote":"127.0.0.1:36358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80,"response count":0,"response size":41,"request content":"compare: success: > failure: >"} {"level":"warn","ts":"2026-08-26T00:17:12.247076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.815814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-26T00:17:12.248313Z","caller":"traceutil/trace.go:171","msg":"trace[1646771858] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1049; }","duration":"247.085757ms","start":"2026-08-26T00:17:12.001214Z","end":"2026-08-26T00:17:12.248299Z","steps":["trace[1646771858] 'agreement among raft nodes before linearized reading' (duration: 245.813123ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:12.247148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"683.656914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-26T00:17:12.248434Z","caller":"traceutil/trace.go:171","msg":"trace[1013731942] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1049; }","duration":"684.977089ms","start":"2026-08-26T00:17:11.563433Z","end":"2026-08-26T00:17:12.24841Z","steps":["trace[1013731942] 'agreement among raft nodes before linearized reading' (duration: 683.680725ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:12.248495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:11.563395Z","time spent":"685.083181ms","remote":"127.0.0.1:36612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-08-26T00:17:12.24716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"975.801286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-26T00:17:12.24859Z","caller":"traceutil/trace.go:171","msg":"trace[1054928691] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1049; }","duration":"977.229172ms","start":"2026-08-26T00:17:11.271338Z","end":"2026-08-26T00:17:12.248567Z","steps":["trace[1054928691] 'agreement among raft nodes before linearized reading' (duration: 975.757075ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:12.248666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:11.271331Z","time spent":"977.314584ms","remote":"127.0.0.1:36450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-26T00:17:12.247251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"977.691812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-26T00:17:12.248828Z","caller":"traceutil/trace.go:171","msg":"trace[546466400] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1049; }","duration":"979.290051ms","start":"2026-08-26T00:17:11.269523Z","end":"2026-08-26T00:17:12.248813Z","steps":["trace[546466400] 'agreement among raft nodes before linearized reading' (duration: 977.58013ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:12.248872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:11.269516Z","time spent":"979.344822ms","remote":"127.0.0.1:36450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-26T00:17:12.249006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:11.23396Z","time spent":"1.013715339s","remote":"127.0.0.1:55054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-26T00:17:12.525495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.482464ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-26T00:17:12.526502Z","caller":"traceutil/trace.go:171","msg":"trace[472771705] transaction","detail":"{read_only:false; response_revision:1050; number_of_response:1; }","duration":"273.024574ms","start":"2026-08-26T00:17:12.253431Z","end":"2026-08-26T00:17:12.526455Z","steps":["trace[472771705] 'process raft request' (duration: 130.43377ms)","trace[472771705] 'compare' (duration: 141.304641ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:17:12.526655Z","caller":"traceutil/trace.go:171","msg":"trace[414447689] transaction","detail":"{read_only:false; response_revision:1051; number_of_response:1; }","duration":"272.094788ms","start":"2026-08-26T00:17:12.254541Z","end":"2026-08-26T00:17:12.526636Z","steps":["trace[414447689] 'process raft request' (duration: 271.804922ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:17:12.526873Z","caller":"traceutil/trace.go:171","msg":"trace[370989028] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"270.993347ms","start":"2026-08-26T00:17:12.255842Z","end":"2026-08-26T00:17:12.526835Z","steps":["trace[370989028] 'process raft request' (duration: 270.59653ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:17:12.526945Z","caller":"traceutil/trace.go:171","msg":"trace[1493229725] transaction","detail":"{read_only:false; response_revision:1053; number_of_response:1; }","duration":"271.021587ms","start":"2026-08-26T00:17:12.255872Z","end":"2026-08-26T00:17:12.526893Z","steps":["trace[1493229725] 'process raft request' (duration: 270.60439ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:12.527144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.506659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:1 size:2929"} {"level":"info","ts":"2026-08-26T00:17:12.527184Z","caller":"traceutil/trace.go:171","msg":"trace[533678993] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:1; response_revision:1053; }","duration":"269.595491ms","start":"2026-08-26T00:17:12.257578Z","end":"2026-08-26T00:17:12.527174Z","steps":["trace[533678993] 'agreement among raft nodes before linearized reading' (duration: 269.439059ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:17:12.526551Z","caller":"traceutil/trace.go:171","msg":"trace[243014858] linearizableReadLoop","detail":"{readStateIndex:1086; appliedIndex:1082; }","duration":"268.904839ms","start":"2026-08-26T00:17:12.257623Z","end":"2026-08-26T00:17:12.526528Z","steps":["trace[243014858] 'read index received' (duration: 126.332735ms)","trace[243014858] 'applied index is now lower than readState.Index' (duration: 142.570854ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:17:12.527925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.816065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:17:12.527969Z","caller":"traceutil/trace.go:171","msg":"trace[1468056367] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1053; }","duration":"269.901717ms","start":"2026-08-26T00:17:12.258057Z","end":"2026-08-26T00:17:12.527958Z","steps":["trace[1468056367] 'agreement among raft nodes before linearized reading' (duration: 269.811435ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:17:13.044405Z","caller":"traceutil/trace.go:171","msg":"trace[1962084091] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1054; }","duration":"516.205254ms","start":"2026-08-26T00:17:12.528166Z","end":"2026-08-26T00:17:13.044371Z","steps":["trace[1962084091] 'process raft request' (duration: 475.114953ms)","trace[1962084091] 'compare' (duration: 40.779316ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:17:13.044658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:12.528148Z","time spent":"516.359118ms","remote":"127.0.0.1:36424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":74,"response count":0,"response size":41,"request content":"compare: success: > failure: >"} {"level":"info","ts":"2026-08-26T00:17:13.343163Z","caller":"traceutil/trace.go:171","msg":"trace[1611589687] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"226.036822ms","start":"2026-08-26T00:17:13.117091Z","end":"2026-08-26T00:17:13.343128Z","steps":["trace[1611589687] 'process raft request' (duration: 137.547264ms)","trace[1611589687] 'compare' (duration: 88.288345ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:17:13.401412Z","caller":"traceutil/trace.go:171","msg":"trace[2056781980] linearizableReadLoop","detail":"{readStateIndex:1089; appliedIndex:1087; }","duration":"232.895748ms","start":"2026-08-26T00:17:13.168472Z","end":"2026-08-26T00:17:13.401368Z","steps":["trace[2056781980] 'read index received' (duration: 86.180056ms)","trace[2056781980] 'applied index is now lower than readState.Index' (duration: 146.714832ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:17:13.401539Z","caller":"traceutil/trace.go:171","msg":"trace[2126386177] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"282.190499ms","start":"2026-08-26T00:17:13.119298Z","end":"2026-08-26T00:17:13.401489Z","steps":["trace[2126386177] 'process raft request' (duration: 281.899784ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:13.401649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.152302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-26T00:17:13.401806Z","caller":"traceutil/trace.go:171","msg":"trace[920576008] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1056; }","duration":"233.55974ms","start":"2026-08-26T00:17:13.168231Z","end":"2026-08-26T00:17:13.401791Z","steps":["trace[920576008] 'agreement among raft nodes before linearized reading' (duration: 233.350476ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:17:14.684092Z","caller":"traceutil/trace.go:171","msg":"trace[240830586] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"147.196253ms","start":"2026-08-26T00:17:14.53687Z","end":"2026-08-26T00:17:14.684067Z","steps":["trace[240830586] 'process raft request' (duration: 147.076751ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:17:14.684209Z","caller":"traceutil/trace.go:171","msg":"trace[57388526] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"144.83466ms","start":"2026-08-26T00:17:14.539344Z","end":"2026-08-26T00:17:14.684179Z","steps":["trace[57388526] 'process raft request' (duration: 144.686558ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:17:14.684185Z","caller":"traceutil/trace.go:171","msg":"trace[1103997752] transaction","detail":"{read_only:false; response_revision:1058; number_of_response:1; }","duration":"147.492909ms","start":"2026-08-26T00:17:14.536639Z","end":"2026-08-26T00:17:14.684131Z","steps":["trace[1103997752] 'process raft request' (duration: 120.355266ms)","trace[1103997752] 'compare' (duration: 26.788696ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:17:20.891815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.465819ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-26T00:17:20.891925Z","caller":"traceutil/trace.go:171","msg":"trace[2069920483] linearizableReadLoop","detail":"{readStateIndex:1113; appliedIndex:1112; }","duration":"125.265681ms","start":"2026-08-26T00:17:20.76664Z","end":"2026-08-26T00:17:20.891906Z","steps":["trace[2069920483] 'read index received' (duration: 1.518567ms)","trace[2069920483] 'applied index is now lower than readState.Index' (duration: 123.745434ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:17:20.89203Z","caller":"traceutil/trace.go:171","msg":"trace[2136878838] transaction","detail":"{read_only:false; response_revision:1079; number_of_response:1; }","duration":"133.608707ms","start":"2026-08-26T00:17:20.758385Z","end":"2026-08-26T00:17:20.891994Z","steps":["trace[2136878838] 'compare' (duration: 123.241885ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:20.892155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.509695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-26T00:17:20.892185Z","caller":"traceutil/trace.go:171","msg":"trace[402832733] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1079; }","duration":"125.560146ms","start":"2026-08-26T00:17:20.766614Z","end":"2026-08-26T00:17:20.892174Z","steps":["trace[402832733] 'agreement among raft nodes before linearized reading' (duration: 125.386353ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:20.892485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.001857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-26T00:17:20.892534Z","caller":"traceutil/trace.go:171","msg":"trace[602355122] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1079; }","duration":"125.082258ms","start":"2026-08-26T00:17:20.76744Z","end":"2026-08-26T00:17:20.892522Z","steps":["trace[602355122] 'agreement among raft nodes before linearized reading' (duration: 124.907645ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:26.195271Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17157483382474225962,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:17:26.695569Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17157483382474225962,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:17:27.196279Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17157483382474225962,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:17:27.696858Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17157483382474225962,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:17:28.197403Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17157483382474225962,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:17:28.697704Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17157483382474225962,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:17:29.198826Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17157483382474225962,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:17:29.233604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000261508s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-26T00:17:29.23373Z","caller":"traceutil/trace.go:171","msg":"trace[373874268] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000446961s","start":"2026-08-26T00:17:27.233261Z","end":"2026-08-26T00:17:29.233708Z","steps":["trace[373874268] 'agreement among raft nodes before linearized reading' (duration: 2.000296789s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:29.233803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:27.233239Z","time spent":"2.000551363s","remote":"127.0.0.1:55054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/26 00:17:29 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-26T00:17:29.699814Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17157483382474225962,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:17:30.200506Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17157483382474225962,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:17:30.701073Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17157483382474225962,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:17:31.201767Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17157483382474225962,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:17:31.234294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000415169s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-08-26T00:17:31.234554Z","caller":"traceutil/trace.go:171","msg":"trace[825496785] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000757605s","start":"2026-08-26T00:17:29.233768Z","end":"2026-08-26T00:17:31.234526Z","steps":["trace[825496785] 'agreement among raft nodes before linearized reading' (duration: 2.0004647s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:31.234842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:29.233744Z","time spent":"2.001004938s","remote":"127.0.0.1:55062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/26 00:17:31 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-26T00:17:31.702147Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17157483382474225962,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:17:31.728343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999013323s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-26T00:17:31.728459Z","caller":"traceutil/trace.go:171","msg":"trace[590891163] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; }","duration":"4.999199297s","start":"2026-08-26T00:17:26.729237Z","end":"2026-08-26T00:17:31.728436Z","steps":["trace[590891163] 'agreement among raft nodes before linearized reading' (duration: 4.999057474s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:31.728525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:26.729208Z","time spent":"4.999303068s","remote":"127.0.0.1:36450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} 2026/08/26 00:17:31 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-26T00:17:31.918399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999529373s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-26T00:17:31.91848Z","caller":"traceutil/trace.go:171","msg":"trace[1326341227] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"4.999690906s","start":"2026-08-26T00:17:26.91877Z","end":"2026-08-26T00:17:31.918461Z","steps":["trace[1326341227] 'agreement among raft nodes before linearized reading' (duration: 4.999573524s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:31.918531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:26.918751Z","time spent":"4.999771157s","remote":"127.0.0.1:36450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} 2026/08/26 00:17:31 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-26T00:17:31.98602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999750485s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-08-26T00:17:31.986113Z","caller":"traceutil/trace.go:171","msg":"trace[580477657] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"4.999874567s","start":"2026-08-26T00:17:26.986212Z","end":"2026-08-26T00:17:31.986087Z","steps":["trace[580477657] 'agreement among raft nodes before linearized reading' (duration: 4.999767965s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:31.986162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:26.986201Z","time spent":"4.999952199s","remote":"127.0.0.1:36450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} 2026/08/26 00:17:31 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-26T00:17:32.202796Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17157483382474225962,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:17:32.472714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:27.473619Z","time spent":"4.99908603s","remote":"127.0.0.1:36450","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-08-26T00:17:32.519794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:25.518791Z","time spent":"7.000974282s","remote":"127.0.0.1:36450","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-08-26T00:17:32.694887Z","caller":"etcdserver/v3_server.go:909","msg":"timed out waiting for read index response (local node might have slow network)","timeout":"7s"} {"level":"warn","ts":"2026-08-26T00:17:32.695112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.000922557s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.119\" ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-08-26T00:17:32.695174Z","caller":"traceutil/trace.go:171","msg":"trace[857549233] range","detail":"{range_begin:/registry/masterleases/162.253.55.119; range_end:; }","duration":"7.001052719s","start":"2026-08-26T00:17:25.694097Z","end":"2026-08-26T00:17:32.69515Z","steps":["trace[857549233] 'agreement among raft nodes before linearized reading' (duration: 7.000971398s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:32.695231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:25.694075Z","time spent":"7.001144491s","remote":"127.0.0.1:36202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":0,"request content":"key:\"/registry/masterleases/162.253.55.119\" "} {"level":"warn","ts":"2026-08-26T00:17:33.195104Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17157483382474225969,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:17:33.242533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000405515s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-08-26T00:17:33.242629Z","caller":"traceutil/trace.go:171","msg":"trace[49950307] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000562987s","start":"2026-08-26T00:17:31.24204Z","end":"2026-08-26T00:17:33.242603Z","steps":["trace[49950307] 'agreement among raft nodes before linearized reading' (duration: 2.000433745s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:33.242714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:31.242028Z","time spent":"2.000672739s","remote":"127.0.0.1:55054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-26T00:17:33.696317Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17157483382474225969,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:17:34.197035Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17157483382474225969,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:17:34.697224Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17157483382474225969,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:17:34.932552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:27.931366Z","time spent":"7.001179075s","remote":"127.0.0.1:36450","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-08-26T00:17:35.197654Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17157483382474225969,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:17:35.243928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999762474s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-08-26T00:17:35.244018Z","caller":"traceutil/trace.go:171","msg":"trace[362230662] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999918297s","start":"2026-08-26T00:17:33.244079Z","end":"2026-08-26T00:17:35.243998Z","steps":["trace[362230662] 'agreement among raft nodes before linearized reading' (duration: 1.999806475s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:35.244099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:33.244053Z","time spent":"2.000036799s","remote":"127.0.0.1:55062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/26 00:17:35 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-26T00:17:35.501639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"977.061273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-26T00:17:35.501741Z","caller":"traceutil/trace.go:171","msg":"trace[967767481] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; }","duration":"977.242207ms","start":"2026-08-26T00:17:34.524475Z","end":"2026-08-26T00:17:35.501717Z","steps":["trace[967767481] 'agreement among raft nodes before linearized reading' (duration: 977.101964ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:35.501798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:34.524453Z","time spent":"977.333219ms","remote":"127.0.0.1:36450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} 2026/08/26 00:17:35 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-26T00:17:35.698348Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17157483382474225969,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:17:36.198761Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17157483382474225969,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:17:36.69986Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17157483382474225969,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:17:36.729243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.998074154s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-26T00:17:36.729336Z","caller":"traceutil/trace.go:171","msg":"trace[822011644] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; }","duration":"2.998241187s","start":"2026-08-26T00:17:33.731072Z","end":"2026-08-26T00:17:36.729313Z","steps":["trace[822011644] 'agreement among raft nodes before linearized reading' (duration: 2.998118715s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:36.729402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:33.731042Z","time spent":"2.998346248s","remote":"127.0.0.1:36450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} 2026/08/26 00:17:36 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-26T00:17:36.918906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.998260649s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-26T00:17:36.918998Z","caller":"traceutil/trace.go:171","msg":"trace[1685653483] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"2.998438802s","start":"2026-08-26T00:17:33.920537Z","end":"2026-08-26T00:17:36.918976Z","steps":["trace[1685653483] 'agreement among raft nodes before linearized reading' (duration: 2.99831382s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:36.919188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:33.920514Z","time spent":"2.998615034s","remote":"127.0.0.1:36450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} 2026/08/26 00:17:36 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-26T00:17:36.973536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"10.001078864s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-26T00:17:36.973627Z","caller":"traceutil/trace.go:171","msg":"trace[585301072] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; }","duration":"10.001242007s","start":"2026-08-26T00:17:26.972361Z","end":"2026-08-26T00:17:36.973603Z","steps":["trace[585301072] 'agreement among raft nodes before linearized reading' (duration: 10.001125755s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:36.973708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:26.972335Z","time spent":"10.001361679s","remote":"127.0.0.1:36450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} 2026/08/26 00:17:36 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-26T00:17:36.987157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.999981448s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-26T00:17:36.987422Z","caller":"traceutil/trace.go:171","msg":"trace[1313178960] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"3.000145401s","start":"2026-08-26T00:17:33.987119Z","end":"2026-08-26T00:17:36.987264Z","steps":["trace[1313178960] 'agreement among raft nodes before linearized reading' (duration: 3.000015069s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:36.987845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:33.9871Z","time spent":"3.00072531s","remote":"127.0.0.1:36450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} 2026/08/26 00:17:36 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-26T00:17:37.103749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:30.102901Z","time spent":"7.000840872s","remote":"127.0.0.1:36450","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-08-26T00:17:37.200582Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17157483382474225969,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:17:37.251565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000291112s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-26T00:17:37.251647Z","caller":"traceutil/trace.go:171","msg":"trace[1801293008] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000428254s","start":"2026-08-26T00:17:35.251201Z","end":"2026-08-26T00:17:37.251629Z","steps":["trace[1801293008] 'agreement among raft nodes before linearized reading' (duration: 2.000331112s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:37.251689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:35.251179Z","time spent":"2.000501816s","remote":"127.0.0.1:55054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/26 00:17:37 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-26T00:17:37.472618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.99816687s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-26T00:17:37.472783Z","caller":"traceutil/trace.go:171","msg":"trace[467327594] range","detail":"{range_begin:/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io; range_end:; }","duration":"4.998386793s","start":"2026-08-26T00:17:32.474373Z","end":"2026-08-26T00:17:37.472759Z","steps":["trace[467327594] 'agreement among raft nodes before linearized reading' (duration: 4.99819717s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:37.472869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:32.474356Z","time spent":"4.998499515s","remote":"127.0.0.1:36450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":0,"request content":"key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" "} 2026/08/26 00:17:37 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-26T00:17:37.701081Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17157483382474225969,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:17:37.78789Z","caller":"etcdserver/server.go:1165","msg":"failed to revoke lease","lease-id":"6e1ba03b6c0b38d7","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-08-26T00:17:38.201372Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17157483382474225969,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:17:38.238784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:31.237804Z","time spent":"7.00097155s","remote":"127.0.0.1:36240","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-08-26T00:17:38.70148Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17157483382474225969,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:17:39.20205Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17157483382474225969,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:17:39.251827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000091771s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-26T00:17:39.25193Z","caller":"traceutil/trace.go:171","msg":"trace[351665834] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000224082s","start":"2026-08-26T00:17:37.251687Z","end":"2026-08-26T00:17:39.251911Z","steps":["trace[351665834] 'agreement among raft nodes before linearized reading' (duration: 2.00010698s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:39.251985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:37.251678Z","time spent":"2.000297633s","remote":"127.0.0.1:55062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/26 00:17:39 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-26T00:17:39.69558Z","caller":"etcdserver/v3_server.go:909","msg":"timed out waiting for read index response (local node might have slow network)","timeout":"7s"} {"level":"warn","ts":"2026-08-26T00:17:39.695795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.319587207s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-08-26T00:17:39.695852Z","caller":"traceutil/trace.go:171","msg":"trace[1210331860] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; }","duration":"7.319680638s","start":"2026-08-26T00:17:32.376151Z","end":"2026-08-26T00:17:39.695831Z","steps":["trace[1210331860] 'agreement among raft nodes before linearized reading' (duration: 7.319584587s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:39.69591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:32.376087Z","time spent":"7.31980586s","remote":"127.0.0.1:37160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":0,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"warn","ts":"2026-08-26T00:17:39.696014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.616599784s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-08-26T00:17:39.696031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.107058043s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-08-26T00:17:39.696065Z","caller":"traceutil/trace.go:171","msg":"trace[214127003] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; }","duration":"9.107140255s","start":"2026-08-26T00:17:30.588917Z","end":"2026-08-26T00:17:39.696057Z","steps":["trace[214127003] 'agreement among raft nodes before linearized reading' (duration: 9.107096814s)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:17:39.696066Z","caller":"traceutil/trace.go:171","msg":"trace[598063806] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; }","duration":"7.616720226s","start":"2026-08-26T00:17:32.079332Z","end":"2026-08-26T00:17:39.696052Z","steps":["trace[598063806] 'agreement among raft nodes before linearized reading' (duration: 7.616649904s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:39.696076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"10.280800635s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-08-26T00:17:39.696092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:30.588897Z","time spent":"9.107187435s","remote":"127.0.0.1:36422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":0,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-08-26T00:17:39.696103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:32.079312Z","time spent":"7.616781066s","remote":"127.0.0.1:36622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":0,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-08-26T00:17:39.695974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"11.618969985s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-08-26T00:17:39.696128Z","caller":"traceutil/trace.go:171","msg":"trace[1397551625] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; }","duration":"10.280902196s","start":"2026-08-26T00:17:29.415206Z","end":"2026-08-26T00:17:39.696109Z","steps":["trace[1397551625] 'agreement among raft nodes before linearized reading' (duration: 10.280853576s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:39.696184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:29.415182Z","time spent":"10.280984957s","remote":"127.0.0.1:36594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":0,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-08-26T00:17:39.696185Z","caller":"traceutil/trace.go:171","msg":"trace[751151306] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; }","duration":"11.619251029s","start":"2026-08-26T00:17:28.076897Z","end":"2026-08-26T00:17:39.696148Z","steps":["trace[751151306] 'agreement among raft nodes before linearized reading' (duration: 11.619014315s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:39.696275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:28.076878Z","time spent":"11.619375942s","remote":"127.0.0.1:37130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":0,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-08-26T00:17:39.69597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"12.104375684s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-08-26T00:17:39.696449Z","caller":"traceutil/trace.go:171","msg":"trace[3111058] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; }","duration":"12.104907973s","start":"2026-08-26T00:17:27.591532Z","end":"2026-08-26T00:17:39.69644Z","steps":["trace[3111058] 'agreement among raft nodes before linearized reading' (duration: 12.104424115s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:39.696502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:27.591512Z","time spent":"12.104977043s","remote":"127.0.0.1:36634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":0,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-08-26T00:17:39.696062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.623296101s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-08-26T00:17:39.696695Z","caller":"traceutil/trace.go:171","msg":"trace[2001344235] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; }","duration":"9.624069814s","start":"2026-08-26T00:17:30.072613Z","end":"2026-08-26T00:17:39.696683Z","steps":["trace[2001344235] 'agreement among raft nodes before linearized reading' (duration: 9.623331822s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:39.696728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:30.072591Z","time spent":"9.624125595s","remote":"127.0.0.1:57550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":0,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-08-26T00:17:40.196158Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17157483382474225979,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:17:40.696798Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17157483382474225979,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:17:41.197597Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17157483382474225979,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:17:41.25968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000277863s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-26T00:17:41.260065Z","caller":"traceutil/trace.go:171","msg":"trace[3746678] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00073642s","start":"2026-08-26T00:17:39.259312Z","end":"2026-08-26T00:17:41.260048Z","steps":["trace[3746678] 'agreement among raft nodes before linearized reading' (duration: 2.000321894s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:41.260127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:39.259287Z","time spent":"2.000826482s","remote":"127.0.0.1:55054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/26 00:17:41 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-26T00:17:41.698353Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17157483382474225979,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:17:41.729716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:36.733041Z","time spent":"4.996667211s","remote":"127.0.0.1:36450","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/08/26 00:17:41 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-26T00:17:41.936957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:34.93661Z","time spent":"7.000323567s","remote":"127.0.0.1:36450","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-08-26T00:17:41.987944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:36.989999Z","time spent":"4.997935089s","remote":"127.0.0.1:36450","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/08/26 00:17:41 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-26T00:17:42.199318Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17157483382474225979,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:17:42.505114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:35.504629Z","time spent":"7.000478182s","remote":"127.0.0.1:36450","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-08-26T00:17:42.51207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:36.974496Z","time spent":"5.537566264s","remote":"127.0.0.1:36450","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/08/26 00:17:42 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-26T00:17:42.699807Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17157483382474225979,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:17:43.200663Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17157483382474225979,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:17:43.260062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.99999225s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-08-26T00:17:43.260221Z","caller":"traceutil/trace.go:171","msg":"trace[1045843467] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000210954s","start":"2026-08-26T00:17:41.259988Z","end":"2026-08-26T00:17:43.260199Z","steps":["trace[1045843467] 'agreement among raft nodes before linearized reading' (duration: 2.000031061s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:43.260305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:41.259962Z","time spent":"2.000330295s","remote":"127.0.0.1:55062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/26 00:17:43 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-26T00:17:43.701122Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17157483382474225979,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:17:44.018644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999320164s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-26T00:17:44.018734Z","caller":"traceutil/trace.go:171","msg":"trace[911960064] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999485856s","start":"2026-08-26T00:17:42.019228Z","end":"2026-08-26T00:17:44.018714Z","steps":["trace[911960064] 'agreement among raft nodes before linearized reading' (duration: 1.999366125s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:44.018784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:42.019203Z","time spent":"1.999572127s","remote":"127.0.0.1:55054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/26 00:17:44 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-26T00:17:44.109374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:37.108401Z","time spent":"7.000959142s","remote":"127.0.0.1:36450","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-08-26T00:17:44.202352Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17157483382474225979,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:17:44.47749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:37.476609Z","time spent":"7.00087399s","remote":"127.0.0.1:36240","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-08-26T00:17:44.703351Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17157483382474225979,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:17:44.79528Z","caller":"etcdserver/server.go:1165","msg":"failed to revoke lease","lease-id":"6e1ba03b6c0b38d7","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-08-26T00:17:45.204295Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17157483382474225979,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:17:45.243744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:38.242686Z","time spent":"7.00105136s","remote":"127.0.0.1:36240","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-08-26T00:17:45.26643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000239244s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-26T00:17:45.266531Z","caller":"traceutil/trace.go:171","msg":"trace[1336181932] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000394957s","start":"2026-08-26T00:17:43.266117Z","end":"2026-08-26T00:17:45.266512Z","steps":["trace[1336181932] 'agreement among raft nodes before linearized reading' (duration: 2.000270355s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:45.266582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:43.266102Z","time spent":"2.000471568s","remote":"127.0.0.1:55054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/26 00:17:45 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-26T00:17:45.704967Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17157483382474225979,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:17:46.205667Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17157483382474225979,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:17:46.696084Z","caller":"etcdserver/v3_server.go:909","msg":"timed out waiting for read index response (local node might have slow network)","timeout":"7s"} {"level":"warn","ts":"2026-08-26T00:17:46.696241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.441512379s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-08-26T00:17:46.696294Z","caller":"traceutil/trace.go:171","msg":"trace[638215865] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; }","duration":"7.441628021s","start":"2026-08-26T00:17:39.254645Z","end":"2026-08-26T00:17:46.696273Z","steps":["trace[638215865] 'agreement among raft nodes before linearized reading' (duration: 7.44155398s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:46.696349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:39.254627Z","time spent":"7.441713052s","remote":"127.0.0.1:36358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":0,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"warn","ts":"2026-08-26T00:17:46.696407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"12.278631959s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-08-26T00:17:46.696433Z","caller":"traceutil/trace.go:171","msg":"trace[496076125] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; }","duration":"12.278714361s","start":"2026-08-26T00:17:34.417711Z","end":"2026-08-26T00:17:46.696426Z","steps":["trace[496076125] 'agreement among raft nodes before linearized reading' (duration: 12.27868369s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:46.696463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:34.417688Z","time spent":"12.278768022s","remote":"127.0.0.1:36602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":0,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-08-26T00:17:46.696509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"13.762707941s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-08-26T00:17:46.696533Z","caller":"traceutil/trace.go:171","msg":"trace[341544416] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; }","duration":"13.762796152s","start":"2026-08-26T00:17:32.933731Z","end":"2026-08-26T00:17:46.696527Z","steps":["trace[341544416] 'agreement among raft nodes before linearized reading' (duration: 13.762762312s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:46.696563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:32.933704Z","time spent":"13.762851322s","remote":"127.0.0.1:57558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":0,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-08-26T00:17:46.696567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"11.002070346s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.119\" ","response":"","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-08-26T00:17:46.696678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.445865147s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-08-26T00:17:46.696688Z","caller":"traceutil/trace.go:171","msg":"trace[1059491104] range","detail":"{range_begin:/registry/masterleases/162.253.55.119; range_end:; }","duration":"11.002269349s","start":"2026-08-26T00:17:35.69439Z","end":"2026-08-26T00:17:46.696659Z","steps":["trace[1059491104] 'agreement among raft nodes before linearized reading' (duration: 11.002111677s)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:17:46.696756Z","caller":"traceutil/trace.go:171","msg":"trace[1572332599] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; }","duration":"9.445996899s","start":"2026-08-26T00:17:37.250731Z","end":"2026-08-26T00:17:46.696728Z","steps":["trace[1572332599] 'agreement among raft nodes before linearized reading' (duration: 9.445913098s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:46.696768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:35.694369Z","time spent":"11.00238279s","remote":"127.0.0.1:36202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":0,"request content":"key:\"/registry/masterleases/162.253.55.119\" "} {"level":"warn","ts":"2026-08-26T00:17:46.696826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:37.250707Z","time spent":"9.44610393s","remote":"127.0.0.1:36666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":0,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-08-26T00:17:46.696783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"11.404171424s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-08-26T00:17:46.697019Z","caller":"traceutil/trace.go:171","msg":"trace[1297341906] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; }","duration":"11.404605461s","start":"2026-08-26T00:17:35.292329Z","end":"2026-08-26T00:17:46.696934Z","steps":["trace[1297341906] 'agreement among raft nodes before linearized reading' (duration: 11.404209645s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:46.697207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:35.292304Z","time spent":"11.404883185s","remote":"127.0.0.1:36288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":0,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-08-26T00:17:46.69725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"11.389890895s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-08-26T00:17:46.697223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"13.250675951s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-08-26T00:17:46.697407Z","caller":"traceutil/trace.go:171","msg":"trace[1503723350] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; }","duration":"13.250866605s","start":"2026-08-26T00:17:33.446488Z","end":"2026-08-26T00:17:46.697354Z","steps":["trace[1503723350] 'agreement among raft nodes before linearized reading' (duration: 13.250721312s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:46.697452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"13.730574816s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-08-26T00:17:46.697534Z","caller":"traceutil/trace.go:171","msg":"trace[1637583888] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; }","duration":"13.730704187s","start":"2026-08-26T00:17:32.966813Z","end":"2026-08-26T00:17:46.697517Z","steps":["trace[1637583888] 'agreement among raft nodes before linearized reading' (duration: 13.729622311s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:46.697588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:32.966795Z","time spent":"13.730772809s","remote":"127.0.0.1:36654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":0,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-08-26T00:17:46.697615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:33.446465Z","time spent":"13.250976466s","remote":"127.0.0.1:36562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":0,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"info","ts":"2026-08-26T00:17:46.697627Z","caller":"traceutil/trace.go:171","msg":"trace[689749827] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; }","duration":"11.389954226s","start":"2026-08-26T00:17:35.307332Z","end":"2026-08-26T00:17:46.697286Z","steps":["trace[689749827] 'agreement among raft nodes before linearized reading' (duration: 11.389889475s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:46.697959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:35.307269Z","time spent":"11.390663316s","remote":"127.0.0.1:36440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":0,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"warn","ts":"2026-08-26T00:17:47.196911Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17157483382474225983,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:17:47.267049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000414656s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-08-26T00:17:47.267152Z","caller":"traceutil/trace.go:171","msg":"trace[611164734] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000581309s","start":"2026-08-26T00:17:45.26655Z","end":"2026-08-26T00:17:47.267131Z","steps":["trace[611164734] 'agreement among raft nodes before linearized reading' (duration: 2.000430426s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:47.267207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:45.266538Z","time spent":"2.000659011s","remote":"127.0.0.1:55062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-26T00:17:47.329516Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"21.810488781s","expected-duration":"1s"} {"level":"warn","ts":"2026-08-26T00:17:47.455186Z","caller":"etcdserver/util.go:123","msg":"failed to apply request","took":"52.711µs","request":"header: lease_revoke:","response":"size:28","error":"lease not found"} {"level":"warn","ts":"2026-08-26T00:17:47.45533Z","caller":"etcdserver/util.go:123","msg":"failed to apply request","took":"14.41µs","request":"header: lease_revoke:","response":"size:28","error":"lease not found"} {"level":"info","ts":"2026-08-26T00:17:47.455378Z","caller":"traceutil/trace.go:171","msg":"trace[1415210044] linearizableReadLoop","detail":"{readStateIndex:1147; appliedIndex:1129; }","duration":"759.195244ms","start":"2026-08-26T00:17:46.696166Z","end":"2026-08-26T00:17:47.455362Z","steps":["trace[1415210044] 'read index received' (duration: 633.583623ms)","trace[1415210044] 'applied index is now lower than readState.Index' (duration: 125.610741ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:17:47.455455Z","caller":"traceutil/trace.go:171","msg":"trace[893535251] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1105; }","duration":"5.514503324s","start":"2026-08-26T00:17:41.940942Z","end":"2026-08-26T00:17:47.455445Z","steps":["trace[893535251] 'process raft request' (duration: 5.514330241s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:47.45554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:41.940914Z","time spent":"5.514562445s","remote":"127.0.0.1:36450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":69,"response count":0,"response size":721,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-26T00:17:47.455885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.215786345s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-26T00:17:47.455948Z","caller":"traceutil/trace.go:171","msg":"trace[889812796] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1105; }","duration":"4.215862646s","start":"2026-08-26T00:17:43.240071Z","end":"2026-08-26T00:17:47.455934Z","steps":["trace[889812796] 'agreement among raft nodes before linearized reading' (duration: 4.215754355s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:47.455957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.96528563s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-26T00:17:47.455981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.06812188s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-26T00:17:47.456022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.008854123s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:17:47.45603Z","caller":"traceutil/trace.go:171","msg":"trace[9317746] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1105; }","duration":"3.965395503s","start":"2026-08-26T00:17:43.490618Z","end":"2026-08-26T00:17:47.456013Z","steps":["trace[9317746] 'agreement among raft nodes before linearized reading' (duration: 3.965253611s)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:17:47.456049Z","caller":"traceutil/trace.go:171","msg":"trace[1684238074] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1105; }","duration":"4.008924854s","start":"2026-08-26T00:17:43.447118Z","end":"2026-08-26T00:17:47.456043Z","steps":["trace[1684238074] 'agreement among raft nodes before linearized reading' (duration: 4.008887304s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:47.456067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:43.447102Z","time spent":"4.008960274s","remote":"127.0.0.1:36320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"info","ts":"2026-08-26T00:17:47.45606Z","caller":"traceutil/trace.go:171","msg":"trace[209787397] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:1105; }","duration":"3.068261111s","start":"2026-08-26T00:17:44.38778Z","end":"2026-08-26T00:17:47.456041Z","steps":["trace[209787397] 'agreement among raft nodes before linearized reading' (duration: 3.068071088s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:47.45612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.493709416s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-26T00:17:47.456126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:44.387763Z","time spent":"3.068340182s","remote":"127.0.0.1:36616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":4,"response size":30,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"info","ts":"2026-08-26T00:17:47.456152Z","caller":"traceutil/trace.go:171","msg":"trace[644793455] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1105; }","duration":"3.493755087s","start":"2026-08-26T00:17:43.96239Z","end":"2026-08-26T00:17:47.456145Z","steps":["trace[644793455] 'agreement among raft nodes before linearized reading' (duration: 3.493697466s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:47.45617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:43.962294Z","time spent":"3.493871788s","remote":"127.0.0.1:36408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-08-26T00:17:47.456262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.208652411s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18cf32d1369d69f1\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-08-26T00:17:47.456293Z","caller":"traceutil/trace.go:171","msg":"trace[480098357] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18cf32d1369d69f1; range_end:; response_count:1; response_revision:1105; }","duration":"2.208728643s","start":"2026-08-26T00:17:45.247555Z","end":"2026-08-26T00:17:47.456283Z","steps":["trace[480098357] 'agreement among raft nodes before linearized reading' (duration: 2.208668132s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:47.456068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:43.490606Z","time spent":"3.965455383s","remote":"127.0.0.1:38468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-08-26T00:17:47.456336Z","caller":"etcdserver/server.go:1165","msg":"failed to revoke lease","lease-id":"6e1ba03b6c0b38d7","error":"lease not found"} {"level":"warn","ts":"2026-08-26T00:17:47.456376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.100221816s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:17:47.456394Z","caller":"traceutil/trace.go:171","msg":"trace[478006534] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1105; }","duration":"7.100240786s","start":"2026-08-26T00:17:40.356147Z","end":"2026-08-26T00:17:47.456388Z","steps":["trace[478006534] 'agreement among raft nodes before linearized reading' (duration: 7.100209486s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:47.455983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:43.24Z","time spent":"4.215972018s","remote":"127.0.0.1:36344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":3,"response size":30,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-08-26T00:17:47.456411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:40.356073Z","time spent":"7.100332988s","remote":"127.0.0.1:37146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-08-26T00:17:47.456318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:45.247535Z","time spent":"2.208777493s","remote":"127.0.0.1:36240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":797,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.18cf32d1369d69f1\" "} {"level":"warn","ts":"2026-08-26T00:17:47.456536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.593808416s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:17:47.456556Z","caller":"traceutil/trace.go:171","msg":"trace[620000397] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1105; }","duration":"4.593887018s","start":"2026-08-26T00:17:42.862664Z","end":"2026-08-26T00:17:47.456551Z","steps":["trace[620000397] 'agreement among raft nodes before linearized reading' (duration: 4.593852277s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:47.456578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:42.862634Z","time spent":"4.593938488s","remote":"127.0.0.1:36620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-08-26T00:17:47.456582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.702335837s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-26T00:17:47.456616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.447839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7111"} {"level":"info","ts":"2026-08-26T00:17:47.456638Z","caller":"traceutil/trace.go:171","msg":"trace[1338442324] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:1105; }","duration":"232.51101ms","start":"2026-08-26T00:17:47.22412Z","end":"2026-08-26T00:17:47.456631Z","steps":["trace[1338442324] 'agreement among raft nodes before linearized reading' (duration: 232.468989ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:17:47.456628Z","caller":"traceutil/trace.go:171","msg":"trace[786083881] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1105; }","duration":"6.702434278s","start":"2026-08-26T00:17:40.754181Z","end":"2026-08-26T00:17:47.456616Z","steps":["trace[786083881] 'agreement among raft nodes before linearized reading' (duration: 6.702360427s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:47.456672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:40.754159Z","time spent":"6.702501859s","remote":"127.0.0.1:36452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":3,"response size":30,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-08-26T00:17:47.456554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"757.823744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.119\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:17:47.456707Z","caller":"traceutil/trace.go:171","msg":"trace[442316448] range","detail":"{range_begin:/registry/masterleases/162.253.55.119; range_end:; response_count:0; response_revision:1105; }","duration":"758.015296ms","start":"2026-08-26T00:17:46.698684Z","end":"2026-08-26T00:17:47.4567Z","steps":["trace[442316448] 'agreement among raft nodes before linearized reading' (duration: 757.845614ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:47.45673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:46.698666Z","time spent":"758.057696ms","remote":"127.0.0.1:36202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":28,"request content":"key:\"/registry/masterleases/162.253.55.119\" "} {"level":"warn","ts":"2026-08-26T00:17:47.456755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.987606621s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:17:47.456776Z","caller":"traceutil/trace.go:171","msg":"trace[1929427066] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1105; }","duration":"4.987673832s","start":"2026-08-26T00:17:42.469096Z","end":"2026-08-26T00:17:47.45677Z","steps":["trace[1929427066] 'agreement among raft nodes before linearized reading' (duration: 4.987641912s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:47.456794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:42.469074Z","time spent":"4.987714802s","remote":"127.0.0.1:57508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"warn","ts":"2026-08-26T00:17:47.456579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.310435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:17:47.45682Z","caller":"traceutil/trace.go:171","msg":"trace[1911351307] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1105; }","duration":"183.602039ms","start":"2026-08-26T00:17:47.273211Z","end":"2026-08-26T00:17:47.456813Z","steps":["trace[1911351307] 'agreement among raft nodes before linearized reading' (duration: 183.355945ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:17:47.457049Z","caller":"traceutil/trace.go:171","msg":"trace[1757102111] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1105; }","duration":"3.343381135s","start":"2026-08-26T00:17:44.11365Z","end":"2026-08-26T00:17:47.457031Z","steps":["trace[1757102111] 'process raft request' (duration: 3.341647099s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:47.457162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:44.11362Z","time spent":"3.343469406s","remote":"127.0.0.1:36450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":45,"response count":0,"response size":569,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T00:18:40.130791Z","caller":"traceutil/trace.go:171","msg":"trace[1168267123] transaction","detail":"{read_only:false; response_revision:1346; number_of_response:1; }","duration":"368.935555ms","start":"2026-08-26T00:18:39.761817Z","end":"2026-08-26T00:18:40.130752Z","steps":["trace[1168267123] 'process raft request' (duration: 368.716282ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:18:40.13104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:18:39.761792Z","time spent":"369.118767ms","remote":"127.0.0.1:36450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T00:18:48.400818Z","caller":"traceutil/trace.go:171","msg":"trace[153346172] linearizableReadLoop","detail":"{readStateIndex:1435; appliedIndex:1434; }","duration":"168.203395ms","start":"2026-08-26T00:18:48.232591Z","end":"2026-08-26T00:18:48.400795Z","steps":["trace[153346172] 'read index received' (duration: 168.025803ms)","trace[153346172] 'applied index is now lower than readState.Index' (duration: 176.412µs)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:18:48.400925Z","caller":"traceutil/trace.go:171","msg":"trace[1642576060] transaction","detail":"{read_only:false; response_revision:1378; number_of_response:1; }","duration":"233.610897ms","start":"2026-08-26T00:18:48.167287Z","end":"2026-08-26T00:18:48.400898Z","steps":["trace[1642576060] 'process raft request' (duration: 233.323303ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:18:48.40103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.427658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:18:48.401084Z","caller":"traceutil/trace.go:171","msg":"trace[1551999768] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1378; }","duration":"168.53765ms","start":"2026-08-26T00:18:48.232537Z","end":"2026-08-26T00:18:48.401075Z","steps":["trace[1551999768] 'agreement among raft nodes before linearized reading' (duration: 168.435129ms)"],"step_count":1}