{"level":"warn","ts":"2026-05-08T10:16:03.045594Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-08T10:16:03.045689Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.238:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.238:2380","--initial-cluster=instance=https://199.204.45.238:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.238:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.238: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-05-08T10:16:03.045764Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-08T10:16:03.045777Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.238:2380"]} {"level":"info","ts":"2026-05-08T10:16:03.045796Z","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-05-08T10:16:03.046481Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.238:2379"]} {"level":"info","ts":"2026-05-08T10:16:03.046583Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.238:2380"],"listen-peer-urls":["https://199.204.45.238:2380"],"advertise-client-urls":["https://199.204.45.238:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.238:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.238: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-05-08T10:16:03.068525Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"15.815399ms"} {"level":"info","ts":"2026-05-08T10:16:03.274556Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d7dbc9f5893cc1f6","cluster-id":"823d1d08ab21430c"} {"level":"info","ts":"2026-05-08T10:16:03.274649Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 switched to configuration voters=()"} {"level":"info","ts":"2026-05-08T10:16:03.274681Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became follower at term 0"} {"level":"info","ts":"2026-05-08T10:16:03.274691Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d7dbc9f5893cc1f6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-08T10:16:03.274712Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became follower at term 1"} {"level":"info","ts":"2026-05-08T10:16:03.274749Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 switched to configuration voters=(15554247794460770806)"} {"level":"warn","ts":"2026-05-08T10:16:03.727286Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-08T10:16:03.879665Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-08T10:16:03.893613Z","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-05-08T10:16:03.94669Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d7dbc9f5893cc1f6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-08T10:16:03.946827Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d7dbc9f5893cc1f6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-08T10:16:03.948496Z","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-05-08T10:16:03.948698Z","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-05-08T10:16:03.948721Z","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-05-08T10:16:03.951531Z","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-05-08T10:16:03.95182Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d7dbc9f5893cc1f6","initial-advertise-peer-urls":["https://199.204.45.238:2380"],"listen-peer-urls":["https://199.204.45.238:2380"],"advertise-client-urls":["https://199.204.45.238:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.238:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-08T10:16:03.951895Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-08T10:16:03.952091Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.238:2380"} {"level":"info","ts":"2026-05-08T10:16:03.952142Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.238:2380"} {"level":"info","ts":"2026-05-08T10:16:04.004586Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 switched to configuration voters=(15554247794460770806)"} {"level":"info","ts":"2026-05-08T10:16:04.004752Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"823d1d08ab21430c","local-member-id":"d7dbc9f5893cc1f6","added-peer-id":"d7dbc9f5893cc1f6","added-peer-peer-urls":["https://199.204.45.238:2380"]} {"level":"info","ts":"2026-05-08T10:16:04.77592Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 is starting a new election at term 1"} {"level":"info","ts":"2026-05-08T10:16:04.775991Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-08T10:16:04.776037Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 received MsgPreVoteResp from d7dbc9f5893cc1f6 at term 1"} {"level":"info","ts":"2026-05-08T10:16:04.776056Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became candidate at term 2"} {"level":"info","ts":"2026-05-08T10:16:04.776072Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 received MsgVoteResp from d7dbc9f5893cc1f6 at term 2"} {"level":"info","ts":"2026-05-08T10:16:04.776097Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became leader at term 2"} {"level":"info","ts":"2026-05-08T10:16:04.776114Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d7dbc9f5893cc1f6 elected leader d7dbc9f5893cc1f6 at term 2"} {"level":"info","ts":"2026-05-08T10:16:04.817649Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-08T10:16:04.817692Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d7dbc9f5893cc1f6","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.238:2379]}","request-path":"/0/members/d7dbc9f5893cc1f6/attributes","cluster-id":"823d1d08ab21430c","publish-timeout":"7s"} {"level":"info","ts":"2026-05-08T10:16:04.817812Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-08T10:16:04.818058Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-08T10:16:04.818177Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-08T10:16:04.81786Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-08T10:16:04.822898Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.238:2379"} {"level":"info","ts":"2026-05-08T10:16:04.823051Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-08T10:16:04.832302Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"823d1d08ab21430c","local-member-id":"d7dbc9f5893cc1f6","cluster-version":"3.5"} {"level":"info","ts":"2026-05-08T10:16:04.832762Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-08T10:16:04.832891Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-08T10:16:06.444085Z","caller":"traceutil/trace.go:171","msg":"trace[1664703401] transaction","detail":"{read_only:false; response_revision:29; number_of_response:1; }","duration":"150.047019ms","start":"2026-05-08T10:16:06.293995Z","end":"2026-05-08T10:16:06.444042Z","steps":["trace[1664703401] 'process raft request' (duration: 143.59619ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:16:06.444182Z","caller":"traceutil/trace.go:171","msg":"trace[910245818] transaction","detail":"{read_only:false; response_revision:31; number_of_response:1; }","duration":"148.40483ms","start":"2026-05-08T10:16:06.295745Z","end":"2026-05-08T10:16:06.444149Z","steps":["trace[910245818] 'process raft request' (duration: 148.277295ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:16:06.444284Z","caller":"traceutil/trace.go:171","msg":"trace[468979723] transaction","detail":"{read_only:false; response_revision:34; number_of_response:1; }","duration":"148.230854ms","start":"2026-05-08T10:16:06.296Z","end":"2026-05-08T10:16:06.444231Z","steps":["trace[468979723] 'process raft request' (duration: 148.092109ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:16:06.444385Z","caller":"traceutil/trace.go:171","msg":"trace[304848522] transaction","detail":"{read_only:false; response_revision:30; number_of_response:1; }","duration":"150.295248ms","start":"2026-05-08T10:16:06.294076Z","end":"2026-05-08T10:16:06.444371Z","steps":["trace[304848522] 'process raft request' (duration: 149.911264ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:16:06.444306Z","caller":"traceutil/trace.go:171","msg":"trace[63719469] transaction","detail":"{read_only:false; response_revision:32; number_of_response:1; }","duration":"148.484913ms","start":"2026-05-08T10:16:06.295788Z","end":"2026-05-08T10:16:06.444272Z","steps":["trace[63719469] 'process raft request' (duration: 148.270276ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:16:06.444429Z","caller":"traceutil/trace.go:171","msg":"trace[2116787450] transaction","detail":"{read_only:false; response_revision:33; number_of_response:1; }","duration":"148.40285ms","start":"2026-05-08T10:16:06.29589Z","end":"2026-05-08T10:16:06.444293Z","steps":["trace[2116787450] 'process raft request' (duration: 148.183152ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:16:06.44458Z","caller":"traceutil/trace.go:171","msg":"trace[1551653463] linearizableReadLoop","detail":"{readStateIndex:38; appliedIndex:29; }","duration":"128.141492ms","start":"2026-05-08T10:16:06.316384Z","end":"2026-05-08T10:16:06.444526Z","steps":["trace[1551653463] 'read index received' (duration: 38.811715ms)","trace[1551653463] 'applied index is now lower than readState.Index' (duration: 89.325177ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:16:06.444856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.715888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-08T10:16:06.444954Z","caller":"traceutil/trace.go:171","msg":"trace[466745043] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:35; }","duration":"107.839133ms","start":"2026-05-08T10:16:06.337096Z","end":"2026-05-08T10:16:06.444935Z","steps":["trace[466745043] 'agreement among raft nodes before linearized reading' (duration: 107.641626ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:06.444856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.395941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-08T10:16:06.445097Z","caller":"traceutil/trace.go:171","msg":"trace[1328468802] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:35; }","duration":"128.734244ms","start":"2026-05-08T10:16:06.316343Z","end":"2026-05-08T10:16:06.445078Z","steps":["trace[1328468802] 'agreement among raft nodes before linearized reading' (duration: 128.274737ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:16:06.630729Z","caller":"traceutil/trace.go:171","msg":"trace[870461723] linearizableReadLoop","detail":"{readStateIndex:46; appliedIndex:44; }","duration":"108.418913ms","start":"2026-05-08T10:16:06.522282Z","end":"2026-05-08T10:16:06.630701Z","steps":["trace[870461723] 'read index received' (duration: 27.994802ms)","trace[870461723] 'applied index is now lower than readState.Index' (duration: 80.423501ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:16:06.630857Z","caller":"traceutil/trace.go:171","msg":"trace[1746067133] transaction","detail":"{read_only:false; response_revision:42; number_of_response:1; }","duration":"115.098369ms","start":"2026-05-08T10:16:06.515707Z","end":"2026-05-08T10:16:06.630806Z","steps":["trace[1746067133] 'process raft request' (duration: 114.920363ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:06.63087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.576978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/default/\" range_end:\"/registry/resourcequotas/default0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-08T10:16:06.630899Z","caller":"traceutil/trace.go:171","msg":"trace[1748316738] range","detail":"{range_begin:/registry/resourcequotas/default/; range_end:/registry/resourcequotas/default0; response_count:0; response_revision:42; }","duration":"108.64747ms","start":"2026-05-08T10:16:06.52224Z","end":"2026-05-08T10:16:06.630888Z","steps":["trace[1748316738] 'agreement among raft nodes before linearized reading' (duration: 108.582098ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:16:06.630831Z","caller":"traceutil/trace.go:171","msg":"trace[899266526] transaction","detail":"{read_only:false; response_revision:41; number_of_response:1; }","duration":"116.457597ms","start":"2026-05-08T10:16:06.514319Z","end":"2026-05-08T10:16:06.630777Z","steps":["trace[899266526] 'process raft request' (duration: 36.050627ms)","trace[899266526] 'compare' (duration: 80.11902ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:16:07.09628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.465642ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-08T10:16:07.096494Z","caller":"traceutil/trace.go:171","msg":"trace[1713150798] transaction","detail":"{read_only:false; response_revision:51; number_of_response:1; }","duration":"355.543751ms","start":"2026-05-08T10:16:06.740936Z","end":"2026-05-08T10:16:07.09648Z","steps":["trace[1713150798] 'process raft request' (duration: 355.51237ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:07.096549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:06.740912Z","time spent":"355.614684ms","remote":"127.0.0.1:36370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":845,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-08T10:16:07.096625Z","caller":"traceutil/trace.go:171","msg":"trace[1316385874] transaction","detail":"{read_only:false; response_revision:50; number_of_response:1; }","duration":"385.96129ms","start":"2026-05-08T10:16:06.710651Z","end":"2026-05-08T10:16:07.096612Z","steps":["trace[1316385874] 'process raft request' (duration: 385.717851ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:16:07.096696Z","caller":"traceutil/trace.go:171","msg":"trace[621175210] transaction","detail":"{read_only:false; response_revision:49; number_of_response:1; }","duration":"386.054373ms","start":"2026-05-08T10:16:06.710555Z","end":"2026-05-08T10:16:07.096609Z","steps":["trace[621175210] 'process raft request' (duration: 214.798103ms)","trace[621175210] 'compare' (duration: 170.299996ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:16:07.096529Z","caller":"traceutil/trace.go:171","msg":"trace[1124230465] linearizableReadLoop","detail":"{readStateIndex:55; appliedIndex:53; }","duration":"380.912671ms","start":"2026-05-08T10:16:06.715587Z","end":"2026-05-08T10:16:07.096499Z","steps":["trace[1124230465] 'read index received' (duration: 209.746294ms)","trace[1124230465] 'applied index is now lower than readState.Index' (duration: 171.163297ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:16:07.096846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:06.710534Z","time spent":"386.23377ms","remote":"127.0.0.1:36546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":779,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-08T10:16:07.096905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:06.710624Z","time spent":"386.039903ms","remote":"127.0.0.1:36546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1216,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T10:16:07.096918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.318755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18ad8fa3927313e1\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-08T10:16:07.096984Z","caller":"traceutil/trace.go:171","msg":"trace[1979550371] range","detail":"{range_begin:/registry/events/default/instance.18ad8fa3927313e1; range_end:; response_count:0; response_revision:51; }","duration":"381.413749ms","start":"2026-05-08T10:16:06.715554Z","end":"2026-05-08T10:16:07.096967Z","steps":["trace[1979550371] 'agreement among raft nodes before linearized reading' (duration: 381.313585ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:07.097035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:06.715541Z","time spent":"381.482362ms","remote":"127.0.0.1:36176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/events/default/instance.18ad8fa3927313e1\" "} {"level":"warn","ts":"2026-05-08T10:16:07.097458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.522598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-08T10:16:07.097504Z","caller":"traceutil/trace.go:171","msg":"trace[564971107] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:51; }","duration":"101.612572ms","start":"2026-05-08T10:16:06.99588Z","end":"2026-05-08T10:16:07.097493Z","steps":["trace[564971107] 'agreement among raft nodes before linearized reading' (duration: 101.53604ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:07.097605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.821614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-08T10:16:07.097632Z","caller":"traceutil/trace.go:171","msg":"trace[1277646843] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:51; }","duration":"100.948659ms","start":"2026-05-08T10:16:06.996675Z","end":"2026-05-08T10:16:07.097624Z","steps":["trace[1277646843] 'agreement among raft nodes before linearized reading' (duration: 100.904237ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:16:07.295538Z","caller":"traceutil/trace.go:171","msg":"trace[415542571] linearizableReadLoop","detail":"{readStateIndex:62; appliedIndex:60; }","duration":"105.791581ms","start":"2026-05-08T10:16:07.189725Z","end":"2026-05-08T10:16:07.295517Z","steps":["trace[415542571] 'read index received' (duration: 10.395489ms)","trace[415542571] 'applied index is now lower than readState.Index' (duration: 95.395232ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:16:07.295652Z","caller":"traceutil/trace.go:171","msg":"trace[1984859061] transaction","detail":"{read_only:false; response_revision:58; number_of_response:1; }","duration":"104.601999ms","start":"2026-05-08T10:16:07.191041Z","end":"2026-05-08T10:16:07.295643Z","steps":["trace[1984859061] 'process raft request' (duration: 104.389711ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:16:07.295687Z","caller":"traceutil/trace.go:171","msg":"trace[1633863320] transaction","detail":"{read_only:false; response_revision:57; number_of_response:1; }","duration":"106.016009ms","start":"2026-05-08T10:16:07.18965Z","end":"2026-05-08T10:16:07.295666Z","steps":["trace[1633863320] 'process raft request' (duration: 105.647326ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:07.295814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.071311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18ad8fa392732044\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-08T10:16:07.295859Z","caller":"traceutil/trace.go:171","msg":"trace[271200238] range","detail":"{range_begin:/registry/events/default/instance.18ad8fa392732044; range_end:; response_count:0; response_revision:58; }","duration":"106.131724ms","start":"2026-05-08T10:16:07.18971Z","end":"2026-05-08T10:16:07.295842Z","steps":["trace[271200238] 'agreement among raft nodes before linearized reading' (duration: 106.065481ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:07.295889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.403287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-08T10:16:07.295934Z","caller":"traceutil/trace.go:171","msg":"trace[2092030854] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-edit; range_end:; response_count:0; response_revision:58; }","duration":"105.512782ms","start":"2026-05-08T10:16:07.190411Z","end":"2026-05-08T10:16:07.295924Z","steps":["trace[2092030854] 'agreement among raft nodes before linearized reading' (duration: 105.407408ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:16:07.551594Z","caller":"traceutil/trace.go:171","msg":"trace[1147108985] transaction","detail":"{read_only:false; response_revision:60; number_of_response:1; }","duration":"251.570189ms","start":"2026-05-08T10:16:07.299994Z","end":"2026-05-08T10:16:07.551564Z","steps":["trace[1147108985] 'process raft request' (duration: 187.55439ms)","trace[1147108985] 'compare' (duration: 63.445349ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:16:07.552166Z","caller":"traceutil/trace.go:171","msg":"trace[1679711710] transaction","detail":"{read_only:false; response_revision:61; number_of_response:1; }","duration":"252.074917ms","start":"2026-05-08T10:16:07.300064Z","end":"2026-05-08T10:16:07.552139Z","steps":["trace[1679711710] 'process raft request' (duration: 251.449184ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:07.552462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.438734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-view\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-08T10:16:07.552513Z","caller":"traceutil/trace.go:171","msg":"trace[172240484] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-view; range_end:; response_count:0; response_revision:61; }","duration":"251.543308ms","start":"2026-05-08T10:16:07.300957Z","end":"2026-05-08T10:16:07.5525Z","steps":["trace[172240484] 'agreement among raft nodes before linearized reading' (duration: 251.438735ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:16:07.552358Z","caller":"traceutil/trace.go:171","msg":"trace[605066792] linearizableReadLoop","detail":"{readStateIndex:66; appliedIndex:63; }","duration":"251.088622ms","start":"2026-05-08T10:16:07.301006Z","end":"2026-05-08T10:16:07.552095Z","steps":["trace[605066792] 'read index received' (duration: 53.87368ms)","trace[605066792] 'applied index is now lower than readState.Index' (duration: 197.211732ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:16:07.55307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.569248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18ad8fa39272e8cb\" ","response":"range_response_count:1 size:654"} {"level":"info","ts":"2026-05-08T10:16:07.55311Z","caller":"traceutil/trace.go:171","msg":"trace[1734451042] range","detail":"{range_begin:/registry/events/default/instance.18ad8fa39272e8cb; range_end:; response_count:1; response_revision:61; }","duration":"194.652971ms","start":"2026-05-08T10:16:07.358447Z","end":"2026-05-08T10:16:07.5531Z","steps":["trace[1734451042] 'agreement among raft nodes before linearized reading' (duration: 194.511776ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:07.900339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.058712ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-08T10:16:07.900839Z","caller":"traceutil/trace.go:171","msg":"trace[408575205] linearizableReadLoop","detail":"{readStateIndex:72; appliedIndex:70; }","duration":"305.011475ms","start":"2026-05-08T10:16:07.595763Z","end":"2026-05-08T10:16:07.900775Z","steps":["trace[408575205] 'read index received' (duration: 185.339902ms)","trace[408575205] 'applied index is now lower than readState.Index' (duration: 119.670613ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:16:07.901298Z","caller":"traceutil/trace.go:171","msg":"trace[581796753] transaction","detail":"{read_only:false; response_revision:66; number_of_response:1; }","duration":"319.391555ms","start":"2026-05-08T10:16:07.58168Z","end":"2026-05-08T10:16:07.901071Z","steps":["trace[581796753] 'process raft request' (duration: 199.473812ms)","trace[581796753] 'compare' (duration: 118.784072ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:16:07.901666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.835859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18ad8fa392732044\" ","response":"range_response_count:1 size:648"} {"level":"info","ts":"2026-05-08T10:16:07.901778Z","caller":"traceutil/trace.go:171","msg":"trace[2016976498] range","detail":"{range_begin:/registry/events/default/instance.18ad8fa392732044; range_end:; response_count:1; response_revision:67; }","duration":"304.985054ms","start":"2026-05-08T10:16:07.596775Z","end":"2026-05-08T10:16:07.90176Z","steps":["trace[2016976498] 'agreement among raft nodes before linearized reading' (duration: 304.769236ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:07.901795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:07.581612Z","time spent":"319.994936ms","remote":"127.0.0.1:36546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":671,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-08T10:16:07.901864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:07.596761Z","time spent":"305.080647ms","remote":"127.0.0.1:36176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":672,"request content":"key:\"/registry/events/default/instance.18ad8fa392732044\" "} {"level":"warn","ts":"2026-05-08T10:16:07.902001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.234888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/cluster-admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-08T10:16:07.902056Z","caller":"traceutil/trace.go:171","msg":"trace[1463518141] range","detail":"{range_begin:/registry/clusterroles/cluster-admin; range_end:; response_count:0; response_revision:67; }","duration":"306.323741ms","start":"2026-05-08T10:16:07.595717Z","end":"2026-05-08T10:16:07.90204Z","steps":["trace[1463518141] 'agreement among raft nodes before linearized reading' (duration: 306.238858ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:07.902102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:07.595699Z","time spent":"306.389604ms","remote":"127.0.0.1:36456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":28,"request content":"key:\"/registry/clusterroles/cluster-admin\" "} {"level":"info","ts":"2026-05-08T10:16:07.901396Z","caller":"traceutil/trace.go:171","msg":"trace[1333754413] transaction","detail":"{read_only:false; response_revision:67; number_of_response:1; }","duration":"318.567596ms","start":"2026-05-08T10:16:07.58281Z","end":"2026-05-08T10:16:07.901378Z","steps":["trace[1333754413] 'process raft request' (duration: 317.86077ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:07.902346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:07.582798Z","time spent":"319.445226ms","remote":"127.0.0.1:36546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T10:16:08.308445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.333132ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-05-08T10:16:08.308564Z","caller":"traceutil/trace.go:171","msg":"trace[1512540814] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"335.808179ms","start":"2026-05-08T10:16:07.972736Z","end":"2026-05-08T10:16:08.308544Z","steps":["trace[1512540814] 'process raft request' (duration: 75.30238ms)","trace[1512540814] 'compare' (duration: 260.229459ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:16:08.308622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:07.97272Z","time spent":"335.874492ms","remote":"127.0.0.1:36176","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":642,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T10:16:08.338542Z","caller":"traceutil/trace.go:171","msg":"trace[864992318] transaction","detail":"{read_only:false; response_revision:73; number_of_response:1; }","duration":"365.107849ms","start":"2026-05-08T10:16:07.973406Z","end":"2026-05-08T10:16:08.338514Z","steps":["trace[864992318] 'process raft request' (duration: 364.931732ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:16:08.338569Z","caller":"traceutil/trace.go:171","msg":"trace[1865509951] linearizableReadLoop","detail":"{readStateIndex:80; appliedIndex:77; }","duration":"222.700678ms","start":"2026-05-08T10:16:08.115842Z","end":"2026-05-08T10:16:08.338543Z","steps":["trace[1865509951] 'read index received' (duration: 222.437778ms)","trace[1865509951] 'applied index is now lower than readState.Index' (duration: 261.749µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:16:08.338631Z","caller":"traceutil/trace.go:171","msg":"trace[244684530] transaction","detail":"{read_only:false; response_revision:74; number_of_response:1; }","duration":"364.534448ms","start":"2026-05-08T10:16:07.974069Z","end":"2026-05-08T10:16:08.338604Z","steps":["trace[244684530] 'process raft request' (duration: 364.379432ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:16:08.338692Z","caller":"traceutil/trace.go:171","msg":"trace[1948250655] transaction","detail":"{read_only:false; response_revision:75; number_of_response:1; }","duration":"363.7507ms","start":"2026-05-08T10:16:07.974929Z","end":"2026-05-08T10:16:08.33868Z","steps":["trace[1948250655] 'process raft request' (duration: 363.573484ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:08.338699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:07.973389Z","time spent":"365.227632ms","remote":"127.0.0.1:36556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":622,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-08T10:16:08.338794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:07.974921Z","time spent":"363.820533ms","remote":"127.0.0.1:36456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":634,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-08T10:16:08.338901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.050899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-08T10:16:08.339075Z","caller":"traceutil/trace.go:171","msg":"trace[801630366] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:75; }","duration":"223.230026ms","start":"2026-05-08T10:16:08.115826Z","end":"2026-05-08T10:16:08.339056Z","steps":["trace[801630366] 'agreement among raft nodes before linearized reading' (duration: 222.985967ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:08.338954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.661249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-08T10:16:08.339158Z","caller":"traceutil/trace.go:171","msg":"trace[1269190896] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:75; }","duration":"175.931179ms","start":"2026-05-08T10:16:08.163215Z","end":"2026-05-08T10:16:08.339146Z","steps":["trace[1269190896] 'agreement among raft nodes before linearized reading' (duration: 175.643669ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:08.33871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:07.974049Z","time spent":"364.612661ms","remote":"127.0.0.1:36546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1121,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T10:16:08.574014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.593189ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-05-08T10:16:08.574262Z","caller":"traceutil/trace.go:171","msg":"trace[1977854470] transaction","detail":"{read_only:false; response_revision:78; number_of_response:1; }","duration":"166.119922ms","start":"2026-05-08T10:16:08.408114Z","end":"2026-05-08T10:16:08.574234Z","steps":["trace[1977854470] 'process raft request' (duration: 52.194912ms)","trace[1977854470] 'compare' (duration: 113.449303ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:16:08.574547Z","caller":"traceutil/trace.go:171","msg":"trace[262726873] transaction","detail":"{read_only:false; response_revision:79; number_of_response:1; }","duration":"165.797631ms","start":"2026-05-08T10:16:08.408726Z","end":"2026-05-08T10:16:08.574523Z","steps":["trace[262726873] 'process raft request' (duration: 165.412347ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:16:08.574581Z","caller":"traceutil/trace.go:171","msg":"trace[45784646] linearizableReadLoop","detail":"{readStateIndex:86; appliedIndex:82; }","duration":"161.687625ms","start":"2026-05-08T10:16:08.412878Z","end":"2026-05-08T10:16:08.574565Z","steps":["trace[45784646] 'read index received' (duration: 47.386901ms)","trace[45784646] 'applied index is now lower than readState.Index' (duration: 114.299794ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:16:08.57471Z","caller":"traceutil/trace.go:171","msg":"trace[542559124] transaction","detail":"{read_only:false; response_revision:81; number_of_response:1; }","duration":"164.901969ms","start":"2026-05-08T10:16:08.409798Z","end":"2026-05-08T10:16:08.5747Z","steps":["trace[542559124] 'process raft request' (duration: 164.703792ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:08.57482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.648963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-08T10:16:08.574866Z","caller":"traceutil/trace.go:171","msg":"trace[1862704917] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:81; }","duration":"161.717706ms","start":"2026-05-08T10:16:08.413138Z","end":"2026-05-08T10:16:08.574856Z","steps":["trace[1862704917] 'agreement among raft nodes before linearized reading' (duration: 161.626303ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:16:08.574699Z","caller":"traceutil/trace.go:171","msg":"trace[2095921610] transaction","detail":"{read_only:false; response_revision:80; number_of_response:1; }","duration":"165.707918ms","start":"2026-05-08T10:16:08.408964Z","end":"2026-05-08T10:16:08.574672Z","steps":["trace[2095921610] 'process raft request' (duration: 165.48983ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:08.575182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.041787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-08T10:16:08.575229Z","caller":"traceutil/trace.go:171","msg":"trace[1012686558] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:81; }","duration":"162.371949ms","start":"2026-05-08T10:16:08.412844Z","end":"2026-05-08T10:16:08.575216Z","steps":["trace[1012686558] 'agreement among raft nodes before linearized reading' (duration: 161.905373ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:16:08.882909Z","caller":"traceutil/trace.go:171","msg":"trace[667363867] linearizableReadLoop","detail":"{readStateIndex:93; appliedIndex:91; }","duration":"223.959193ms","start":"2026-05-08T10:16:08.658888Z","end":"2026-05-08T10:16:08.882847Z","steps":["trace[667363867] 'read index received' (duration: 12.212053ms)","trace[667363867] 'applied index is now lower than readState.Index' (duration: 211.74579ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:16:08.883274Z","caller":"traceutil/trace.go:171","msg":"trace[1750162302] transaction","detail":"{read_only:false; response_revision:89; number_of_response:1; }","duration":"223.336341ms","start":"2026-05-08T10:16:08.659924Z","end":"2026-05-08T10:16:08.883261Z","steps":["trace[1750162302] 'process raft request' (duration: 222.876065ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:08.88328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.357127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-08T10:16:08.883257Z","caller":"traceutil/trace.go:171","msg":"trace[264955038] transaction","detail":"{read_only:false; response_revision:88; number_of_response:1; }","duration":"225.872191ms","start":"2026-05-08T10:16:08.657274Z","end":"2026-05-08T10:16:08.883146Z","steps":["trace[264955038] 'process raft request' (duration: 191.752241ms)","trace[264955038] 'compare' (duration: 33.56261ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:16:08.883344Z","caller":"traceutil/trace.go:171","msg":"trace[920518614] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:89; }","duration":"224.486953ms","start":"2026-05-08T10:16:08.65884Z","end":"2026-05-08T10:16:08.883327Z","steps":["trace[920518614] 'agreement among raft nodes before linearized reading' (duration: 224.334237ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:08.886408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.621269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-05-08T10:16:08.886591Z","caller":"traceutil/trace.go:171","msg":"trace[509097558] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:91; }","duration":"226.836015ms","start":"2026-05-08T10:16:08.659725Z","end":"2026-05-08T10:16:08.886561Z","steps":["trace[509097558] 'agreement among raft nodes before linearized reading' (duration: 226.521834ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:08.886902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.903273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:3977"} {"level":"info","ts":"2026-05-08T10:16:08.886943Z","caller":"traceutil/trace.go:171","msg":"trace[2044662728] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:91; }","duration":"183.988166ms","start":"2026-05-08T10:16:08.702945Z","end":"2026-05-08T10:16:08.886933Z","steps":["trace[2044662728] 'agreement among raft nodes before linearized reading' (duration: 183.848581ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:08.886951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.641967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:public-info-viewer\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-08T10:16:08.886458Z","caller":"traceutil/trace.go:171","msg":"trace[1428756367] transaction","detail":"{read_only:false; response_revision:90; number_of_response:1; }","duration":"181.612722ms","start":"2026-05-08T10:16:08.704823Z","end":"2026-05-08T10:16:08.886436Z","steps":["trace[1428756367] 'process raft request' (duration: 181.242929ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:16:08.887119Z","caller":"traceutil/trace.go:171","msg":"trace[1580057086] range","detail":"{range_begin:/registry/clusterroles/system:public-info-viewer; range_end:; response_count:0; response_revision:91; }","duration":"213.837614ms","start":"2026-05-08T10:16:08.673258Z","end":"2026-05-08T10:16:08.887096Z","steps":["trace[1580057086] 'agreement among raft nodes before linearized reading' (duration: 213.598886ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:16:09.075957Z","caller":"traceutil/trace.go:171","msg":"trace[1964961942] linearizableReadLoop","detail":"{readStateIndex:101; appliedIndex:100; }","duration":"154.858634ms","start":"2026-05-08T10:16:08.921073Z","end":"2026-05-08T10:16:09.075932Z","steps":["trace[1964961942] 'read index received' (duration: 95.527199ms)","trace[1964961942] 'applied index is now lower than readState.Index' (duration: 59.330155ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:16:09.076092Z","caller":"traceutil/trace.go:171","msg":"trace[588851106] transaction","detail":"{read_only:false; response_revision:96; number_of_response:1; }","duration":"169.4156ms","start":"2026-05-08T10:16:08.906665Z","end":"2026-05-08T10:16:09.07608Z","steps":["trace[588851106] 'process raft request' (duration: 109.895308ms)","trace[588851106] 'compare' (duration: 59.240743ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:16:09.076406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.619445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18ad8fa392732044\" ","response":"range_response_count:1 size:648"} {"level":"info","ts":"2026-05-08T10:16:09.076572Z","caller":"traceutil/trace.go:171","msg":"trace[551703818] range","detail":"{range_begin:/registry/events/default/instance.18ad8fa392732044; range_end:; response_count:1; response_revision:96; }","duration":"154.842903ms","start":"2026-05-08T10:16:08.921668Z","end":"2026-05-08T10:16:09.076511Z","steps":["trace[551703818] 'agreement among raft nodes before linearized reading' (duration: 154.569653ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:09.076925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.822078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-08T10:16:09.076982Z","caller":"traceutil/trace.go:171","msg":"trace[2105589296] range","detail":"{range_begin:/registry/clusterroles/admin; range_end:; response_count:0; response_revision:96; }","duration":"155.931822ms","start":"2026-05-08T10:16:08.921036Z","end":"2026-05-08T10:16:09.076968Z","steps":["trace[2105589296] 'agreement among raft nodes before linearized reading' (duration: 155.818548ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:16:09.381931Z","caller":"traceutil/trace.go:171","msg":"trace[1109265281] linearizableReadLoop","detail":"{readStateIndex:104; appliedIndex:103; }","duration":"213.388411ms","start":"2026-05-08T10:16:09.168508Z","end":"2026-05-08T10:16:09.381896Z","steps":["trace[1109265281] 'read index received' (duration: 147.852466ms)","trace[1109265281] 'applied index is now lower than readState.Index' (duration: 65.534405ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:16:09.382381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.783465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-08T10:16:09.38253Z","caller":"traceutil/trace.go:171","msg":"trace[1275888906] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:0; response_revision:99; }","duration":"214.048654ms","start":"2026-05-08T10:16:09.168448Z","end":"2026-05-08T10:16:09.382497Z","steps":["trace[1275888906] 'agreement among raft nodes before linearized reading' (duration: 213.577228ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:16:09.382658Z","caller":"traceutil/trace.go:171","msg":"trace[1073585807] transaction","detail":"{read_only:false; response_revision:99; number_of_response:1; }","duration":"215.196945ms","start":"2026-05-08T10:16:09.167402Z","end":"2026-05-08T10:16:09.382599Z","steps":["trace[1073585807] 'process raft request' (duration: 149.206813ms)","trace[1073585807] 'compare' (duration: 65.134032ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:16:09.566405Z","caller":"traceutil/trace.go:171","msg":"trace[429956919] transaction","detail":"{read_only:false; response_revision:101; number_of_response:1; }","duration":"177.716195ms","start":"2026-05-08T10:16:09.388664Z","end":"2026-05-08T10:16:09.56638Z","steps":["trace[429956919] 'process raft request' (duration: 177.453285ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:16:09.566985Z","caller":"traceutil/trace.go:171","msg":"trace[1196680832] transaction","detail":"{read_only:false; response_revision:100; number_of_response:1; }","duration":"179.84516ms","start":"2026-05-08T10:16:09.387118Z","end":"2026-05-08T10:16:09.566963Z","steps":["trace[1196680832] 'process raft request' (duration: 159.376874ms)","trace[1196680832] 'compare' (duration: 19.45596ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:16:09.950483Z","caller":"traceutil/trace.go:171","msg":"trace[798026942] transaction","detail":"{read_only:false; response_revision:114; number_of_response:1; }","duration":"108.926065ms","start":"2026-05-08T10:16:09.841517Z","end":"2026-05-08T10:16:09.950443Z","steps":["trace[798026942] 'process raft request' (duration: 89.91415ms)","trace[798026942] 'compare' (duration: 18.816328ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:16:10.157692Z","caller":"traceutil/trace.go:171","msg":"trace[995986004] transaction","detail":"{read_only:false; response_revision:122; number_of_response:1; }","duration":"111.182067ms","start":"2026-05-08T10:16:10.046464Z","end":"2026-05-08T10:16:10.157646Z","steps":["trace[995986004] 'process raft request' (duration: 111.040062ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:16:10.157727Z","caller":"traceutil/trace.go:171","msg":"trace[898510716] transaction","detail":"{read_only:false; response_revision:121; number_of_response:1; }","duration":"111.26956ms","start":"2026-05-08T10:16:10.046416Z","end":"2026-05-08T10:16:10.157685Z","steps":["trace[898510716] 'process raft request' (duration: 52.460893ms)","trace[898510716] 'compare' (duration: 58.268858ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:16:10.433023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.268933ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-08T10:16:10.433226Z","caller":"traceutil/trace.go:171","msg":"trace[866914531] linearizableReadLoop","detail":"{readStateIndex:136; appliedIndex:134; }","duration":"170.347116ms","start":"2026-05-08T10:16:10.262861Z","end":"2026-05-08T10:16:10.433208Z","steps":["trace[866914531] 'read index received' (duration: 39.841325ms)","trace[866914531] 'applied index is now lower than readState.Index' (duration: 130.504741ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:16:10.433608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.73732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:16:10.433652Z","caller":"traceutil/trace.go:171","msg":"trace[1263201610] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:131; }","duration":"170.823922ms","start":"2026-05-08T10:16:10.262816Z","end":"2026-05-08T10:16:10.43364Z","steps":["trace[1263201610] 'agreement among raft nodes before linearized reading' (duration: 170.569663ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:16:10.433893Z","caller":"traceutil/trace.go:171","msg":"trace[313363994] transaction","detail":"{read_only:false; response_revision:130; number_of_response:1; }","duration":"174.108209ms","start":"2026-05-08T10:16:10.259748Z","end":"2026-05-08T10:16:10.433856Z","steps":["trace[313363994] 'process raft request' (duration: 42.925984ms)","trace[313363994] 'compare' (duration: 129.849668ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:16:10.433915Z","caller":"traceutil/trace.go:171","msg":"trace[2085684727] transaction","detail":"{read_only:false; response_revision:131; number_of_response:1; }","duration":"173.868951ms","start":"2026-05-08T10:16:10.260015Z","end":"2026-05-08T10:16:10.433884Z","steps":["trace[2085684727] 'process raft request' (duration: 173.121534ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:16:10.679218Z","caller":"traceutil/trace.go:171","msg":"trace[1309814639] linearizableReadLoop","detail":"{readStateIndex:137; appliedIndex:136; }","duration":"244.358764ms","start":"2026-05-08T10:16:10.434816Z","end":"2026-05-08T10:16:10.679175Z","steps":["trace[1309814639] 'read index received' (duration: 194.516405ms)","trace[1309814639] 'applied index is now lower than readState.Index' (duration: 49.841569ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:16:10.679297Z","caller":"traceutil/trace.go:171","msg":"trace[1697803387] transaction","detail":"{read_only:false; response_revision:132; number_of_response:1; }","duration":"268.991878ms","start":"2026-05-08T10:16:10.410297Z","end":"2026-05-08T10:16:10.679289Z","steps":["trace[1697803387] 'process raft request' (duration: 218.940551ms)","trace[1697803387] 'compare' (duration: 49.812788ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:16:10.67952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.669415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:16:10.679678Z","caller":"traceutil/trace.go:171","msg":"trace[480914304] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:132; }","duration":"244.872912ms","start":"2026-05-08T10:16:10.434784Z","end":"2026-05-08T10:16:10.679657Z","steps":["trace[480914304] 'agreement among raft nodes before linearized reading' (duration: 244.583752ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:10.697218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.969053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:certificatesigningrequests:nodeclient\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:16:10.697287Z","caller":"traceutil/trace.go:171","msg":"trace[1158116200] transaction","detail":"{read_only:false; response_revision:133; number_of_response:1; }","duration":"262.313952ms","start":"2026-05-08T10:16:10.434938Z","end":"2026-05-08T10:16:10.697252Z","steps":["trace[1158116200] 'process raft request' (duration: 261.97722ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:16:10.697332Z","caller":"traceutil/trace.go:171","msg":"trace[545928431] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:certificatesigningrequests:nodeclient; range_end:; response_count:0; response_revision:134; }","duration":"261.120859ms","start":"2026-05-08T10:16:10.436179Z","end":"2026-05-08T10:16:10.6973Z","steps":["trace[545928431] 'agreement among raft nodes before linearized reading' (duration: 260.942573ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:16:10.697768Z","caller":"traceutil/trace.go:171","msg":"trace[409784325] transaction","detail":"{read_only:false; response_revision:134; number_of_response:1; }","duration":"260.293779ms","start":"2026-05-08T10:16:10.437455Z","end":"2026-05-08T10:16:10.697749Z","steps":["trace[409784325] 'process raft request' (duration: 259.592234ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:16:10.88812Z","caller":"traceutil/trace.go:171","msg":"trace[1205359777] transaction","detail":"{read_only:false; response_revision:139; number_of_response:1; }","duration":"146.78661ms","start":"2026-05-08T10:16:10.741264Z","end":"2026-05-08T10:16:10.888051Z","steps":["trace[1205359777] 'process raft request' (duration: 57.76568ms)","trace[1205359777] 'compare' (duration: 88.765931ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:16:10.888264Z","caller":"traceutil/trace.go:171","msg":"trace[1653343366] transaction","detail":"{read_only:false; response_revision:141; number_of_response:1; }","duration":"121.753682ms","start":"2026-05-08T10:16:10.766454Z","end":"2026-05-08T10:16:10.888207Z","steps":["trace[1653343366] 'process raft request' (duration: 121.7016ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:16:10.888312Z","caller":"traceutil/trace.go:171","msg":"trace[814906145] transaction","detail":"{read_only:false; response_revision:140; number_of_response:1; }","duration":"139.807612ms","start":"2026-05-08T10:16:10.748471Z","end":"2026-05-08T10:16:10.888279Z","steps":["trace[814906145] 'process raft request' (duration: 139.550293ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:16:11.281799Z","caller":"traceutil/trace.go:171","msg":"trace[1591799374] transaction","detail":"{read_only:false; response_revision:145; number_of_response:1; }","duration":"363.713783ms","start":"2026-05-08T10:16:10.918017Z","end":"2026-05-08T10:16:11.281731Z","steps":["trace[1591799374] 'process raft request' (duration: 283.35928ms)","trace[1591799374] 'compare' (duration: 79.97816ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:16:11.282056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:10.917993Z","time spent":"363.949622ms","remote":"127.0.0.1:36456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":666,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-08T10:16:11.282152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.562961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-08T10:16:11.282203Z","caller":"traceutil/trace.go:171","msg":"trace[973185018] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:146; }","duration":"360.679475ms","start":"2026-05-08T10:16:10.921509Z","end":"2026-05-08T10:16:11.282188Z","steps":["trace[973185018] 'agreement among raft nodes before linearized reading' (duration: 360.581992ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:11.282239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:10.921494Z","time spent":"360.737678ms","remote":"127.0.0.1:36380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-08T10:16:11.282195Z","caller":"traceutil/trace.go:171","msg":"trace[999184386] transaction","detail":"{read_only:false; response_revision:146; number_of_response:1; }","duration":"362.980247ms","start":"2026-05-08T10:16:10.919086Z","end":"2026-05-08T10:16:11.282066Z","steps":["trace[999184386] 'process raft request' (duration: 362.49822ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:11.283063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:10.919072Z","time spent":"363.836798ms","remote":"127.0.0.1:36380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T10:16:11.281671Z","caller":"traceutil/trace.go:171","msg":"trace[337209875] linearizableReadLoop","detail":"{readStateIndex:151; appliedIndex:149; }","duration":"360.067944ms","start":"2026-05-08T10:16:10.921579Z","end":"2026-05-08T10:16:11.281647Z","steps":["trace[337209875] 'read index received' (duration: 279.768813ms)","trace[337209875] 'applied index is now lower than readState.Index' (duration: 80.297921ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:16:11.475916Z","caller":"traceutil/trace.go:171","msg":"trace[967827806] transaction","detail":"{read_only:false; response_revision:150; number_of_response:1; }","duration":"123.840687ms","start":"2026-05-08T10:16:11.352028Z","end":"2026-05-08T10:16:11.475868Z","steps":["trace[967827806] 'process raft request' (duration: 123.64187ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:16:11.659291Z","caller":"traceutil/trace.go:171","msg":"trace[973002536] transaction","detail":"{read_only:false; response_revision:153; number_of_response:1; }","duration":"123.867618ms","start":"2026-05-08T10:16:11.535387Z","end":"2026-05-08T10:16:11.659255Z","steps":["trace[973002536] 'process raft request' (duration: 62.768859ms)","trace[973002536] 'compare' (duration: 60.621692ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:16:11.972246Z","caller":"traceutil/trace.go:171","msg":"trace[369542178] transaction","detail":"{read_only:false; response_revision:161; number_of_response:1; }","duration":"101.872598ms","start":"2026-05-08T10:16:11.870343Z","end":"2026-05-08T10:16:11.972216Z","steps":["trace[369542178] 'process raft request' (duration: 84.726949ms)","trace[369542178] 'compare' (duration: 16.981233ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:16:13.163931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.710812ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-08T10:16:13.164019Z","caller":"traceutil/trace.go:171","msg":"trace[1171859412] transaction","detail":"{read_only:false; response_revision:206; number_of_response:1; }","duration":"237.82513ms","start":"2026-05-08T10:16:12.926172Z","end":"2026-05-08T10:16:13.163997Z","steps":["trace[1171859412] 'process raft request' (duration: 16.998583ms)","trace[1171859412] 'compare' (duration: 220.524836ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:16:13.356581Z","caller":"traceutil/trace.go:171","msg":"trace[1888183207] transaction","detail":"{read_only:false; response_revision:209; number_of_response:1; }","duration":"112.090973ms","start":"2026-05-08T10:16:13.244447Z","end":"2026-05-08T10:16:13.356538Z","steps":["trace[1888183207] 'process raft request' (duration: 44.320045ms)","trace[1888183207] 'compare' (duration: 67.54918ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:16:13.587195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.57467ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-08T10:16:13.587403Z","caller":"traceutil/trace.go:171","msg":"trace[849534523] transaction","detail":"{read_only:false; response_revision:215; number_of_response:1; }","duration":"138.45437ms","start":"2026-05-08T10:16:13.448916Z","end":"2026-05-08T10:16:13.587371Z","steps":["trace[849534523] 'process raft request' (duration: 14.590569ms)","trace[849534523] 'compare' (duration: 123.353353ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:16:13.943616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.457894ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-08T10:16:13.94378Z","caller":"traceutil/trace.go:171","msg":"trace[1928672446] transaction","detail":"{read_only:false; response_revision:222; number_of_response:1; }","duration":"224.947733ms","start":"2026-05-08T10:16:13.718814Z","end":"2026-05-08T10:16:13.943762Z","steps":["trace[1928672446] 'process raft request' (duration: 116.402386ms)","trace[1928672446] 'compare' (duration: 108.197315ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:16:14.684319Z","caller":"traceutil/trace.go:171","msg":"trace[1990566754] transaction","detail":"{read_only:false; response_revision:248; number_of_response:1; }","duration":"250.424662ms","start":"2026-05-08T10:16:14.433854Z","end":"2026-05-08T10:16:14.684279Z","steps":["trace[1990566754] 'process raft request' (duration: 158.356959ms)","trace[1990566754] 'compare' (duration: 91.832694ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:16:15.482506Z","caller":"traceutil/trace.go:171","msg":"trace[1708242664] transaction","detail":"{read_only:false; response_revision:255; number_of_response:1; }","duration":"236.816919ms","start":"2026-05-08T10:16:15.245668Z","end":"2026-05-08T10:16:15.482485Z","steps":["trace[1708242664] 'process raft request' (duration: 236.643053ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:15.683256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.0482ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-08T10:16:15.683719Z","caller":"traceutil/trace.go:171","msg":"trace[1541624336] transaction","detail":"{read_only:false; response_revision:256; number_of_response:1; }","duration":"196.430922ms","start":"2026-05-08T10:16:15.487266Z","end":"2026-05-08T10:16:15.683697Z","steps":["trace[1541624336] 'process raft request' (duration: 33.851493ms)","trace[1541624336] 'compare' (duration: 161.932776ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:16:15.683829Z","caller":"traceutil/trace.go:171","msg":"trace[863301478] transaction","detail":"{read_only:false; response_revision:257; number_of_response:1; }","duration":"194.750164ms","start":"2026-05-08T10:16:15.489007Z","end":"2026-05-08T10:16:15.683757Z","steps":["trace[863301478] 'process raft request' (duration: 194.348269ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:16:15.683829Z","caller":"traceutil/trace.go:171","msg":"trace[613203233] transaction","detail":"{read_only:false; response_revision:258; number_of_response:1; }","duration":"191.304301ms","start":"2026-05-08T10:16:15.492499Z","end":"2026-05-08T10:16:15.683803Z","steps":["trace[613203233] 'process raft request' (duration: 191.113244ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:16.354819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.59196ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-08T10:16:16.354927Z","caller":"traceutil/trace.go:171","msg":"trace[479894053] linearizableReadLoop","detail":"{readStateIndex:265; appliedIndex:264; }","duration":"510.335489ms","start":"2026-05-08T10:16:15.844574Z","end":"2026-05-08T10:16:16.35491Z","steps":["trace[479894053] 'read index received' (duration: 166.49935ms)","trace[479894053] 'applied index is now lower than readState.Index' (duration: 343.834519ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:16:16.355104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.516365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:16:16.35516Z","caller":"traceutil/trace.go:171","msg":"trace[1792942764] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:259; }","duration":"510.619528ms","start":"2026-05-08T10:16:15.844527Z","end":"2026-05-08T10:16:16.355146Z","steps":["trace[1792942764] 'agreement among raft nodes before linearized reading' (duration: 510.499404ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:16.355188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:15.844457Z","time spent":"510.726503ms","remote":"127.0.0.1:36380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-08T10:16:16.355207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.648917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:16:16.355248Z","caller":"traceutil/trace.go:171","msg":"trace[936204644] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:259; }","duration":"247.797663ms","start":"2026-05-08T10:16:16.107437Z","end":"2026-05-08T10:16:16.355235Z","steps":["trace[936204644] 'agreement among raft nodes before linearized reading' (duration: 247.657248ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:16:16.355237Z","caller":"traceutil/trace.go:171","msg":"trace[2091623676] transaction","detail":"{read_only:false; response_revision:259; number_of_response:1; }","duration":"663.156282ms","start":"2026-05-08T10:16:15.692044Z","end":"2026-05-08T10:16:16.3552Z","steps":["trace[2091623676] 'process raft request' (duration: 319.106386ms)","trace[2091623676] 'compare' (duration: 343.32741ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:16:16.355378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:15.692026Z","time spent":"663.291156ms","remote":"127.0.0.1:36192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11440,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-08T10:16:21.951241Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976532246867201349,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-08T10:16:22.105867Z","caller":"traceutil/trace.go:171","msg":"trace[510249487] linearizableReadLoop","detail":"{readStateIndex:368; appliedIndex:367; }","duration":"655.282977ms","start":"2026-05-08T10:16:21.45056Z","end":"2026-05-08T10:16:22.105843Z","steps":["trace[510249487] 'read index received' (duration: 655.028988ms)","trace[510249487] 'applied index is now lower than readState.Index' (duration: 253.009µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:16:22.105947Z","caller":"traceutil/trace.go:171","msg":"trace[1663728672] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"697.334096ms","start":"2026-05-08T10:16:21.408571Z","end":"2026-05-08T10:16:22.105905Z","steps":["trace[1663728672] 'process raft request' (duration: 697.072926ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:22.106073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.275017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-05-08T10:16:22.106118Z","caller":"traceutil/trace.go:171","msg":"trace[1139879987] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:355; }","duration":"605.382351ms","start":"2026-05-08T10:16:21.500723Z","end":"2026-05-08T10:16:22.106105Z","steps":["trace[1139879987] 'agreement among raft nodes before linearized reading' (duration: 605.251426ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:22.106137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:21.408552Z","time spent":"697.500941ms","remote":"127.0.0.1:36202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1723,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-08T10:16:22.106161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:21.500694Z","time spent":"605.455763ms","remote":"127.0.0.1:36302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":227,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"warn","ts":"2026-05-08T10:16:22.106148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.575468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"warn","ts":"2026-05-08T10:16:22.10613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.4109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-08T10:16:22.106301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.909585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-05-08T10:16:22.1063Z","caller":"traceutil/trace.go:171","msg":"trace[601651251] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:355; }","duration":"398.600847ms","start":"2026-05-08T10:16:21.707679Z","end":"2026-05-08T10:16:22.10628Z","steps":["trace[601651251] 'agreement among raft nodes before linearized reading' (duration: 398.323827ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:16:22.106336Z","caller":"traceutil/trace.go:171","msg":"trace[646272147] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:355; }","duration":"305.967908ms","start":"2026-05-08T10:16:21.800359Z","end":"2026-05-08T10:16:22.106327Z","steps":["trace[646272147] 'agreement among raft nodes before linearized reading' (duration: 305.912176ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:22.106344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.374265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-05-08T10:16:22.106371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:21.800348Z","time spent":"306.013199ms","remote":"127.0.0.1:36302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":217,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" "} {"level":"info","ts":"2026-05-08T10:16:22.106411Z","caller":"traceutil/trace.go:171","msg":"trace[114457418] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:355; }","duration":"205.462268ms","start":"2026-05-08T10:16:21.900926Z","end":"2026-05-08T10:16:22.106388Z","steps":["trace[114457418] 'agreement among raft nodes before linearized reading' (duration: 205.365424ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:22.106429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.745586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-05-08T10:16:22.106486Z","caller":"traceutil/trace.go:171","msg":"trace[1850314784] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:355; }","duration":"356.821529ms","start":"2026-05-08T10:16:21.74965Z","end":"2026-05-08T10:16:22.106472Z","steps":["trace[1850314784] 'agreement among raft nodes before linearized reading' (duration: 356.731756ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:22.106355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:21.707672Z","time spent":"398.669799ms","remote":"127.0.0.1:36380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-08T10:16:22.106519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.177613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-05-08T10:16:22.106534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:21.749636Z","time spent":"356.887191ms","remote":"127.0.0.1:36302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":221,"request content":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" "} {"level":"info","ts":"2026-05-08T10:16:22.106567Z","caller":"traceutil/trace.go:171","msg":"trace[259270532] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:355; }","duration":"555.226605ms","start":"2026-05-08T10:16:21.551327Z","end":"2026-05-08T10:16:22.106553Z","steps":["trace[259270532] 'agreement among raft nodes before linearized reading' (duration: 555.140412ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:22.106614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:21.551265Z","time spent":"555.339499ms","remote":"127.0.0.1:36302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"warn","ts":"2026-05-08T10:16:22.10665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.653795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-08T10:16:22.106514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.610608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"warn","ts":"2026-05-08T10:16:22.10664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.009736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-05-08T10:16:22.106727Z","caller":"traceutil/trace.go:171","msg":"trace[1745041345] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:355; }","duration":"505.905158ms","start":"2026-05-08T10:16:21.600808Z","end":"2026-05-08T10:16:22.106713Z","steps":["trace[1745041345] 'agreement among raft nodes before linearized reading' (duration: 505.527675ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:16:22.106742Z","caller":"traceutil/trace.go:171","msg":"trace[136418034] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:355; }","duration":"401.743398ms","start":"2026-05-08T10:16:21.704969Z","end":"2026-05-08T10:16:22.106713Z","steps":["trace[136418034] 'agreement among raft nodes before linearized reading' (duration: 401.633265ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:22.106766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:21.600784Z","time spent":"505.9738ms","remote":"127.0.0.1:36302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":238,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"warn","ts":"2026-05-08T10:16:22.106793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.899831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"warn","ts":"2026-05-08T10:16:22.10681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:21.704956Z","time spent":"401.839512ms","remote":"127.0.0.1:36380","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-05-08T10:16:22.106234Z","caller":"traceutil/trace.go:171","msg":"trace[342146811] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:355; }","duration":"655.705303ms","start":"2026-05-08T10:16:21.450507Z","end":"2026-05-08T10:16:22.106213Z","steps":["trace[342146811] 'agreement among raft nodes before linearized reading' (duration: 655.445613ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:16:22.106864Z","caller":"traceutil/trace.go:171","msg":"trace[1968431622] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:355; }","duration":"405.010745ms","start":"2026-05-08T10:16:21.701834Z","end":"2026-05-08T10:16:22.106845Z","steps":["trace[1968431622] 'agreement among raft nodes before linearized reading' (duration: 404.86632ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:22.106895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:21.450489Z","time spent":"656.397838ms","remote":"127.0.0.1:36302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":258,"request content":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" "} {"level":"info","ts":"2026-05-08T10:16:22.106848Z","caller":"traceutil/trace.go:171","msg":"trace[1799825440] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:355; }","duration":"455.468602ms","start":"2026-05-08T10:16:21.65129Z","end":"2026-05-08T10:16:22.106758Z","steps":["trace[1799825440] 'agreement among raft nodes before linearized reading' (duration: 454.811289ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:22.107185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:21.651267Z","time spent":"455.897897ms","remote":"127.0.0.1:36302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":233,"request content":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" "} {"level":"warn","ts":"2026-05-08T10:16:22.107557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.467585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-05-08T10:16:22.10762Z","caller":"traceutil/trace.go:171","msg":"trace[406537531] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:355; }","duration":"257.260143ms","start":"2026-05-08T10:16:21.850337Z","end":"2026-05-08T10:16:22.107598Z","steps":["trace[406537531] 'agreement among raft nodes before linearized reading' (duration: 255.913175ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:22.108021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:21.701811Z","time spent":"405.088208ms","remote":"127.0.0.1:36302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":1,"response size":242,"request content":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" "} {"level":"info","ts":"2026-05-08T10:16:22.285199Z","caller":"traceutil/trace.go:171","msg":"trace[53883149] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"171.491658ms","start":"2026-05-08T10:16:22.113687Z","end":"2026-05-08T10:16:22.285178Z","steps":["trace[53883149] 'process raft request' (duration: 160.778227ms)","trace[53883149] 'compare' (duration: 10.628948ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:16:22.285776Z","caller":"traceutil/trace.go:171","msg":"trace[632865312] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"171.841561ms","start":"2026-05-08T10:16:22.113919Z","end":"2026-05-08T10:16:22.285761Z","steps":["trace[632865312] 'process raft request' (duration: 171.643564ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:16:22.285814Z","caller":"traceutil/trace.go:171","msg":"trace[19234882] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"170.095759ms","start":"2026-05-08T10:16:22.115707Z","end":"2026-05-08T10:16:22.285803Z","steps":["trace[19234882] 'process raft request' (duration: 169.981255ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:16:22.285978Z","caller":"traceutil/trace.go:171","msg":"trace[938063597] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"170.396079ms","start":"2026-05-08T10:16:22.115571Z","end":"2026-05-08T10:16:22.285967Z","steps":["trace[938063597] 'process raft request' (duration: 170.075098ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:16:22.286264Z","caller":"traceutil/trace.go:171","msg":"trace[581152270] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"169.202317ms","start":"2026-05-08T10:16:22.117043Z","end":"2026-05-08T10:16:22.286245Z","steps":["trace[581152270] 'process raft request' (duration: 168.677049ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:16:22.28629Z","caller":"traceutil/trace.go:171","msg":"trace[1817924333] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"167.847959ms","start":"2026-05-08T10:16:22.118406Z","end":"2026-05-08T10:16:22.286254Z","steps":["trace[1817924333] 'process raft request' (duration: 167.370992ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:16:22.286583Z","caller":"traceutil/trace.go:171","msg":"trace[797338708] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"169.387184ms","start":"2026-05-08T10:16:22.11718Z","end":"2026-05-08T10:16:22.286567Z","steps":["trace[797338708] 'process raft request' (duration: 168.573175ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:16:22.286668Z","caller":"traceutil/trace.go:171","msg":"trace[843937670] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"168.125708ms","start":"2026-05-08T10:16:22.118514Z","end":"2026-05-08T10:16:22.286639Z","steps":["trace[843937670] 'process raft request' (duration: 167.294819ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:16:22.287261Z","caller":"traceutil/trace.go:171","msg":"trace[1243489464] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"168.651748ms","start":"2026-05-08T10:16:22.118591Z","end":"2026-05-08T10:16:22.287242Z","steps":["trace[1243489464] 'process raft request' (duration: 167.330611ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:16:22.28749Z","caller":"traceutil/trace.go:171","msg":"trace[552755297] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"168.826054ms","start":"2026-05-08T10:16:22.118647Z","end":"2026-05-08T10:16:22.287473Z","steps":["trace[552755297] 'process raft request' (duration: 167.560479ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:16:22.287668Z","caller":"traceutil/trace.go:171","msg":"trace[1089169985] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"168.994219ms","start":"2026-05-08T10:16:22.118653Z","end":"2026-05-08T10:16:22.287648Z","steps":["trace[1089169985] 'process raft request' (duration: 168.764751ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:16:22.287772Z","caller":"traceutil/trace.go:171","msg":"trace[1503778298] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"167.997154ms","start":"2026-05-08T10:16:22.119761Z","end":"2026-05-08T10:16:22.287758Z","steps":["trace[1503778298] 'process raft request' (duration: 167.88718ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:16:22.287909Z","caller":"traceutil/trace.go:171","msg":"trace[1174341478] linearizableReadLoop","detail":"{readStateIndex:372; appliedIndex:368; }","duration":"170.824465ms","start":"2026-05-08T10:16:22.117039Z","end":"2026-05-08T10:16:22.287863Z","steps":["trace[1174341478] 'read index received' (duration: 157.405597ms)","trace[1174341478] 'applied index is now lower than readState.Index' (duration: 13.417958ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:16:22.287994Z","caller":"traceutil/trace.go:171","msg":"trace[126533202] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"168.239203ms","start":"2026-05-08T10:16:22.119744Z","end":"2026-05-08T10:16:22.287983Z","steps":["trace[126533202] 'process raft request' (duration: 167.85702ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:22.288028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.97476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-05-08T10:16:22.288133Z","caller":"traceutil/trace.go:171","msg":"trace[651657020] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"168.274564ms","start":"2026-05-08T10:16:22.119843Z","end":"2026-05-08T10:16:22.288118Z","steps":["trace[651657020] 'process raft request' (duration: 167.838399ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:16:22.288165Z","caller":"traceutil/trace.go:171","msg":"trace[453171059] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:369; }","duration":"171.142137ms","start":"2026-05-08T10:16:22.117014Z","end":"2026-05-08T10:16:22.288156Z","steps":["trace[453171059] 'agreement among raft nodes before linearized reading' (duration: 170.922789ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:16:22.733847Z","caller":"traceutil/trace.go:171","msg":"trace[93151177] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"103.536078ms","start":"2026-05-08T10:16:22.630288Z","end":"2026-05-08T10:16:22.733824Z","steps":["trace[93151177] 'process raft request' (duration: 103.401813ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:16:24.24835Z","caller":"traceutil/trace.go:171","msg":"trace[931426410] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"183.948016ms","start":"2026-05-08T10:16:24.064364Z","end":"2026-05-08T10:16:24.248312Z","steps":["trace[931426410] 'process raft request' (duration: 183.808281ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:24.972204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.250044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6964"} {"level":"info","ts":"2026-05-08T10:16:24.972296Z","caller":"traceutil/trace.go:171","msg":"trace[1373255837] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:410; }","duration":"173.39948ms","start":"2026-05-08T10:16:24.798878Z","end":"2026-05-08T10:16:24.972278Z","steps":["trace[1373255837] 'range keys from in-memory index tree' (duration: 173.089808ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:16:25.457047Z","caller":"traceutil/trace.go:171","msg":"trace[257963410] linearizableReadLoop","detail":"{readStateIndex:426; appliedIndex:425; }","duration":"171.442091ms","start":"2026-05-08T10:16:25.285569Z","end":"2026-05-08T10:16:25.457011Z","steps":["trace[257963410] 'read index received' (duration: 171.067858ms)","trace[257963410] 'applied index is now lower than readState.Index' (duration: 372.953µs)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:16:25.457246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.653188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:16:25.457287Z","caller":"traceutil/trace.go:171","msg":"trace[217295025] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:411; }","duration":"171.807194ms","start":"2026-05-08T10:16:25.285466Z","end":"2026-05-08T10:16:25.457273Z","steps":["trace[217295025] 'agreement among raft nodes before linearized reading' (duration: 171.680069ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:16:25.457886Z","caller":"traceutil/trace.go:171","msg":"trace[2043721356] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"475.866653ms","start":"2026-05-08T10:16:24.982003Z","end":"2026-05-08T10:16:25.45787Z","steps":["trace[2043721356] 'process raft request' (duration: 474.732733ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:25.458013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:24.981952Z","time spent":"475.978917ms","remote":"127.0.0.1:36284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6950,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T10:16:25.656308Z","caller":"traceutil/trace.go:171","msg":"trace[1531354430] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"185.627717ms","start":"2026-05-08T10:16:25.470646Z","end":"2026-05-08T10:16:25.656274Z","steps":["trace[1531354430] 'process raft request' (duration: 185.451831ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:16:26.096262Z","caller":"traceutil/trace.go:171","msg":"trace[854668599] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"140.556241ms","start":"2026-05-08T10:16:25.955685Z","end":"2026-05-08T10:16:26.096241Z","steps":["trace[854668599] 'process raft request' (duration: 140.405556ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:16:26.513428Z","caller":"traceutil/trace.go:171","msg":"trace[427366523] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"140.108415ms","start":"2026-05-08T10:16:26.373298Z","end":"2026-05-08T10:16:26.513407Z","steps":["trace[427366523] 'process raft request' (duration: 139.980491ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:27.69957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.043280689s","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-05-08T10:16:27.699707Z","caller":"traceutil/trace.go:171","msg":"trace[2117411360] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:414; }","duration":"1.043429164s","start":"2026-05-08T10:16:26.656253Z","end":"2026-05-08T10:16:27.699682Z","steps":["trace[2117411360] 'range keys from in-memory index tree' (duration: 1.043265999s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:27.700095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.018391969s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-08T10:16:27.700626Z","caller":"traceutil/trace.go:171","msg":"trace[835419599] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"756.341493ms","start":"2026-05-08T10:16:26.94427Z","end":"2026-05-08T10:16:27.700611Z","steps":["trace[835419599] 'process raft request' (duration: 756.293302ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:16:27.700668Z","caller":"traceutil/trace.go:171","msg":"trace[990307369] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"1.324195381s","start":"2026-05-08T10:16:26.376453Z","end":"2026-05-08T10:16:27.700649Z","steps":["trace[990307369] 'process raft request' (duration: 1.323754387s)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:16:27.700736Z","caller":"traceutil/trace.go:171","msg":"trace[606546307] linearizableReadLoop","detail":"{readStateIndex:431; appliedIndex:428; }","duration":"1.250396319s","start":"2026-05-08T10:16:26.450324Z","end":"2026-05-08T10:16:27.70072Z","steps":["trace[606546307] 'read index received' (duration: 62.986216ms)","trace[606546307] 'applied index is now lower than readState.Index' (duration: 1.187408153s)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:16:27.70076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:26.376445Z","time spent":"1.324267823s","remote":"127.0.0.1:36284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4119,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T10:16:27.700862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.250534463s","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-05-08T10:16:27.700892Z","caller":"traceutil/trace.go:171","msg":"trace[2073652833] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:417; }","duration":"1.250588865s","start":"2026-05-08T10:16:26.450293Z","end":"2026-05-08T10:16:27.700882Z","steps":["trace[2073652833] 'agreement among raft nodes before linearized reading' (duration: 1.250509272s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:27.7009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.578241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-08T10:16:27.70092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:26.450278Z","time spent":"1.250636467s","remote":"127.0.0.1:36380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-08T10:16:27.70091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"948.123471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-08T10:16:27.70096Z","caller":"traceutil/trace.go:171","msg":"trace[478550985] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:417; }","duration":"415.673344ms","start":"2026-05-08T10:16:27.285268Z","end":"2026-05-08T10:16:27.700941Z","steps":["trace[478550985] 'agreement among raft nodes before linearized reading' (duration: 415.55816ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:27.701019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:27.285248Z","time spent":"415.759087ms","remote":"127.0.0.1:36088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-08T10:16:27.701111Z","caller":"traceutil/trace.go:171","msg":"trace[1272144053] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:417; }","duration":"948.236155ms","start":"2026-05-08T10:16:26.752729Z","end":"2026-05-08T10:16:27.700965Z","steps":["trace[1272144053] 'agreement among raft nodes before linearized reading' (duration: 948.09464ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:27.70117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:26.752713Z","time spent":"948.444301ms","remote":"127.0.0.1:36380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-08T10:16:27.701251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:26.944245Z","time spent":"756.426406ms","remote":"127.0.0.1:36380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T10:16:27.700553Z","caller":"traceutil/trace.go:171","msg":"trace[2137138596] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"1.326493023s","start":"2026-05-08T10:16:26.373979Z","end":"2026-05-08T10:16:27.700472Z","steps":["trace[2137138596] 'process raft request' (duration: 307.615858ms)","trace[2137138596] 'compare' (duration: 1.017856993s)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:16:27.702121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:26.373965Z","time spent":"1.328109754s","remote":"127.0.0.1:36380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T10:16:37.920409Z","caller":"traceutil/trace.go:171","msg":"trace[995536631] linearizableReadLoop","detail":"{readStateIndex:486; appliedIndex:485; }","duration":"169.589084ms","start":"2026-05-08T10:16:37.750793Z","end":"2026-05-08T10:16:37.920382Z","steps":["trace[995536631] 'read index received' (duration: 169.419814ms)","trace[995536631] 'applied index is now lower than readState.Index' (duration: 167.77µs)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:16:37.920676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.855536ms","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-05-08T10:16:37.920745Z","caller":"traceutil/trace.go:171","msg":"trace[2114990632] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:469; }","duration":"169.982276ms","start":"2026-05-08T10:16:37.750746Z","end":"2026-05-08T10:16:37.920729Z","steps":["trace[2114990632] 'agreement among raft nodes before linearized reading' (duration: 169.774695ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:16:37.921062Z","caller":"traceutil/trace.go:171","msg":"trace[993197286] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"171.844205ms","start":"2026-05-08T10:16:37.749171Z","end":"2026-05-08T10:16:37.921016Z","steps":["trace[993197286] 'process raft request' (duration: 171.054532ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:37.957124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.678137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-08T10:16:37.957387Z","caller":"traceutil/trace.go:171","msg":"trace[1911232684] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:471; }","duration":"194.799948ms","start":"2026-05-08T10:16:37.762388Z","end":"2026-05-08T10:16:37.957188Z","steps":["trace[1911232684] 'agreement among raft nodes before linearized reading' (duration: 194.637787ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:37.958558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.416815ms","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-05-08T10:16:37.958651Z","caller":"traceutil/trace.go:171","msg":"trace[25253300] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:471; }","duration":"202.542695ms","start":"2026-05-08T10:16:37.756086Z","end":"2026-05-08T10:16:37.958628Z","steps":["trace[25253300] 'agreement among raft nodes before linearized reading' (duration: 200.895477ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:16:40.905844Z","caller":"traceutil/trace.go:171","msg":"trace[1691728942] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"302.392324ms","start":"2026-05-08T10:16:40.603418Z","end":"2026-05-08T10:16:40.905811Z","steps":["trace[1691728942] 'process raft request' (duration: 302.151652ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:40.906013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:40.603391Z","time spent":"302.531564ms","remote":"127.0.0.1:36380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T10:16:44.294196Z","caller":"traceutil/trace.go:171","msg":"trace[740472262] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"212.376823ms","start":"2026-05-08T10:16:44.081798Z","end":"2026-05-08T10:16:44.294174Z","steps":["trace[740472262] 'process raft request' (duration: 212.069411ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:16:44.300991Z","caller":"traceutil/trace.go:171","msg":"trace[1868434690] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"218.540151ms","start":"2026-05-08T10:16:44.082435Z","end":"2026-05-08T10:16:44.300975Z","steps":["trace[1868434690] 'process raft request' (duration: 218.275329ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:16:44.301222Z","caller":"traceutil/trace.go:171","msg":"trace[1292001891] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"217.867276ms","start":"2026-05-08T10:16:44.083342Z","end":"2026-05-08T10:16:44.301209Z","steps":["trace[1292001891] 'process raft request' (duration: 217.561234ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:16:44.431214Z","caller":"traceutil/trace.go:171","msg":"trace[113948733] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"110.386775ms","start":"2026-05-08T10:16:44.32079Z","end":"2026-05-08T10:16:44.431177Z","steps":["trace[113948733] 'process raft request' (duration: 92.620567ms)","trace[113948733] 'compare' (duration: 17.588446ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:16:46.585617Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976532246867201663,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-08T10:16:46.88409Z","caller":"traceutil/trace.go:171","msg":"trace[947144421] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"810.059752ms","start":"2026-05-08T10:16:46.074011Z","end":"2026-05-08T10:16:46.884071Z","steps":["trace[947144421] 'process raft request' (duration: 809.968511ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:46.884191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:46.073985Z","time spent":"810.159873ms","remote":"127.0.0.1:36156","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-08T10:16:46.900595Z","caller":"traceutil/trace.go:171","msg":"trace[1050980642] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"785.588534ms","start":"2026-05-08T10:16:46.114983Z","end":"2026-05-08T10:16:46.900571Z","steps":["trace[1050980642] 'process raft request' (duration: 785.406703ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:16:46.90063Z","caller":"traceutil/trace.go:171","msg":"trace[1640479778] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"792.598094ms","start":"2026-05-08T10:16:46.108015Z","end":"2026-05-08T10:16:46.900613Z","steps":["trace[1640479778] 'process raft request' (duration: 792.308001ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:46.900714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:46.107991Z","time spent":"792.679674ms","remote":"127.0.0.1:36156","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-08T10:16:46.900732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:46.114966Z","time spent":"785.694635ms","remote":"127.0.0.1:36156","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-08T10:16:46.900692Z","caller":"traceutil/trace.go:171","msg":"trace[1869129751] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"779.701594ms","start":"2026-05-08T10:16:46.12096Z","end":"2026-05-08T10:16:46.900662Z","steps":["trace[1869129751] 'process raft request' (duration: 779.496092ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:16:46.900755Z","caller":"traceutil/trace.go:171","msg":"trace[137450177] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"779.663414ms","start":"2026-05-08T10:16:46.121061Z","end":"2026-05-08T10:16:46.900724Z","steps":["trace[137450177] 'process raft request' (duration: 779.466472ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:46.900837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:46.121056Z","time spent":"779.745384ms","remote":"127.0.0.1:36156","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-08T10:16:46.90084Z","caller":"traceutil/trace.go:171","msg":"trace[537650433] linearizableReadLoop","detail":"{readStateIndex:552; appliedIndex:541; }","duration":"815.292756ms","start":"2026-05-08T10:16:46.085488Z","end":"2026-05-08T10:16:46.900781Z","steps":["trace[537650433] 'read index received' (duration: 798.626305ms)","trace[537650433] 'applied index is now lower than readState.Index' (duration: 16.665701ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:16:46.900872Z","caller":"traceutil/trace.go:171","msg":"trace[1189302510] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"755.72548ms","start":"2026-05-08T10:16:46.145135Z","end":"2026-05-08T10:16:46.90086Z","steps":["trace[1189302510] 'process raft request' (duration: 755.479298ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:16:46.900951Z","caller":"traceutil/trace.go:171","msg":"trace[1703601686] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"663.7863ms","start":"2026-05-08T10:16:46.237153Z","end":"2026-05-08T10:16:46.90094Z","steps":["trace[1703601686] 'process raft request' (duration: 663.529477ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:46.901019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"815.525528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-08T10:16:46.90104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:46.237124Z","time spent":"663.85937ms","remote":"127.0.0.1:36156","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-08T10:16:46.900959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:46.145111Z","time spent":"755.79123ms","remote":"127.0.0.1:36156","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-08T10:16:46.901009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:46.120942Z","time spent":"779.911396ms","remote":"127.0.0.1:36156","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-08T10:16:46.901085Z","caller":"traceutil/trace.go:171","msg":"trace[1876201161] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"780.086738ms","start":"2026-05-08T10:16:46.120987Z","end":"2026-05-08T10:16:46.901074Z","steps":["trace[1876201161] 'process raft request' (duration: 779.503923ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:46.901142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:46.120976Z","time spent":"780.143088ms","remote":"127.0.0.1:36156","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-08T10:16:46.901052Z","caller":"traceutil/trace.go:171","msg":"trace[1712880878] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:533; }","duration":"815.586689ms","start":"2026-05-08T10:16:46.085458Z","end":"2026-05-08T10:16:46.901045Z","steps":["trace[1712880878] 'agreement among raft nodes before linearized reading' (duration: 815.508988ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:46.901191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:46.085444Z","time spent":"815.74057ms","remote":"127.0.0.1:36380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-08T10:16:46.901061Z","caller":"traceutil/trace.go:171","msg":"trace[1971187399] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"778.510104ms","start":"2026-05-08T10:16:46.122536Z","end":"2026-05-08T10:16:46.901046Z","steps":["trace[1971187399] 'process raft request' (duration: 778.034219ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:16:46.901246Z","caller":"traceutil/trace.go:171","msg":"trace[2039246001] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"821.795842ms","start":"2026-05-08T10:16:46.079437Z","end":"2026-05-08T10:16:46.901232Z","steps":["trace[2039246001] 'process raft request' (duration: 820.687293ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:16:46.901229Z","caller":"traceutil/trace.go:171","msg":"trace[811217448] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"783.505437ms","start":"2026-05-08T10:16:46.117318Z","end":"2026-05-08T10:16:46.900824Z","steps":["trace[811217448] 'process raft request' (duration: 783.102113ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:46.901344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:46.079416Z","time spent":"821.897433ms","remote":"127.0.0.1:36156","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-08T10:16:46.901617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:46.1173Z","time spent":"784.087701ms","remote":"127.0.0.1:36156","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-08T10:16:46.901375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:46.122527Z","time spent":"778.732146ms","remote":"127.0.0.1:36156","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-08T10:16:46.900586Z","caller":"traceutil/trace.go:171","msg":"trace[1519202298] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"821.079965ms","start":"2026-05-08T10:16:46.079474Z","end":"2026-05-08T10:16:46.900554Z","steps":["trace[1519202298] 'process raft request' (duration: 820.759903ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:46.901839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:46.079445Z","time spent":"822.362817ms","remote":"127.0.0.1:36156","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-08T10:16:46.907988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.610421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:16:46.90806Z","caller":"traceutil/trace.go:171","msg":"trace[2035670097] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:535; }","duration":"621.724162ms","start":"2026-05-08T10:16:46.28632Z","end":"2026-05-08T10:16:46.908045Z","steps":["trace[2035670097] 'agreement among raft nodes before linearized reading' (duration: 621.608791ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:46.908106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:46.286297Z","time spent":"621.801312ms","remote":"127.0.0.1:36088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-08T10:16:46.908709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.38159ms","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-05-08T10:16:46.908743Z","caller":"traceutil/trace.go:171","msg":"trace[1741100789] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:535; }","duration":"600.453562ms","start":"2026-05-08T10:16:46.308281Z","end":"2026-05-08T10:16:46.908734Z","steps":["trace[1741100789] 'agreement among raft nodes before linearized reading' (duration: 600.335821ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:46.90877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:46.308261Z","time spent":"600.502822ms","remote":"127.0.0.1:36380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-08T10:16:46.908906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.388448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-08T10:16:46.908937Z","caller":"traceutil/trace.go:171","msg":"trace[119612741] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:535; }","duration":"602.458708ms","start":"2026-05-08T10:16:46.306471Z","end":"2026-05-08T10:16:46.90893Z","steps":["trace[119612741] 'agreement among raft nodes before linearized reading' (duration: 602.405278ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:46.908963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:46.306448Z","time spent":"602.509579ms","remote":"127.0.0.1:36380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-08T10:16:51.071441Z","caller":"traceutil/trace.go:171","msg":"trace[564935049] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"134.994244ms","start":"2026-05-08T10:16:50.936421Z","end":"2026-05-08T10:16:51.071415Z","steps":["trace[564935049] 'process raft request' (duration: 134.686411ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:16:51.072443Z","caller":"traceutil/trace.go:171","msg":"trace[2015741037] linearizableReadLoop","detail":"{readStateIndex:635; appliedIndex:635; }","duration":"134.58881ms","start":"2026-05-08T10:16:50.937829Z","end":"2026-05-08T10:16:51.072418Z","steps":["trace[2015741037] 'read index received' (duration: 134.5794ms)","trace[2015741037] 'applied index is now lower than readState.Index' (duration: 7.63µs)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:16:51.072659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.806782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-08T10:16:51.072687Z","caller":"traceutil/trace.go:171","msg":"trace[672529266] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:613; }","duration":"134.874073ms","start":"2026-05-08T10:16:50.937804Z","end":"2026-05-08T10:16:51.072678Z","steps":["trace[672529266] 'agreement among raft nodes before linearized reading' (duration: 134.720731ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:51.57347Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976532246867201808,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-08T10:16:51.669954Z","caller":"traceutil/trace.go:171","msg":"trace[1724346204] linearizableReadLoop","detail":"{readStateIndex:637; appliedIndex:635; }","duration":"597.406457ms","start":"2026-05-08T10:16:51.072506Z","end":"2026-05-08T10:16:51.669912Z","steps":["trace[1724346204] 'read index received' (duration: 576.692564ms)","trace[1724346204] 'applied index is now lower than readState.Index' (duration: 20.713073ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:16:51.670055Z","caller":"traceutil/trace.go:171","msg":"trace[480729219] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"706.695496ms","start":"2026-05-08T10:16:50.963351Z","end":"2026-05-08T10:16:51.670047Z","steps":["trace[480729219] 'process raft request' (duration: 696.506271ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:51.670188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:50.963329Z","time spent":"706.773716ms","remote":"127.0.0.1:36192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036157,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T10:16:51.670453Z","caller":"traceutil/trace.go:171","msg":"trace[1004247888] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"594.157904ms","start":"2026-05-08T10:16:51.076285Z","end":"2026-05-08T10:16:51.670443Z","steps":["trace[1004247888] 'process raft request' (duration: 593.570218ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:51.6705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:51.076263Z","time spent":"594.212494ms","remote":"127.0.0.1:36380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T10:16:51.670686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"680.105072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-08T10:16:51.670715Z","caller":"traceutil/trace.go:171","msg":"trace[1747172346] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:615; }","duration":"680.166002ms","start":"2026-05-08T10:16:50.990542Z","end":"2026-05-08T10:16:51.670708Z","steps":["trace[1747172346] 'agreement among raft nodes before linearized reading' (duration: 680.068301ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:51.670739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:50.990521Z","time spent":"680.212803ms","remote":"127.0.0.1:36380","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-05-08T10:16:51.670847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.024568ms","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-05-08T10:16:51.670871Z","caller":"traceutil/trace.go:171","msg":"trace[223224850] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:615; }","duration":"732.069808ms","start":"2026-05-08T10:16:50.938795Z","end":"2026-05-08T10:16:51.670865Z","steps":["trace[223224850] 'agreement among raft nodes before linearized reading' (duration: 732.010277ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:51.670894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:50.938786Z","time spent":"732.101438ms","remote":"127.0.0.1:36380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-08T10:16:51.670989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.568671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:16:51.671014Z","caller":"traceutil/trace.go:171","msg":"trace[1088749729] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:615; }","duration":"568.619111ms","start":"2026-05-08T10:16:51.102387Z","end":"2026-05-08T10:16:51.671006Z","steps":["trace[1088749729] 'agreement among raft nodes before linearized reading' (duration: 568.579781ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:51.671036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:51.102375Z","time spent":"568.654722ms","remote":"127.0.0.1:34546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 "} {"level":"warn","ts":"2026-05-08T10:16:51.671841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.041894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:16:51.671867Z","caller":"traceutil/trace.go:171","msg":"trace[1092196448] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:615; }","duration":"195.101974ms","start":"2026-05-08T10:16:51.476759Z","end":"2026-05-08T10:16:51.671861Z","steps":["trace[1092196448] 'agreement among raft nodes before linearized reading' (duration: 195.064514ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:51.67199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.5068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:16:51.672015Z","caller":"traceutil/trace.go:171","msg":"trace[1834733503] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:615; }","duration":"196.55996ms","start":"2026-05-08T10:16:51.475449Z","end":"2026-05-08T10:16:51.672009Z","steps":["trace[1834733503] 'agreement among raft nodes before linearized reading' (duration: 196.521149ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:51.672148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.669761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:16:51.672173Z","caller":"traceutil/trace.go:171","msg":"trace[472082256] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:615; }","duration":"196.717731ms","start":"2026-05-08T10:16:51.475449Z","end":"2026-05-08T10:16:51.672167Z","steps":["trace[472082256] 'agreement among raft nodes before linearized reading' (duration: 196.678611ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:51.672305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.707696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:16:51.67233Z","caller":"traceutil/trace.go:171","msg":"trace[820811054] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:615; }","duration":"249.752616ms","start":"2026-05-08T10:16:51.422571Z","end":"2026-05-08T10:16:51.672324Z","steps":["trace[820811054] 'agreement among raft nodes before linearized reading' (duration: 249.703896ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:51.67246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.10825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:16:51.672486Z","caller":"traceutil/trace.go:171","msg":"trace[1420946439] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:615; }","duration":"250.171291ms","start":"2026-05-08T10:16:51.422309Z","end":"2026-05-08T10:16:51.67248Z","steps":["trace[1420946439] 'agreement among raft nodes before linearized reading' (duration: 250.13037ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:51.672624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.403653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:16:51.672836Z","caller":"traceutil/trace.go:171","msg":"trace[952817654] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:615; }","duration":"250.643185ms","start":"2026-05-08T10:16:51.422187Z","end":"2026-05-08T10:16:51.67283Z","steps":["trace[952817654] 'agreement among raft nodes before linearized reading' (duration: 250.417013ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:51.672972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.896925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:16:51.672995Z","caller":"traceutil/trace.go:171","msg":"trace[923848360] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:615; }","duration":"274.941585ms","start":"2026-05-08T10:16:51.398047Z","end":"2026-05-08T10:16:51.672989Z","steps":["trace[923848360] 'agreement among raft nodes before linearized reading' (duration: 274.900835ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:51.673141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.107047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:16:51.673166Z","caller":"traceutil/trace.go:171","msg":"trace[1806693734] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:615; }","duration":"275.160218ms","start":"2026-05-08T10:16:51.397999Z","end":"2026-05-08T10:16:51.673159Z","steps":["trace[1806693734] 'agreement among raft nodes before linearized reading' (duration: 275.121327ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:51.673305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.826794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:16:51.673348Z","caller":"traceutil/trace.go:171","msg":"trace[946704328] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:615; }","duration":"312.853954ms","start":"2026-05-08T10:16:51.36047Z","end":"2026-05-08T10:16:51.673324Z","steps":["trace[946704328] 'agreement among raft nodes before linearized reading' (duration: 312.814534ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:51.673395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:51.360455Z","time spent":"312.930325ms","remote":"127.0.0.1:34604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-05-08T10:16:51.673551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.097667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:16:51.673617Z","caller":"traceutil/trace.go:171","msg":"trace[380150924] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:615; }","duration":"313.174627ms","start":"2026-05-08T10:16:51.360434Z","end":"2026-05-08T10:16:51.673609Z","steps":["trace[380150924] 'agreement among raft nodes before linearized reading' (duration: 313.097177ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:51.673892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.562222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:16:51.673981Z","caller":"traceutil/trace.go:171","msg":"trace[222167583] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:615; }","duration":"313.680263ms","start":"2026-05-08T10:16:51.360284Z","end":"2026-05-08T10:16:51.673964Z","steps":["trace[222167583] 'agreement among raft nodes before linearized reading' (duration: 313.520361ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:51.674016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.431876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-08T10:16:51.674036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:51.360244Z","time spent":"313.766884ms","remote":"127.0.0.1:34588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:500 "} {"level":"info","ts":"2026-05-08T10:16:51.674049Z","caller":"traceutil/trace.go:171","msg":"trace[1043980564] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:615; }","duration":"516.494886ms","start":"2026-05-08T10:16:51.157544Z","end":"2026-05-08T10:16:51.674039Z","steps":["trace[1043980564] 'agreement among raft nodes before linearized reading' (duration: 516.444565ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:51.674066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:51.157509Z","time spent":"516.552667ms","remote":"127.0.0.1:34562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 "} {"level":"warn","ts":"2026-05-08T10:16:51.67417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.288398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:16:51.674192Z","caller":"traceutil/trace.go:171","msg":"trace[277092948] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:615; }","duration":"349.354728ms","start":"2026-05-08T10:16:51.32483Z","end":"2026-05-08T10:16:51.674185Z","steps":["trace[277092948] 'agreement among raft nodes before linearized reading' (duration: 349.324998ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:51.67421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:51.32481Z","time spent":"349.39572ms","remote":"127.0.0.1:34588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-05-08T10:16:51.67426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.399895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:16:51.674281Z","caller":"traceutil/trace.go:171","msg":"trace[921169282] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:615; }","duration":"444.432576ms","start":"2026-05-08T10:16:51.229843Z","end":"2026-05-08T10:16:51.674275Z","steps":["trace[921169282] 'agreement among raft nodes before linearized reading' (duration: 444.404116ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:51.674305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:51.229835Z","time spent":"444.463717ms","remote":"127.0.0.1:34572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 "} {"level":"warn","ts":"2026-05-08T10:16:51.674333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.937291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:16:51.674362Z","caller":"traceutil/trace.go:171","msg":"trace[125658814] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:615; }","duration":"516.984531ms","start":"2026-05-08T10:16:51.157371Z","end":"2026-05-08T10:16:51.674355Z","steps":["trace[125658814] 'agreement among raft nodes before linearized reading' (duration: 516.937341ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:51.674428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:51.157361Z","time spent":"517.058472ms","remote":"127.0.0.1:34562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 "} {"level":"warn","ts":"2026-05-08T10:16:51.674437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.255924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:16:51.674455Z","caller":"traceutil/trace.go:171","msg":"trace[978195564] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:615; }","duration":"445.361915ms","start":"2026-05-08T10:16:51.229089Z","end":"2026-05-08T10:16:51.674451Z","steps":["trace[978195564] 'agreement among raft nodes before linearized reading' (duration: 445.291434ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:51.674475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:51.229064Z","time spent":"445.406416ms","remote":"127.0.0.1:34572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-05-08T10:16:51.674469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.607762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-08T10:16:51.67442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.135199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:16:51.674534Z","caller":"traceutil/trace.go:171","msg":"trace[861361553] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:615; }","duration":"391.764135ms","start":"2026-05-08T10:16:51.282753Z","end":"2026-05-08T10:16:51.674517Z","steps":["trace[861361553] 'agreement among raft nodes before linearized reading' (duration: 391.600053ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:51.67457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:51.282737Z","time spent":"391.827265ms","remote":"127.0.0.1:36088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-08T10:16:51.674625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.230624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-08T10:16:51.674659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.966755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:16:51.67467Z","caller":"traceutil/trace.go:171","msg":"trace[1992866715] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:615; }","duration":"517.297614ms","start":"2026-05-08T10:16:51.157366Z","end":"2026-05-08T10:16:51.674664Z","steps":["trace[1992866715] 'agreement among raft nodes before linearized reading' (duration: 517.235914ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:16:51.67468Z","caller":"traceutil/trace.go:171","msg":"trace[595655099] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:615; }","duration":"350.017265ms","start":"2026-05-08T10:16:51.324658Z","end":"2026-05-08T10:16:51.674675Z","steps":["trace[595655099] 'agreement among raft nodes before linearized reading' (duration: 349.984305ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:51.674693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:51.157353Z","time spent":"517.333544ms","remote":"127.0.0.1:34562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"warn","ts":"2026-05-08T10:16:51.6747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:51.32464Z","time spent":"350.055286ms","remote":"127.0.0.1:34588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 "} {"level":"warn","ts":"2026-05-08T10:16:51.67464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:51.360427Z","time spent":"314.168029ms","remote":"127.0.0.1:34604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 "} {"level":"info","ts":"2026-05-08T10:16:51.674674Z","caller":"traceutil/trace.go:171","msg":"trace[1210710585] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:615; }","duration":"396.431143ms","start":"2026-05-08T10:16:51.278092Z","end":"2026-05-08T10:16:51.674523Z","steps":["trace[1210710585] 'agreement among raft nodes before linearized reading' (duration: 396.14846ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:51.674765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:51.27807Z","time spent":"396.688315ms","remote":"127.0.0.1:34574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-05-08T10:16:51.674543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.521177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-08T10:16:51.674802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.160401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:16:51.674834Z","caller":"traceutil/trace.go:171","msg":"trace[439714676] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:615; }","duration":"445.83235ms","start":"2026-05-08T10:16:51.228992Z","end":"2026-05-08T10:16:51.674825Z","steps":["trace[439714676] 'agreement among raft nodes before linearized reading' (duration: 445.535237ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:51.674871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:51.228975Z","time spent":"445.88253ms","remote":"127.0.0.1:34572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-08T10:16:51.674959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.696635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-08T10:16:51.675018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.419651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:16:51.67504Z","caller":"traceutil/trace.go:171","msg":"trace[473979449] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:615; }","duration":"572.477291ms","start":"2026-05-08T10:16:51.102558Z","end":"2026-05-08T10:16:51.675035Z","steps":["trace[473979449] 'agreement among raft nodes before linearized reading' (duration: 572.445971ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:51.675059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:51.102538Z","time spent":"572.515731ms","remote":"127.0.0.1:34546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-08T10:16:51.675134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.979099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:16:51.675152Z","caller":"traceutil/trace.go:171","msg":"trace[1391649367] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:615; }","duration":"545.057539ms","start":"2026-05-08T10:16:51.13009Z","end":"2026-05-08T10:16:51.675148Z","steps":["trace[1391649367] 'agreement among raft nodes before linearized reading' (duration: 545.025619ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:51.675165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:51.130074Z","time spent":"545.08776ms","remote":"127.0.0.1:34558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"info","ts":"2026-05-08T10:16:51.67518Z","caller":"traceutil/trace.go:171","msg":"trace[120823079] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:615; }","duration":"395.985038ms","start":"2026-05-08T10:16:51.278998Z","end":"2026-05-08T10:16:51.674983Z","steps":["trace[120823079] 'agreement among raft nodes before linearized reading' (duration: 395.695195ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:51.675216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:51.278989Z","time spent":"396.217041ms","remote":"127.0.0.1:34574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:500 "} {"level":"warn","ts":"2026-05-08T10:16:51.675243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.267032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:16:51.675261Z","caller":"traceutil/trace.go:171","msg":"trace[34071627] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:615; }","duration":"545.310802ms","start":"2026-05-08T10:16:51.129946Z","end":"2026-05-08T10:16:51.675257Z","steps":["trace[34071627] 'agreement among raft nodes before linearized reading' (duration: 545.282282ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:51.675276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:51.129929Z","time spent":"545.342642ms","remote":"127.0.0.1:34558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 "} {"level":"warn","ts":"2026-05-08T10:16:51.675479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.292852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:16:51.675507Z","caller":"traceutil/trace.go:171","msg":"trace[146152264] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:615; }","duration":"397.336532ms","start":"2026-05-08T10:16:51.278163Z","end":"2026-05-08T10:16:51.6755Z","steps":["trace[146152264] 'agreement among raft nodes before linearized reading' (duration: 397.292581ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:51.675632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:51.278158Z","time spent":"397.383192ms","remote":"127.0.0.1:34574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"info","ts":"2026-05-08T10:16:51.674844Z","caller":"traceutil/trace.go:171","msg":"trace[487619148] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:615; }","duration":"544.214211ms","start":"2026-05-08T10:16:51.130619Z","end":"2026-05-08T10:16:51.674833Z","steps":["trace[487619148] 'agreement among raft nodes before linearized reading' (duration: 544.15832ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:51.675841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:51.130614Z","time spent":"545.197191ms","remote":"127.0.0.1:34546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:500 "} {"level":"info","ts":"2026-05-08T10:16:54.829357Z","caller":"traceutil/trace.go:171","msg":"trace[1091339143] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:655; }","duration":"461.379504ms","start":"2026-05-08T10:16:54.367945Z","end":"2026-05-08T10:16:54.829325Z","steps":["trace[1091339143] 'read index received' (duration: 460.404383ms)","trace[1091339143] 'applied index is now lower than readState.Index' (duration: 973.721µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:16:54.829484Z","caller":"traceutil/trace.go:171","msg":"trace[454778204] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"617.268731ms","start":"2026-05-08T10:16:54.212133Z","end":"2026-05-08T10:16:54.829402Z","steps":["trace[454778204] 'process raft request' (duration: 616.409782ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:54.82972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.071404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-c7r96\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-08T10:16:54.829842Z","caller":"traceutil/trace.go:171","msg":"trace[523301783] range","detail":"{range_begin:/registry/pods/kube-system/cilium-c7r96; range_end:; response_count:1; response_revision:634; }","duration":"386.228656ms","start":"2026-05-08T10:16:54.443592Z","end":"2026-05-08T10:16:54.829821Z","steps":["trace[523301783] 'agreement among raft nodes before linearized reading' (duration: 386.005084ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:54.829901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:54.443563Z","time spent":"386.323347ms","remote":"127.0.0.1:36284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16989,"request content":"key:\"/registry/pods/kube-system/cilium-c7r96\" "} {"level":"warn","ts":"2026-05-08T10:16:54.829745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.665427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.238\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-08T10:16:54.830045Z","caller":"traceutil/trace.go:171","msg":"trace[317505798] range","detail":"{range_begin:/registry/masterleases/199.204.45.238; range_end:; response_count:1; response_revision:634; }","duration":"462.086611ms","start":"2026-05-08T10:16:54.367909Z","end":"2026-05-08T10:16:54.829996Z","steps":["trace[317505798] 'agreement among raft nodes before linearized reading' (duration: 461.538525ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:54.830064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:54.212102Z","time spent":"617.688336ms","remote":"127.0.0.1:36192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T10:16:54.830171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:54.367889Z","time spent":"462.235303ms","remote":"127.0.0.1:36142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.204.45.238\" "} {"level":"warn","ts":"2026-05-08T10:16:55.58579Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976532246867201877,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-08T10:16:56.01733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.009845408s","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-05-08T10:16:56.017858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:54.831932Z","time spent":"1.185911654s","remote":"127.0.0.1:36142","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-08T10:16:56.017967Z","caller":"traceutil/trace.go:171","msg":"trace[1893777797] linearizableReadLoop","detail":"{readStateIndex:659; appliedIndex:656; }","duration":"932.853005ms","start":"2026-05-08T10:16:55.085081Z","end":"2026-05-08T10:16:56.017934Z","steps":["trace[1893777797] 'read index received' (duration: 904.856867ms)","trace[1893777797] 'applied index is now lower than readState.Index' (duration: 27.995068ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:16:56.018106Z","caller":"traceutil/trace.go:171","msg":"trace[1953337205] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"1.165828297s","start":"2026-05-08T10:16:54.852216Z","end":"2026-05-08T10:16:56.018044Z","steps":["trace[1953337205] 'process raft request' (duration: 1.165388952s)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:16:56.018137Z","caller":"traceutil/trace.go:171","msg":"trace[1917956246] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"1.12792821s","start":"2026-05-08T10:16:54.890028Z","end":"2026-05-08T10:16:56.017956Z","steps":["trace[1917956246] 'process raft request' (duration: 1.127825779s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:56.018256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.338569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-08T10:16:56.018335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"933.221729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-08T10:16:56.018345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:54.852183Z","time spent":"1.16602567s","remote":"127.0.0.1:36284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T10:16:56.018379Z","caller":"traceutil/trace.go:171","msg":"trace[1677898577] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:636; }","duration":"732.510411ms","start":"2026-05-08T10:16:55.285837Z","end":"2026-05-08T10:16:56.018347Z","steps":["trace[1677898577] 'agreement among raft nodes before linearized reading' (duration: 732.319739ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:16:56.018399Z","caller":"traceutil/trace.go:171","msg":"trace[1609305909] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:636; }","duration":"933.350951ms","start":"2026-05-08T10:16:55.085028Z","end":"2026-05-08T10:16:56.018379Z","steps":["trace[1609305909] 'agreement among raft nodes before linearized reading' (duration: 933.098738ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:56.018454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:55.285818Z","time spent":"732.620382ms","remote":"127.0.0.1:36088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-08T10:16:56.018448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:54.890005Z","time spent":"1.128254804s","remote":"127.0.0.1:36358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-08T10:16:56.018557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.892048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-08T10:16:56.018456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:55.085008Z","time spent":"933.435132ms","remote":"127.0.0.1:36380","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-05-08T10:16:56.018644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.779382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-08T10:16:56.018657Z","caller":"traceutil/trace.go:171","msg":"trace[478082359] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:636; }","duration":"303.053749ms","start":"2026-05-08T10:16:55.715576Z","end":"2026-05-08T10:16:56.01863Z","steps":["trace[478082359] 'agreement among raft nodes before linearized reading' (duration: 302.761316ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:16:56.018678Z","caller":"traceutil/trace.go:171","msg":"trace[839951051] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:636; }","duration":"318.853903ms","start":"2026-05-08T10:16:55.699815Z","end":"2026-05-08T10:16:56.018669Z","steps":["trace[839951051] 'agreement among raft nodes before linearized reading' (duration: 318.718341ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:56.018689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.492585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-08T10:16:56.018704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:55.699795Z","time spent":"318.903763ms","remote":"127.0.0.1:36380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-08T10:16:56.018706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:55.715566Z","time spent":"303.12994ms","remote":"127.0.0.1:36380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-08T10:16:56.01873Z","caller":"traceutil/trace.go:171","msg":"trace[1847010915] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:636; }","duration":"297.547315ms","start":"2026-05-08T10:16:55.721171Z","end":"2026-05-08T10:16:56.018718Z","steps":["trace[1847010915] 'agreement among raft nodes before linearized reading' (duration: 297.347183ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:58.025739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.369209267s","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-05-08T10:16:58.025858Z","caller":"traceutil/trace.go:171","msg":"trace[967556326] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:637; }","duration":"1.369337609s","start":"2026-05-08T10:16:56.656503Z","end":"2026-05-08T10:16:58.025841Z","steps":["trace[967556326] 'range keys from in-memory index tree' (duration: 1.369192067s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:58.026031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.888346406s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-08T10:16:58.026213Z","caller":"traceutil/trace.go:171","msg":"trace[230570959] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"2.002224193s","start":"2026-05-08T10:16:56.023971Z","end":"2026-05-08T10:16:58.026195Z","steps":["trace[230570959] 'process raft request' (duration: 2.002136122s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:58.026276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:56.023964Z","time spent":"2.002279734s","remote":"127.0.0.1:36380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T10:16:58.026243Z","caller":"traceutil/trace.go:171","msg":"trace[351923635] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"2.002290314s","start":"2026-05-08T10:16:56.023886Z","end":"2026-05-08T10:16:58.026176Z","steps":["trace[351923635] 'process raft request' (duration: 113.706315ms)","trace[351923635] 'compare' (duration: 1.887825189s)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:16:58.026319Z","caller":"traceutil/trace.go:171","msg":"trace[1033315448] linearizableReadLoop","detail":"{readStateIndex:662; appliedIndex:659; }","duration":"2.002145842s","start":"2026-05-08T10:16:56.02416Z","end":"2026-05-08T10:16:58.026306Z","steps":["trace[1033315448] 'read index received' (duration: 20.810288ms)","trace[1033315448] 'applied index is now lower than readState.Index' (duration: 1.981334734s)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:16:58.026453Z","caller":"traceutil/trace.go:171","msg":"trace[2051710705] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"2.002273174s","start":"2026-05-08T10:16:56.02417Z","end":"2026-05-08T10:16:58.026443Z","steps":["trace[2051710705] 'process raft request' (duration: 2.001998131s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:58.026507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:56.023867Z","time spent":"2.002476826s","remote":"127.0.0.1:36380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T10:16:58.026511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:56.024103Z","time spent":"2.002373104s","remote":"127.0.0.1:36380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T10:16:58.026639Z","caller":"traceutil/trace.go:171","msg":"trace[407768391] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"2.001577325s","start":"2026-05-08T10:16:56.025053Z","end":"2026-05-08T10:16:58.02663Z","steps":["trace[407768391] 'process raft request' (duration: 2.001211751s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:58.026692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:56.025043Z","time spent":"2.001621366s","remote":"127.0.0.1:36380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T10:16:58.026783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.002627768s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-05-08T10:16:58.026813Z","caller":"traceutil/trace.go:171","msg":"trace[1636152608] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:641; }","duration":"2.002680699s","start":"2026-05-08T10:16:56.024124Z","end":"2026-05-08T10:16:58.026805Z","steps":["trace[1636152608] 'agreement among raft nodes before linearized reading' (duration: 2.002625508s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:58.026841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:56.024109Z","time spent":"2.002725839s","remote":"127.0.0.1:36302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":217,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"warn","ts":"2026-05-08T10:16:58.027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.979418702s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-05-08T10:16:58.027045Z","caller":"traceutil/trace.go:171","msg":"trace[141841308] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:641; }","duration":"1.979504382s","start":"2026-05-08T10:16:56.047533Z","end":"2026-05-08T10:16:58.027037Z","steps":["trace[141841308] 'agreement among raft nodes before linearized reading' (duration: 1.979433672s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:58.027067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:56.047504Z","time spent":"1.979557233s","remote":"127.0.0.1:36272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":446,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-05-08T10:16:58.027237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"741.472197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:16:58.02727Z","caller":"traceutil/trace.go:171","msg":"trace[1061718147] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:641; }","duration":"741.534618ms","start":"2026-05-08T10:16:57.285727Z","end":"2026-05-08T10:16:58.027262Z","steps":["trace[1061718147] 'agreement among raft nodes before linearized reading' (duration: 741.487348ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:16:58.027291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:16:57.285704Z","time spent":"741.581629ms","remote":"127.0.0.1:36088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-08T10:16:59.883391Z","caller":"traceutil/trace.go:171","msg":"trace[1066228189] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"107.392739ms","start":"2026-05-08T10:16:59.775968Z","end":"2026-05-08T10:16:59.883361Z","steps":["trace[1066228189] 'process raft request' (duration: 107.237157ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:17:10.474511Z","caller":"traceutil/trace.go:171","msg":"trace[22598323] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"392.159502ms","start":"2026-05-08T10:17:10.082333Z","end":"2026-05-08T10:17:10.474492Z","steps":["trace[22598323] 'process raft request' (duration: 392.04556ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:17:10.474639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:17:10.082298Z","time spent":"392.276724ms","remote":"127.0.0.1:36380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T10:17:10.475261Z","caller":"traceutil/trace.go:171","msg":"trace[1431816109] linearizableReadLoop","detail":"{readStateIndex:808; appliedIndex:806; }","duration":"385.798131ms","start":"2026-05-08T10:17:10.089442Z","end":"2026-05-08T10:17:10.47524Z","steps":["trace[1431816109] 'read index received' (duration: 385.011238ms)","trace[1431816109] 'applied index is now lower than readState.Index' (duration: 785.983µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:17:10.475396Z","caller":"traceutil/trace.go:171","msg":"trace[776513370] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"391.746575ms","start":"2026-05-08T10:17:10.083637Z","end":"2026-05-08T10:17:10.475384Z","steps":["trace[776513370] 'process raft request' (duration: 391.477511ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:17:10.475466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.842771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-08T10:17:10.475497Z","caller":"traceutil/trace.go:171","msg":"trace[735826722] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:780; }","duration":"385.895483ms","start":"2026-05-08T10:17:10.089593Z","end":"2026-05-08T10:17:10.475488Z","steps":["trace[735826722] 'agreement among raft nodes before linearized reading' (duration: 385.802851ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:17:10.475503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:17:10.083619Z","time spent":"391.811997ms","remote":"127.0.0.1:36380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T10:17:10.475569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.135276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-08T10:17:10.475587Z","caller":"traceutil/trace.go:171","msg":"trace[619011604] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:780; }","duration":"386.183987ms","start":"2026-05-08T10:17:10.089397Z","end":"2026-05-08T10:17:10.475581Z","steps":["trace[619011604] 'agreement among raft nodes before linearized reading' (duration: 386.134966ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:17:10.475603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:17:10.08938Z","time spent":"386.218887ms","remote":"127.0.0.1:36380","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-05-08T10:17:10.47552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:17:10.089585Z","time spent":"385.930533ms","remote":"127.0.0.1:36380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-08T10:17:13.805801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.32867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:17:13.80587Z","caller":"traceutil/trace.go:171","msg":"trace[1844775679] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:800; }","duration":"519.427931ms","start":"2026-05-08T10:17:13.286424Z","end":"2026-05-08T10:17:13.805852Z","steps":["trace[1844775679] 'range keys from in-memory index tree' (duration: 519.260998ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:17:13.805898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:17:13.286409Z","time spent":"519.482462ms","remote":"127.0.0.1:36088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-08T10:17:13.805892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.294194ms","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-05-08T10:17:13.805982Z","caller":"traceutil/trace.go:171","msg":"trace[478106186] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:800; }","duration":"366.406185ms","start":"2026-05-08T10:17:13.439539Z","end":"2026-05-08T10:17:13.805945Z","steps":["trace[478106186] 'range keys from in-memory index tree' (duration: 366.157881ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:17:13.806025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:17:13.439519Z","time spent":"366.496938ms","remote":"127.0.0.1:36380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-08T10:17:13.806012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.265925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-08T10:17:13.806086Z","caller":"traceutil/trace.go:171","msg":"trace[935119360] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:800; }","duration":"312.389107ms","start":"2026-05-08T10:17:13.493679Z","end":"2026-05-08T10:17:13.806068Z","steps":["trace[935119360] 'count revisions from in-memory index tree' (duration: 312.192644ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:17:13.806127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:17:13.49366Z","time spent":"312.455498ms","remote":"127.0.0.1:36358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":1,"response size":31,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"info","ts":"2026-05-08T10:17:13.814414Z","caller":"traceutil/trace.go:171","msg":"trace[804143669] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"251.964282ms","start":"2026-05-08T10:17:13.562421Z","end":"2026-05-08T10:17:13.814385Z","steps":["trace[804143669] 'process raft request' (duration: 251.790649ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:17:15.01864Z","caller":"traceutil/trace.go:171","msg":"trace[1116710757] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"198.458532ms","start":"2026-05-08T10:17:14.820159Z","end":"2026-05-08T10:17:15.018617Z","steps":["trace[1116710757] 'process raft request' (duration: 198.295849ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:17:27.969287Z","caller":"traceutil/trace.go:171","msg":"trace[1186926593] linearizableReadLoop","detail":"{readStateIndex:992; appliedIndex:991; }","duration":"141.666067ms","start":"2026-05-08T10:17:27.827601Z","end":"2026-05-08T10:17:27.969267Z","steps":["trace[1186926593] 'read index received' (duration: 141.550055ms)","trace[1186926593] 'applied index is now lower than readState.Index' (duration: 115.142µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:17:27.969329Z","caller":"traceutil/trace.go:171","msg":"trace[1861849080] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"269.822939ms","start":"2026-05-08T10:17:27.699472Z","end":"2026-05-08T10:17:27.969294Z","steps":["trace[1861849080] 'process raft request' (duration: 269.687896ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:17:27.969556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.914673ms","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":"info","ts":"2026-05-08T10:17:27.969668Z","caller":"traceutil/trace.go:171","msg":"trace[1452174275] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:961; }","duration":"142.092735ms","start":"2026-05-08T10:17:27.827554Z","end":"2026-05-08T10:17:27.969647Z","steps":["trace[1452174275] 'agreement among raft nodes before linearized reading' (duration: 141.83132ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:18:31.569832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.606905ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-08T10:18:31.569965Z","caller":"traceutil/trace.go:171","msg":"trace[2004146295] transaction","detail":"{read_only:false; response_revision:1188; number_of_response:1; }","duration":"433.278264ms","start":"2026-05-08T10:18:31.136665Z","end":"2026-05-08T10:18:31.569944Z","steps":["trace[2004146295] 'process raft request' (duration: 116.471323ms)","trace[2004146295] 'compare' (duration: 316.322187ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:18:31.570025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:18:31.136647Z","time spent":"433.349865ms","remote":"127.0.0.1:36380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T10:18:31.594562Z","caller":"traceutil/trace.go:171","msg":"trace[1663577337] linearizableReadLoop","detail":"{readStateIndex:1235; appliedIndex:1232; }","duration":"309.705555ms","start":"2026-05-08T10:18:31.284812Z","end":"2026-05-08T10:18:31.594518Z","steps":["trace[1663577337] 'read index received' (duration: 309.452208ms)","trace[1663577337] 'applied index is now lower than readState.Index' (duration: 252.547µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:18:31.59464Z","caller":"traceutil/trace.go:171","msg":"trace[1616063546] transaction","detail":"{read_only:false; response_revision:1189; number_of_response:1; }","duration":"457.672374ms","start":"2026-05-08T10:18:31.13696Z","end":"2026-05-08T10:18:31.594633Z","steps":["trace[1616063546] 'process raft request' (duration: 457.373895ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:18:31.594682Z","caller":"traceutil/trace.go:171","msg":"trace[737306492] transaction","detail":"{read_only:false; response_revision:1191; number_of_response:1; }","duration":"455.784963ms","start":"2026-05-08T10:18:31.13888Z","end":"2026-05-08T10:18:31.594665Z","steps":["trace[737306492] 'process raft request' (duration: 455.604178ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:18:31.594739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:18:31.136953Z","time spent":"457.719756ms","remote":"127.0.0.1:36380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T10:18:31.5948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:18:31.138862Z","time spent":"455.855424ms","remote":"127.0.0.1:36380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T10:18:31.594865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.036774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:18:31.594589Z","caller":"traceutil/trace.go:171","msg":"trace[77573894] transaction","detail":"{read_only:false; response_revision:1190; number_of_response:1; }","duration":"457.086718ms","start":"2026-05-08T10:18:31.137476Z","end":"2026-05-08T10:18:31.594562Z","steps":["trace[77573894] 'process raft request' (duration: 456.971885ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:18:31.594916Z","caller":"traceutil/trace.go:171","msg":"trace[1343568966] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1191; }","duration":"310.125037ms","start":"2026-05-08T10:18:31.284776Z","end":"2026-05-08T10:18:31.594901Z","steps":["trace[1343568966] 'agreement among raft nodes before linearized reading' (duration: 310.037375ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:18:31.594947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:18:31.284742Z","time spent":"310.199349ms","remote":"127.0.0.1:36088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-08T10:18:31.594948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:18:31.137467Z","time spent":"457.450608ms","remote":"127.0.0.1:36380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T10:18:53.892248Z","caller":"traceutil/trace.go:171","msg":"trace[530873309] linearizableReadLoop","detail":"{readStateIndex:1320; appliedIndex:1319; }","duration":"108.280331ms","start":"2026-05-08T10:18:53.783946Z","end":"2026-05-08T10:18:53.892226Z","steps":["trace[530873309] 'read index received' (duration: 74.509667ms)","trace[530873309] 'applied index is now lower than readState.Index' (duration: 33.769744ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:18:53.892396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.425145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7f5f9e0b-f884-44df-b52e-01bc99bca4d3\" ","response":"range_response_count:1 size:3579"} {"level":"info","ts":"2026-05-08T10:18:53.892397Z","caller":"traceutil/trace.go:171","msg":"trace[44308392] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"110.021951ms","start":"2026-05-08T10:18:53.782325Z","end":"2026-05-08T10:18:53.892346Z","steps":["trace[44308392] 'process raft request' (duration: 76.230497ms)","trace[44308392] 'compare' (duration: 33.566918ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:18:53.892432Z","caller":"traceutil/trace.go:171","msg":"trace[1567521460] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7f5f9e0b-f884-44df-b52e-01bc99bca4d3; range_end:; response_count:1; response_revision:1271; }","duration":"108.493477ms","start":"2026-05-08T10:18:53.783925Z","end":"2026-05-08T10:18:53.892418Z","steps":["trace[1567521460] 'agreement among raft nodes before linearized reading' (duration: 108.416345ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:18:57.364521Z","caller":"traceutil/trace.go:171","msg":"trace[1686788720] linearizableReadLoop","detail":"{readStateIndex:1338; appliedIndex:1337; }","duration":"414.38073ms","start":"2026-05-08T10:18:56.950115Z","end":"2026-05-08T10:18:57.364496Z","steps":["trace[1686788720] 'read index received' (duration: 414.270327ms)","trace[1686788720] 'applied index is now lower than readState.Index' (duration: 109.223µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:18:57.36471Z","caller":"traceutil/trace.go:171","msg":"trace[1127525266] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"468.883548ms","start":"2026-05-08T10:18:56.895812Z","end":"2026-05-08T10:18:57.364695Z","steps":["trace[1127525266] 'process raft request' (duration: 468.568729ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:18:57.364876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.74197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7f5f9e0b-f884-44df-b52e-01bc99bca4d3\" ","response":"range_response_count:1 size:3696"} {"level":"info","ts":"2026-05-08T10:18:57.364967Z","caller":"traceutil/trace.go:171","msg":"trace[480670211] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7f5f9e0b-f884-44df-b52e-01bc99bca4d3; range_end:; response_count:1; response_revision:1288; }","duration":"414.880884ms","start":"2026-05-08T10:18:56.95007Z","end":"2026-05-08T10:18:57.364951Z","steps":["trace[480670211] 'agreement among raft nodes before linearized reading' (duration: 414.637207ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:18:57.364968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:18:56.895793Z","time spent":"469.027412ms","remote":"127.0.0.1:36284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3681,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T10:18:57.365039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:18:56.950053Z","time spent":"414.976267ms","remote":"127.0.0.1:36284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3720,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7f5f9e0b-f884-44df-b52e-01bc99bca4d3\" "} {"level":"info","ts":"2026-05-08T10:18:57.493345Z","caller":"traceutil/trace.go:171","msg":"trace[576527786] linearizableReadLoop","detail":"{readStateIndex:1339; appliedIndex:1338; }","duration":"128.693944ms","start":"2026-05-08T10:18:57.364615Z","end":"2026-05-08T10:18:57.493309Z","steps":["trace[576527786] 'read index received' (duration: 114.268185ms)","trace[576527786] 'applied index is now lower than readState.Index' (duration: 14.424569ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:18:57.49339Z","caller":"traceutil/trace.go:171","msg":"trace[2072034984] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"258.145479ms","start":"2026-05-08T10:18:57.235212Z","end":"2026-05-08T10:18:57.493357Z","steps":["trace[2072034984] 'process raft request' (duration: 243.679199ms)","trace[2072034984] 'compare' (duration: 14.291825ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:18:57.49348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.686155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:18:57.493594Z","caller":"traceutil/trace.go:171","msg":"trace[113851788] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1289; }","duration":"207.842939ms","start":"2026-05-08T10:18:57.28573Z","end":"2026-05-08T10:18:57.493573Z","steps":["trace[113851788] 'agreement among raft nodes before linearized reading' (duration: 207.681905ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:18:57.493881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.846779ms","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-05-08T10:18:57.493944Z","caller":"traceutil/trace.go:171","msg":"trace[197724326] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1289; }","duration":"107.947551ms","start":"2026-05-08T10:18:57.385977Z","end":"2026-05-08T10:18:57.493924Z","steps":["trace[197724326] 'agreement among raft nodes before linearized reading' (duration: 107.817128ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:18:58.015823Z","caller":"traceutil/trace.go:171","msg":"trace[1655364639] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"110.684872ms","start":"2026-05-08T10:18:57.905115Z","end":"2026-05-08T10:18:58.0158Z","steps":["trace[1655364639] 'process raft request' (duration: 110.538067ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:19:03.129667Z","caller":"traceutil/trace.go:171","msg":"trace[968538378] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"199.324262ms","start":"2026-05-08T10:19:02.93031Z","end":"2026-05-08T10:19:03.129634Z","steps":["trace[968538378] 'process raft request' (duration: 198.89767ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:19:10.58023Z","caller":"traceutil/trace.go:171","msg":"trace[1761520805] transaction","detail":"{read_only:false; response_revision:1353; number_of_response:1; }","duration":"478.418233ms","start":"2026-05-08T10:19:10.101789Z","end":"2026-05-08T10:19:10.580207Z","steps":["trace[1761520805] 'process raft request' (duration: 478.279359ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:19:10.580457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:19:10.101777Z","time spent":"478.522386ms","remote":"127.0.0.1:36380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T10:19:10.925915Z","caller":"traceutil/trace.go:171","msg":"trace[1924065664] linearizableReadLoop","detail":"{readStateIndex:1408; appliedIndex:1406; }","duration":"640.295508ms","start":"2026-05-08T10:19:10.285595Z","end":"2026-05-08T10:19:10.92589Z","steps":["trace[1924065664] 'read index received' (duration: 294.500425ms)","trace[1924065664] 'applied index is now lower than readState.Index' (duration: 345.794183ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:19:10.926072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.455464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:19:10.926129Z","caller":"traceutil/trace.go:171","msg":"trace[1202395648] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1354; }","duration":"640.565507ms","start":"2026-05-08T10:19:10.285554Z","end":"2026-05-08T10:19:10.926119Z","steps":["trace[1202395648] 'agreement among raft nodes before linearized reading' (duration: 640.467184ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:19:10.926181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:19:10.285506Z","time spent":"640.667589ms","remote":"127.0.0.1:36088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-08T10:19:10.926044Z","caller":"traceutil/trace.go:171","msg":"trace[1394244441] transaction","detail":"{read_only:false; response_revision:1354; number_of_response:1; }","duration":"823.245648ms","start":"2026-05-08T10:19:10.102763Z","end":"2026-05-08T10:19:10.926008Z","steps":["trace[1394244441] 'process raft request' (duration: 822.965319ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:19:10.92632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.752596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:19:10.926388Z","caller":"traceutil/trace.go:171","msg":"trace[456179854] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1354; }","duration":"334.857879ms","start":"2026-05-08T10:19:10.591513Z","end":"2026-05-08T10:19:10.926371Z","steps":["trace[456179854] 'agreement among raft nodes before linearized reading' (duration: 334.750226ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:19:10.926446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:19:10.591497Z","time spent":"334.940342ms","remote":"127.0.0.1:36260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":29,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"warn","ts":"2026-05-08T10:19:10.926574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:19:10.102735Z","time spent":"823.624608ms","remote":"127.0.0.1:36380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T10:19:13.358635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.93991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-08T10:19:13.358727Z","caller":"traceutil/trace.go:171","msg":"trace[1128973241] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1366; }","duration":"300.096405ms","start":"2026-05-08T10:19:13.058612Z","end":"2026-05-08T10:19:13.358709Z","steps":["trace[1128973241] 'count revisions from in-memory index tree' (duration: 299.795166ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:19:13.35877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:19:13.058591Z","time spent":"300.167427ms","remote":"127.0.0.1:36260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":31,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-05-08T10:19:13.35879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.947313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-08T10:19:13.358903Z","caller":"traceutil/trace.go:171","msg":"trace[388375105] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1366; }","duration":"169.108468ms","start":"2026-05-08T10:19:13.189772Z","end":"2026-05-08T10:19:13.35888Z","steps":["trace[388375105] 'range keys from in-memory index tree' (duration: 168.710506ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:19:13.888835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.546936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:19:13.889032Z","caller":"traceutil/trace.go:171","msg":"trace[404199506] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1367; }","duration":"368.773023ms","start":"2026-05-08T10:19:13.520222Z","end":"2026-05-08T10:19:13.888996Z","steps":["trace[404199506] 'count revisions from in-memory index tree' (duration: 368.444874ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:19:13.88979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:19:13.520205Z","time spent":"369.539496ms","remote":"127.0.0.1:36408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":29,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-08T10:19:13.890167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.633231ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-08T10:19:13.890326Z","caller":"traceutil/trace.go:171","msg":"trace[1583808852] linearizableReadLoop","detail":"{readStateIndex:1423; appliedIndex:1422; }","duration":"246.131994ms","start":"2026-05-08T10:19:13.644113Z","end":"2026-05-08T10:19:13.890245Z","steps":["trace[1583808852] 'read index received' (duration: 99.188924ms)","trace[1583808852] 'applied index is now lower than readState.Index' (duration: 146.94121ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:19:13.890502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.379641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-08T10:19:13.890568Z","caller":"traceutil/trace.go:171","msg":"trace[1498596304] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1368; }","duration":"246.486724ms","start":"2026-05-08T10:19:13.644062Z","end":"2026-05-08T10:19:13.890549Z","steps":["trace[1498596304] 'agreement among raft nodes before linearized reading' (duration: 246.340159ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:19:13.890628Z","caller":"traceutil/trace.go:171","msg":"trace[1648012955] transaction","detail":"{read_only:false; response_revision:1368; number_of_response:1; }","duration":"308.123713ms","start":"2026-05-08T10:19:13.582268Z","end":"2026-05-08T10:19:13.890392Z","steps":["trace[1648012955] 'process raft request' (duration: 161.035219ms)","trace[1648012955] 'compare' (duration: 145.504127ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:19:13.890804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:19:13.58224Z","time spent":"308.512745ms","remote":"127.0.0.1:36380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T10:19:13.890911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.754636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:19:13.891026Z","caller":"traceutil/trace.go:171","msg":"trace[1972519158] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1368; }","duration":"111.88466ms","start":"2026-05-08T10:19:13.7791Z","end":"2026-05-08T10:19:13.890985Z","steps":["trace[1972519158] 'agreement among raft nodes before linearized reading' (duration: 111.726135ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:19:14.088393Z","caller":"traceutil/trace.go:171","msg":"trace[2017629362] transaction","detail":"{read_only:false; response_revision:1370; number_of_response:1; }","duration":"167.495464ms","start":"2026-05-08T10:19:13.920875Z","end":"2026-05-08T10:19:14.088371Z","steps":["trace[2017629362] 'process raft request' (duration: 161.429544ms)"],"step_count":1}