{"level":"warn","ts":"2026-06-09T17:04:22.417763Z","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-09T17:04:22.417862Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.81:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.81:2380","--initial-cluster=instance=https://199.19.213.81:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.81:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.81: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-09T17:04:22.417948Z","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-09T17:04:22.41796Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.81:2380"]} {"level":"info","ts":"2026-06-09T17:04:22.417982Z","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-09T17:04:22.418599Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.81:2379"]} {"level":"info","ts":"2026-06-09T17:04:22.418673Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.81:2380"],"listen-peer-urls":["https://199.19.213.81:2380"],"advertise-client-urls":["https://199.19.213.81:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.81:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.81: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-09T17:04:22.430141Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"11.269905ms"} {"level":"info","ts":"2026-06-09T17:04:22.465389Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1627b0b72e417489","cluster-id":"d42e5eae8a1b814c"} {"level":"info","ts":"2026-06-09T17:04:22.465497Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1627b0b72e417489 switched to configuration voters=()"} {"level":"info","ts":"2026-06-09T17:04:22.465531Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1627b0b72e417489 became follower at term 0"} {"level":"info","ts":"2026-06-09T17:04:22.465592Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1627b0b72e417489 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-09T17:04:22.465617Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1627b0b72e417489 became follower at term 1"} {"level":"info","ts":"2026-06-09T17:04:22.465701Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1627b0b72e417489 switched to configuration voters=(1596438893727675529)"} {"level":"warn","ts":"2026-06-09T17:04:22.488253Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-09T17:04:22.491114Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-09T17:04:22.493172Z","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-09T17:04:22.495811Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1627b0b72e417489","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-09T17:04:22.496014Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1627b0b72e417489","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-09T17:04:22.496135Z","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-09T17:04:22.496211Z","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-09T17:04:22.496229Z","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-09T17:04:22.497101Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1627b0b72e417489 switched to configuration voters=(1596438893727675529)"} {"level":"info","ts":"2026-06-09T17:04:22.497217Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"d42e5eae8a1b814c","local-member-id":"1627b0b72e417489","added-peer-id":"1627b0b72e417489","added-peer-peer-urls":["https://199.19.213.81:2380"]} {"level":"info","ts":"2026-06-09T17:04:22.498423Z","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-09T17:04:22.498488Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.81:2380"} {"level":"info","ts":"2026-06-09T17:04:22.498529Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.81:2380"} {"level":"info","ts":"2026-06-09T17:04:22.498731Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1627b0b72e417489","initial-advertise-peer-urls":["https://199.19.213.81:2380"],"listen-peer-urls":["https://199.19.213.81:2380"],"advertise-client-urls":["https://199.19.213.81:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.81:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-09T17:04:22.498769Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-09T17:04:23.46646Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1627b0b72e417489 is starting a new election at term 1"} {"level":"info","ts":"2026-06-09T17:04:23.466558Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1627b0b72e417489 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-09T17:04:23.466589Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1627b0b72e417489 received MsgPreVoteResp from 1627b0b72e417489 at term 1"} {"level":"info","ts":"2026-06-09T17:04:23.466609Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1627b0b72e417489 became candidate at term 2"} {"level":"info","ts":"2026-06-09T17:04:23.466628Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1627b0b72e417489 received MsgVoteResp from 1627b0b72e417489 at term 2"} {"level":"info","ts":"2026-06-09T17:04:23.466679Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1627b0b72e417489 became leader at term 2"} {"level":"info","ts":"2026-06-09T17:04:23.466697Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1627b0b72e417489 elected leader 1627b0b72e417489 at term 2"} {"level":"info","ts":"2026-06-09T17:04:23.520246Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1627b0b72e417489","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.81:2379]}","request-path":"/0/members/1627b0b72e417489/attributes","cluster-id":"d42e5eae8a1b814c","publish-timeout":"7s"} {"level":"info","ts":"2026-06-09T17:04:23.520296Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-09T17:04:23.520252Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-09T17:04:23.520264Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-09T17:04:23.521073Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-09T17:04:23.521135Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-09T17:04:23.524504Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.81:2379"} {"level":"info","ts":"2026-06-09T17:04:23.524875Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-09T17:04:23.577929Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"d42e5eae8a1b814c","local-member-id":"1627b0b72e417489","cluster-version":"3.5"} {"level":"info","ts":"2026-06-09T17:04:23.578077Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-09T17:04:23.578141Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-06-09T17:04:30.079806Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.486142219s","expected-duration":"1s"} {"level":"warn","ts":"2026-06-09T17:04:30.223727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.889791542s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-09T17:04:30.224037Z","caller":"traceutil/trace.go:171","msg":"trace[150934382] transaction","detail":"{read_only:false; response_revision:266; number_of_response:1; }","duration":"1.630438948s","start":"2026-06-09T17:04:28.593581Z","end":"2026-06-09T17:04:30.22402Z","steps":["trace[150934382] 'process raft request' (duration: 1.630289085s)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:30.223969Z","caller":"traceutil/trace.go:171","msg":"trace[1707748966] transaction","detail":"{read_only:false; response_revision:265; number_of_response:1; }","duration":"1.992007339s","start":"2026-06-09T17:04:28.231881Z","end":"2026-06-09T17:04:30.223888Z","steps":["trace[1707748966] 'process raft request' (duration: 101.470141ms)","trace[1707748966] 'compare' (duration: 1.888027457s)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:04:30.224055Z","caller":"traceutil/trace.go:171","msg":"trace[1518100297] linearizableReadLoop","detail":"{readStateIndex:271; appliedIndex:270; }","duration":"1.72201641s","start":"2026-06-09T17:04:28.501979Z","end":"2026-06-09T17:04:30.223995Z","steps":["trace[1518100297] 'read index received' (duration: 41.311µs)","trace[1518100297] 'applied index is now lower than readState.Index' (duration: 1.721972518s)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:04:30.224113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:28.593569Z","time spent":"1.630514729s","remote":"127.0.0.1:41440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-09T17:04:30.224209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:28.231857Z","time spent":"1.992246894s","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:04:30.224216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.722201273s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/\" range_end:\"/registry/deployments/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:04:30.224264Z","caller":"traceutil/trace.go:171","msg":"trace[741783771] range","detail":"{range_begin:/registry/deployments/kube-system/; range_end:/registry/deployments/kube-system0; response_count:0; response_revision:266; }","duration":"1.722326015s","start":"2026-06-09T17:04:28.501925Z","end":"2026-06-09T17:04:30.224251Z","steps":["trace[741783771] 'agreement among raft nodes before linearized reading' (duration: 1.722203293s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:30.224319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:28.501871Z","time spent":"1.722439838s","remote":"127.0.0.1:41834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/kube-system/\" range_end:\"/registry/deployments/kube-system0\" "} {"level":"warn","ts":"2026-06-09T17:04:30.724302Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8397417447545881425,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-09T17:04:30.903234Z","caller":"traceutil/trace.go:171","msg":"trace[1038977304] transaction","detail":"{read_only:false; number_of_response:0; response_revision:266; }","duration":"2.207544093s","start":"2026-06-09T17:04:28.69566Z","end":"2026-06-09T17:04:30.903204Z","steps":["trace[1038977304] 'process raft request' (duration: 2.20735609s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:30.903412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:28.695632Z","time spent":"2.207689107s","remote":"127.0.0.1:41544","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-09T17:04:30.90345Z","caller":"traceutil/trace.go:171","msg":"trace[1848337475] transaction","detail":"{read_only:false; number_of_response:0; response_revision:267; }","duration":"2.190659792s","start":"2026-06-09T17:04:28.712764Z","end":"2026-06-09T17:04:30.903424Z","steps":["trace[1848337475] 'process raft request' (duration: 2.190443888s)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:30.903566Z","caller":"traceutil/trace.go:171","msg":"trace[168951286] transaction","detail":"{read_only:false; number_of_response:0; response_revision:267; }","duration":"2.190767604s","start":"2026-06-09T17:04:28.712783Z","end":"2026-06-09T17:04:30.903551Z","steps":["trace[168951286] 'process raft request' (duration: 2.190442568s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:30.90359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:28.712742Z","time spent":"2.190778705s","remote":"127.0.0.1:41548","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-09T17:04:30.903658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:28.712745Z","time spent":"2.190864276s","remote":"127.0.0.1:41548","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-09T17:04:30.903317Z","caller":"traceutil/trace.go:171","msg":"trace[1218365406] transaction","detail":"{read_only:false; response_revision:268; number_of_response:1; }","duration":"2.190444708s","start":"2026-06-09T17:04:28.712848Z","end":"2026-06-09T17:04:30.903292Z","steps":["trace[1218365406] 'process raft request' (duration: 2.190397737s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:30.903941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:28.712812Z","time spent":"2.190957538s","remote":"127.0.0.1:41548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5877,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-09T17:04:30.903923Z","caller":"traceutil/trace.go:171","msg":"trace[603680079] transaction","detail":"{read_only:false; number_of_response:0; response_revision:267; }","duration":"2.191175112s","start":"2026-06-09T17:04:28.712508Z","end":"2026-06-09T17:04:30.903683Z","steps":["trace[603680079] 'process raft request' (duration: 2.190679102s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:30.904204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:28.712498Z","time spent":"2.191586372s","remote":"127.0.0.1:41548","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-09T17:04:30.904442Z","caller":"traceutil/trace.go:171","msg":"trace[2079870839] transaction","detail":"{read_only:false; response_revision:267; number_of_response:1; }","duration":"2.190996188s","start":"2026-06-09T17:04:28.712303Z","end":"2026-06-09T17:04:30.903299Z","steps":["trace[2079870839] 'process raft request' (duration: 2.190807745s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:30.904614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:28.712267Z","time spent":"2.192226414s","remote":"127.0.0.1:41548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-09T17:04:30.928457Z","caller":"traceutil/trace.go:171","msg":"trace[621724409] linearizableReadLoop","detail":"{readStateIndex:279; appliedIndex:273; }","duration":"704.31001ms","start":"2026-06-09T17:04:30.224126Z","end":"2026-06-09T17:04:30.928436Z","steps":["trace[621724409] 'read index received' (duration: 679.038157ms)","trace[621724409] 'applied index is now lower than readState.Index' (duration: 25.270633ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:04:30.928625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.335131055s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-06-09T17:04:30.928657Z","caller":"traceutil/trace.go:171","msg":"trace[1230024934] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:269; }","duration":"2.335218357s","start":"2026-06-09T17:04:28.593427Z","end":"2026-06-09T17:04:30.928646Z","steps":["trace[1230024934] 'agreement among raft nodes before linearized reading' (duration: 2.335118065s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:30.92868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:28.593407Z","time spent":"2.335267198s","remote":"127.0.0.1:41766","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":687,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-06-09T17:04:30.928746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"695.00484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/coredns\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:04:30.928859Z","caller":"traceutil/trace.go:171","msg":"trace[1066910365] range","detail":"{range_begin:/registry/configmaps/kube-system/coredns; range_end:; response_count:0; response_revision:269; }","duration":"695.161363ms","start":"2026-06-09T17:04:30.233669Z","end":"2026-06-09T17:04:30.928831Z","steps":["trace[1066910365] 'agreement among raft nodes before linearized reading' (duration: 694.97219ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:30.928926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:30.233654Z","time spent":"695.257865ms","remote":"127.0.0.1:41472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":28,"request content":"key:\"/registry/configmaps/kube-system/coredns\" "} {"level":"warn","ts":"2026-06-09T17:04:30.929139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.112421271s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:04:30.929189Z","caller":"traceutil/trace.go:171","msg":"trace[1108546481] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:269; }","duration":"2.112543363s","start":"2026-06-09T17:04:28.816634Z","end":"2026-06-09T17:04:30.929177Z","steps":["trace[1108546481] 'agreement among raft nodes before linearized reading' (duration: 2.112428891s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:30.92918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.333777877s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-09T17:04:30.929247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:28.81661Z","time spent":"2.112625375s","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-09T17:04:30.929291Z","caller":"traceutil/trace.go:171","msg":"trace[1317269726] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:269; }","duration":"2.33391941s","start":"2026-06-09T17:04:28.595351Z","end":"2026-06-09T17:04:30.92927Z","steps":["trace[1317269726] 'agreement among raft nodes before linearized reading' (duration: 2.333767807s)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:30.929384Z","caller":"traceutil/trace.go:171","msg":"trace[2081958509] transaction","detail":"{read_only:false; response_revision:269; number_of_response:1; }","duration":"701.519683ms","start":"2026-06-09T17:04:30.227845Z","end":"2026-06-09T17:04:30.929365Z","steps":["trace[2081958509] 'process raft request' (duration: 700.485932ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:30.929453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:30.227829Z","time spent":"701.582964ms","remote":"127.0.0.1:41440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":625,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-09T17:04:30.92942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:28.595332Z","time spent":"2.334072134s","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"warn","ts":"2026-06-09T17:04:30.929604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.710583518s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:04:30.929659Z","caller":"traceutil/trace.go:171","msg":"trace[471211419] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:269; }","duration":"1.710684369s","start":"2026-06-09T17:04:29.218965Z","end":"2026-06-09T17:04:30.929649Z","steps":["trace[471211419] 'agreement among raft nodes before linearized reading' (duration: 1.709489925s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:30.929688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:29.218943Z","time spent":"1.710736981s","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-09T17:04:30.929714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.4120765s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-09T17:04:30.929749Z","caller":"traceutil/trace.go:171","msg":"trace[1471401761] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:269; }","duration":"1.412152511s","start":"2026-06-09T17:04:29.517588Z","end":"2026-06-09T17:04:30.929741Z","steps":["trace[1471401761] 'agreement among raft nodes before linearized reading' (duration: 1.41209022s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:30.929777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:29.517566Z","time spent":"1.412204663s","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-09T17:04:31.287415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.62051ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-09T17:04:31.287627Z","caller":"traceutil/trace.go:171","msg":"trace[1281145862] transaction","detail":"{read_only:false; response_revision:271; number_of_response:1; }","duration":"353.64595ms","start":"2026-06-09T17:04:30.933946Z","end":"2026-06-09T17:04:31.287592Z","steps":["trace[1281145862] 'process raft request' (duration: 213.781735ms)","trace[1281145862] 'compare' (duration: 139.468417ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:04:31.287848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:30.933938Z","time spent":"353.805124ms","remote":"127.0.0.1:41630","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-09T17:04:31.287922Z","caller":"traceutil/trace.go:171","msg":"trace[1837060064] transaction","detail":"{read_only:false; response_revision:273; number_of_response:1; }","duration":"352.821073ms","start":"2026-06-09T17:04:30.934959Z","end":"2026-06-09T17:04:31.28778Z","steps":["trace[1837060064] 'process raft request' (duration: 352.592298ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:31.287916Z","caller":"traceutil/trace.go:171","msg":"trace[1704063254] transaction","detail":"{read_only:false; response_revision:274; number_of_response:1; }","duration":"351.731552ms","start":"2026-06-09T17:04:30.936152Z","end":"2026-06-09T17:04:31.287884Z","steps":["trace[1704063254] 'process raft request' (duration: 351.434346ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:31.287677Z","caller":"traceutil/trace.go:171","msg":"trace[1840621378] transaction","detail":"{read_only:false; response_revision:272; number_of_response:1; }","duration":"353.567358ms","start":"2026-06-09T17:04:30.934084Z","end":"2026-06-09T17:04:31.287651Z","steps":["trace[1840621378] 'process raft request' (duration: 353.421795ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:31.288052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:30.93495Z","time spent":"353.020377ms","remote":"127.0.0.1:41440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":642,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-09T17:04:31.28814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:30.934068Z","time spent":"353.984948ms","remote":"127.0.0.1:41472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":597,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-09T17:04:31.288179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:30.93614Z","time spent":"351.906586ms","remote":"127.0.0.1:41630","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-09T17:04:31.288458Z","caller":"traceutil/trace.go:171","msg":"trace[1791741761] transaction","detail":"{read_only:false; response_revision:275; number_of_response:1; }","duration":"339.332029ms","start":"2026-06-09T17:04:30.949109Z","end":"2026-06-09T17:04:31.288441Z","steps":["trace[1791741761] 'process raft request' (duration: 338.762448ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:31.288541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:30.949087Z","time spent":"339.409581ms","remote":"127.0.0.1:41548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7279,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:04:31.288629Z","caller":"traceutil/trace.go:171","msg":"trace[2132217220] linearizableReadLoop","detail":"{readStateIndex:284; appliedIndex:280; }","duration":"352.405546ms","start":"2026-06-09T17:04:30.936214Z","end":"2026-06-09T17:04:31.288619Z","steps":["trace[2132217220] 'read index received' (duration: 54.934726ms)","trace[2132217220] 'applied index is now lower than readState.Index' (duration: 297.46821ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:04:31.288587Z","caller":"traceutil/trace.go:171","msg":"trace[335372772] transaction","detail":"{read_only:false; response_revision:276; number_of_response:1; }","duration":"292.653772ms","start":"2026-06-09T17:04:30.995826Z","end":"2026-06-09T17:04:31.28848Z","steps":["trace[335372772] 'process raft request' (duration: 292.581821ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:31.28882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.926888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-06-09T17:04:31.288787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.569919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4016"} {"level":"info","ts":"2026-06-09T17:04:31.288935Z","caller":"traceutil/trace.go:171","msg":"trace[988260459] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:276; }","duration":"352.794013ms","start":"2026-06-09T17:04:30.936129Z","end":"2026-06-09T17:04:31.288923Z","steps":["trace[988260459] 'agreement among raft nodes before linearized reading' (duration: 352.566158ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:31.288976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:30.936121Z","time spent":"352.846795ms","remote":"127.0.0.1:41544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4039,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-06-09T17:04:31.288939Z","caller":"traceutil/trace.go:171","msg":"trace[430503889] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:276; }","duration":"294.078271ms","start":"2026-06-09T17:04:30.994848Z","end":"2026-06-09T17:04:31.288927Z","steps":["trace[430503889] 'agreement among raft nodes before linearized reading' (duration: 293.916808ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:31.288885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.551899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4016"} {"level":"info","ts":"2026-06-09T17:04:31.289183Z","caller":"traceutil/trace.go:171","msg":"trace[1863911587] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:276; }","duration":"352.922647ms","start":"2026-06-09T17:04:30.936247Z","end":"2026-06-09T17:04:31.289169Z","steps":["trace[1863911587] 'agreement among raft nodes before linearized reading' (duration: 352.507807ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:31.289232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:30.936242Z","time spent":"352.976968ms","remote":"127.0.0.1:41544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4039,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-06-09T17:04:31.578494Z","caller":"traceutil/trace.go:171","msg":"trace[600270465] transaction","detail":"{read_only:false; response_revision:282; number_of_response:1; }","duration":"276.554815ms","start":"2026-06-09T17:04:31.301872Z","end":"2026-06-09T17:04:31.578427Z","steps":["trace[600270465] 'process raft request' (duration: 204.615262ms)","trace[600270465] 'compare' (duration: 71.557565ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:04:31.578607Z","caller":"traceutil/trace.go:171","msg":"trace[454445023] transaction","detail":"{read_only:false; response_revision:283; number_of_response:1; }","duration":"275.534674ms","start":"2026-06-09T17:04:31.303029Z","end":"2026-06-09T17:04:31.578564Z","steps":["trace[454445023] 'process raft request' (duration: 275.29644ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:31.579016Z","caller":"traceutil/trace.go:171","msg":"trace[1818163582] transaction","detail":"{read_only:false; response_revision:284; number_of_response:1; }","duration":"275.27002ms","start":"2026-06-09T17:04:31.30371Z","end":"2026-06-09T17:04:31.57898Z","steps":["trace[1818163582] 'process raft request' (duration: 274.749688ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:31.579216Z","caller":"traceutil/trace.go:171","msg":"trace[171210458] linearizableReadLoop","detail":"{readStateIndex:294; appliedIndex:291; }","duration":"203.98566ms","start":"2026-06-09T17:04:31.375151Z","end":"2026-06-09T17:04:31.579137Z","steps":["trace[171210458] 'read index received' (duration: 130.883852ms)","trace[171210458] 'applied index is now lower than readState.Index' (duration: 73.099738ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:04:31.579845Z","caller":"traceutil/trace.go:171","msg":"trace[947115806] transaction","detail":"{read_only:false; response_revision:285; number_of_response:1; }","duration":"199.874895ms","start":"2026-06-09T17:04:31.379954Z","end":"2026-06-09T17:04:31.579829Z","steps":["trace[947115806] 'process raft request' (duration: 198.874305ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:31.579885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.443883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:04:31.58007Z","caller":"traceutil/trace.go:171","msg":"trace[1891856729] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:0; response_revision:285; }","duration":"275.548395ms","start":"2026-06-09T17:04:31.304378Z","end":"2026-06-09T17:04:31.579926Z","steps":["trace[1891856729] 'agreement among raft nodes before linearized reading' (duration: 274.928413ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:31.580175Z","caller":"traceutil/trace.go:171","msg":"trace[389561663] transaction","detail":"{read_only:false; response_revision:286; number_of_response:1; }","duration":"199.149621ms","start":"2026-06-09T17:04:31.381001Z","end":"2026-06-09T17:04:31.580151Z","steps":["trace[389561663] 'process raft request' (duration: 198.724322ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:31.774817Z","caller":"traceutil/trace.go:171","msg":"trace[962853299] transaction","detail":"{read_only:false; response_revision:288; number_of_response:1; }","duration":"186.713667ms","start":"2026-06-09T17:04:31.588057Z","end":"2026-06-09T17:04:31.77477Z","steps":["trace[962853299] 'process raft request' (duration: 161.935194ms)","trace[962853299] 'compare' (duration: 24.545579ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:04:31.774826Z","caller":"traceutil/trace.go:171","msg":"trace[933345681] transaction","detail":"{read_only:false; response_revision:290; number_of_response:1; }","duration":"176.380278ms","start":"2026-06-09T17:04:31.598421Z","end":"2026-06-09T17:04:31.774801Z","steps":["trace[933345681] 'process raft request' (duration: 176.331897ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:31.774887Z","caller":"traceutil/trace.go:171","msg":"trace[787596954] transaction","detail":"{read_only:false; response_revision:289; number_of_response:1; }","duration":"186.493114ms","start":"2026-06-09T17:04:31.588384Z","end":"2026-06-09T17:04:31.774877Z","steps":["trace[787596954] 'process raft request' (duration: 186.30074ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:32.558057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.022444ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-09T17:04:32.558266Z","caller":"traceutil/trace.go:171","msg":"trace[1417908927] transaction","detail":"{read_only:false; response_revision:304; number_of_response:1; }","duration":"463.908095ms","start":"2026-06-09T17:04:32.09431Z","end":"2026-06-09T17:04:32.558218Z","steps":["trace[1417908927] 'process raft request' (duration: 320.620585ms)","trace[1417908927] 'compare' (duration: 142.8155ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:04:32.558406Z","caller":"traceutil/trace.go:171","msg":"trace[143552271] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"421.883358ms","start":"2026-06-09T17:04:32.136499Z","end":"2026-06-09T17:04:32.558382Z","steps":["trace[143552271] 'process raft request' (duration: 421.689934ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:32.558445Z","caller":"traceutil/trace.go:171","msg":"trace[1958752591] linearizableReadLoop","detail":"{readStateIndex:314; appliedIndex:313; }","duration":"421.943599ms","start":"2026-06-09T17:04:32.13645Z","end":"2026-06-09T17:04:32.558394Z","steps":["trace[1958752591] 'read index received' (duration: 278.576898ms)","trace[1958752591] 'applied index is now lower than readState.Index' (duration: 143.359661ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:04:32.558613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.147483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-09T17:04:32.558594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:32.136477Z","time spent":"422.0307ms","remote":"127.0.0.1:41700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":435,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-09T17:04:32.558656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.168783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-06-09T17:04:32.558686Z","caller":"traceutil/trace.go:171","msg":"trace[450853431] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:0; response_revision:305; }","duration":"422.251345ms","start":"2026-06-09T17:04:32.136414Z","end":"2026-06-09T17:04:32.558665Z","steps":["trace[450853431] 'agreement among raft nodes before linearized reading' (duration: 422.122732ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:32.5587Z","caller":"traceutil/trace.go:171","msg":"trace[1856869849] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:305; }","duration":"422.244315ms","start":"2026-06-09T17:04:32.136444Z","end":"2026-06-09T17:04:32.558688Z","steps":["trace[1856869849] 'agreement among raft nodes before linearized reading' (duration: 422.144973ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:32.558698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.749291ms","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-09T17:04:32.558734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:32.136431Z","time spent":"422.295076ms","remote":"127.0.0.1:41570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":239,"request content":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" "} {"level":"warn","ts":"2026-06-09T17:04:32.558737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:32.136394Z","time spent":"422.330897ms","remote":"127.0.0.1:41570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" "} {"level":"info","ts":"2026-06-09T17:04:32.558741Z","caller":"traceutil/trace.go:171","msg":"trace[216964166] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:305; }","duration":"329.849352ms","start":"2026-06-09T17:04:32.228879Z","end":"2026-06-09T17:04:32.558729Z","steps":["trace[216964166] 'agreement among raft nodes before linearized reading' (duration: 329.722039ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:32.558788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:32.228855Z","time spent":"329.924074ms","remote":"127.0.0.1:41630","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-09T17:04:32.559074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:32.094295Z","time spent":"464.046397ms","remote":"127.0.0.1:41548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6946,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:04:33.11168Z","caller":"traceutil/trace.go:171","msg":"trace[550690798] transaction","detail":"{read_only:false; response_revision:308; number_of_response:1; }","duration":"542.475481ms","start":"2026-06-09T17:04:32.569179Z","end":"2026-06-09T17:04:33.111655Z","steps":["trace[550690798] 'process raft request' (duration: 542.371239ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:33.111781Z","caller":"traceutil/trace.go:171","msg":"trace[1986664943] transaction","detail":"{read_only:false; response_revision:307; number_of_response:1; }","duration":"546.428471ms","start":"2026-06-09T17:04:32.565334Z","end":"2026-06-09T17:04:33.111763Z","steps":["trace[1986664943] 'process raft request' (duration: 539.638572ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:33.111813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:32.569163Z","time spent":"542.581703ms","remote":"127.0.0.1:41548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7279,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:04:33.112247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:32.565321Z","time spent":"546.498333ms","remote":"127.0.0.1:41570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":194,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-09T17:04:33.622442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.439ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-09T17:04:33.622606Z","caller":"traceutil/trace.go:171","msg":"trace[1481433528] linearizableReadLoop","detail":"{readStateIndex:320; appliedIndex:319; }","duration":"471.733412ms","start":"2026-06-09T17:04:33.150839Z","end":"2026-06-09T17:04:33.622573Z","steps":["trace[1481433528] 'read index received' (duration: 159.986436ms)","trace[1481433528] 'applied index is now lower than readState.Index' (duration: 311.745226ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:04:33.622644Z","caller":"traceutil/trace.go:171","msg":"trace[242508202] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"499.781635ms","start":"2026-06-09T17:04:33.122828Z","end":"2026-06-09T17:04:33.62261Z","steps":["trace[242508202] 'process raft request' (duration: 188.05477ms)","trace[242508202] 'compare' (duration: 311.165104ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:04:33.62277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.619468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-09T17:04:33.622803Z","caller":"traceutil/trace.go:171","msg":"trace[151302314] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:310; }","duration":"325.691339ms","start":"2026-06-09T17:04:33.2971Z","end":"2026-06-09T17:04:33.622791Z","steps":["trace[151302314] 'agreement among raft nodes before linearized reading' (duration: 325.610558ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:33.62278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.916506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ephemeral-volume-controller\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-09T17:04:33.622816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:33.12281Z","time spent":"499.909268ms","remote":"127.0.0.1:41548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4307,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:04:33.622844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:33.297072Z","time spent":"325.763841ms","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-09T17:04:33.622858Z","caller":"traceutil/trace.go:171","msg":"trace[990850096] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ephemeral-volume-controller; range_end:; response_count:0; response_revision:310; }","duration":"472.044118ms","start":"2026-06-09T17:04:33.150791Z","end":"2026-06-09T17:04:33.622835Z","steps":["trace[990850096] 'agreement among raft nodes before linearized reading' (duration: 471.905476ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:33.622897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:33.150776Z","time spent":"472.113449ms","remote":"127.0.0.1:41570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/ephemeral-volume-controller\" "} {"level":"warn","ts":"2026-06-09T17:04:33.623173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.25662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-09T17:04:33.623254Z","caller":"traceutil/trace.go:171","msg":"trace[1661092861] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:310; }","duration":"319.392412ms","start":"2026-06-09T17:04:33.303841Z","end":"2026-06-09T17:04:33.623233Z","steps":["trace[1661092861] 'agreement among raft nodes before linearized reading' (duration: 319.200027ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:33.623311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:33.303802Z","time spent":"319.499795ms","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-09T17:04:33.86406Z","caller":"traceutil/trace.go:171","msg":"trace[1043305667] transaction","detail":"{read_only:false; response_revision:315; number_of_response:1; }","duration":"140.344856ms","start":"2026-06-09T17:04:33.72369Z","end":"2026-06-09T17:04:33.864035Z","steps":["trace[1043305667] 'process raft request' (duration: 44.08461ms)","trace[1043305667] 'compare' (duration: 96.102642ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:04:34.212972Z","caller":"traceutil/trace.go:171","msg":"trace[416809067] transaction","detail":"{read_only:false; response_revision:323; number_of_response:1; }","duration":"140.499355ms","start":"2026-06-09T17:04:34.072416Z","end":"2026-06-09T17:04:34.212916Z","steps":["trace[416809067] 'process raft request' (duration: 140.391493ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:34.589489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.248237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-06-09T17:04:34.589592Z","caller":"traceutil/trace.go:171","msg":"trace[196429743] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:328; }","duration":"113.39471ms","start":"2026-06-09T17:04:34.476173Z","end":"2026-06-09T17:04:34.589568Z","steps":["trace[196429743] 'range keys from in-memory index tree' (duration: 113.139445ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:36.576524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.72489ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-09T17:04:36.576635Z","caller":"traceutil/trace.go:171","msg":"trace[1610814472] transaction","detail":"{read_only:false; response_revision:344; number_of_response:1; }","duration":"549.43379ms","start":"2026-06-09T17:04:36.027183Z","end":"2026-06-09T17:04:36.576617Z","steps":["trace[1610814472] 'process raft request' (duration: 446.535677ms)","trace[1610814472] 'compare' (duration: 102.557397ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:04:36.576704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:36.02716Z","time spent":"549.516282ms","remote":"127.0.0.1:41570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":217,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-09T17:04:36.82219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.614568ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-09T17:04:36.822298Z","caller":"traceutil/trace.go:171","msg":"trace[110815413] linearizableReadLoop","detail":"{readStateIndex:356; appliedIndex:355; }","duration":"166.58133ms","start":"2026-06-09T17:04:36.6557Z","end":"2026-06-09T17:04:36.822281Z","steps":["trace[110815413] 'read index received' (duration: 24.727357ms)","trace[110815413] 'applied index is now lower than readState.Index' (duration: 141.852433ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:04:36.822383Z","caller":"traceutil/trace.go:171","msg":"trace[638307620] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"168.771285ms","start":"2026-06-09T17:04:36.653557Z","end":"2026-06-09T17:04:36.822328Z","steps":["trace[638307620] 'process raft request' (duration: 26.920052ms)","trace[638307620] 'compare' (duration: 141.480095ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:04:36.822428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.714733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-06-09T17:04:36.822603Z","caller":"traceutil/trace.go:171","msg":"trace[2015488656] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:346; }","duration":"166.859477ms","start":"2026-06-09T17:04:36.65568Z","end":"2026-06-09T17:04:36.82254Z","steps":["trace[2015488656] 'agreement among raft nodes before linearized reading' (duration: 166.694663ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:36.822811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.03539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.81\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-06-09T17:04:36.822877Z","caller":"traceutil/trace.go:171","msg":"trace[529620298] range","detail":"{range_begin:/registry/masterleases/199.19.213.81; range_end:; response_count:1; response_revision:346; }","duration":"129.324276ms","start":"2026-06-09T17:04:36.693536Z","end":"2026-06-09T17:04:36.82286Z","steps":["trace[529620298] 'agreement among raft nodes before linearized reading' (duration: 129.081691ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:37.039926Z","caller":"traceutil/trace.go:171","msg":"trace[1267963187] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"144.910027ms","start":"2026-06-09T17:04:36.894987Z","end":"2026-06-09T17:04:37.039897Z","steps":["trace[1267963187] 'process raft request' (duration: 144.695933ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:37.040456Z","caller":"traceutil/trace.go:171","msg":"trace[2100445699] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"144.429006ms","start":"2026-06-09T17:04:36.896004Z","end":"2026-06-09T17:04:37.040433Z","steps":["trace[2100445699] 'process raft request' (duration: 144.070439ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:37.040594Z","caller":"traceutil/trace.go:171","msg":"trace[135139950] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"145.447378ms","start":"2026-06-09T17:04:36.895075Z","end":"2026-06-09T17:04:37.040522Z","steps":["trace[135139950] 'process raft request' (duration: 144.767884ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:37.04066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.703589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-06-09T17:04:37.040781Z","caller":"traceutil/trace.go:171","msg":"trace[537630568] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:352; }","duration":"139.895082ms","start":"2026-06-09T17:04:36.900866Z","end":"2026-06-09T17:04:37.040761Z","steps":["trace[537630568] 'agreement among raft nodes before linearized reading' (duration: 139.663378ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:37.040771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.772987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-06-09T17:04:37.041186Z","caller":"traceutil/trace.go:171","msg":"trace[149787480] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:352; }","duration":"114.267957ms","start":"2026-06-09T17:04:36.926895Z","end":"2026-06-09T17:04:37.041163Z","steps":["trace[149787480] 'agreement among raft nodes before linearized reading' (duration: 113.534212ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:37.271862Z","caller":"traceutil/trace.go:171","msg":"trace[107493210] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"225.120039ms","start":"2026-06-09T17:04:37.046718Z","end":"2026-06-09T17:04:37.271838Z","steps":["trace[107493210] 'process raft request' (duration: 225.084258ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:37.271882Z","caller":"traceutil/trace.go:171","msg":"trace[792011095] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"298.193802ms","start":"2026-06-09T17:04:36.973639Z","end":"2026-06-09T17:04:37.271832Z","steps":["trace[792011095] 'process raft request' (duration: 253.885661ms)","trace[792011095] 'compare' (duration: 44.066536ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:04:37.272034Z","caller":"traceutil/trace.go:171","msg":"trace[92690398] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"225.702793ms","start":"2026-06-09T17:04:37.046302Z","end":"2026-06-09T17:04:37.272004Z","steps":["trace[92690398] 'process raft request' (duration: 225.466707ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:37.272082Z","caller":"traceutil/trace.go:171","msg":"trace[203021615] linearizableReadLoop","detail":"{readStateIndex:364; appliedIndex:363; }","duration":"231.838289ms","start":"2026-06-09T17:04:37.040203Z","end":"2026-06-09T17:04:37.272042Z","steps":["trace[203021615] 'read index received' (duration: 187.405085ms)","trace[203021615] 'applied index is now lower than readState.Index' (duration: 44.426964ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:04:37.272178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.763474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-06-09T17:04:37.272262Z","caller":"traceutil/trace.go:171","msg":"trace[1230076683] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:355; }","duration":"244.882257ms","start":"2026-06-09T17:04:37.027358Z","end":"2026-06-09T17:04:37.27224Z","steps":["trace[1230076683] 'agreement among raft nodes before linearized reading' (duration: 244.691343ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:37.272367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.298542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-06-09T17:04:37.272756Z","caller":"traceutil/trace.go:171","msg":"trace[826165431] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:355; }","duration":"295.701891ms","start":"2026-06-09T17:04:36.97703Z","end":"2026-06-09T17:04:37.272732Z","steps":["trace[826165431] 'agreement among raft nodes before linearized reading' (duration: 295.227471ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:37.272526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.815087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-06-09T17:04:37.272832Z","caller":"traceutil/trace.go:171","msg":"trace[1949171495] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:355; }","duration":"196.184416ms","start":"2026-06-09T17:04:37.076641Z","end":"2026-06-09T17:04:37.272826Z","steps":["trace[1949171495] 'agreement among raft nodes before linearized reading' (duration: 195.828488ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:37.272592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.511603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-06-09T17:04:37.272896Z","caller":"traceutil/trace.go:171","msg":"trace[424426265] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:355; }","duration":"145.8719ms","start":"2026-06-09T17:04:37.127018Z","end":"2026-06-09T17:04:37.27289Z","steps":["trace[424426265] 'agreement among raft nodes before linearized reading' (duration: 145.535343ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:37.706666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.34811ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-09T17:04:37.707023Z","caller":"traceutil/trace.go:171","msg":"trace[934342564] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"358.479853ms","start":"2026-06-09T17:04:37.348519Z","end":"2026-06-09T17:04:37.706999Z","steps":["trace[934342564] 'process raft request' (duration: 358.405212ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:37.706896Z","caller":"traceutil/trace.go:171","msg":"trace[1904284755] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"360.608116ms","start":"2026-06-09T17:04:37.346238Z","end":"2026-06-09T17:04:37.706846Z","steps":["trace[1904284755] 'process raft request' (duration: 177.973241ms)","trace[1904284755] 'compare' (duration: 182.127905ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:04:37.707134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:37.348507Z","time spent":"358.573085ms","remote":"127.0.0.1:41544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4354,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:04:37.707255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:37.346217Z","time spent":"360.943823ms","remote":"127.0.0.1:41570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":164,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-09T17:04:37.707569Z","caller":"traceutil/trace.go:171","msg":"trace[1642346625] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"360.389423ms","start":"2026-06-09T17:04:37.347102Z","end":"2026-06-09T17:04:37.707491Z","steps":["trace[1642346625] 'process raft request' (duration: 359.738269ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:37.707755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:37.34708Z","time spent":"360.591497ms","remote":"127.0.0.1:41548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3378,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-09T17:04:37.707805Z","caller":"traceutil/trace.go:171","msg":"trace[482074675] linearizableReadLoop","detail":"{readStateIndex:373; appliedIndex:372; }","duration":"360.753451ms","start":"2026-06-09T17:04:37.34703Z","end":"2026-06-09T17:04:37.707784Z","steps":["trace[482074675] 'read index received' (duration: 177.141345ms)","trace[482074675] 'applied index is now lower than readState.Index' (duration: 183.610586ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:04:37.707957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.215863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"warn","ts":"2026-06-09T17:04:37.707975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.927874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:3706"} {"level":"info","ts":"2026-06-09T17:04:37.708Z","caller":"traceutil/trace.go:171","msg":"trace[972758455] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:364; }","duration":"331.260404ms","start":"2026-06-09T17:04:37.376728Z","end":"2026-06-09T17:04:37.707988Z","steps":["trace[972758455] 'agreement among raft nodes before linearized reading' (duration: 331.176812ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:37.708035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:37.376692Z","time spent":"331.336285ms","remote":"127.0.0.1:41570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":232,"request content":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" "} {"level":"warn","ts":"2026-06-09T17:04:37.708226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.484553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-06-09T17:04:37.708269Z","caller":"traceutil/trace.go:171","msg":"trace[539695990] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:364; }","duration":"130.579375ms","start":"2026-06-09T17:04:37.577681Z","end":"2026-06-09T17:04:37.70826Z","steps":["trace[539695990] 'agreement among raft nodes before linearized reading' (duration: 130.510184ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:37.708426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.079158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4368"} {"level":"info","ts":"2026-06-09T17:04:37.70846Z","caller":"traceutil/trace.go:171","msg":"trace[1043525675] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:364; }","duration":"171.16981ms","start":"2026-06-09T17:04:37.537281Z","end":"2026-06-09T17:04:37.708451Z","steps":["trace[1043525675] 'agreement among raft nodes before linearized reading' (duration: 171.115119ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:37.708631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.93346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-06-09T17:04:37.708666Z","caller":"traceutil/trace.go:171","msg":"trace[1779133699] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:364; }","duration":"180.017712ms","start":"2026-06-09T17:04:37.52864Z","end":"2026-06-09T17:04:37.708657Z","steps":["trace[1779133699] 'agreement among raft nodes before linearized reading' (duration: 179.956191ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:37.709051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.830448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-06-09T17:04:37.709163Z","caller":"traceutil/trace.go:171","msg":"trace[1994607441] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:364; }","duration":"231.997631ms","start":"2026-06-09T17:04:37.477139Z","end":"2026-06-09T17:04:37.709136Z","steps":["trace[1994607441] 'agreement among raft nodes before linearized reading' (duration: 231.777707ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:37.70948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.128642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-06-09T17:04:37.709556Z","caller":"traceutil/trace.go:171","msg":"trace[2008703607] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:364; }","duration":"282.268356ms","start":"2026-06-09T17:04:37.42727Z","end":"2026-06-09T17:04:37.709539Z","steps":["trace[2008703607] 'agreement among raft nodes before linearized reading' (duration: 281.834868ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:37.708026Z","caller":"traceutil/trace.go:171","msg":"trace[1103484127] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:364; }","duration":"361.005985ms","start":"2026-06-09T17:04:37.347003Z","end":"2026-06-09T17:04:37.708009Z","steps":["trace[1103484127] 'agreement among raft nodes before linearized reading' (duration: 360.874203ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:37.714577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:37.346995Z","time spent":"367.430378ms","remote":"127.0.0.1:41834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":3729,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"info","ts":"2026-06-09T17:04:37.974888Z","caller":"traceutil/trace.go:171","msg":"trace[170946391] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"255.375203ms","start":"2026-06-09T17:04:37.719472Z","end":"2026-06-09T17:04:37.974847Z","steps":["trace[170946391] 'process raft request' (duration: 208.351505ms)","trace[170946391] 'compare' (duration: 46.754261ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:04:37.974962Z","caller":"traceutil/trace.go:171","msg":"trace[2132559663] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"255.344003ms","start":"2026-06-09T17:04:37.719598Z","end":"2026-06-09T17:04:37.974942Z","steps":["trace[2132559663] 'process raft request' (duration: 255.19091ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:37.975222Z","caller":"traceutil/trace.go:171","msg":"trace[471353788] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"255.351943ms","start":"2026-06-09T17:04:37.71984Z","end":"2026-06-09T17:04:37.975192Z","steps":["trace[471353788] 'process raft request' (duration: 255.186159ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:37.975529Z","caller":"traceutil/trace.go:171","msg":"trace[906396213] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"255.73145ms","start":"2026-06-09T17:04:37.719785Z","end":"2026-06-09T17:04:37.975516Z","steps":["trace[906396213] 'process raft request' (duration: 255.134328ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:37.975598Z","caller":"traceutil/trace.go:171","msg":"trace[1097029636] linearizableReadLoop","detail":"{readStateIndex:389; appliedIndex:375; }","duration":"245.416158ms","start":"2026-06-09T17:04:37.73017Z","end":"2026-06-09T17:04:37.975586Z","steps":["trace[1097029636] 'read index received' (duration: 31.597089ms)","trace[1097029636] 'applied index is now lower than readState.Index' (duration: 213.818329ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:04:37.97569Z","caller":"traceutil/trace.go:171","msg":"trace[665191488] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"255.813011ms","start":"2026-06-09T17:04:37.71987Z","end":"2026-06-09T17:04:37.975683Z","steps":["trace[665191488] 'process raft request' (duration: 255.201969ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:37.975852Z","caller":"traceutil/trace.go:171","msg":"trace[84328792] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"255.931354ms","start":"2026-06-09T17:04:37.719914Z","end":"2026-06-09T17:04:37.975845Z","steps":["trace[84328792] 'process raft request' (duration: 255.391233ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:37.976154Z","caller":"traceutil/trace.go:171","msg":"trace[1348162506] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"256.519506ms","start":"2026-06-09T17:04:37.719625Z","end":"2026-06-09T17:04:37.976144Z","steps":["trace[1348162506] 'process raft request' (duration: 255.24173ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:37.976316Z","caller":"traceutil/trace.go:171","msg":"trace[221469987] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"255.485345ms","start":"2026-06-09T17:04:37.720788Z","end":"2026-06-09T17:04:37.976273Z","steps":["trace[221469987] 'process raft request' (duration: 254.732299ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:37.976654Z","caller":"traceutil/trace.go:171","msg":"trace[441922551] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"256.397964ms","start":"2026-06-09T17:04:37.720245Z","end":"2026-06-09T17:04:37.976643Z","steps":["trace[441922551] 'process raft request' (duration: 255.143958ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:37.976811Z","caller":"traceutil/trace.go:171","msg":"trace[146493931] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"256.355603ms","start":"2026-06-09T17:04:37.720449Z","end":"2026-06-09T17:04:37.976805Z","steps":["trace[146493931] 'process raft request' (duration: 254.980084ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:37.976969Z","caller":"traceutil/trace.go:171","msg":"trace[41830817] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"256.339282ms","start":"2026-06-09T17:04:37.720621Z","end":"2026-06-09T17:04:37.97696Z","steps":["trace[41830817] 'process raft request' (duration: 254.856901ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:37.978502Z","caller":"traceutil/trace.go:171","msg":"trace[1552600936] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"255.464874ms","start":"2026-06-09T17:04:37.723019Z","end":"2026-06-09T17:04:37.978484Z","steps":["trace[1552600936] 'process raft request' (duration: 252.538794ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:37.97878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.595822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-06-09T17:04:37.978828Z","caller":"traceutil/trace.go:171","msg":"trace[852031101] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:378; }","duration":"248.678155ms","start":"2026-06-09T17:04:37.730133Z","end":"2026-06-09T17:04:37.978811Z","steps":["trace[852031101] 'agreement among raft nodes before linearized reading' (duration: 248.580103ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:37.979105Z","caller":"traceutil/trace.go:171","msg":"trace[810391727] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"259.256363ms","start":"2026-06-09T17:04:37.71984Z","end":"2026-06-09T17:04:37.979097Z","steps":["trace[810391727] 'process raft request' (duration: 255.129108ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:37.979837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.815297ms","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-09T17:04:37.979888Z","caller":"traceutil/trace.go:171","msg":"trace[1655676673] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:378; }","duration":"248.90145ms","start":"2026-06-09T17:04:37.730973Z","end":"2026-06-09T17:04:37.979875Z","steps":["trace[1655676673] 'agreement among raft nodes before linearized reading' (duration: 248.792928ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:37.980384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.99747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-09T17:04:37.980426Z","caller":"traceutil/trace.go:171","msg":"trace[120351207] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:378; }","duration":"244.05152ms","start":"2026-06-09T17:04:37.736362Z","end":"2026-06-09T17:04:37.980413Z","steps":["trace[120351207] 'agreement among raft nodes before linearized reading' (duration: 243.975688ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:38.430532Z","caller":"traceutil/trace.go:171","msg":"trace[402574732] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"445.764042ms","start":"2026-06-09T17:04:37.984714Z","end":"2026-06-09T17:04:38.430478Z","steps":["trace[402574732] 'process raft request' (duration: 375.001884ms)","trace[402574732] 'compare' (duration: 70.453652ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:04:38.430639Z","caller":"traceutil/trace.go:171","msg":"trace[846632214] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"445.783573ms","start":"2026-06-09T17:04:37.984815Z","end":"2026-06-09T17:04:38.430598Z","steps":["trace[846632214] 'process raft request' (duration: 445.577859ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:38.430648Z","caller":"traceutil/trace.go:171","msg":"trace[1503587310] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"445.437696ms","start":"2026-06-09T17:04:37.985174Z","end":"2026-06-09T17:04:38.430612Z","steps":["trace[1503587310] 'process raft request' (duration: 445.312314ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:38.430711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:37.984688Z","time spent":"445.938176ms","remote":"127.0.0.1:41440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":687,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-09T17:04:38.430801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:37.984808Z","time spent":"445.904505ms","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:04:38.430983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:37.985165Z","time spent":"445.583489ms","remote":"127.0.0.1:41848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2886,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:04:38.431218Z","caller":"traceutil/trace.go:171","msg":"trace[1368641909] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"445.978378ms","start":"2026-06-09T17:04:37.985223Z","end":"2026-06-09T17:04:38.431202Z","steps":["trace[1368641909] 'process raft request' (duration: 445.315704ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:38.43132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:37.985218Z","time spent":"446.044939ms","remote":"127.0.0.1:41620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2259,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:04:38.431559Z","caller":"traceutil/trace.go:171","msg":"trace[1118961522] linearizableReadLoop","detail":"{readStateIndex:392; appliedIndex:390; }","duration":"446.458877ms","start":"2026-06-09T17:04:37.985071Z","end":"2026-06-09T17:04:38.43153Z","steps":["trace[1118961522] 'read index received' (duration: 32.293234ms)","trace[1118961522] 'applied index is now lower than readState.Index' (duration: 414.164243ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:04:38.431689Z","caller":"traceutil/trace.go:171","msg":"trace[742182338] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"444.510438ms","start":"2026-06-09T17:04:37.987164Z","end":"2026-06-09T17:04:38.431674Z","steps":["trace[742182338] 'process raft request' (duration: 444.321924ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:38.431747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.648741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-89vw4\" ","response":"range_response_count:1 size:3439"} {"level":"info","ts":"2026-06-09T17:04:38.431793Z","caller":"traceutil/trace.go:171","msg":"trace[1327863625] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-89vw4; range_end:; response_count:1; response_revision:387; }","duration":"446.748853ms","start":"2026-06-09T17:04:37.985029Z","end":"2026-06-09T17:04:38.431778Z","steps":["trace[1327863625] 'agreement among raft nodes before linearized reading' (duration: 446.650961ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:38.431836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:37.985011Z","time spent":"446.816594ms","remote":"127.0.0.1:41548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":3462,"request content":"key:\"/registry/pods/kube-system/kube-proxy-89vw4\" "} {"level":"info","ts":"2026-06-09T17:04:38.43184Z","caller":"traceutil/trace.go:171","msg":"trace[1702011775] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"445.402025ms","start":"2026-06-09T17:04:37.98642Z","end":"2026-06-09T17:04:38.431822Z","steps":["trace[1702011775] 'process raft request' (duration: 444.915605ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:38.431917Z","caller":"traceutil/trace.go:171","msg":"trace[828803833] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"445.431016ms","start":"2026-06-09T17:04:37.986238Z","end":"2026-06-09T17:04:38.431669Z","steps":["trace[828803833] 'process raft request' (duration: 444.766792ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:38.43199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:37.986409Z","time spent":"445.506518ms","remote":"127.0.0.1:41702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3461,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:04:38.432024Z","caller":"traceutil/trace.go:171","msg":"trace[1001911277] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"444.967407ms","start":"2026-06-09T17:04:37.987042Z","end":"2026-06-09T17:04:38.43201Z","steps":["trace[1001911277] 'process raft request' (duration: 444.394875ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:38.432165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:37.986227Z","time spent":"445.829234ms","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:04:38.431847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:37.987154Z","time spent":"444.61433ms","remote":"127.0.0.1:41702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2264,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:04:38.432192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:37.987027Z","time spent":"445.070039ms","remote":"127.0.0.1:41548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3527,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-09T17:04:38.440431Z","caller":"traceutil/trace.go:171","msg":"trace[992365535] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"418.733227ms","start":"2026-06-09T17:04:38.021677Z","end":"2026-06-09T17:04:38.44041Z","steps":["trace[992365535] 'process raft request' (duration: 418.554344ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:38.440571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:38.021646Z","time spent":"418.855429ms","remote":"127.0.0.1:41472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1731,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-09T17:04:38.440742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.507598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"info","ts":"2026-06-09T17:04:38.44081Z","caller":"traceutil/trace.go:171","msg":"trace[1036526070] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:388; }","duration":"279.623891ms","start":"2026-06-09T17:04:38.16117Z","end":"2026-06-09T17:04:38.440794Z","steps":["trace[1036526070] 'agreement among raft nodes before linearized reading' (duration: 279.473088ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:38.625073Z","caller":"traceutil/trace.go:171","msg":"trace[1812526753] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"183.368828ms","start":"2026-06-09T17:04:38.441678Z","end":"2026-06-09T17:04:38.625047Z","steps":["trace[1812526753] 'process raft request' (duration: 95.462417ms)","trace[1812526753] 'compare' (duration: 87.754938ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:04:38.625185Z","caller":"traceutil/trace.go:171","msg":"trace[1342072980] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"144.382645ms","start":"2026-06-09T17:04:38.480797Z","end":"2026-06-09T17:04:38.62518Z","steps":["trace[1342072980] 'process raft request' (duration: 144.279873ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:38.62516Z","caller":"traceutil/trace.go:171","msg":"trace[1063498349] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"139.662017ms","start":"2026-06-09T17:04:38.485488Z","end":"2026-06-09T17:04:38.62515Z","steps":["trace[1063498349] 'process raft request' (duration: 139.625296ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:38.625292Z","caller":"traceutil/trace.go:171","msg":"trace[1077837113] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"180.143201ms","start":"2026-06-09T17:04:38.44513Z","end":"2026-06-09T17:04:38.625274Z","steps":["trace[1077837113] 'process raft request' (duration: 179.892306ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:38.625386Z","caller":"traceutil/trace.go:171","msg":"trace[1398722682] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"180.987608ms","start":"2026-06-09T17:04:38.444383Z","end":"2026-06-09T17:04:38.625371Z","steps":["trace[1398722682] 'process raft request' (duration: 180.606721ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:38.625246Z","caller":"traceutil/trace.go:171","msg":"trace[858924634] linearizableReadLoop","detail":"{readStateIndex:406; appliedIndex:402; }","duration":"149.060481ms","start":"2026-06-09T17:04:38.476135Z","end":"2026-06-09T17:04:38.625196Z","steps":["trace[858924634] 'read index received' (duration: 60.958606ms)","trace[858924634] 'applied index is now lower than readState.Index' (duration: 88.096825ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:04:38.625467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.006559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-89vw4\" ","response":"range_response_count:1 size:4462"} {"level":"info","ts":"2026-06-09T17:04:38.625487Z","caller":"traceutil/trace.go:171","msg":"trace[1930274095] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"180.092439ms","start":"2026-06-09T17:04:38.44533Z","end":"2026-06-09T17:04:38.625422Z","steps":["trace[1930274095] 'process raft request' (duration: 179.714362ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:38.625552Z","caller":"traceutil/trace.go:171","msg":"trace[1281747152] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-89vw4; range_end:; response_count:1; response_revision:397; }","duration":"183.169694ms","start":"2026-06-09T17:04:38.442368Z","end":"2026-06-09T17:04:38.625538Z","steps":["trace[1281747152] 'agreement among raft nodes before linearized reading' (duration: 182.95824ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:38.626038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.909437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/kube-proxy\" ","response":"range_response_count:1 size:2901"} {"level":"info","ts":"2026-06-09T17:04:38.627196Z","caller":"traceutil/trace.go:171","msg":"trace[1754036221] range","detail":"{range_begin:/registry/daemonsets/kube-system/kube-proxy; range_end:; response_count:1; response_revision:397; }","duration":"146.04939ms","start":"2026-06-09T17:04:38.480049Z","end":"2026-06-09T17:04:38.626098Z","steps":["trace[1754036221] 'agreement among raft nodes before linearized reading' (duration: 145.871996ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:39.104968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.862063ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-09T17:04:39.105283Z","caller":"traceutil/trace.go:171","msg":"trace[1414493647] linearizableReadLoop","detail":"{readStateIndex:413; appliedIndex:412; }","duration":"418.064757ms","start":"2026-06-09T17:04:38.687187Z","end":"2026-06-09T17:04:39.105252Z","steps":["trace[1414493647] 'read index received' (duration: 66.838347ms)","trace[1414493647] 'applied index is now lower than readState.Index' (duration: 351.22346ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:04:39.105312Z","caller":"traceutil/trace.go:171","msg":"trace[1905367563] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"432.701259ms","start":"2026-06-09T17:04:38.672567Z","end":"2026-06-09T17:04:39.105268Z","steps":["trace[1905367563] 'process raft request' (duration: 81.372837ms)","trace[1905367563] 'compare' (duration: 350.643237ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:04:39.105442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.253721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7294"} {"level":"warn","ts":"2026-06-09T17:04:39.105477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:38.672546Z","time spent":"432.836811ms","remote":"127.0.0.1:41834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4041,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:04:39.105509Z","caller":"traceutil/trace.go:171","msg":"trace[1163814194] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:402; }","duration":"418.356563ms","start":"2026-06-09T17:04:38.687135Z","end":"2026-06-09T17:04:39.105492Z","steps":["trace[1163814194] 'agreement among raft nodes before linearized reading' (duration: 418.236451ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:39.105544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:38.687115Z","time spent":"418.420844ms","remote":"127.0.0.1:41548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7317,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"warn","ts":"2026-06-09T17:04:39.105722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.994102ms","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-09T17:04:39.105759Z","caller":"traceutil/trace.go:171","msg":"trace[1531465307] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:402; }","duration":"279.061623ms","start":"2026-06-09T17:04:38.826686Z","end":"2026-06-09T17:04:39.105748Z","steps":["trace[1531465307] 'agreement among raft nodes before linearized reading' (duration: 278.993982ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:39.105863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.056048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:04:39.105938Z","caller":"traceutil/trace.go:171","msg":"trace[1621336162] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:402; }","duration":"190.170341ms","start":"2026-06-09T17:04:38.915757Z","end":"2026-06-09T17:04:39.105927Z","steps":["trace[1621336162] 'agreement among raft nodes before linearized reading' (duration: 190.078499ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:39.418442Z","caller":"traceutil/trace.go:171","msg":"trace[1152174235] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"303.570846ms","start":"2026-06-09T17:04:39.114843Z","end":"2026-06-09T17:04:39.418414Z","steps":["trace[1152174235] 'process raft request' (duration: 271.856391ms)","trace[1152174235] 'compare' (duration: 31.327226ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:04:39.418601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:39.114813Z","time spent":"303.691257ms","remote":"127.0.0.1:41548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7279,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:04:47.123291Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8397417447545881754,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-09T17:04:47.623379Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8397417447545881754,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-09T17:04:47.785507Z","caller":"traceutil/trace.go:171","msg":"trace[1753722386] linearizableReadLoop","detail":"{readStateIndex:444; appliedIndex:443; }","duration":"1.163092814s","start":"2026-06-09T17:04:46.622385Z","end":"2026-06-09T17:04:47.785478Z","steps":["trace[1753722386] 'read index received' (duration: 1.162869269s)","trace[1753722386] 'applied index is now lower than readState.Index' (duration: 222.385µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:04:47.785553Z","caller":"traceutil/trace.go:171","msg":"trace[1386335658] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"1.234471074s","start":"2026-06-09T17:04:46.551061Z","end":"2026-06-09T17:04:47.785532Z","steps":["trace[1386335658] 'process raft request' (duration: 1.234278979s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:47.785693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.163275968s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-09T17:04:47.78577Z","caller":"traceutil/trace.go:171","msg":"trace[1372817102] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:431; }","duration":"1.16338931s","start":"2026-06-09T17:04:46.62235Z","end":"2026-06-09T17:04:47.785739Z","steps":["trace[1372817102] 'agreement among raft nodes before linearized reading' (duration: 1.163272488s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:47.7858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.092161564s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.81\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-06-09T17:04:47.785805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-09T17:04:47.785816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:46.622333Z","time spent":"1.163471252s","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-09T17:04:47.785844Z","caller":"traceutil/trace.go:171","msg":"trace[1181598845] range","detail":"{range_begin:/registry/masterleases/199.19.213.81; range_end:; response_count:1; response_revision:431; }","duration":"1.092241306s","start":"2026-06-09T17:04:46.69359Z","end":"2026-06-09T17:04:47.785831Z","steps":["trace[1181598845] 'agreement among raft nodes before linearized reading' (duration: 1.092160914s)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:47.785859Z","caller":"traceutil/trace.go:171","msg":"trace[310410958] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:431; }","duration":"165.615242ms","start":"2026-06-09T17:04:47.620228Z","end":"2026-06-09T17:04:47.785843Z","steps":["trace[310410958] 'agreement among raft nodes before linearized reading' (duration: 165.5313ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:47.785881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:46.693569Z","time spent":"1.092302787s","remote":"127.0.0.1:41404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.81\" "} {"level":"warn","ts":"2026-06-09T17:04:47.785691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:46.551047Z","time spent":"1.234560236s","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:04:47.786136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.439676ms","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-09T17:04:47.786772Z","caller":"traceutil/trace.go:171","msg":"trace[77297340] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:431; }","duration":"553.12571ms","start":"2026-06-09T17:04:47.233628Z","end":"2026-06-09T17:04:47.786754Z","steps":["trace[77297340] 'agreement among raft nodes before linearized reading' (duration: 552.463786ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:47.78684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:47.233609Z","time spent":"553.213141ms","remote":"127.0.0.1:41630","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-09T17:04:51.980927Z","caller":"traceutil/trace.go:171","msg":"trace[601376648] transaction","detail":"{read_only:false; response_revision:443; number_of_response:1; }","duration":"127.891178ms","start":"2026-06-09T17:04:51.852997Z","end":"2026-06-09T17:04:51.980889Z","steps":["trace[601376648] 'process raft request' (duration: 68.261404ms)","trace[601376648] 'compare' (duration: 59.4602ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:04:54.023465Z","caller":"traceutil/trace.go:171","msg":"trace[878911370] linearizableReadLoop","detail":"{readStateIndex:461; appliedIndex:460; }","duration":"107.898214ms","start":"2026-06-09T17:04:53.91554Z","end":"2026-06-09T17:04:54.023438Z","steps":["trace[878911370] 'read index received' (duration: 34.248631ms)","trace[878911370] 'applied index is now lower than readState.Index' (duration: 73.632183ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:04:54.02365Z","caller":"traceutil/trace.go:171","msg":"trace[731402879] transaction","detail":"{read_only:false; response_revision:446; number_of_response:1; }","duration":"130.094282ms","start":"2026-06-09T17:04:53.893539Z","end":"2026-06-09T17:04:54.023634Z","steps":["trace[731402879] 'process raft request' (duration: 56.323797ms)","trace[731402879] 'compare' (duration: 73.421528ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:04:54.023959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.409075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:04:54.024048Z","caller":"traceutil/trace.go:171","msg":"trace[996506634] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:446; }","duration":"108.536707ms","start":"2026-06-09T17:04:53.915492Z","end":"2026-06-09T17:04:54.024028Z","steps":["trace[996506634] 'agreement among raft nodes before linearized reading' (duration: 108.428415ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:54.602035Z","caller":"traceutil/trace.go:171","msg":"trace[335771946] transaction","detail":"{read_only:false; response_revision:449; number_of_response:1; }","duration":"178.962827ms","start":"2026-06-09T17:04:54.423047Z","end":"2026-06-09T17:04:54.60201Z","steps":["trace[335771946] 'process raft request' (duration: 178.804313ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:56.312943Z","caller":"traceutil/trace.go:171","msg":"trace[2088547725] linearizableReadLoop","detail":"{readStateIndex:469; appliedIndex:468; }","duration":"195.957216ms","start":"2026-06-09T17:04:56.116964Z","end":"2026-06-09T17:04:56.312921Z","steps":["trace[2088547725] 'read index received' (duration: 120.838317ms)","trace[2088547725] 'applied index is now lower than readState.Index' (duration: 75.118289ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:04:56.313101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.124179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-09T17:04:56.313129Z","caller":"traceutil/trace.go:171","msg":"trace[1582312324] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:454; }","duration":"196.215341ms","start":"2026-06-09T17:04:56.116904Z","end":"2026-06-09T17:04:56.31312Z","steps":["trace[1582312324] 'agreement among raft nodes before linearized reading' (duration: 196.125449ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:56.313095Z","caller":"traceutil/trace.go:171","msg":"trace[1710428872] transaction","detail":"{read_only:false; response_revision:454; number_of_response:1; }","duration":"233.382288ms","start":"2026-06-09T17:04:56.079663Z","end":"2026-06-09T17:04:56.313045Z","steps":["trace[1710428872] 'process raft request' (duration: 158.194717ms)","trace[1710428872] 'compare' (duration: 74.945566ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:04:56.313266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.401115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-06-09T17:04:56.313406Z","caller":"traceutil/trace.go:171","msg":"trace[750925518] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:454; }","duration":"144.577428ms","start":"2026-06-09T17:04:56.168785Z","end":"2026-06-09T17:04:56.313363Z","steps":["trace[750925518] 'agreement among raft nodes before linearized reading' (duration: 144.336133ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:56.606644Z","caller":"traceutil/trace.go:171","msg":"trace[1153303237] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"128.045809ms","start":"2026-06-09T17:04:56.478567Z","end":"2026-06-09T17:04:56.606613Z","steps":["trace[1153303237] 'process raft request' (duration: 104.811107ms)","trace[1153303237] 'compare' (duration: 23.11192ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:04:58.104272Z","caller":"traceutil/trace.go:171","msg":"trace[343988043] linearizableReadLoop","detail":"{readStateIndex:502; appliedIndex:501; }","duration":"189.019656ms","start":"2026-06-09T17:04:57.915234Z","end":"2026-06-09T17:04:58.104253Z","steps":["trace[343988043] 'read index received' (duration: 188.851733ms)","trace[343988043] 'applied index is now lower than readState.Index' (duration: 167.223µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:04:58.104421Z","caller":"traceutil/trace.go:171","msg":"trace[1708233287] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"199.535998ms","start":"2026-06-09T17:04:57.904775Z","end":"2026-06-09T17:04:58.104311Z","steps":["trace[1708233287] 'process raft request' (duration: 199.364425ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:58.104456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.174159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:04:58.104537Z","caller":"traceutil/trace.go:171","msg":"trace[2123732537] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:485; }","duration":"189.297773ms","start":"2026-06-09T17:04:57.915218Z","end":"2026-06-09T17:04:58.104516Z","steps":["trace[2123732537] 'agreement among raft nodes before linearized reading' (duration: 189.134038ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:05:10.109513Z","caller":"traceutil/trace.go:171","msg":"trace[1064806311] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"202.334366ms","start":"2026-06-09T17:05:09.907104Z","end":"2026-06-09T17:05:10.109439Z","steps":["trace[1064806311] 'process raft request' (duration: 102.52454ms)","trace[1064806311] 'compare' (duration: 99.708784ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:05:10.110849Z","caller":"traceutil/trace.go:171","msg":"trace[1357644173] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"134.093242ms","start":"2026-06-09T17:05:09.976731Z","end":"2026-06-09T17:05:10.110825Z","steps":["trace[1357644173] 'process raft request' (duration: 134.02342ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:05:10.111183Z","caller":"traceutil/trace.go:171","msg":"trace[1232920224] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"197.539513ms","start":"2026-06-09T17:05:09.913633Z","end":"2026-06-09T17:05:10.111173Z","steps":["trace[1232920224] 'process raft request' (duration: 196.554341ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:05:10.1116Z","caller":"traceutil/trace.go:171","msg":"trace[372456017] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"196.735445ms","start":"2026-06-09T17:05:09.914854Z","end":"2026-06-09T17:05:10.11159Z","steps":["trace[372456017] 'process raft request' (duration: 195.498879ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:05:10.111821Z","caller":"traceutil/trace.go:171","msg":"trace[488445774] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"161.979939ms","start":"2026-06-09T17:05:09.949831Z","end":"2026-06-09T17:05:10.111811Z","steps":["trace[488445774] 'process raft request' (duration: 160.769213ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:05:11.018895Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8397417447545882055,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-09T17:05:11.048613Z","caller":"traceutil/trace.go:171","msg":"trace[1425769608] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"704.502419ms","start":"2026-06-09T17:05:10.344081Z","end":"2026-06-09T17:05:11.048583Z","steps":["trace[1425769608] 'process raft request' (duration: 698.97113ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:05:11.048794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:05:10.344058Z","time spent":"704.645342ms","remote":"127.0.0.1:41412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":98689,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:05:11.056817Z","caller":"traceutil/trace.go:171","msg":"trace[1664006887] linearizableReadLoop","detail":"{readStateIndex:627; appliedIndex:624; }","duration":"538.691466ms","start":"2026-06-09T17:05:10.518101Z","end":"2026-06-09T17:05:11.056793Z","steps":["trace[1664006887] 'read index received' (duration: 524.862889ms)","trace[1664006887] 'applied index is now lower than readState.Index' (duration: 13.827207ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:05:11.056912Z","caller":"traceutil/trace.go:171","msg":"trace[1556396655] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"645.724506ms","start":"2026-06-09T17:05:10.41118Z","end":"2026-06-09T17:05:11.056905Z","steps":["trace[1556396655] 'process raft request' (duration: 644.896659ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:05:11.056988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:05:10.411156Z","time spent":"645.773817ms","remote":"127.0.0.1:41412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":151221,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:05:11.057137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.944824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-09T17:05:11.057162Z","caller":"traceutil/trace.go:171","msg":"trace[1160372019] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:606; }","duration":"473.990026ms","start":"2026-06-09T17:05:10.583163Z","end":"2026-06-09T17:05:11.057153Z","steps":["trace[1160372019] 'agreement among raft nodes before linearized reading' (duration: 473.905274ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:05:11.057202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:05:10.58315Z","time spent":"474.041517ms","remote":"127.0.0.1:41630","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-09T17:05:11.057489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.107547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:05:11.057532Z","caller":"traceutil/trace.go:171","msg":"trace[665908077] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:606; }","duration":"144.188869ms","start":"2026-06-09T17:05:10.913332Z","end":"2026-06-09T17:05:11.057521Z","steps":["trace[665908077] 'agreement among raft nodes before linearized reading' (duration: 144.124598ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:05:11.057579Z","caller":"traceutil/trace.go:171","msg":"trace[1822218177] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"483.245405ms","start":"2026-06-09T17:05:10.574316Z","end":"2026-06-09T17:05:11.057561Z","steps":["trace[1822218177] 'process raft request' (duration: 482.218313ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:05:11.057145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.027664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-06-09T17:05:11.057687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:05:10.574283Z","time spent":"483.360668ms","remote":"127.0.0.1:41412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-09T17:05:11.057774Z","caller":"traceutil/trace.go:171","msg":"trace[168674798] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:606; }","duration":"539.659467ms","start":"2026-06-09T17:05:10.518057Z","end":"2026-06-09T17:05:11.057717Z","steps":["trace[168674798] 'agreement among raft nodes before linearized reading' (duration: 538.927731ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:05:11.057817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:05:10.518036Z","time spent":"539.76825ms","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-09T17:05:14.478625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.59988ms","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-09T17:05:14.478688Z","caller":"traceutil/trace.go:171","msg":"trace[35353862] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:636; }","duration":"163.705181ms","start":"2026-06-09T17:05:14.314969Z","end":"2026-06-09T17:05:14.478675Z","steps":["trace[35353862] 'range keys from in-memory index tree' (duration: 163.471977ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:05:18.354621Z","caller":"traceutil/trace.go:171","msg":"trace[1234629302] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"115.896788ms","start":"2026-06-09T17:05:18.238706Z","end":"2026-06-09T17:05:18.354603Z","steps":["trace[1234629302] 'process raft request' (duration: 115.783116ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:05:18.851875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.915101ms","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-09T17:05:18.851939Z","caller":"traceutil/trace.go:171","msg":"trace[1538700637] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:666; }","duration":"323.023693ms","start":"2026-06-09T17:05:18.528898Z","end":"2026-06-09T17:05:18.851922Z","steps":["trace[1538700637] 'range keys from in-memory index tree' (duration: 322.768738ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:05:18.851966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:05:18.52888Z","time spent":"323.079684ms","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-09T17:05:19.221793Z","caller":"traceutil/trace.go:171","msg":"trace[1627809232] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"366.599101ms","start":"2026-06-09T17:05:18.855174Z","end":"2026-06-09T17:05:19.221773Z","steps":["trace[1627809232] 'process raft request' (duration: 366.337365ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:05:19.221934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:05:18.855144Z","time spent":"366.703533ms","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:05:20.453769Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.232272947s","expected-duration":"1s"} {"level":"info","ts":"2026-06-09T17:05:20.455006Z","caller":"traceutil/trace.go:171","msg":"trace[1557388569] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"1.592066972s","start":"2026-06-09T17:05:18.862904Z","end":"2026-06-09T17:05:20.454971Z","steps":["trace[1557388569] 'process raft request' (duration: 1.591881347s)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:05:20.454981Z","caller":"traceutil/trace.go:171","msg":"trace[1574717229] linearizableReadLoop","detail":"{readStateIndex:691; appliedIndex:689; }","duration":"1.539478992s","start":"2026-06-09T17:05:18.915473Z","end":"2026-06-09T17:05:20.454952Z","steps":["trace[1574717229] 'read index received' (duration: 306.111931ms)","trace[1574717229] 'applied index is now lower than readState.Index' (duration: 1.233366011s)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:05:20.455131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:05:18.862892Z","time spent":"1.592158333s","remote":"127.0.0.1:41548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:05:20.45525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.539721868s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:05:20.455343Z","caller":"traceutil/trace.go:171","msg":"trace[2133647793] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:668; }","duration":"1.539900511s","start":"2026-06-09T17:05:18.915424Z","end":"2026-06-09T17:05:20.455324Z","steps":["trace[2133647793] 'agreement among raft nodes before linearized reading' (duration: 1.539676816s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:05:20.455278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.326070991s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-06-09T17:05:20.455402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.318369043s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-06-09T17:05:20.45544Z","caller":"traceutil/trace.go:171","msg":"trace[1433956393] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:668; }","duration":"1.326276384s","start":"2026-06-09T17:05:19.129136Z","end":"2026-06-09T17:05:20.455413Z","steps":["trace[1433956393] 'agreement among raft nodes before linearized reading' (duration: 1.325971318s)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:05:20.45546Z","caller":"traceutil/trace.go:171","msg":"trace[1443035483] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:668; }","duration":"1.318462606s","start":"2026-06-09T17:05:19.136979Z","end":"2026-06-09T17:05:20.455441Z","steps":["trace[1443035483] 'agreement among raft nodes before linearized reading' (duration: 1.318257871s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:05:20.455484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:05:19.129112Z","time spent":"1.326358836s","remote":"127.0.0.1:41630","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-09T17:05:20.455506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:05:19.136956Z","time spent":"1.318537457s","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-09T17:05:20.455398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:05:18.91541Z","time spent":"1.539978884s","remote":"127.0.0.1:41382","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-09T17:05:20.738932Z","caller":"traceutil/trace.go:171","msg":"trace[568051833] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"278.631972ms","start":"2026-06-09T17:05:20.46024Z","end":"2026-06-09T17:05:20.738872Z","steps":["trace[568051833] 'process raft request' (duration: 247.055628ms)","trace[568051833] 'compare' (duration: 31.456922ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:05:21.818112Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.110887444s","expected-duration":"1s"} {"level":"warn","ts":"2026-06-09T17:05:22.463721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000202279s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-06-09T17:05:22.463823Z","caller":"traceutil/trace.go:171","msg":"trace[1680285854] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000291561s","start":"2026-06-09T17:05:20.463486Z","end":"2026-06-09T17:05:22.463778Z","steps":["trace[1680285854] 'agreement among raft nodes before linearized reading' (duration: 2.000199649s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:05:22.463861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:05:20.463446Z","time spent":"2.000407964s","remote":"127.0.0.1:41382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-09T17:05:22.705106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"886.724738ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-09T17:05:22.705414Z","caller":"traceutil/trace.go:171","msg":"trace[670834769] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"2.24508827s","start":"2026-06-09T17:05:20.460294Z","end":"2026-06-09T17:05:22.705382Z","steps":["trace[670834769] 'process raft request' (duration: 1.358005114s)","trace[670834769] 'compare' (duration: 886.562354ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:05:22.705463Z","caller":"traceutil/trace.go:171","msg":"trace[555738363] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"2.245074839s","start":"2026-06-09T17:05:20.460374Z","end":"2026-06-09T17:05:22.705449Z","steps":["trace[555738363] 'process raft request' (duration: 2.244815484s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:05:22.705549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:05:20.460352Z","time spent":"2.24514346s","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:05:22.705602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:05:20.460267Z","time spent":"2.245220262s","remote":"127.0.0.1:41630","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-09T17:05:22.70579Z","caller":"traceutil/trace.go:171","msg":"trace[2034397977] linearizableReadLoop","detail":"{readStateIndex:694; appliedIndex:691; }","duration":"2.242004602s","start":"2026-06-09T17:05:20.463492Z","end":"2026-06-09T17:05:22.705497Z","steps":["trace[2034397977] 'read index received' (duration: 243.792727ms)","trace[2034397977] 'applied index is now lower than readState.Index' (duration: 1.998209615s)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:05:22.730695Z","caller":"traceutil/trace.go:171","msg":"trace[1261179810] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"335.830574ms","start":"2026-06-09T17:05:22.394847Z","end":"2026-06-09T17:05:22.730677Z","steps":["trace[1261179810] 'process raft request' (duration: 335.490897ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:05:22.730815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:05:22.394823Z","time spent":"335.919586ms","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:05:22.730887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.920006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:05:22.730945Z","caller":"traceutil/trace.go:171","msg":"trace[465305020] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:672; }","duration":"266.025128ms","start":"2026-06-09T17:05:22.464901Z","end":"2026-06-09T17:05:22.730926Z","steps":["trace[465305020] 'agreement among raft nodes before linearized reading' (duration: 265.949387ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:05:22.731302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.505440736s","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-09T17:05:22.73135Z","caller":"traceutil/trace.go:171","msg":"trace[778500994] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:672; }","duration":"1.505527388s","start":"2026-06-09T17:05:21.22581Z","end":"2026-06-09T17:05:22.731338Z","steps":["trace[778500994] 'agreement among raft nodes before linearized reading' (duration: 1.50468735s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:05:22.731395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:05:21.225777Z","time spent":"1.505602719s","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-09T17:05:22.877431Z","caller":"traceutil/trace.go:171","msg":"trace[731637406] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"142.218749ms","start":"2026-06-09T17:05:22.735191Z","end":"2026-06-09T17:05:22.87741Z","steps":["trace[731637406] 'process raft request' (duration: 100.893291ms)","trace[731637406] 'compare' (duration: 41.173255ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:05:22.877636Z","caller":"traceutil/trace.go:171","msg":"trace[791533006] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"142.215009ms","start":"2026-06-09T17:05:22.735401Z","end":"2026-06-09T17:05:22.877616Z","steps":["trace[791533006] 'process raft request' (duration: 141.974714ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:05:22.877621Z","caller":"traceutil/trace.go:171","msg":"trace[718283470] linearizableReadLoop","detail":"{readStateIndex:698; appliedIndex:696; }","duration":"137.983817ms","start":"2026-06-09T17:05:22.739605Z","end":"2026-06-09T17:05:22.877589Z","steps":["trace[718283470] 'read index received' (duration: 96.561897ms)","trace[718283470] 'applied index is now lower than readState.Index' (duration: 41.41996ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:05:22.87822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.605541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:05:22.87828Z","caller":"traceutil/trace.go:171","msg":"trace[928131576] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:674; }","duration":"138.708382ms","start":"2026-06-09T17:05:22.739556Z","end":"2026-06-09T17:05:22.878265Z","steps":["trace[928131576] 'agreement among raft nodes before linearized reading' (duration: 138.543939ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:05:22.878335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.231528ms","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-09T17:05:22.878406Z","caller":"traceutil/trace.go:171","msg":"trace[1057993066] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:674; }","duration":"136.330441ms","start":"2026-06-09T17:05:22.742056Z","end":"2026-06-09T17:05:22.878387Z","steps":["trace[1057993066] 'agreement among raft nodes before linearized reading' (duration: 136.064405ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:05:24.834091Z","caller":"traceutil/trace.go:171","msg":"trace[1709902287] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"119.810646ms","start":"2026-06-09T17:05:24.714257Z","end":"2026-06-09T17:05:24.834068Z","steps":["trace[1709902287] 'process raft request' (duration: 119.597631ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:05:24.904239Z","caller":"traceutil/trace.go:171","msg":"trace[1712977892] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"189.952352ms","start":"2026-06-09T17:05:24.714255Z","end":"2026-06-09T17:05:24.904207Z","steps":["trace[1712977892] 'process raft request' (duration: 189.741107ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:05:25.061425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.632599ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-09T17:05:25.06153Z","caller":"traceutil/trace.go:171","msg":"trace[1224164838] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"152.917689ms","start":"2026-06-09T17:05:24.908593Z","end":"2026-06-09T17:05:25.061511Z","steps":["trace[1224164838] 'process raft request' (duration: 47.114385ms)","trace[1224164838] 'compare' (duration: 105.531967ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:05:25.144276Z","caller":"traceutil/trace.go:171","msg":"trace[796294841] linearizableReadLoop","detail":"{readStateIndex:705; appliedIndex:703; }","duration":"228.695358ms","start":"2026-06-09T17:05:24.915556Z","end":"2026-06-09T17:05:25.144251Z","steps":["trace[796294841] 'read index received' (duration: 40.168344ms)","trace[796294841] 'applied index is now lower than readState.Index' (duration: 188.525994ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:05:25.144352Z","caller":"traceutil/trace.go:171","msg":"trace[1530946356] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"234.022784ms","start":"2026-06-09T17:05:24.910299Z","end":"2026-06-09T17:05:25.144322Z","steps":["trace[1530946356] 'process raft request' (duration: 233.819749ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:05:25.144478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.896682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:05:25.144524Z","caller":"traceutil/trace.go:171","msg":"trace[766006177] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:681; }","duration":"228.975215ms","start":"2026-06-09T17:05:24.915538Z","end":"2026-06-09T17:05:25.144513Z","steps":["trace[766006177] 'agreement among raft nodes before linearized reading' (duration: 228.808031ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:05:27.075574Z","caller":"traceutil/trace.go:171","msg":"trace[1511321994] linearizableReadLoop","detail":"{readStateIndex:717; appliedIndex:716; }","duration":"167.44708ms","start":"2026-06-09T17:05:26.908105Z","end":"2026-06-09T17:05:27.075552Z","steps":["trace[1511321994] 'read index received' (duration: 27.190023ms)","trace[1511321994] 'applied index is now lower than readState.Index' (duration: 140.256107ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:05:27.075914Z","caller":"traceutil/trace.go:171","msg":"trace[514772985] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"213.615675ms","start":"2026-06-09T17:05:26.862289Z","end":"2026-06-09T17:05:27.075904Z","steps":["trace[514772985] 'process raft request' (duration: 205.019727ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:05:27.075974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.433276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:05:27.076038Z","caller":"traceutil/trace.go:171","msg":"trace[809966518] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:692; }","duration":"160.561309ms","start":"2026-06-09T17:05:26.91546Z","end":"2026-06-09T17:05:27.076021Z","steps":["trace[809966518] 'agreement among raft nodes before linearized reading' (duration: 160.441757ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:05:27.076321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.213987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-09T17:05:27.076409Z","caller":"traceutil/trace.go:171","msg":"trace[151723350] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:692; }","duration":"168.34357ms","start":"2026-06-09T17:05:26.908052Z","end":"2026-06-09T17:05:27.076396Z","steps":["trace[151723350] 'agreement among raft nodes before linearized reading' (duration: 167.773377ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:05:28.160808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.709277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-srgnp\" ","response":"range_response_count:1 size:17786"} {"level":"info","ts":"2026-06-09T17:05:28.161123Z","caller":"traceutil/trace.go:171","msg":"trace[929497334] range","detail":"{range_begin:/registry/pods/kube-system/cilium-srgnp; range_end:; response_count:1; response_revision:699; }","duration":"361.075705ms","start":"2026-06-09T17:05:27.800021Z","end":"2026-06-09T17:05:28.161097Z","steps":["trace[929497334] 'range keys from in-memory index tree' (duration: 360.461263ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:05:28.161181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:05:27.8Z","time spent":"361.165529ms","remote":"127.0.0.1:41548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17809,"request content":"key:\"/registry/pods/kube-system/cilium-srgnp\" "} {"level":"info","ts":"2026-06-09T17:05:28.386331Z","caller":"traceutil/trace.go:171","msg":"trace[1800485999] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"102.932606ms","start":"2026-06-09T17:05:28.283372Z","end":"2026-06-09T17:05:28.386304Z","steps":["trace[1800485999] 'process raft request' (duration: 100.046743ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:05:33.150211Z","caller":"traceutil/trace.go:171","msg":"trace[87306898] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"141.09313ms","start":"2026-06-09T17:05:33.009066Z","end":"2026-06-09T17:05:33.150159Z","steps":["trace[87306898] 'process raft request' (duration: 140.752212ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:05:33.15012Z","caller":"traceutil/trace.go:171","msg":"trace[1036790336] transaction","detail":"{read_only:false; number_of_response:0; response_revision:777; }","duration":"140.995788ms","start":"2026-06-09T17:05:33.009083Z","end":"2026-06-09T17:05:33.150079Z","steps":["trace[1036790336] 'process raft request' (duration: 140.946327ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:05:33.152904Z","caller":"traceutil/trace.go:171","msg":"trace[760518568] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"142.184325ms","start":"2026-06-09T17:05:33.01069Z","end":"2026-06-09T17:05:33.152874Z","steps":["trace[760518568] 'process raft request' (duration: 141.986449ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:05:38.613137Z","caller":"traceutil/trace.go:171","msg":"trace[605555366] linearizableReadLoop","detail":"{readStateIndex:849; appliedIndex:848; }","duration":"284.723325ms","start":"2026-06-09T17:05:38.328384Z","end":"2026-06-09T17:05:38.613107Z","steps":["trace[605555366] 'read index received' (duration: 284.335427ms)","trace[605555366] 'applied index is now lower than readState.Index' (duration: 386.528µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:05:38.613187Z","caller":"traceutil/trace.go:171","msg":"trace[889919148] transaction","detail":"{read_only:false; response_revision:820; number_of_response:1; }","duration":"303.762744ms","start":"2026-06-09T17:05:38.309373Z","end":"2026-06-09T17:05:38.613136Z","steps":["trace[889919148] 'process raft request' (duration: 303.406406ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:05:38.613346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.93297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-09T17:05:38.613383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:05:38.309362Z","time spent":"303.916958ms","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:05:38.613408Z","caller":"traceutil/trace.go:171","msg":"trace[318248660] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:820; }","duration":"285.043743ms","start":"2026-06-09T17:05:38.328347Z","end":"2026-06-09T17:05:38.613391Z","steps":["trace[318248660] 'agreement among raft nodes before linearized reading' (duration: 284.914519ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:05:38.613565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.943837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:05:38.613608Z","caller":"traceutil/trace.go:171","msg":"trace[1090931415] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:820; }","duration":"252.997028ms","start":"2026-06-09T17:05:38.3606Z","end":"2026-06-09T17:05:38.613597Z","steps":["trace[1090931415] 'agreement among raft nodes before linearized reading' (duration: 252.923097ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:05:38.613722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.020862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-09T17:05:38.613949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.381956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-09T17:05:38.614062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.151763ms","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-09T17:05:38.61409Z","caller":"traceutil/trace.go:171","msg":"trace[130294365] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:820; }","duration":"268.53429ms","start":"2026-06-09T17:05:38.345537Z","end":"2026-06-09T17:05:38.613994Z","steps":["trace[130294365] 'agreement among raft nodes before linearized reading' (duration: 268.318856ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:05:38.614128Z","caller":"traceutil/trace.go:171","msg":"trace[920816471] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:820; }","duration":"269.246425ms","start":"2026-06-09T17:05:38.344871Z","end":"2026-06-09T17:05:38.614117Z","steps":["trace[920816471] 'agreement among raft nodes before linearized reading' (duration: 269.149783ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:05:38.614153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.443265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:05:38.614201Z","caller":"traceutil/trace.go:171","msg":"trace[883083405] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:820; }","duration":"236.502546ms","start":"2026-06-09T17:05:38.377682Z","end":"2026-06-09T17:05:38.614185Z","steps":["trace[883083405] 'agreement among raft nodes before linearized reading' (duration: 236.426404ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:05:38.614342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.075538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-09T17:05:38.614752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.984022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:05:38.614837Z","caller":"traceutil/trace.go:171","msg":"trace[184362451] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:820; }","duration":"270.078044ms","start":"2026-06-09T17:05:38.344728Z","end":"2026-06-09T17:05:38.614806Z","steps":["trace[184362451] 'agreement among raft nodes before linearized reading' (duration: 269.948181ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:05:38.614351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.976789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:05:38.614791Z","caller":"traceutil/trace.go:171","msg":"trace[1670657362] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:820; }","duration":"237.543589ms","start":"2026-06-09T17:05:38.377224Z","end":"2026-06-09T17:05:38.614768Z","steps":["trace[1670657362] 'agreement among raft nodes before linearized reading' (duration: 237.063498ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:05:38.614402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.101352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:05:38.61518Z","caller":"traceutil/trace.go:171","msg":"trace[1157864523] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:820; }","duration":"254.89497ms","start":"2026-06-09T17:05:38.360274Z","end":"2026-06-09T17:05:38.615169Z","steps":["trace[1157864523] 'agreement among raft nodes before linearized reading' (duration: 254.104162ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:05:38.613954Z","caller":"traceutil/trace.go:171","msg":"trace[737464283] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:820; }","duration":"285.511463ms","start":"2026-06-09T17:05:38.328343Z","end":"2026-06-09T17:05:38.613854Z","steps":["trace[737464283] 'agreement among raft nodes before linearized reading' (duration: 284.990532ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:05:38.61452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.232972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:05:38.615432Z","caller":"traceutil/trace.go:171","msg":"trace[968544528] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:820; }","duration":"238.160132ms","start":"2026-06-09T17:05:38.377239Z","end":"2026-06-09T17:05:38.615399Z","steps":["trace[968544528] 'agreement among raft nodes before linearized reading' (duration: 237.248841ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:05:38.615057Z","caller":"traceutil/trace.go:171","msg":"trace[311231874] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:820; }","duration":"254.662255ms","start":"2026-06-09T17:05:38.360346Z","end":"2026-06-09T17:05:38.615008Z","steps":["trace[311231874] 'agreement among raft nodes before linearized reading' (duration: 253.948909ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:05:43.839373Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8397417447545882627,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-09T17:05:44.33982Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8397417447545882627,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-09T17:05:44.435913Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.19734458s","expected-duration":"1s"} {"level":"info","ts":"2026-06-09T17:05:44.436525Z","caller":"traceutil/trace.go:171","msg":"trace[65519672] transaction","detail":"{read_only:false; response_revision:841; number_of_response:1; }","duration":"1.198034006s","start":"2026-06-09T17:05:43.238466Z","end":"2026-06-09T17:05:44.4365Z","steps":["trace[65519672] 'process raft request' (duration: 1.19779661s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:05:44.436705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:05:43.238444Z","time spent":"1.198184089s","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:05:44.439991Z","caller":"traceutil/trace.go:171","msg":"trace[1338877191] linearizableReadLoop","detail":"{readStateIndex:872; appliedIndex:870; }","duration":"1.101064319s","start":"2026-06-09T17:05:43.338882Z","end":"2026-06-09T17:05:44.439946Z","steps":["trace[1338877191] 'read index received' (duration: 1.097363827s)","trace[1338877191] 'applied index is now lower than readState.Index' (duration: 3.698732ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:05:44.440084Z","caller":"traceutil/trace.go:171","msg":"trace[817452462] transaction","detail":"{read_only:false; response_revision:842; number_of_response:1; }","duration":"537.704515ms","start":"2026-06-09T17:05:43.902352Z","end":"2026-06-09T17:05:44.440057Z","steps":["trace[817452462] 'process raft request' (duration: 537.501351ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:05:44.440176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.992233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-09T17:05:44.440203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:05:43.902328Z","time spent":"537.812337ms","remote":"127.0.0.1:41608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:05:44.44024Z","caller":"traceutil/trace.go:171","msg":"trace[1507197355] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:842; }","duration":"421.069074ms","start":"2026-06-09T17:05:44.019137Z","end":"2026-06-09T17:05:44.440206Z","steps":["trace[1507197355] 'agreement among raft nodes before linearized reading' (duration: 421.000593ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:05:44.44026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:05:44.019115Z","time spent":"421.139516ms","remote":"127.0.0.1:41782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-06-09T17:05:44.44026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.021808223s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-09T17:05:44.440323Z","caller":"traceutil/trace.go:171","msg":"trace[360542315] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:842; }","duration":"1.021909464s","start":"2026-06-09T17:05:43.418398Z","end":"2026-06-09T17:05:44.440307Z","steps":["trace[360542315] 'agreement among raft nodes before linearized reading' (duration: 1.02172209s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:05:44.440377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:05:43.418374Z","time spent":"1.021990327s","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-09T17:05:44.440481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.014020461s","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-09T17:05:44.440503Z","caller":"traceutil/trace.go:171","msg":"trace[1992502251] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:842; }","duration":"1.014065391s","start":"2026-06-09T17:05:43.426432Z","end":"2026-06-09T17:05:44.440498Z","steps":["trace[1992502251] 'agreement among raft nodes before linearized reading' (duration: 1.0139885s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:05:44.440516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:05:43.426421Z","time spent":"1.014091973s","remote":"127.0.0.1:41630","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-09T17:05:44.440512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.148845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:05:44.440643Z","caller":"traceutil/trace.go:171","msg":"trace[538541904] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:842; }","duration":"524.341459ms","start":"2026-06-09T17:05:43.916252Z","end":"2026-06-09T17:05:44.440593Z","steps":["trace[538541904] 'agreement among raft nodes before linearized reading' (duration: 524.154625ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:05:44.440791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:05:43.91624Z","time spent":"524.535344ms","remote":"127.0.0.1:41382","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-09T17:05:44.440906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.101927498s","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-09T17:05:44.441216Z","caller":"traceutil/trace.go:171","msg":"trace[406055942] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:842; }","duration":"1.102344237s","start":"2026-06-09T17:05:43.338849Z","end":"2026-06-09T17:05:44.441193Z","steps":["trace[406055942] 'agreement among raft nodes before linearized reading' (duration: 1.101274203s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:05:44.441571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:05:43.338834Z","time spent":"1.102717115s","remote":"127.0.0.1:41630","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-09T17:05:48.888081Z","caller":"traceutil/trace.go:171","msg":"trace[1002026467] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"131.22346ms","start":"2026-06-09T17:05:48.756838Z","end":"2026-06-09T17:05:48.888061Z","steps":["trace[1002026467] 'process raft request' (duration: 123.072749ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:05:50.196623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.016415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/eg\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:05:50.196747Z","caller":"traceutil/trace.go:171","msg":"trace[365981904] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/eg; range_end:; response_count:0; response_revision:896; }","duration":"125.1856ms","start":"2026-06-09T17:05:50.071544Z","end":"2026-06-09T17:05:50.19673Z","steps":["trace[365981904] 'range keys from in-memory index tree' (duration: 124.942773ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:05:51.044644Z","caller":"traceutil/trace.go:171","msg":"trace[428782755] transaction","detail":"{read_only:false; response_revision:908; number_of_response:1; }","duration":"119.592125ms","start":"2026-06-09T17:05:50.925025Z","end":"2026-06-09T17:05:51.044617Z","steps":["trace[428782755] 'process raft request' (duration: 119.439512ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:05:51.244138Z","caller":"traceutil/trace.go:171","msg":"trace[2010483181] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"192.022481ms","start":"2026-06-09T17:05:51.052091Z","end":"2026-06-09T17:05:51.244113Z","steps":["trace[2010483181] 'process raft request' (duration: 172.530579ms)","trace[2010483181] 'compare' (duration: 19.364919ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:05:51.248618Z","caller":"traceutil/trace.go:171","msg":"trace[1999441405] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"195.989518ms","start":"2026-06-09T17:05:51.052597Z","end":"2026-06-09T17:05:51.248587Z","steps":["trace[1999441405] 'process raft request' (duration: 195.844824ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:05:54.485726Z","caller":"traceutil/trace.go:171","msg":"trace[306518420] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"124.581411ms","start":"2026-06-09T17:05:54.361126Z","end":"2026-06-09T17:05:54.485708Z","steps":["trace[306518420] 'process raft request' (duration: 124.466549ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:06:03.741645Z","caller":"traceutil/trace.go:171","msg":"trace[935557592] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"361.800931ms","start":"2026-06-09T17:06:03.379821Z","end":"2026-06-09T17:06:03.741622Z","steps":["trace[935557592] 'process raft request' (duration: 361.631517ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:06:03.741781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:06:03.379798Z","time spent":"361.913034ms","remote":"127.0.0.1:41544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5316,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:06:13.131526Z","caller":"traceutil/trace.go:171","msg":"trace[1078679246] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"133.124364ms","start":"2026-06-09T17:06:12.998369Z","end":"2026-06-09T17:06:13.131494Z","steps":["trace[1078679246] 'process raft request' (duration: 40.073975ms)","trace[1078679246] 'compare' (duration: 92.762863ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:06:13.131683Z","caller":"traceutil/trace.go:171","msg":"trace[2036960678] linearizableReadLoop","detail":"{readStateIndex:1099; appliedIndex:1097; }","duration":"104.475164ms","start":"2026-06-09T17:06:13.027176Z","end":"2026-06-09T17:06:13.131652Z","steps":["trace[2036960678] 'read index received' (duration: 11.353635ms)","trace[2036960678] 'applied index is now lower than readState.Index' (duration: 93.120009ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:06:13.131807Z","caller":"traceutil/trace.go:171","msg":"trace[694645996] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"132.443057ms","start":"2026-06-09T17:06:12.999351Z","end":"2026-06-09T17:06:13.131794Z","steps":["trace[694645996] 'process raft request' (duration: 132.08146ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:06:13.132053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.116562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-06-09T17:06:13.132159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.968185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-06-09T17:06:13.132162Z","caller":"traceutil/trace.go:171","msg":"trace[1356775525] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1062; }","duration":"103.264726ms","start":"2026-06-09T17:06:13.02887Z","end":"2026-06-09T17:06:13.132134Z","steps":["trace[1356775525] 'agreement among raft nodes before linearized reading' (duration: 102.98962ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:06:13.132232Z","caller":"traceutil/trace.go:171","msg":"trace[248446104] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1062; }","duration":"105.046326ms","start":"2026-06-09T17:06:13.027161Z","end":"2026-06-09T17:06:13.132207Z","steps":["trace[248446104] 'agreement among raft nodes before linearized reading' (duration: 104.76027ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:06:13.271907Z","caller":"traceutil/trace.go:171","msg":"trace[760638379] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"135.138669ms","start":"2026-06-09T17:06:13.136742Z","end":"2026-06-09T17:06:13.27188Z","steps":["trace[760638379] 'process raft request' (duration: 125.525474ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:06:13.273947Z","caller":"traceutil/trace.go:171","msg":"trace[867378317] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"137.098513ms","start":"2026-06-09T17:06:13.136826Z","end":"2026-06-09T17:06:13.273925Z","steps":["trace[867378317] 'process raft request' (duration: 136.040119ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:06:17.136702Z","caller":"traceutil/trace.go:171","msg":"trace[429857974] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"119.525264ms","start":"2026-06-09T17:06:17.017149Z","end":"2026-06-09T17:06:17.136674Z","steps":["trace[429857974] 'process raft request' (duration: 119.309158ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:06:18.120976Z","caller":"traceutil/trace.go:171","msg":"trace[253642311] transaction","detail":"{read_only:false; response_revision:1080; number_of_response:1; }","duration":"104.151661ms","start":"2026-06-09T17:06:18.0168Z","end":"2026-06-09T17:06:18.120951Z","steps":["trace[253642311] 'process raft request' (duration: 104.006118ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:06:18.412548Z","caller":"traceutil/trace.go:171","msg":"trace[529020308] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1083; }","duration":"127.262718ms","start":"2026-06-09T17:06:18.285255Z","end":"2026-06-09T17:06:18.412517Z","steps":["trace[529020308] 'process raft request' (duration: 48.136237ms)","trace[529020308] 'compare' (duration: 78.968288ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:06:18.989718Z","caller":"traceutil/trace.go:171","msg":"trace[1805824653] transaction","detail":"{read_only:false; response_revision:1084; number_of_response:1; }","duration":"149.867844ms","start":"2026-06-09T17:06:18.839825Z","end":"2026-06-09T17:06:18.989693Z","steps":["trace[1805824653] 'process raft request' (duration: 149.645659ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:06:19.513583Z","caller":"traceutil/trace.go:171","msg":"trace[875178084] linearizableReadLoop","detail":"{readStateIndex:1125; appliedIndex:1123; }","duration":"202.138525ms","start":"2026-06-09T17:06:19.311411Z","end":"2026-06-09T17:06:19.513549Z","steps":["trace[875178084] 'read index received' (duration: 3.924719ms)","trace[875178084] 'applied index is now lower than readState.Index' (duration: 198.212556ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:06:19.513694Z","caller":"traceutil/trace.go:171","msg":"trace[195352418] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"292.036826ms","start":"2026-06-09T17:06:19.22162Z","end":"2026-06-09T17:06:19.513657Z","steps":["trace[195352418] 'process raft request' (duration: 215.635756ms)","trace[195352418] 'compare' (duration: 76.167385ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:06:19.513818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.280893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-09T17:06:19.513878Z","caller":"traceutil/trace.go:171","msg":"trace[747755773] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1087; }","duration":"179.368664ms","start":"2026-06-09T17:06:19.334483Z","end":"2026-06-09T17:06:19.513852Z","steps":["trace[747755773] 'agreement among raft nodes before linearized reading' (duration: 179.221351ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:06:19.513932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.469952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-09T17:06:19.514033Z","caller":"traceutil/trace.go:171","msg":"trace[37531769] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1087; }","duration":"202.647236ms","start":"2026-06-09T17:06:19.311363Z","end":"2026-06-09T17:06:19.51401Z","steps":["trace[37531769] 'agreement among raft nodes before linearized reading' (duration: 202.317439ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:06:25.672478Z","caller":"traceutil/trace.go:171","msg":"trace[335230513] linearizableReadLoop","detail":"{readStateIndex:1146; appliedIndex:1145; }","duration":"102.739274ms","start":"2026-06-09T17:06:25.569718Z","end":"2026-06-09T17:06:25.672457Z","steps":["trace[335230513] 'read index received' (duration: 102.295054ms)","trace[335230513] 'applied index is now lower than readState.Index' (duration: 443.28µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:06:25.672673Z","caller":"traceutil/trace.go:171","msg":"trace[748041523] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"102.97852ms","start":"2026-06-09T17:06:25.569672Z","end":"2026-06-09T17:06:25.67265Z","steps":["trace[748041523] 'process raft request' (duration: 102.407688ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:06:25.672709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.99654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-09T17:06:25.672742Z","caller":"traceutil/trace.go:171","msg":"trace[1175341445] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1107; }","duration":"103.086082ms","start":"2026-06-09T17:06:25.569645Z","end":"2026-06-09T17:06:25.672732Z","steps":["trace[1175341445] 'agreement among raft nodes before linearized reading' (duration: 102.895598ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:06:33.44404Z","caller":"traceutil/trace.go:171","msg":"trace[504819597] transaction","detail":"{read_only:false; response_revision:1130; number_of_response:1; }","duration":"104.967799ms","start":"2026-06-09T17:06:33.339035Z","end":"2026-06-09T17:06:33.444003Z","steps":["trace[504819597] 'process raft request' (duration: 104.673223ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:06:34.363575Z","caller":"traceutil/trace.go:171","msg":"trace[510778930] transaction","detail":"{read_only:false; response_revision:1136; number_of_response:1; }","duration":"114.204697ms","start":"2026-06-09T17:06:34.249346Z","end":"2026-06-09T17:06:34.36355Z","steps":["trace[510778930] 'process raft request' (duration: 114.058523ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:06:39.938109Z","caller":"traceutil/trace.go:171","msg":"trace[880917385] transaction","detail":"{read_only:false; response_revision:1155; number_of_response:1; }","duration":"119.157822ms","start":"2026-06-09T17:06:39.818919Z","end":"2026-06-09T17:06:39.938077Z","steps":["trace[880917385] 'process raft request' (duration: 63.833896ms)","trace[880917385] 'compare' (duration: 55.219744ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:06:39.938093Z","caller":"traceutil/trace.go:171","msg":"trace[561233530] linearizableReadLoop","detail":"{readStateIndex:1197; appliedIndex:1196; }","duration":"109.608088ms","start":"2026-06-09T17:06:39.828452Z","end":"2026-06-09T17:06:39.93806Z","steps":["trace[561233530] 'read index received' (duration: 54.228031ms)","trace[561233530] 'applied index is now lower than readState.Index' (duration: 55.378967ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:06:39.938368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.902754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-09T17:06:39.938447Z","caller":"traceutil/trace.go:171","msg":"trace[1620616117] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1155; }","duration":"110.006406ms","start":"2026-06-09T17:06:39.828423Z","end":"2026-06-09T17:06:39.93843Z","steps":["trace[1620616117] 'agreement among raft nodes before linearized reading' (duration: 109.73056ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:06:43.333429Z","caller":"traceutil/trace.go:171","msg":"trace[679110350] transaction","detail":"{read_only:false; response_revision:1163; number_of_response:1; }","duration":"161.946468ms","start":"2026-06-09T17:06:43.171449Z","end":"2026-06-09T17:06:43.333396Z","steps":["trace[679110350] 'process raft request' (duration: 161.761863ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:06:52.038123Z","caller":"traceutil/trace.go:171","msg":"trace[625917375] linearizableReadLoop","detail":"{readStateIndex:1236; appliedIndex:1235; }","duration":"120.864168ms","start":"2026-06-09T17:06:51.917238Z","end":"2026-06-09T17:06:52.038102Z","steps":["trace[625917375] 'read index received' (duration: 120.830488ms)","trace[625917375] 'applied index is now lower than readState.Index' (duration: 32.7µs)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:06:52.038278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.034952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:06:52.038362Z","caller":"traceutil/trace.go:171","msg":"trace[1399896464] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1192; }","duration":"121.135534ms","start":"2026-06-09T17:06:51.917165Z","end":"2026-06-09T17:06:52.038301Z","steps":["trace[1399896464] 'agreement among raft nodes before linearized reading' (duration: 121.066532ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:06:52.038251Z","caller":"traceutil/trace.go:171","msg":"trace[1366141931] transaction","detail":"{read_only:false; response_revision:1192; number_of_response:1; }","duration":"207.257757ms","start":"2026-06-09T17:06:51.83094Z","end":"2026-06-09T17:06:52.038198Z","steps":["trace[1366141931] 'process raft request' (duration: 207.032542ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:06:52.261293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.263398ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-09T17:06:52.261605Z","caller":"traceutil/trace.go:171","msg":"trace[150011981] transaction","detail":"{read_only:false; response_revision:1193; number_of_response:1; }","duration":"218.055181ms","start":"2026-06-09T17:06:52.04352Z","end":"2026-06-09T17:06:52.261576Z","steps":["trace[150011981] 'process raft request' (duration: 111.383963ms)","trace[150011981] 'compare' (duration: 105.815019ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:06:52.261613Z","caller":"traceutil/trace.go:171","msg":"trace[1538070037] linearizableReadLoop","detail":"{readStateIndex:1237; appliedIndex:1236; }","duration":"185.137338ms","start":"2026-06-09T17:06:52.076459Z","end":"2026-06-09T17:06:52.261596Z","steps":["trace[1538070037] 'read index received' (duration: 78.465731ms)","trace[1538070037] 'applied index is now lower than readState.Index' (duration: 106.670127ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:06:52.308464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.975576ms","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-09T17:06:52.308567Z","caller":"traceutil/trace.go:171","msg":"trace[123249420] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1193; }","duration":"232.14952ms","start":"2026-06-09T17:06:52.076392Z","end":"2026-06-09T17:06:52.308542Z","steps":["trace[123249420] 'agreement among raft nodes before linearized reading' (duration: 185.259301ms)","trace[123249420] 'range keys from in-memory index tree' (duration: 46.605923ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:06:54.221498Z","caller":"traceutil/trace.go:171","msg":"trace[554128535] transaction","detail":"{read_only:false; response_revision:1198; number_of_response:1; }","duration":"175.885582ms","start":"2026-06-09T17:06:54.045589Z","end":"2026-06-09T17:06:54.221474Z","steps":["trace[554128535] 'process raft request' (duration: 175.749689ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:07:10.04585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.675417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:07:10.045969Z","caller":"traceutil/trace.go:171","msg":"trace[1202953221] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1250; }","duration":"130.82347ms","start":"2026-06-09T17:07:09.915122Z","end":"2026-06-09T17:07:10.045945Z","steps":["trace[1202953221] 'range keys from in-memory index tree' (duration: 130.598875ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:07:10.045963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.247544ms","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-09T17:07:10.046041Z","caller":"traceutil/trace.go:171","msg":"trace[1544829855] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1250; }","duration":"144.378707ms","start":"2026-06-09T17:07:09.90164Z","end":"2026-06-09T17:07:10.046019Z","steps":["trace[1544829855] 'range keys from in-memory index tree' (duration: 143.751044ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:07:16.727536Z","caller":"traceutil/trace.go:171","msg":"trace[1262299545] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"100.157269ms","start":"2026-06-09T17:07:16.627343Z","end":"2026-06-09T17:07:16.727501Z","steps":["trace[1262299545] 'process raft request' (duration: 99.820801ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:07:16.999556Z","caller":"traceutil/trace.go:171","msg":"trace[34558320] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"267.016749ms","start":"2026-06-09T17:07:16.732503Z","end":"2026-06-09T17:07:16.99952Z","steps":["trace[34558320] 'process raft request' (duration: 215.483032ms)","trace[34558320] 'compare' (duration: 51.362493ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:07:17.045623Z","caller":"traceutil/trace.go:171","msg":"trace[1995662182] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"312.98659ms","start":"2026-06-09T17:07:16.732562Z","end":"2026-06-09T17:07:17.045549Z","steps":["trace[1995662182] 'process raft request' (duration: 312.826837ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:07:17.045915Z","caller":"traceutil/trace.go:171","msg":"trace[160305208] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"313.249957ms","start":"2026-06-09T17:07:16.732612Z","end":"2026-06-09T17:07:17.045862Z","steps":["trace[160305208] 'process raft request' (duration: 312.903018ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:07:17.046077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:07:16.732572Z","time spent":"313.415771ms","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:07:17.04599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:07:16.732546Z","time spent":"313.152244ms","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:07:24.423391Z","caller":"traceutil/trace.go:171","msg":"trace[630634746] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"114.164269ms","start":"2026-06-09T17:07:24.309203Z","end":"2026-06-09T17:07:24.423367Z","steps":["trace[630634746] 'process raft request' (duration: 114.044707ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:07:25.903408Z","caller":"traceutil/trace.go:171","msg":"trace[1033528428] transaction","detail":"{read_only:false; response_revision:1315; number_of_response:1; }","duration":"136.410544ms","start":"2026-06-09T17:07:25.766962Z","end":"2026-06-09T17:07:25.903373Z","steps":["trace[1033528428] 'process raft request' (duration: 136.205ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:07:25.920251Z","caller":"traceutil/trace.go:171","msg":"trace[697050955] transaction","detail":"{read_only:false; response_revision:1316; number_of_response:1; }","duration":"109.118975ms","start":"2026-06-09T17:07:25.811116Z","end":"2026-06-09T17:07:25.920235Z","steps":["trace[697050955] 'process raft request' (duration: 109.053093ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:07:26.426708Z","caller":"traceutil/trace.go:171","msg":"trace[492512436] linearizableReadLoop","detail":"{readStateIndex:1371; appliedIndex:1370; }","duration":"168.893511ms","start":"2026-06-09T17:07:26.257789Z","end":"2026-06-09T17:07:26.426682Z","steps":["trace[492512436] 'read index received' (duration: 168.324838ms)","trace[492512436] 'applied index is now lower than readState.Index' (duration: 484.771µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:07:26.426793Z","caller":"traceutil/trace.go:171","msg":"trace[1656678238] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"181.027797ms","start":"2026-06-09T17:07:26.245741Z","end":"2026-06-09T17:07:26.426769Z","steps":["trace[1656678238] 'process raft request' (duration: 180.504414ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:07:26.427036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.184498ms","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-09T17:07:26.427106Z","caller":"traceutil/trace.go:171","msg":"trace[277568468] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1319; }","duration":"169.330121ms","start":"2026-06-09T17:07:26.257741Z","end":"2026-06-09T17:07:26.427071Z","steps":["trace[277568468] 'agreement among raft nodes before linearized reading' (duration: 169.059135ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:07:29.776748Z","caller":"traceutil/trace.go:171","msg":"trace[267227688] linearizableReadLoop","detail":"{readStateIndex:1399; appliedIndex:1397; }","duration":"166.405126ms","start":"2026-06-09T17:07:29.610297Z","end":"2026-06-09T17:07:29.776702Z","steps":["trace[267227688] 'read index received' (duration: 23.555695ms)","trace[267227688] 'applied index is now lower than readState.Index' (duration: 142.847001ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:07:29.776834Z","caller":"traceutil/trace.go:171","msg":"trace[607288641] transaction","detail":"{read_only:false; response_revision:1345; number_of_response:1; }","duration":"176.167278ms","start":"2026-06-09T17:07:29.600608Z","end":"2026-06-09T17:07:29.776776Z","steps":["trace[607288641] 'process raft request' (duration: 174.837387ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:07:29.777121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.155104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:07:29.777243Z","caller":"traceutil/trace.go:171","msg":"trace[911955728] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1345; }","duration":"127.358089ms","start":"2026-06-09T17:07:29.649835Z","end":"2026-06-09T17:07:29.777193Z","steps":["trace[911955728] 'agreement among raft nodes before linearized reading' (duration: 127.169725ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:07:29.777265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.931927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:1 size:1465"} {"level":"info","ts":"2026-06-09T17:07:29.777341Z","caller":"traceutil/trace.go:171","msg":"trace[1228333201] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:1; response_revision:1345; }","duration":"167.094082ms","start":"2026-06-09T17:07:29.610224Z","end":"2026-06-09T17:07:29.777318Z","steps":["trace[1228333201] 'agreement among raft nodes before linearized reading' (duration: 166.738783ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:07:30.775231Z","caller":"traceutil/trace.go:171","msg":"trace[1524889036] transaction","detail":"{read_only:false; response_revision:1350; number_of_response:1; }","duration":"105.847931ms","start":"2026-06-09T17:07:30.669361Z","end":"2026-06-09T17:07:30.775208Z","steps":["trace[1524889036] 'process raft request' (duration: 105.703488ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:07:38.106602Z","caller":"traceutil/trace.go:171","msg":"trace[1964690508] transaction","detail":"{read_only:false; response_revision:1380; number_of_response:1; }","duration":"100.067422ms","start":"2026-06-09T17:07:38.006507Z","end":"2026-06-09T17:07:38.106574Z","steps":["trace[1964690508] 'process raft request' (duration: 99.874578ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:07:40.756949Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8397417447545883926,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-09T17:07:40.839571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"922.995383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:07:40.839678Z","caller":"traceutil/trace.go:171","msg":"trace[272562185] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1388; }","duration":"923.135987ms","start":"2026-06-09T17:07:39.916522Z","end":"2026-06-09T17:07:40.839658Z","steps":["trace[272562185] 'range keys from in-memory index tree' (duration: 922.917652ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:07:40.839721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:07:39.916506Z","time spent":"923.205528ms","remote":"127.0.0.1:41382","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-09T17:07:41.129321Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.016787994s","expected-duration":"1s"} {"level":"info","ts":"2026-06-09T17:07:41.129805Z","caller":"traceutil/trace.go:171","msg":"trace[400653284] linearizableReadLoop","detail":"{readStateIndex:1445; appliedIndex:1444; }","duration":"873.770126ms","start":"2026-06-09T17:07:40.256009Z","end":"2026-06-09T17:07:41.129779Z","steps":["trace[400653284] 'read index received' (duration: 873.622533ms)","trace[400653284] 'applied index is now lower than readState.Index' (duration: 146.383µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:07:41.129842Z","caller":"traceutil/trace.go:171","msg":"trace[1916486356] transaction","detail":"{read_only:false; response_revision:1389; number_of_response:1; }","duration":"1.017391988s","start":"2026-06-09T17:07:40.112427Z","end":"2026-06-09T17:07:41.129819Z","steps":["trace[1916486356] 'process raft request' (duration: 1.017110181s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:07:41.129993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:07:40.112398Z","time spent":"1.017510131s","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:07:41.130088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"801.795771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:07:41.130162Z","caller":"traceutil/trace.go:171","msg":"trace[2033025588] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:1389; }","duration":"801.889624ms","start":"2026-06-09T17:07:40.328228Z","end":"2026-06-09T17:07:41.130118Z","steps":["trace[2033025588] 'agreement among raft nodes before linearized reading' (duration: 801.7083ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:07:41.130208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"767.080063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-09T17:07:41.130225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:07:40.328202Z","time spent":"802.005335ms","remote":"127.0.0.1:35772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"info","ts":"2026-06-09T17:07:41.130248Z","caller":"traceutil/trace.go:171","msg":"trace[59019126] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1389; }","duration":"767.162955ms","start":"2026-06-09T17:07:40.363074Z","end":"2026-06-09T17:07:41.130237Z","steps":["trace[59019126] 'agreement among raft nodes before linearized reading' (duration: 767.096504ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:07:41.130276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:07:40.363053Z","time spent":"767.216096ms","remote":"127.0.0.1:41512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"warn","ts":"2026-06-09T17:07:41.130358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.018838ms","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-09T17:07:41.130435Z","caller":"traceutil/trace.go:171","msg":"trace[1737608613] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1389; }","duration":"261.13961ms","start":"2026-06-09T17:07:40.869273Z","end":"2026-06-09T17:07:41.130413Z","steps":["trace[1737608613] 'agreement among raft nodes before linearized reading' (duration: 260.826723ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:07:41.130547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.997895ms","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-09T17:07:41.130571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.000153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:07:41.130675Z","caller":"traceutil/trace.go:171","msg":"trace[1671793697] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1389; }","duration":"566.304212ms","start":"2026-06-09T17:07:40.564343Z","end":"2026-06-09T17:07:41.130647Z","steps":["trace[1671793697] 'agreement among raft nodes before linearized reading' (duration: 565.854022ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:07:41.130731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"874.728047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-09T17:07:41.130782Z","caller":"traceutil/trace.go:171","msg":"trace[1263652848] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1389; }","duration":"874.780508ms","start":"2026-06-09T17:07:40.255987Z","end":"2026-06-09T17:07:41.130768Z","steps":["trace[1263652848] 'agreement among raft nodes before linearized reading' (duration: 874.068412ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:07:41.130779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:07:40.564323Z","time spent":"566.411884ms","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-09T17:07:41.130821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:07:40.255913Z","time spent":"874.899781ms","remote":"127.0.0.1:41522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":30,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"info","ts":"2026-06-09T17:07:41.130714Z","caller":"traceutil/trace.go:171","msg":"trace[228263532] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1389; }","duration":"289.211618ms","start":"2026-06-09T17:07:40.841475Z","end":"2026-06-09T17:07:41.130687Z","steps":["trace[228263532] 'agreement among raft nodes before linearized reading' (duration: 288.940722ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:07:41.130626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.367792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-09T17:07:41.131054Z","caller":"traceutil/trace.go:171","msg":"trace[162072589] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1389; }","duration":"123.74661ms","start":"2026-06-09T17:07:41.007209Z","end":"2026-06-09T17:07:41.130955Z","steps":["trace[162072589] 'agreement among raft nodes before linearized reading' (duration: 123.28959ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:07:41.379908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.624394ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-09T17:07:41.379996Z","caller":"traceutil/trace.go:171","msg":"trace[1860570367] transaction","detail":"{read_only:false; response_revision:1390; number_of_response:1; }","duration":"243.172053ms","start":"2026-06-09T17:07:41.136806Z","end":"2026-06-09T17:07:41.379978Z","steps":["trace[1860570367] 'process raft request' (duration: 80.397055ms)","trace[1860570367] 'compare' (duration: 162.540702ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:07:41.645683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.808881ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-09T17:07:41.645967Z","caller":"traceutil/trace.go:171","msg":"trace[303758462] linearizableReadLoop","detail":"{readStateIndex:1448; appliedIndex:1445; }","duration":"506.021034ms","start":"2026-06-09T17:07:41.139922Z","end":"2026-06-09T17:07:41.645943Z","steps":["trace[303758462] 'read index received' (duration: 77.283895ms)","trace[303758462] 'applied index is now lower than readState.Index' (duration: 428.732709ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:07:41.646011Z","caller":"traceutil/trace.go:171","msg":"trace[395276476] transaction","detail":"{read_only:false; response_revision:1391; number_of_response:1; }","duration":"508.652644ms","start":"2026-06-09T17:07:41.137318Z","end":"2026-06-09T17:07:41.645971Z","steps":["trace[395276476] 'process raft request' (duration: 355.423244ms)","trace[395276476] 'compare' (duration: 152.643287ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:07:41.646172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:07:41.137298Z","time spent":"508.780587ms","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:07:41.646264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.546747ms","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-09T17:07:41.646261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.309581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:07:41.646308Z","caller":"traceutil/trace.go:171","msg":"trace[96018784] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1392; }","duration":"344.626979ms","start":"2026-06-09T17:07:41.301668Z","end":"2026-06-09T17:07:41.646295Z","steps":["trace[96018784] 'agreement among raft nodes before linearized reading' (duration: 344.413893ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:07:41.646293Z","caller":"traceutil/trace.go:171","msg":"trace[434965708] transaction","detail":"{read_only:false; response_revision:1392; number_of_response:1; }","duration":"508.555293ms","start":"2026-06-09T17:07:41.137701Z","end":"2026-06-09T17:07:41.646256Z","steps":["trace[434965708] 'process raft request' (duration: 508.134463ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:07:41.646349Z","caller":"traceutil/trace.go:171","msg":"trace[1429799574] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1392; }","duration":"506.431893ms","start":"2026-06-09T17:07:41.139896Z","end":"2026-06-09T17:07:41.646327Z","steps":["trace[1429799574] 'agreement among raft nodes before linearized reading' (duration: 506.191947ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:07:41.646356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:07:41.301651Z","time spent":"344.694891ms","remote":"127.0.0.1:41630","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-09T17:07:41.64645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:07:41.139887Z","time spent":"506.553157ms","remote":"127.0.0.1:41382","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-09T17:07:41.64654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.221078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-09T17:07:41.646569Z","caller":"traceutil/trace.go:171","msg":"trace[1851179874] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1392; }","duration":"292.28452ms","start":"2026-06-09T17:07:41.354277Z","end":"2026-06-09T17:07:41.646562Z","steps":["trace[1851179874] 'agreement among raft nodes before linearized reading' (duration: 292.170697ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:07:41.646751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.541472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:07:41.646863Z","caller":"traceutil/trace.go:171","msg":"trace[262444986] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1392; }","duration":"243.90439ms","start":"2026-06-09T17:07:41.402936Z","end":"2026-06-09T17:07:41.646841Z","steps":["trace[262444986] 'agreement among raft nodes before linearized reading' (duration: 243.259664ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:07:41.647009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:07:41.137658Z","time spent":"509.287459ms","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:07:42.589157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.287929ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-09T17:07:42.589963Z","caller":"traceutil/trace.go:171","msg":"trace[743824035] transaction","detail":"{read_only:false; response_revision:1394; number_of_response:1; }","duration":"937.571018ms","start":"2026-06-09T17:07:41.652356Z","end":"2026-06-09T17:07:42.589927Z","steps":["trace[743824035] 'process raft request' (duration: 313.406519ms)","trace[743824035] 'compare' (duration: 623.107745ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:07:42.59013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:07:41.652327Z","time spent":"937.715032ms","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:07:43.008005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.730672ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-09T17:07:43.008316Z","caller":"traceutil/trace.go:171","msg":"trace[398176096] linearizableReadLoop","detail":"{readStateIndex:1451; appliedIndex:1449; }","duration":"1.123724456s","start":"2026-06-09T17:07:41.88437Z","end":"2026-06-09T17:07:43.008094Z","steps":["trace[398176096] 'read index received' (duration: 81.322617ms)","trace[398176096] 'applied index is now lower than readState.Index' (duration: 1.042400479s)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:07:43.008454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.124064943s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:07:43.008539Z","caller":"traceutil/trace.go:171","msg":"trace[310401641] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1394; }","duration":"1.124204916s","start":"2026-06-09T17:07:41.884314Z","end":"2026-06-09T17:07:43.008519Z","steps":["trace[310401641] 'agreement among raft nodes before linearized reading' (duration: 1.124056333s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:07:43.008588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:07:41.884292Z","time spent":"1.124285219s","remote":"127.0.0.1:35582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"warn","ts":"2026-06-09T17:07:43.008809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.092425867s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:07:43.008891Z","caller":"traceutil/trace.go:171","msg":"trace[1705315086] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1394; }","duration":"1.092554729s","start":"2026-06-09T17:07:41.916318Z","end":"2026-06-09T17:07:43.008873Z","steps":["trace[1705315086] 'agreement among raft nodes before linearized reading' (duration: 1.092419846s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:07:43.008936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:07:41.916297Z","time spent":"1.092631181s","remote":"127.0.0.1:41368","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-09T17:07:43.00883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"880.772677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-09T17:07:43.009074Z","caller":"traceutil/trace.go:171","msg":"trace[1211531188] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1394; }","duration":"881.057093ms","start":"2026-06-09T17:07:42.127991Z","end":"2026-06-09T17:07:43.009048Z","steps":["trace[1211531188] 'agreement among raft nodes before linearized reading' (duration: 880.778497ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:07:43.009196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:07:42.12797Z","time spent":"881.195276ms","remote":"127.0.0.1:35290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"info","ts":"2026-06-09T17:07:45.620948Z","caller":"traceutil/trace.go:171","msg":"trace[622934311] transaction","detail":"{read_only:false; response_revision:1407; number_of_response:1; }","duration":"120.702082ms","start":"2026-06-09T17:07:45.50022Z","end":"2026-06-09T17:07:45.620922Z","steps":["trace[622934311] 'process raft request' (duration: 120.545299ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:07:49.989225Z","caller":"traceutil/trace.go:171","msg":"trace[1263669092] transaction","detail":"{read_only:false; response_revision:1424; number_of_response:1; }","duration":"105.342494ms","start":"2026-06-09T17:07:49.883855Z","end":"2026-06-09T17:07:49.989197Z","steps":["trace[1263669092] 'process raft request' (duration: 100.131155ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:07:50.785013Z","caller":"traceutil/trace.go:171","msg":"trace[1816193796] transaction","detail":"{read_only:false; response_revision:1425; number_of_response:1; }","duration":"152.483384ms","start":"2026-06-09T17:07:50.632502Z","end":"2026-06-09T17:07:50.784986Z","steps":["trace[1816193796] 'process raft request' (duration: 152.292399ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:07:51.050728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.483907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:07:51.050808Z","caller":"traceutil/trace.go:171","msg":"trace[447589367] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1425; }","duration":"134.609448ms","start":"2026-06-09T17:07:50.916179Z","end":"2026-06-09T17:07:51.050788Z","steps":["trace[447589367] 'range keys from in-memory index tree' (duration: 134.412496ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:07:51.372137Z","caller":"traceutil/trace.go:171","msg":"trace[2037245124] transaction","detail":"{read_only:false; response_revision:1426; number_of_response:1; }","duration":"146.798179ms","start":"2026-06-09T17:07:51.225299Z","end":"2026-06-09T17:07:51.372097Z","steps":["trace[2037245124] 'process raft request' (duration: 146.593677ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:07:52.201215Z","caller":"traceutil/trace.go:171","msg":"trace[603992331] transaction","detail":"{read_only:false; response_revision:1430; number_of_response:1; }","duration":"203.323089ms","start":"2026-06-09T17:07:51.997866Z","end":"2026-06-09T17:07:52.201189Z","steps":["trace[603992331] 'process raft request' (duration: 203.174258ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:07:52.207368Z","caller":"traceutil/trace.go:171","msg":"trace[42620566] linearizableReadLoop","detail":"{readStateIndex:1490; appliedIndex:1489; }","duration":"164.832438ms","start":"2026-06-09T17:07:52.042505Z","end":"2026-06-09T17:07:52.207338Z","steps":["trace[42620566] 'read index received' (duration: 158.719599ms)","trace[42620566] 'applied index is now lower than readState.Index' (duration: 6.111329ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:07:52.208068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.542145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329944"} {"level":"info","ts":"2026-06-09T17:07:52.208149Z","caller":"traceutil/trace.go:171","msg":"trace[370042704] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:1430; }","duration":"165.659345ms","start":"2026-06-09T17:07:52.042456Z","end":"2026-06-09T17:07:52.208115Z","steps":["trace[370042704] 'agreement among raft nodes before linearized reading' (duration: 164.99267ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:07:54.428004Z","caller":"traceutil/trace.go:171","msg":"trace[881927237] linearizableReadLoop","detail":"{readStateIndex:1496; appliedIndex:1495; }","duration":"217.921782ms","start":"2026-06-09T17:07:54.210036Z","end":"2026-06-09T17:07:54.427958Z","steps":["trace[881927237] 'read index received' (duration: 217.816231ms)","trace[881927237] 'applied index is now lower than readState.Index' (duration: 104.311µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:07:54.4281Z","caller":"traceutil/trace.go:171","msg":"trace[792407234] transaction","detail":"{read_only:false; response_revision:1436; number_of_response:1; }","duration":"219.96864ms","start":"2026-06-09T17:07:54.208097Z","end":"2026-06-09T17:07:54.428065Z","steps":["trace[792407234] 'process raft request' (duration: 219.702287ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:07:54.428194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.131115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/cilium-operator\" ","response":"range_response_count:1 size:2379"} {"level":"warn","ts":"2026-06-09T17:07:54.428215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.434068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/cluster-admin\" ","response":"range_response_count:1 size:553"} {"level":"info","ts":"2026-06-09T17:07:54.428293Z","caller":"traceutil/trace.go:171","msg":"trace[984300441] range","detail":"{range_begin:/registry/clusterroles/cluster-admin; range_end:; response_count:1; response_revision:1436; }","duration":"154.559229ms","start":"2026-06-09T17:07:54.273718Z","end":"2026-06-09T17:07:54.428277Z","steps":["trace[984300441] 'agreement among raft nodes before linearized reading' (duration: 154.424078ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:07:54.428295Z","caller":"traceutil/trace.go:171","msg":"trace[1777598116] range","detail":"{range_begin:/registry/clusterroles/cilium-operator; range_end:; response_count:1; response_revision:1436; }","duration":"218.267865ms","start":"2026-06-09T17:07:54.210004Z","end":"2026-06-09T17:07:54.428272Z","steps":["trace[1777598116] 'agreement among raft nodes before linearized reading' (duration: 218.089124ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:07:59.610738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.405855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:endpointslice-controller\" ","response":"range_response_count:1 size:771"} {"level":"info","ts":"2026-06-09T17:07:59.610821Z","caller":"traceutil/trace.go:171","msg":"trace[1995808875] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:endpointslice-controller; range_end:; response_count:1; response_revision:1452; }","duration":"121.530926ms","start":"2026-06-09T17:07:59.489272Z","end":"2026-06-09T17:07:59.610803Z","steps":["trace[1995808875] 'range keys from in-memory index tree' (duration: 121.279884ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:07:59.770939Z","caller":"traceutil/trace.go:171","msg":"trace[2035144609] transaction","detail":"{read_only:false; response_revision:1453; number_of_response:1; }","duration":"150.55096ms","start":"2026-06-09T17:07:59.620343Z","end":"2026-06-09T17:07:59.770893Z","steps":["trace[2035144609] 'process raft request' (duration: 150.367658ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:08:01.573711Z","caller":"traceutil/trace.go:171","msg":"trace[1698122006] transaction","detail":"{read_only:false; response_revision:1459; number_of_response:1; }","duration":"100.871864ms","start":"2026-06-09T17:08:01.472812Z","end":"2026-06-09T17:08:01.573684Z","steps":["trace[1698122006] 'process raft request' (duration: 100.686862ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:08:02.579104Z","caller":"traceutil/trace.go:171","msg":"trace[1901428410] linearizableReadLoop","detail":"{readStateIndex:1525; appliedIndex:1524; }","duration":"104.861279ms","start":"2026-06-09T17:08:02.474204Z","end":"2026-06-09T17:08:02.579065Z","steps":["trace[1901428410] 'read index received' (duration: 104.481376ms)","trace[1901428410] 'applied index is now lower than readState.Index' (duration: 378.603µs)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:08:02.579452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.224692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:service-account-controller\" ","response":"range_response_count:1 size:779"} {"level":"info","ts":"2026-06-09T17:08:02.57953Z","caller":"traceutil/trace.go:171","msg":"trace[58934459] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:service-account-controller; range_end:; response_count:1; response_revision:1463; }","duration":"105.350574ms","start":"2026-06-09T17:08:02.474159Z","end":"2026-06-09T17:08:02.57951Z","steps":["trace[58934459] 'agreement among raft nodes before linearized reading' (duration: 105.176712ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:08:02.579533Z","caller":"traceutil/trace.go:171","msg":"trace[1164548378] transaction","detail":"{read_only:false; response_revision:1463; number_of_response:1; }","duration":"114.255834ms","start":"2026-06-09T17:08:02.465246Z","end":"2026-06-09T17:08:02.579501Z","steps":["trace[1164548378] 'process raft request' (duration: 113.540778ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:08:16.432523Z","caller":"traceutil/trace.go:171","msg":"trace[1438010076] linearizableReadLoop","detail":"{readStateIndex:1573; appliedIndex:1572; }","duration":"169.649621ms","start":"2026-06-09T17:08:16.262839Z","end":"2026-06-09T17:08:16.432489Z","steps":["trace[1438010076] 'read index received' (duration: 169.456278ms)","trace[1438010076] 'applied index is now lower than readState.Index' (duration: 191.853µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:08:16.432577Z","caller":"traceutil/trace.go:171","msg":"trace[550883719] transaction","detail":"{read_only:false; response_revision:1509; number_of_response:1; }","duration":"209.537443ms","start":"2026-06-09T17:08:16.223012Z","end":"2026-06-09T17:08:16.43255Z","steps":["trace[550883719] 'process raft request' (duration: 209.258679ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:08:16.43277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.236755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/default\" ","response":"range_response_count:1 size:179"} {"level":"info","ts":"2026-06-09T17:08:16.43282Z","caller":"traceutil/trace.go:171","msg":"trace[996150100] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/default; range_end:; response_count:1; response_revision:1509; }","duration":"166.308726ms","start":"2026-06-09T17:08:16.266498Z","end":"2026-06-09T17:08:16.432806Z","steps":["trace[996150100] 'agreement among raft nodes before linearized reading' (duration: 166.191775ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:08:16.432803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.566454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:08:16.43295Z","caller":"traceutil/trace.go:171","msg":"trace[85664587] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1509; }","duration":"120.803796ms","start":"2026-06-09T17:08:16.31212Z","end":"2026-06-09T17:08:16.432924Z","steps":["trace[85664587] 'agreement among raft nodes before linearized reading' (duration: 120.608344ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:08:16.433369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.026385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-06-09T17:08:16.433423Z","caller":"traceutil/trace.go:171","msg":"trace[993851883] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:1509; }","duration":"170.625181ms","start":"2026-06-09T17:08:16.262784Z","end":"2026-06-09T17:08:16.433409Z","steps":["trace[993851883] 'agreement among raft nodes before linearized reading' (duration: 169.901644ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:08:16.630417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.089851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/default\" ","response":"range_response_count:1 size:179"} {"level":"warn","ts":"2026-06-09T17:08:16.630419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.346932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-06-09T17:08:16.630495Z","caller":"traceutil/trace.go:171","msg":"trace[1196900044] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/default; range_end:; response_count:1; response_revision:1509; }","duration":"123.208482ms","start":"2026-06-09T17:08:16.507272Z","end":"2026-06-09T17:08:16.630481Z","steps":["trace[1196900044] 'range keys from in-memory index tree' (duration: 122.980089ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:08:16.630533Z","caller":"traceutil/trace.go:171","msg":"trace[1067004092] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:1509; }","duration":"122.510514ms","start":"2026-06-09T17:08:16.508001Z","end":"2026-06-09T17:08:16.630511Z","steps":["trace[1067004092] 'range keys from in-memory index tree' (duration: 122.193991ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:08:17.275114Z","caller":"traceutil/trace.go:171","msg":"trace[78974711] linearizableReadLoop","detail":"{readStateIndex:1577; appliedIndex:1576; }","duration":"162.743042ms","start":"2026-06-09T17:08:17.112347Z","end":"2026-06-09T17:08:17.27509Z","steps":["trace[78974711] 'read index received' (duration: 162.52735ms)","trace[78974711] 'applied index is now lower than readState.Index' (duration: 214.472µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:08:17.27522Z","caller":"traceutil/trace.go:171","msg":"trace[1740524318] transaction","detail":"{read_only:false; response_revision:1512; number_of_response:1; }","duration":"229.067072ms","start":"2026-06-09T17:08:17.046126Z","end":"2026-06-09T17:08:17.275193Z","steps":["trace[1740524318] 'process raft request' (duration: 228.816039ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:08:17.275324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.957235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"warn","ts":"2026-06-09T17:08:17.275321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.953085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-06-09T17:08:17.275379Z","caller":"traceutil/trace.go:171","msg":"trace[1820300165] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:1512; }","duration":"162.063076ms","start":"2026-06-09T17:08:17.113299Z","end":"2026-06-09T17:08:17.275362Z","steps":["trace[1820300165] 'agreement among raft nodes before linearized reading' (duration: 161.951575ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:08:17.275397Z","caller":"traceutil/trace.go:171","msg":"trace[296176039] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:1512; }","duration":"163.071206ms","start":"2026-06-09T17:08:17.112306Z","end":"2026-06-09T17:08:17.275377Z","steps":["trace[296176039] 'agreement among raft nodes before linearized reading' (duration: 162.909475ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:08:17.529537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.0507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/kube-system/kube-dns\" ","response":"range_response_count:1 size:1211"} {"level":"warn","ts":"2026-06-09T17:08:17.529595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.041469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslicemirroring-controller\" ","response":"range_response_count:1 size:232"} {"level":"info","ts":"2026-06-09T17:08:17.52969Z","caller":"traceutil/trace.go:171","msg":"trace[1345710384] range","detail":"{range_begin:/registry/services/specs/kube-system/kube-dns; range_end:; response_count:1; response_revision:1512; }","duration":"148.270053ms","start":"2026-06-09T17:08:17.381391Z","end":"2026-06-09T17:08:17.529661Z","steps":["trace[1345710384] 'range keys from in-memory index tree' (duration: 147.866458ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:08:17.529699Z","caller":"traceutil/trace.go:171","msg":"trace[1221473061] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslicemirroring-controller; range_end:; response_count:1; response_revision:1512; }","duration":"146.19522ms","start":"2026-06-09T17:08:17.383482Z","end":"2026-06-09T17:08:17.529677Z","steps":["trace[1221473061] 'range keys from in-memory index tree' (duration: 145.923217ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:08:19.878504Z","caller":"traceutil/trace.go:171","msg":"trace[1714227833] transaction","detail":"{read_only:false; response_revision:1519; number_of_response:1; }","duration":"101.006187ms","start":"2026-06-09T17:08:19.777425Z","end":"2026-06-09T17:08:19.878431Z","steps":["trace[1714227833] 'process raft request' (duration: 100.785485ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:08:27.071054Z","caller":"traceutil/trace.go:171","msg":"trace[1824776919] transaction","detail":"{read_only:false; response_revision:1544; number_of_response:1; }","duration":"117.668696ms","start":"2026-06-09T17:08:26.953355Z","end":"2026-06-09T17:08:27.071023Z","steps":["trace[1824776919] 'process raft request' (duration: 117.228222ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:08:29.301608Z","caller":"traceutil/trace.go:171","msg":"trace[421721415] linearizableReadLoop","detail":"{readStateIndex:1617; appliedIndex:1616; }","duration":"187.179108ms","start":"2026-06-09T17:08:29.114408Z","end":"2026-06-09T17:08:29.301587Z","steps":["trace[421721415] 'read index received' (duration: 186.972936ms)","trace[421721415] 'applied index is now lower than readState.Index' (duration: 205.232µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:08:29.301743Z","caller":"traceutil/trace.go:171","msg":"trace[88018163] transaction","detail":"{read_only:false; response_revision:1550; number_of_response:1; }","duration":"221.835192ms","start":"2026-06-09T17:08:29.079883Z","end":"2026-06-09T17:08:29.301719Z","steps":["trace[88018163] 'process raft request' (duration: 221.564099ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:08:29.301905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.428431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-67659f764b-jx6z6\" ","response":"range_response_count:1 size:4907"} {"level":"info","ts":"2026-06-09T17:08:29.301952Z","caller":"traceutil/trace.go:171","msg":"trace[1174516602] range","detail":"{range_begin:/registry/pods/kube-system/coredns-67659f764b-jx6z6; range_end:; response_count:1; response_revision:1550; }","duration":"187.559353ms","start":"2026-06-09T17:08:29.11438Z","end":"2026-06-09T17:08:29.301939Z","steps":["trace[1174516602] 'agreement among raft nodes before linearized reading' (duration: 187.321571ms)"],"step_count":1}