{"level":"warn","ts":"2026-06-16T10:26:05.152719Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-16T10:26:05.152796Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.169: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.169:2380","--initial-cluster=instance=https://199.204.45.169:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.169:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.169: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-06-16T10:26:05.152852Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-16T10:26:05.152865Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.169:2380"]} {"level":"info","ts":"2026-06-16T10:26:05.152881Z","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-06-16T10:26:05.153429Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"]} {"level":"info","ts":"2026-06-16T10:26:05.153501Z","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.169:2380"],"listen-peer-urls":["https://199.204.45.169:2380"],"advertise-client-urls":["https://199.204.45.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.169: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-06-16T10:26:05.156074Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.394976ms"} {"level":"info","ts":"2026-06-16T10:26:05.162091Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1c917701ce165bac","cluster-id":"bfb5247022b87cb1"} {"level":"info","ts":"2026-06-16T10:26:05.16218Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=()"} {"level":"info","ts":"2026-06-16T10:26:05.162212Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became follower at term 0"} {"level":"info","ts":"2026-06-16T10:26:05.162226Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1c917701ce165bac [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-16T10:26:05.162247Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became follower at term 1"} {"level":"info","ts":"2026-06-16T10:26:05.162292Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=(2058557354321271724)"} {"level":"warn","ts":"2026-06-16T10:26:05.166947Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-16T10:26:05.169975Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-16T10:26:05.171206Z","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-06-16T10:26:05.173709Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1c917701ce165bac","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-16T10:26:05.1753Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1c917701ce165bac","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-16T10:26:05.175424Z","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-06-16T10:26:05.17549Z","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-06-16T10:26:05.175514Z","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-06-16T10:26:05.17576Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=(2058557354321271724)"} {"level":"info","ts":"2026-06-16T10:26:05.176064Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"bfb5247022b87cb1","local-member-id":"1c917701ce165bac","added-peer-id":"1c917701ce165bac","added-peer-peer-urls":["https://199.204.45.169:2380"]} {"level":"info","ts":"2026-06-16T10:26:05.181589Z","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-06-16T10:26:05.181794Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1c917701ce165bac","initial-advertise-peer-urls":["https://199.204.45.169:2380"],"listen-peer-urls":["https://199.204.45.169:2380"],"advertise-client-urls":["https://199.204.45.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-16T10:26:05.181824Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-16T10:26:05.181924Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.169:2380"} {"level":"info","ts":"2026-06-16T10:26:05.181945Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.169:2380"} {"level":"info","ts":"2026-06-16T10:26:05.663237Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac is starting a new election at term 1"} {"level":"info","ts":"2026-06-16T10:26:05.663299Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became pre-candidate at term 1"} {"level":"info","ts":"2026-06-16T10:26:05.663361Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac received MsgPreVoteResp from 1c917701ce165bac at term 1"} {"level":"info","ts":"2026-06-16T10:26:05.663384Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became candidate at term 2"} {"level":"info","ts":"2026-06-16T10:26:05.6634Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac received MsgVoteResp from 1c917701ce165bac at term 2"} {"level":"info","ts":"2026-06-16T10:26:05.663421Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became leader at term 2"} {"level":"info","ts":"2026-06-16T10:26:05.663439Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1c917701ce165bac elected leader 1c917701ce165bac at term 2"} {"level":"info","ts":"2026-06-16T10:26:05.664054Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1c917701ce165bac","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.169:2379]}","request-path":"/0/members/1c917701ce165bac/attributes","cluster-id":"bfb5247022b87cb1","publish-timeout":"7s"} {"level":"info","ts":"2026-06-16T10:26:05.664101Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-16T10:26:05.664255Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-16T10:26:05.664491Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-16T10:26:05.664548Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-16T10:26:05.664801Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"bfb5247022b87cb1","local-member-id":"1c917701ce165bac","cluster-version":"3.5"} {"level":"info","ts":"2026-06-16T10:26:05.664872Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-16T10:26:05.664894Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-16T10:26:05.664313Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-16T10:26:05.668632Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.169:2379"} {"level":"info","ts":"2026-06-16T10:26:05.670221Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-16T10:26:31.419651Z","caller":"traceutil/trace.go:171","msg":"trace[2029133] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"241.357079ms","start":"2026-06-16T10:26:31.178271Z","end":"2026-06-16T10:26:31.419628Z","steps":["trace[2029133] 'process raft request' (duration: 241.180444ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:26:31.450875Z","caller":"traceutil/trace.go:171","msg":"trace[2052612048] linearizableReadLoop","detail":"{readStateIndex:410; appliedIndex:408; }","duration":"167.35662ms","start":"2026-06-16T10:26:31.283491Z","end":"2026-06-16T10:26:31.450848Z","steps":["trace[2052612048] 'read index received' (duration: 135.9936ms)","trace[2052612048] 'applied index is now lower than readState.Index' (duration: 31.36149ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T10:26:31.450925Z","caller":"traceutil/trace.go:171","msg":"trace[2112809180] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"271.997969ms","start":"2026-06-16T10:26:31.178893Z","end":"2026-06-16T10:26:31.450891Z","steps":["trace[2112809180] 'process raft request' (duration: 268.409311ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:26:31.451132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.482734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T10:26:31.451219Z","caller":"traceutil/trace.go:171","msg":"trace[822574269] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:396; }","duration":"167.772641ms","start":"2026-06-16T10:26:31.283434Z","end":"2026-06-16T10:26:31.451207Z","steps":["trace[822574269] 'agreement among raft nodes before linearized reading' (duration: 167.505334ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:26:49.920688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.251968ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-16T10:26:49.920789Z","caller":"traceutil/trace.go:171","msg":"trace[1496771929] linearizableReadLoop","detail":"{readStateIndex:520; appliedIndex:519; }","duration":"325.626221ms","start":"2026-06-16T10:26:49.595147Z","end":"2026-06-16T10:26:49.920774Z","steps":["trace[1496771929] 'read index received' (duration: 142.633082ms)","trace[1496771929] 'applied index is now lower than readState.Index' (duration: 182.991129ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T10:26:49.92085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.708843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T10:26:49.920875Z","caller":"traceutil/trace.go:171","msg":"trace[908099463] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:502; }","duration":"325.786926ms","start":"2026-06-16T10:26:49.595079Z","end":"2026-06-16T10:26:49.920866Z","steps":["trace[908099463] 'agreement among raft nodes before linearized reading' (duration: 325.737474ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:26:49.920923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:26:49.595061Z","time spent":"325.850237ms","remote":"127.0.0.1:45234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"info","ts":"2026-06-16T10:26:49.921037Z","caller":"traceutil/trace.go:171","msg":"trace[971611988] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"351.10099ms","start":"2026-06-16T10:26:49.569908Z","end":"2026-06-16T10:26:49.921009Z","steps":["trace[971611988] 'process raft request' (duration: 167.940936ms)","trace[971611988] 'compare' (duration: 182.146366ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T10:26:49.921142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:26:49.569897Z","time spent":"351.193712ms","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T10:26:50.697239Z","caller":"traceutil/trace.go:171","msg":"trace[1692745137] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"134.106358ms","start":"2026-06-16T10:26:50.563107Z","end":"2026-06-16T10:26:50.697214Z","steps":["trace[1692745137] 'process raft request' (duration: 131.205169ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:26:50.697578Z","caller":"traceutil/trace.go:171","msg":"trace[872362804] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"116.448963ms","start":"2026-06-16T10:26:50.581095Z","end":"2026-06-16T10:26:50.697544Z","steps":["trace[872362804] 'process raft request' (duration: 116.273098ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:26:56.076655Z","caller":"traceutil/trace.go:171","msg":"trace[176323306] linearizableReadLoop","detail":"{readStateIndex:626; appliedIndex:625; }","duration":"133.231704ms","start":"2026-06-16T10:26:55.943397Z","end":"2026-06-16T10:26:56.076628Z","steps":["trace[176323306] 'read index received' (duration: 132.606987ms)","trace[176323306] 'applied index is now lower than readState.Index' (duration: 623.087µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T10:26:56.076758Z","caller":"traceutil/trace.go:171","msg":"trace[901919666] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"188.50123ms","start":"2026-06-16T10:26:55.88822Z","end":"2026-06-16T10:26:56.076722Z","steps":["trace[901919666] 'process raft request' (duration: 187.883053ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:26:56.076849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.43692ms","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-06-16T10:26:56.076891Z","caller":"traceutil/trace.go:171","msg":"trace[1252464890] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:602; }","duration":"133.559733ms","start":"2026-06-16T10:26:55.943317Z","end":"2026-06-16T10:26:56.076876Z","steps":["trace[1252464890] 'agreement among raft nodes before linearized reading' (duration: 133.416669ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:26:56.632988Z","caller":"traceutil/trace.go:171","msg":"trace[1872077160] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"127.041455ms","start":"2026-06-16T10:26:56.505915Z","end":"2026-06-16T10:26:56.632957Z","steps":["trace[1872077160] 'process raft request' (duration: 126.86492ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:26:57.08668Z","caller":"traceutil/trace.go:171","msg":"trace[350875307] linearizableReadLoop","detail":"{readStateIndex:633; appliedIndex:632; }","duration":"251.036445ms","start":"2026-06-16T10:26:56.835595Z","end":"2026-06-16T10:26:57.086632Z","steps":["trace[350875307] 'read index received' (duration: 250.239243ms)","trace[350875307] 'applied index is now lower than readState.Index' (duration: 796.082µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T10:26:57.086776Z","caller":"traceutil/trace.go:171","msg":"trace[191884198] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"508.217878ms","start":"2026-06-16T10:26:56.57852Z","end":"2026-06-16T10:26:57.086737Z","steps":["trace[191884198] 'process raft request' (duration: 507.56995ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:26:57.086866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.264311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-06-16T10:26:57.086945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:26:56.578494Z","time spent":"508.362782ms","remote":"127.0.0.1:45234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T10:26:57.086954Z","caller":"traceutil/trace.go:171","msg":"trace[856880264] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:609; }","duration":"251.422546ms","start":"2026-06-16T10:26:56.83552Z","end":"2026-06-16T10:26:57.086942Z","steps":["trace[856880264] 'agreement among raft nodes before linearized reading' (duration: 251.22873ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:26:57.987905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"834.919959ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-16T10:26:57.988179Z","caller":"traceutil/trace.go:171","msg":"trace[1345046092] linearizableReadLoop","detail":"{readStateIndex:635; appliedIndex:633; }","duration":"451.560565ms","start":"2026-06-16T10:26:57.53659Z","end":"2026-06-16T10:26:57.988151Z","steps":["trace[1345046092] 'read index received' (duration: 35.021µs)","trace[1345046092] 'applied index is now lower than readState.Index' (duration: 451.523734ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T10:26:57.988298Z","caller":"traceutil/trace.go:171","msg":"trace[1745847665] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"897.063083ms","start":"2026-06-16T10:26:57.091217Z","end":"2026-06-16T10:26:57.98828Z","steps":["trace[1745847665] 'process raft request' (duration: 61.6294ms)","trace[1745847665] 'compare' (duration: 834.738394ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T10:26:57.988379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:26:57.09118Z","time spent":"897.158396ms","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T10:26:57.988416Z","caller":"traceutil/trace.go:171","msg":"trace[354546844] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"831.832794ms","start":"2026-06-16T10:26:57.156539Z","end":"2026-06-16T10:26:57.988372Z","steps":["trace[354546844] 'process raft request' (duration: 831.517495ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:26:57.988607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:26:57.156517Z","time spent":"831.995569ms","remote":"127.0.0.1:45586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-16T10:26:57.988724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.10249ms","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-06-16T10:26:57.988825Z","caller":"traceutil/trace.go:171","msg":"trace[2001782974] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:611; }","duration":"452.249314ms","start":"2026-06-16T10:26:57.536548Z","end":"2026-06-16T10:26:57.988797Z","steps":["trace[2001782974] 'agreement among raft nodes before linearized reading' (duration: 452.022197ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:26:57.988963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:26:57.536527Z","time spent":"452.423489ms","remote":"127.0.0.1:45358","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-06-16T10:26:58.489211Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605829369493647152,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-16T10:26:58.615511Z","caller":"traceutil/trace.go:171","msg":"trace[271716272] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"646.612804ms","start":"2026-06-16T10:26:57.968873Z","end":"2026-06-16T10:26:58.615485Z","steps":["trace[271716272] 'process raft request' (duration: 646.354907ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:26:58.616005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:26:57.968847Z","time spent":"646.738508ms","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T10:26:58.617717Z","caller":"traceutil/trace.go:171","msg":"trace[1473952644] linearizableReadLoop","detail":"{readStateIndex:637; appliedIndex:635; }","duration":"629.463454ms","start":"2026-06-16T10:26:57.988226Z","end":"2026-06-16T10:26:58.617689Z","steps":["trace[1473952644] 'read index received' (duration: 626.983346ms)","trace[1473952644] 'applied index is now lower than readState.Index' (duration: 2.478558ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T10:26:58.617807Z","caller":"traceutil/trace.go:171","msg":"trace[469686433] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"623.83608ms","start":"2026-06-16T10:26:57.993961Z","end":"2026-06-16T10:26:58.617797Z","steps":["trace[469686433] 'process raft request' (duration: 623.645684ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:26:58.617878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:26:57.993936Z","time spent":"623.900302ms","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T10:26:58.618059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.063306103s","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-06-16T10:26:58.618101Z","caller":"traceutil/trace.go:171","msg":"trace[950425916] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:613; }","duration":"1.063366545s","start":"2026-06-16T10:26:57.554722Z","end":"2026-06-16T10:26:58.618088Z","steps":["trace[950425916] 'agreement among raft nodes before linearized reading' (duration: 1.063298253s)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:26:58.61813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:26:57.554711Z","time spent":"1.063412396s","remote":"127.0.0.1:45358","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-06-16T10:26:58.618682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.895663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T10:26:58.618809Z","caller":"traceutil/trace.go:171","msg":"trace[176961769] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:613; }","duration":"340.076727ms","start":"2026-06-16T10:26:58.278697Z","end":"2026-06-16T10:26:58.618774Z","steps":["trace[176961769] 'agreement among raft nodes before linearized reading' (duration: 339.836491ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:26:58.618863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:26:58.278676Z","time spent":"340.17728ms","remote":"127.0.0.1:45174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-16T10:26:58.61873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.071153ms","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-06-16T10:26:58.619528Z","caller":"traceutil/trace.go:171","msg":"trace[1739384958] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:613; }","duration":"532.816513ms","start":"2026-06-16T10:26:58.086614Z","end":"2026-06-16T10:26:58.619431Z","steps":["trace[1739384958] 'agreement among raft nodes before linearized reading' (duration: 531.927399ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:26:58.619578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:26:58.086594Z","time spent":"532.973257ms","remote":"127.0.0.1:45358","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-06-16T10:26:58.618696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.838156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T10:26:58.620131Z","caller":"traceutil/trace.go:171","msg":"trace[263662508] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:613; }","duration":"615.324656ms","start":"2026-06-16T10:26:58.004772Z","end":"2026-06-16T10:26:58.620097Z","steps":["trace[263662508] 'agreement among raft nodes before linearized reading' (duration: 613.762233ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:26:58.620207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:26:58.004757Z","time spent":"615.431339ms","remote":"127.0.0.1:45338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-06-16T10:27:14.091905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.797662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-16T10:27:14.091941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.977557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-dbtkt\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-06-16T10:27:14.092292Z","caller":"traceutil/trace.go:171","msg":"trace[157773412] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-dbtkt; range_end:; response_count:1; response_revision:770; }","duration":"514.366787ms","start":"2026-06-16T10:27:13.577884Z","end":"2026-06-16T10:27:14.092251Z","steps":["trace[157773412] 'range keys from in-memory index tree' (duration: 513.775741ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:27:14.092065Z","caller":"traceutil/trace.go:171","msg":"trace[1637264471] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:770; }","duration":"343.06107ms","start":"2026-06-16T10:27:13.748979Z","end":"2026-06-16T10:27:14.09204Z","steps":["trace[1637264471] 'count revisions from in-memory index tree' (duration: 342.681569ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:27:14.0924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:27:13.577851Z","time spent":"514.517592ms","remote":"127.0.0.1:45314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-dbtkt\" "} {"level":"warn","ts":"2026-06-16T10:27:14.092444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:27:13.748907Z","time spent":"343.474991ms","remote":"127.0.0.1:45292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":2,"response size":30,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-06-16T10:27:14.259576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.735712ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-16T10:27:14.259699Z","caller":"traceutil/trace.go:171","msg":"trace[365811971] linearizableReadLoop","detail":"{readStateIndex:801; appliedIndex:800; }","duration":"206.098272ms","start":"2026-06-16T10:27:14.053585Z","end":"2026-06-16T10:27:14.259683Z","steps":["trace[365811971] 'read index received' (duration: 47.541µs)","trace[365811971] 'applied index is now lower than readState.Index' (duration: 206.049091ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T10:27:14.259893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.291168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-16T10:27:14.259979Z","caller":"traceutil/trace.go:171","msg":"trace[596688102] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:770; }","duration":"206.427542ms","start":"2026-06-16T10:27:14.053534Z","end":"2026-06-16T10:27:14.259961Z","steps":["trace[596688102] 'agreement among raft nodes before linearized reading' (duration: 206.187375ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:27:14.263176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.54018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-16T10:27:14.263241Z","caller":"traceutil/trace.go:171","msg":"trace[699617034] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:771; }","duration":"130.656483ms","start":"2026-06-16T10:27:14.132571Z","end":"2026-06-16T10:27:14.263228Z","steps":["trace[699617034] 'agreement among raft nodes before linearized reading' (duration: 130.511839ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:27:14.263366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.601765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-06-16T10:27:14.263401Z","caller":"traceutil/trace.go:171","msg":"trace[478613410] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:771; }","duration":"117.675877ms","start":"2026-06-16T10:27:14.145716Z","end":"2026-06-16T10:27:14.263392Z","steps":["trace[478613410] 'agreement among raft nodes before linearized reading' (duration: 117.509132ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:27:14.263171Z","caller":"traceutil/trace.go:171","msg":"trace[17241916] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"160.343498ms","start":"2026-06-16T10:27:14.102794Z","end":"2026-06-16T10:27:14.263138Z","steps":["trace[17241916] 'process raft request' (duration: 160.157863ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:27:48.93107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.623538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.169\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-16T10:27:48.931168Z","caller":"traceutil/trace.go:171","msg":"trace[1436189717] range","detail":"{range_begin:/registry/masterleases/199.204.45.169; range_end:; response_count:1; response_revision:985; }","duration":"187.760442ms","start":"2026-06-16T10:27:48.743385Z","end":"2026-06-16T10:27:48.931145Z","steps":["trace[1436189717] 'range keys from in-memory index tree' (duration: 187.445183ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:27:48.931174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.211165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-06-16T10:27:48.931182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.781052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-16T10:27:48.931243Z","caller":"traceutil/trace.go:171","msg":"trace[1324245635] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:985; }","duration":"509.320928ms","start":"2026-06-16T10:27:48.421906Z","end":"2026-06-16T10:27:48.931227Z","steps":["trace[1324245635] 'range keys from in-memory index tree' (duration: 509.058941ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:27:48.931255Z","caller":"traceutil/trace.go:171","msg":"trace[1558834557] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:985; }","duration":"398.888374ms","start":"2026-06-16T10:27:48.532355Z","end":"2026-06-16T10:27:48.931244Z","steps":["trace[1558834557] 'count revisions from in-memory index tree' (duration: 398.71902ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:27:48.931282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:27:48.421881Z","time spent":"509.39216ms","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-16T10:27:48.931294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:27:48.53234Z","time spent":"398.947176ms","remote":"127.0.0.1:45546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":2,"response size":30,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-06-16T10:27:48.931174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.610071ms","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-06-16T10:27:48.931406Z","caller":"traceutil/trace.go:171","msg":"trace[1141306398] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:985; }","duration":"514.858977ms","start":"2026-06-16T10:27:48.416538Z","end":"2026-06-16T10:27:48.931397Z","steps":["trace[1141306398] 'range keys from in-memory index tree' (duration: 514.461147ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:27:48.931435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:27:48.416497Z","time spent":"514.930019ms","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-16T10:27:49.462925Z","caller":"traceutil/trace.go:171","msg":"trace[1205181183] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"526.953844ms","start":"2026-06-16T10:27:48.935953Z","end":"2026-06-16T10:27:49.462907Z","steps":["trace[1205181183] 'process raft request' (duration: 526.900543ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:27:49.463036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:27:48.935937Z","time spent":"527.039697ms","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T10:27:49.463195Z","caller":"traceutil/trace.go:171","msg":"trace[710464345] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"528.156467ms","start":"2026-06-16T10:27:48.935027Z","end":"2026-06-16T10:27:49.463184Z","steps":["trace[710464345] 'process raft request' (duration: 527.665633ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:27:49.463245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:27:48.935011Z","time spent":"528.210419ms","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T10:27:49.463218Z","caller":"traceutil/trace.go:171","msg":"trace[850405268] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"527.15976ms","start":"2026-06-16T10:27:48.936037Z","end":"2026-06-16T10:27:49.463197Z","steps":["trace[850405268] 'process raft request' (duration: 526.848162ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:27:49.463399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:27:48.936011Z","time spent":"527.281444ms","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T10:27:49.463619Z","caller":"traceutil/trace.go:171","msg":"trace[1871445734] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"527.52237ms","start":"2026-06-16T10:27:48.936085Z","end":"2026-06-16T10:27:49.463607Z","steps":["trace[1871445734] 'process raft request' (duration: 527.037527ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:27:49.463763Z","caller":"traceutil/trace.go:171","msg":"trace[1589720315] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"527.303134ms","start":"2026-06-16T10:27:48.936447Z","end":"2026-06-16T10:27:49.46375Z","steps":["trace[1589720315] 'process raft request' (duration: 527.127889ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:27:49.463826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:27:48.936078Z","time spent":"527.564971ms","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T10:27:49.463841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:27:48.936429Z","time spent":"527.367655ms","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T10:27:49.523449Z","caller":"traceutil/trace.go:171","msg":"trace[975910565] linearizableReadLoop","detail":"{readStateIndex:1030; appliedIndex:1024; }","duration":"391.903206ms","start":"2026-06-16T10:27:49.131527Z","end":"2026-06-16T10:27:49.52343Z","steps":["trace[975910565] 'read index received' (duration: 331.274158ms)","trace[975910565] 'applied index is now lower than readState.Index' (duration: 60.627848ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T10:27:49.523479Z","caller":"traceutil/trace.go:171","msg":"trace[27023388] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"445.892195ms","start":"2026-06-16T10:27:49.07756Z","end":"2026-06-16T10:27:49.523452Z","steps":["trace[27023388] 'process raft request' (duration: 445.772531ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:27:49.523589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.060833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-16T10:27:49.523608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.079241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T10:27:49.523615Z","caller":"traceutil/trace.go:171","msg":"trace[876749483] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:991; }","duration":"244.137435ms","start":"2026-06-16T10:27:49.279469Z","end":"2026-06-16T10:27:49.523607Z","steps":["trace[876749483] 'agreement among raft nodes before linearized reading' (duration: 244.061963ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:27:49.523607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:27:49.07754Z","time spent":"445.996458ms","remote":"127.0.0.1:45184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T10:27:49.523647Z","caller":"traceutil/trace.go:171","msg":"trace[1073132463] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:991; }","duration":"392.211775ms","start":"2026-06-16T10:27:49.131426Z","end":"2026-06-16T10:27:49.523638Z","steps":["trace[1073132463] 'agreement among raft nodes before linearized reading' (duration: 392.131902ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:27:49.523672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:27:49.131408Z","time spent":"392.258025ms","remote":"127.0.0.1:45404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"info","ts":"2026-06-16T10:28:10.707262Z","caller":"traceutil/trace.go:171","msg":"trace[626449944] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"372.293726ms","start":"2026-06-16T10:28:10.334944Z","end":"2026-06-16T10:28:10.707238Z","steps":["trace[626449944] 'process raft request' (duration: 372.137212ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:28:10.707414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:28:10.334926Z","time spent":"372.4259ms","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T10:28:12.493935Z","caller":"traceutil/trace.go:171","msg":"trace[639596968] linearizableReadLoop","detail":"{readStateIndex:1108; appliedIndex:1107; }","duration":"126.634251ms","start":"2026-06-16T10:28:12.367277Z","end":"2026-06-16T10:28:12.493912Z","steps":["trace[639596968] 'read index received' (duration: 126.469007ms)","trace[639596968] 'applied index is now lower than readState.Index' (duration: 164.454µs)"],"step_count":2} {"level":"warn","ts":"2026-06-16T10:28:12.494134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.828766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-16T10:28:12.494197Z","caller":"traceutil/trace.go:171","msg":"trace[326891329] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1065; }","duration":"126.911368ms","start":"2026-06-16T10:28:12.367271Z","end":"2026-06-16T10:28:12.494182Z","steps":["trace[326891329] 'agreement among raft nodes before linearized reading' (duration: 126.729104ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:28:12.494209Z","caller":"traceutil/trace.go:171","msg":"trace[418597385] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"211.714809ms","start":"2026-06-16T10:28:12.28244Z","end":"2026-06-16T10:28:12.494155Z","steps":["trace[418597385] 'process raft request' (duration: 211.361189ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:28:13.045363Z","caller":"traceutil/trace.go:171","msg":"trace[1684692097] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"333.664504ms","start":"2026-06-16T10:28:12.711676Z","end":"2026-06-16T10:28:13.04534Z","steps":["trace[1684692097] 'process raft request' (duration: 326.307455ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:28:13.045485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:28:12.711649Z","time spent":"333.774296ms","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T10:28:13.937128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.238488ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-16T10:28:13.937377Z","caller":"traceutil/trace.go:171","msg":"trace[2045733763] transaction","detail":"{read_only:false; response_revision:1068; number_of_response:1; }","duration":"274.783212ms","start":"2026-06-16T10:28:13.662571Z","end":"2026-06-16T10:28:13.937354Z","steps":["trace[2045733763] 'process raft request' (duration: 50.229306ms)","trace[2045733763] 'compare' (duration: 224.079584ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T10:28:13.937432Z","caller":"traceutil/trace.go:171","msg":"trace[1292327312] linearizableReadLoop","detail":"{readStateIndex:1113; appliedIndex:1110; }","duration":"252.712576ms","start":"2026-06-16T10:28:13.684706Z","end":"2026-06-16T10:28:13.937418Z","steps":["trace[1292327312] 'read index received' (duration: 28.106879ms)","trace[1292327312] 'applied index is now lower than readState.Index' (duration: 224.604787ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T10:28:13.937605Z","caller":"traceutil/trace.go:171","msg":"trace[709376037] transaction","detail":"{read_only:false; response_revision:1069; number_of_response:1; }","duration":"274.67531ms","start":"2026-06-16T10:28:13.662878Z","end":"2026-06-16T10:28:13.937554Z","steps":["trace[709376037] 'process raft request' (duration: 274.349041ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:28:13.937655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.950943ms","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-06-16T10:28:13.937722Z","caller":"traceutil/trace.go:171","msg":"trace[524577714] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1070; }","duration":"253.029695ms","start":"2026-06-16T10:28:13.684657Z","end":"2026-06-16T10:28:13.937686Z","steps":["trace[524577714] 'agreement among raft nodes before linearized reading' (duration: 252.84417ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:28:13.937803Z","caller":"traceutil/trace.go:171","msg":"trace[1390034636] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"274.623538ms","start":"2026-06-16T10:28:13.663046Z","end":"2026-06-16T10:28:13.937669Z","steps":["trace[1390034636] 'process raft request' (duration: 274.297769ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:28:30.509552Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605829369493648445,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-16T10:28:30.613036Z","caller":"traceutil/trace.go:171","msg":"trace[1983874775] linearizableReadLoop","detail":"{readStateIndex:1220; appliedIndex:1219; }","duration":"604.402366ms","start":"2026-06-16T10:28:30.008614Z","end":"2026-06-16T10:28:30.613016Z","steps":["trace[1983874775] 'read index received' (duration: 604.278883ms)","trace[1983874775] 'applied index is now lower than readState.Index' (duration: 122.723µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T10:28:30.613105Z","caller":"traceutil/trace.go:171","msg":"trace[514173148] transaction","detail":"{read_only:false; response_revision:1171; number_of_response:1; }","duration":"606.877793ms","start":"2026-06-16T10:28:30.0062Z","end":"2026-06-16T10:28:30.613078Z","steps":["trace[514173148] 'process raft request' (duration: 606.678808ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:28:30.613258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:28:30.006184Z","time spent":"606.987056ms","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T10:28:30.613269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.618042ms","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-06-16T10:28:30.613335Z","caller":"traceutil/trace.go:171","msg":"trace[2101368183] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1171; }","duration":"604.752365ms","start":"2026-06-16T10:28:30.008567Z","end":"2026-06-16T10:28:30.613319Z","steps":["trace[2101368183] 'agreement among raft nodes before linearized reading' (duration: 604.54104ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:28:30.613373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:28:30.008549Z","time spent":"604.815348ms","remote":"127.0.0.1:45358","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":"info","ts":"2026-06-16T10:28:31.028137Z","caller":"traceutil/trace.go:171","msg":"trace[854531618] linearizableReadLoop","detail":"{readStateIndex:1221; appliedIndex:1220; }","duration":"415.030661ms","start":"2026-06-16T10:28:30.613086Z","end":"2026-06-16T10:28:31.028117Z","steps":["trace[854531618] 'read index received' (duration: 414.863247ms)","trace[854531618] 'applied index is now lower than readState.Index' (duration: 166.674µs)"],"step_count":2} {"level":"warn","ts":"2026-06-16T10:28:31.028211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"718.015725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T10:28:31.028253Z","caller":"traceutil/trace.go:171","msg":"trace[882048208] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1172; }","duration":"718.106888ms","start":"2026-06-16T10:28:30.310137Z","end":"2026-06-16T10:28:31.028244Z","steps":["trace[882048208] 'agreement among raft nodes before linearized reading' (duration: 718.007475ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:28:31.02828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:28:30.310118Z","time spent":"718.154319ms","remote":"127.0.0.1:44264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"info","ts":"2026-06-16T10:28:31.028289Z","caller":"traceutil/trace.go:171","msg":"trace[1759019650] transaction","detail":"{read_only:false; response_revision:1172; number_of_response:1; }","duration":"421.484015ms","start":"2026-06-16T10:28:30.606783Z","end":"2026-06-16T10:28:31.028267Z","steps":["trace[1759019650] 'process raft request' (duration: 421.132366ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:28:31.028381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:28:30.606756Z","time spent":"421.572058ms","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T10:28:31.028371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"887.172245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-06-16T10:28:31.028437Z","caller":"traceutil/trace.go:171","msg":"trace[165081508] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1172; }","duration":"887.313598ms","start":"2026-06-16T10:28:30.141107Z","end":"2026-06-16T10:28:31.02842Z","steps":["trace[165081508] 'agreement among raft nodes before linearized reading' (duration: 887.093832ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:28:31.028478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:28:30.141088Z","time spent":"887.381661ms","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-16T10:28:31.028643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"748.93605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T10:28:31.028709Z","caller":"traceutil/trace.go:171","msg":"trace[136666821] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1172; }","duration":"749.476965ms","start":"2026-06-16T10:28:30.279221Z","end":"2026-06-16T10:28:31.028698Z","steps":["trace[136666821] 'agreement among raft nodes before linearized reading' (duration: 748.947211ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:28:31.028763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:28:30.279201Z","time spent":"749.555417ms","remote":"127.0.0.1:45176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-16T10:28:31.028815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.015026859s","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-06-16T10:28:31.028864Z","caller":"traceutil/trace.go:171","msg":"trace[791355596] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1172; }","duration":"1.01510319s","start":"2026-06-16T10:28:30.013749Z","end":"2026-06-16T10:28:31.028852Z","steps":["trace[791355596] 'agreement among raft nodes before linearized reading' (duration: 1.014483233s)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:28:31.028898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:28:30.013736Z","time spent":"1.015154222s","remote":"127.0.0.1:45358","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-06-16T10:28:31.030074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.720746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-16T10:28:31.030124Z","caller":"traceutil/trace.go:171","msg":"trace[333249603] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1173; }","duration":"413.811708ms","start":"2026-06-16T10:28:30.616303Z","end":"2026-06-16T10:28:31.030115Z","steps":["trace[333249603] 'agreement among raft nodes before linearized reading' (duration: 413.719326ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:28:31.030148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:28:30.616281Z","time spent":"413.860559ms","remote":"127.0.0.1:45472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":30,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"info","ts":"2026-06-16T10:28:31.030909Z","caller":"traceutil/trace.go:171","msg":"trace[1893764398] transaction","detail":"{read_only:false; response_revision:1173; number_of_response:1; }","duration":"414.26482ms","start":"2026-06-16T10:28:30.616619Z","end":"2026-06-16T10:28:31.030884Z","steps":["trace[1893764398] 'process raft request' (duration: 413.324035ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:28:31.031069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:28:30.616605Z","time spent":"414.412515ms","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T10:28:43.320499Z","caller":"traceutil/trace.go:171","msg":"trace[896342737] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"157.011207ms","start":"2026-06-16T10:28:43.163458Z","end":"2026-06-16T10:28:43.320469Z","steps":["trace[896342737] 'process raft request' (duration: 156.856983ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:28:43.328496Z","caller":"traceutil/trace.go:171","msg":"trace[1596499968] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"164.792665ms","start":"2026-06-16T10:28:43.163687Z","end":"2026-06-16T10:28:43.328479Z","steps":["trace[1596499968] 'process raft request' (duration: 164.432955ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:28:47.62847Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605829369493648588,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-16T10:28:47.866181Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.117656613s","expected-duration":"1s"} {"level":"info","ts":"2026-06-16T10:28:47.866743Z","caller":"traceutil/trace.go:171","msg":"trace[1157530846] transaction","detail":"{read_only:false; response_revision:1232; number_of_response:1; }","duration":"1.11829635s","start":"2026-06-16T10:28:46.748424Z","end":"2026-06-16T10:28:47.86672Z","steps":["trace[1157530846] 'process raft request' (duration: 1.118148646s)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:28:47.866907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:28:46.748406Z","time spent":"1.118415003s","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T10:28:47.961752Z","caller":"traceutil/trace.go:171","msg":"trace[1727926964] linearizableReadLoop","detail":"{readStateIndex:1285; appliedIndex:1283; }","duration":"833.401161ms","start":"2026-06-16T10:28:47.128333Z","end":"2026-06-16T10:28:47.961735Z","steps":["trace[1727926964] 'read index received' (duration: 738.331082ms)","trace[1727926964] 'applied index is now lower than readState.Index' (duration: 95.069109ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T10:28:47.961912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"833.574595ms","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-06-16T10:28:47.961938Z","caller":"traceutil/trace.go:171","msg":"trace[1318444007] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1233; }","duration":"833.649387ms","start":"2026-06-16T10:28:47.128282Z","end":"2026-06-16T10:28:47.961931Z","steps":["trace[1318444007] 'agreement among raft nodes before linearized reading' (duration: 833.543984ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:28:47.961961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:28:47.128263Z","time spent":"833.693169ms","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-16T10:28:47.961899Z","caller":"traceutil/trace.go:171","msg":"trace[780012482] transaction","detail":"{read_only:false; response_revision:1233; number_of_response:1; }","duration":"623.017064ms","start":"2026-06-16T10:28:47.338797Z","end":"2026-06-16T10:28:47.961815Z","steps":["trace[780012482] 'process raft request' (duration: 546.664983ms)","trace[780012482] 'compare' (duration: 76.200987ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T10:28:47.9621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.351237ms","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":"warn","ts":"2026-06-16T10:28:47.962133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:28:47.338779Z","time spent":"623.238339ms","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T10:28:47.962178Z","caller":"traceutil/trace.go:171","msg":"trace[552938442] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1233; }","duration":"569.48813ms","start":"2026-06-16T10:28:47.392675Z","end":"2026-06-16T10:28:47.962163Z","steps":["trace[552938442] 'agreement among raft nodes before linearized reading' (duration: 569.336706ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:28:47.962241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.548891ms","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-06-16T10:28:47.962264Z","caller":"traceutil/trace.go:171","msg":"trace[1853677308] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1233; }","duration":"622.597323ms","start":"2026-06-16T10:28:47.339661Z","end":"2026-06-16T10:28:47.962258Z","steps":["trace[1853677308] 'agreement among raft nodes before linearized reading' (duration: 622.534701ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:28:47.962282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:28:47.339648Z","time spent":"622.629923ms","remote":"127.0.0.1:45358","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-06-16T10:28:47.962234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:28:47.392664Z","time spent":"569.554743ms","remote":"127.0.0.1:45470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"warn","ts":"2026-06-16T10:28:47.962387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"685.492376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-16T10:28:47.962452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.062281ms","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-06-16T10:28:47.962587Z","caller":"traceutil/trace.go:171","msg":"trace[935005499] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1233; }","duration":"627.291637ms","start":"2026-06-16T10:28:47.335285Z","end":"2026-06-16T10:28:47.962577Z","steps":["trace[935005499] 'agreement among raft nodes before linearized reading' (duration: 627.03025ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:28:47.962613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:28:47.33527Z","time spent":"627.334769ms","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-16T10:28:47.962556Z","caller":"traceutil/trace.go:171","msg":"trace[1525914941] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1233; }","duration":"685.875386ms","start":"2026-06-16T10:28:47.27666Z","end":"2026-06-16T10:28:47.962536Z","steps":["trace[1525914941] 'agreement among raft nodes before linearized reading' (duration: 685.446875ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:28:47.962778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:28:47.27662Z","time spent":"686.142103ms","remote":"127.0.0.1:45174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-16T10:28:48.187304Z","caller":"traceutil/trace.go:171","msg":"trace[334708419] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"220.085765ms","start":"2026-06-16T10:28:47.967202Z","end":"2026-06-16T10:28:48.187288Z","steps":["trace[334708419] 'process raft request' (duration: 132.552797ms)","trace[334708419] 'compare' (duration: 87.337623ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T10:28:48.187644Z","caller":"traceutil/trace.go:171","msg":"trace[155692654] transaction","detail":"{read_only:false; response_revision:1236; number_of_response:1; }","duration":"218.484393ms","start":"2026-06-16T10:28:47.969128Z","end":"2026-06-16T10:28:48.187613Z","steps":["trace[155692654] 'process raft request' (duration: 218.118103ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:28:48.188073Z","caller":"traceutil/trace.go:171","msg":"trace[1072315490] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"217.012834ms","start":"2026-06-16T10:28:47.971034Z","end":"2026-06-16T10:28:48.188047Z","steps":["trace[1072315490] 'process raft request' (duration: 216.87436ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:28:48.942887Z","caller":"traceutil/trace.go:171","msg":"trace[1184888885] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"136.853281ms","start":"2026-06-16T10:28:48.806007Z","end":"2026-06-16T10:28:48.94286Z","steps":["trace[1184888885] 'process raft request' (duration: 103.38618ms)","trace[1184888885] 'compare' (duration: 33.335647ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T10:28:49.563822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.95115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T10:28:49.563914Z","caller":"traceutil/trace.go:171","msg":"trace[598940755] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1238; }","duration":"285.087434ms","start":"2026-06-16T10:28:49.278792Z","end":"2026-06-16T10:28:49.56388Z","steps":["trace[598940755] 'range keys from in-memory index tree' (duration: 284.844788ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:28:50.48619Z","caller":"traceutil/trace.go:171","msg":"trace[686352647] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"610.928273ms","start":"2026-06-16T10:28:49.875239Z","end":"2026-06-16T10:28:50.486167Z","steps":["trace[686352647] 'process raft request' (duration: 610.762819ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:28:50.486334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:28:49.875169Z","time spent":"611.091527ms","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T10:28:50.547404Z","caller":"traceutil/trace.go:171","msg":"trace[787835204] linearizableReadLoop","detail":"{readStateIndex:1293; appliedIndex:1291; }","duration":"556.571686ms","start":"2026-06-16T10:28:49.990811Z","end":"2026-06-16T10:28:50.547382Z","steps":["trace[787835204] 'read index received' (duration: 495.288616ms)","trace[787835204] 'applied index is now lower than readState.Index' (duration: 61.28189ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T10:28:50.547516Z","caller":"traceutil/trace.go:171","msg":"trace[1952142179] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"580.125703ms","start":"2026-06-16T10:28:49.967355Z","end":"2026-06-16T10:28:50.547481Z","steps":["trace[1952142179] 'process raft request' (duration: 579.874806ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:28:50.547613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.788092ms","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-06-16T10:28:50.547659Z","caller":"traceutil/trace.go:171","msg":"trace[234358277] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1240; }","duration":"556.890795ms","start":"2026-06-16T10:28:49.990759Z","end":"2026-06-16T10:28:50.547649Z","steps":["trace[234358277] 'agreement among raft nodes before linearized reading' (duration: 556.746601ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:28:50.547695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:28:49.990738Z","time spent":"556.947237ms","remote":"127.0.0.1:45358","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-06-16T10:28:50.547791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.499784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-06-16T10:28:50.547683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:28:49.967292Z","time spent":"580.303668ms","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T10:28:50.547851Z","caller":"traceutil/trace.go:171","msg":"trace[277284375] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1240; }","duration":"356.596727ms","start":"2026-06-16T10:28:50.191241Z","end":"2026-06-16T10:28:50.547837Z","steps":["trace[277284375] 'agreement among raft nodes before linearized reading' (duration: 356.436032ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:28:50.548937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:28:50.191217Z","time spent":"357.703667ms","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-16T10:28:50.547899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.157724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T10:28:50.549049Z","caller":"traceutil/trace.go:171","msg":"trace[234452469] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1240; }","duration":"269.341916ms","start":"2026-06-16T10:28:50.279693Z","end":"2026-06-16T10:28:50.549035Z","steps":["trace[234452469] 'agreement among raft nodes before linearized reading' (duration: 268.184875ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:28:50.547993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.291402ms","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-06-16T10:28:50.549221Z","caller":"traceutil/trace.go:171","msg":"trace[1691078134] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1240; }","duration":"356.519954ms","start":"2026-06-16T10:28:50.192691Z","end":"2026-06-16T10:28:50.549211Z","steps":["trace[1691078134] 'agreement among raft nodes before linearized reading' (duration: 355.2283ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:28:50.549243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:28:50.192609Z","time spent":"356.628698ms","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-16T10:28:50.54806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.957839ms","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-06-16T10:28:50.54941Z","caller":"traceutil/trace.go:171","msg":"trace[697529928] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1240; }","duration":"357.315976ms","start":"2026-06-16T10:28:50.192075Z","end":"2026-06-16T10:28:50.549391Z","steps":["trace[697529928] 'agreement among raft nodes before linearized reading' (duration: 355.922419ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:28:50.549486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:28:50.192063Z","time spent":"357.402178ms","remote":"127.0.0.1:45358","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-06-16T10:28:50.758769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.503824ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-16T10:28:50.758871Z","caller":"traceutil/trace.go:171","msg":"trace[1650481453] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"206.065962ms","start":"2026-06-16T10:28:50.552786Z","end":"2026-06-16T10:28:50.758852Z","steps":["trace[1650481453] 'process raft request' (duration: 67.405803ms)","trace[1650481453] 'compare' (duration: 138.406672ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T10:28:50.927113Z","caller":"traceutil/trace.go:171","msg":"trace[271105398] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"373.039264ms","start":"2026-06-16T10:28:50.554049Z","end":"2026-06-16T10:28:50.927088Z","steps":["trace[271105398] 'process raft request' (duration: 373.002173ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:28:50.927248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:28:50.554041Z","time spent":"373.138597ms","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T10:28:50.927605Z","caller":"traceutil/trace.go:171","msg":"trace[1675390670] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"373.61557ms","start":"2026-06-16T10:28:50.553978Z","end":"2026-06-16T10:28:50.927594Z","steps":["trace[1675390670] 'process raft request' (duration: 372.87369ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:28:50.927663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:28:50.553961Z","time spent":"373.671481ms","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T10:28:50.927807Z","caller":"traceutil/trace.go:171","msg":"trace[559388653] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"373.773484ms","start":"2026-06-16T10:28:50.554024Z","end":"2026-06-16T10:28:50.927797Z","steps":["trace[559388653] 'process raft request' (duration: 372.985813ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:28:50.927859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:28:50.554012Z","time spent":"373.817865ms","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T10:29:00.476498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.886325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T10:29:00.476581Z","caller":"traceutil/trace.go:171","msg":"trace[804604186] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1324; }","duration":"198.019998ms","start":"2026-06-16T10:29:00.278542Z","end":"2026-06-16T10:29:00.476562Z","steps":["trace[804604186] 'range keys from in-memory index tree' (duration: 197.803632ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:29:01.179561Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605829369493648760,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-16T10:29:01.412156Z","caller":"traceutil/trace.go:171","msg":"trace[703868655] transaction","detail":"{read_only:false; response_revision:1325; number_of_response:1; }","duration":"883.420672ms","start":"2026-06-16T10:29:00.52871Z","end":"2026-06-16T10:29:01.41213Z","steps":["trace[703868655] 'process raft request' (duration: 883.276618ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:29:01.412307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:29:00.528687Z","time spent":"883.538806ms","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T10:29:01.424231Z","caller":"traceutil/trace.go:171","msg":"trace[1438375466] linearizableReadLoop","detail":"{readStateIndex:1381; appliedIndex:1380; }","duration":"744.924158ms","start":"2026-06-16T10:29:00.679274Z","end":"2026-06-16T10:29:01.424198Z","steps":["trace[1438375466] 'read index received' (duration: 733.061393ms)","trace[1438375466] 'applied index is now lower than readState.Index' (duration: 11.861105ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T10:29:01.424604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"745.327259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-06-16T10:29:01.424669Z","caller":"traceutil/trace.go:171","msg":"trace[1732768782] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1326; }","duration":"745.432532ms","start":"2026-06-16T10:29:00.679218Z","end":"2026-06-16T10:29:01.424651Z","steps":["trace[1732768782] 'agreement among raft nodes before linearized reading' (duration: 745.238216ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:29:01.424752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:29:00.6792Z","time spent":"745.540135ms","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-06-16T10:29:01.424938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.78785ms","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-06-16T10:29:01.424966Z","caller":"traceutil/trace.go:171","msg":"trace[1139993666] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1326; }","duration":"398.871593ms","start":"2026-06-16T10:29:01.026088Z","end":"2026-06-16T10:29:01.424959Z","steps":["trace[1139993666] 'agreement among raft nodes before linearized reading' (duration: 398.798761ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:29:01.424999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:29:01.026056Z","time spent":"398.922064ms","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-16T10:29:01.425044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.784104ms","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-06-16T10:29:01.425109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-06-16T10:29:01.425125Z","caller":"traceutil/trace.go:171","msg":"trace[1383466467] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1326; }","duration":"400.66112ms","start":"2026-06-16T10:29:01.024458Z","end":"2026-06-16T10:29:01.425119Z","steps":["trace[1383466467] 'agreement among raft nodes before linearized reading' (duration: 400.621849ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:29:01.425141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:29:01.024451Z","time spent":"400.68647ms","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-16T10:29:01.425113Z","caller":"traceutil/trace.go:171","msg":"trace[2016571798] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1326; }","duration":"403.875186ms","start":"2026-06-16T10:29:01.021227Z","end":"2026-06-16T10:29:01.425102Z","steps":["trace[2016571798] 'agreement among raft nodes before linearized reading' (duration: 403.711201ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:29:01.425245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.749697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-06-16T10:29:01.425352Z","caller":"traceutil/trace.go:171","msg":"trace[1564369517] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1326; }","duration":"437.906301ms","start":"2026-06-16T10:29:00.98743Z","end":"2026-06-16T10:29:01.425336Z","steps":["trace[1564369517] 'agreement among raft nodes before linearized reading' (duration: 437.639114ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:29:01.42443Z","caller":"traceutil/trace.go:171","msg":"trace[887847732] transaction","detail":"{read_only:false; response_revision:1326; number_of_response:1; }","duration":"845.457242ms","start":"2026-06-16T10:29:00.578953Z","end":"2026-06-16T10:29:01.42441Z","steps":["trace[887847732] 'process raft request' (duration: 844.63203ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:29:01.425437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:29:00.987406Z","time spent":"438.017484ms","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-06-16T10:29:01.425065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.654378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T10:29:01.425719Z","caller":"traceutil/trace.go:171","msg":"trace[450595409] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1326; }","duration":"145.340088ms","start":"2026-06-16T10:29:01.280365Z","end":"2026-06-16T10:29:01.425706Z","steps":["trace[450595409] 'agreement among raft nodes before linearized reading' (duration: 144.68563ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:29:01.424954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.503105ms","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-06-16T10:29:01.42594Z","caller":"traceutil/trace.go:171","msg":"trace[595695629] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1326; }","duration":"625.472441ms","start":"2026-06-16T10:29:00.800367Z","end":"2026-06-16T10:29:01.42584Z","steps":["trace[595695629] 'agreement among raft nodes before linearized reading' (duration: 624.270979ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:29:01.426025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:29:00.800348Z","time spent":"625.627515ms","remote":"127.0.0.1:45358","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-06-16T10:29:01.42519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:29:01.021212Z","time spent":"403.957938ms","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-16T10:29:01.426729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:29:00.57893Z","time spent":"846.560202ms","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T10:29:41.91977Z","caller":"traceutil/trace.go:171","msg":"trace[542878227] transaction","detail":"{read_only:false; response_revision:1514; number_of_response:1; }","duration":"125.753885ms","start":"2026-06-16T10:29:41.793969Z","end":"2026-06-16T10:29:41.919723Z","steps":["trace[542878227] 'process raft request' (duration: 123.232738ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:29:41.924526Z","caller":"traceutil/trace.go:171","msg":"trace[1675592065] transaction","detail":"{read_only:false; response_revision:1515; number_of_response:1; }","duration":"130.201644ms","start":"2026-06-16T10:29:41.794236Z","end":"2026-06-16T10:29:41.924438Z","steps":["trace[1675592065] 'process raft request' (duration: 129.976948ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:29:41.924727Z","caller":"traceutil/trace.go:171","msg":"trace[785177158] transaction","detail":"{read_only:false; response_revision:1516; number_of_response:1; }","duration":"130.43347ms","start":"2026-06-16T10:29:41.794272Z","end":"2026-06-16T10:29:41.924705Z","steps":["trace[785177158] 'process raft request' (duration: 130.116191ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:29:41.925029Z","caller":"traceutil/trace.go:171","msg":"trace[1860643614] transaction","detail":"{read_only:false; response_revision:1517; number_of_response:1; }","duration":"130.706437ms","start":"2026-06-16T10:29:41.794285Z","end":"2026-06-16T10:29:41.924992Z","steps":["trace[1860643614] 'process raft request' (duration: 130.342198ms)"],"step_count":1}