{"level":"warn","ts":"2026-05-03T22:08:45.892616Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-03T22:08:45.893213Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.205:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.205:2380","--initial-cluster=instance=https://199.204.45.205:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.205:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.205:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-03T22:08:45.893282Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-03T22:08:45.893291Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.205:2380"]} {"level":"info","ts":"2026-05-03T22:08:45.893306Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-03T22:08:45.893906Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"]} {"level":"info","ts":"2026-05-03T22:08:45.893971Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.205:2380"],"listen-peer-urls":["https://199.204.45.205:2380"],"advertise-client-urls":["https://199.204.45.205:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.205:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-03T22:08:45.903126Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"8.963468ms"} {"level":"info","ts":"2026-05-03T22:08:45.91277Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"71d28e9ac1bc4e5d","cluster-id":"4a4ae660c7a9a6a7"} {"level":"info","ts":"2026-05-03T22:08:45.91297Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=()"} {"level":"info","ts":"2026-05-03T22:08:45.913088Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became follower at term 0"} {"level":"info","ts":"2026-05-03T22:08:45.913123Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 71d28e9ac1bc4e5d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-03T22:08:45.91315Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became follower at term 1"} {"level":"info","ts":"2026-05-03T22:08:45.91399Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=(8201774666721545821)"} {"level":"warn","ts":"2026-05-03T22:08:45.921715Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-03T22:08:45.923089Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-03T22:08:45.925129Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-03T22:08:45.929229Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"71d28e9ac1bc4e5d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-03T22:08:45.933016Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"71d28e9ac1bc4e5d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-03T22:08:45.933296Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-03T22:08:45.933429Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-03T22:08:45.933449Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-03T22:08:45.934013Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=(8201774666721545821)"} {"level":"info","ts":"2026-05-03T22:08:45.934328Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4a4ae660c7a9a6a7","local-member-id":"71d28e9ac1bc4e5d","added-peer-id":"71d28e9ac1bc4e5d","added-peer-peer-urls":["https://199.204.45.205:2380"]} {"level":"info","ts":"2026-05-03T22:08:45.936179Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-03T22:08:45.9364Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.205:2380"} {"level":"info","ts":"2026-05-03T22:08:45.936457Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.205:2380"} {"level":"info","ts":"2026-05-03T22:08:45.936697Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"71d28e9ac1bc4e5d","initial-advertise-peer-urls":["https://199.204.45.205:2380"],"listen-peer-urls":["https://199.204.45.205:2380"],"advertise-client-urls":["https://199.204.45.205:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-03T22:08:45.936738Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-03T22:08:46.115393Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d is starting a new election at term 1"} {"level":"info","ts":"2026-05-03T22:08:46.11545Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became pre-candidate at term 1"} {"level":"info","ts":"2026-05-03T22:08:46.115508Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d received MsgPreVoteResp from 71d28e9ac1bc4e5d at term 1"} {"level":"info","ts":"2026-05-03T22:08:46.11553Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became candidate at term 2"} {"level":"info","ts":"2026-05-03T22:08:46.115586Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d received MsgVoteResp from 71d28e9ac1bc4e5d at term 2"} {"level":"info","ts":"2026-05-03T22:08:46.115604Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became leader at term 2"} {"level":"info","ts":"2026-05-03T22:08:46.115617Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 71d28e9ac1bc4e5d elected leader 71d28e9ac1bc4e5d at term 2"} {"level":"info","ts":"2026-05-03T22:08:46.117211Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"71d28e9ac1bc4e5d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.205:2379]}","request-path":"/0/members/71d28e9ac1bc4e5d/attributes","cluster-id":"4a4ae660c7a9a6a7","publish-timeout":"7s"} {"level":"info","ts":"2026-05-03T22:08:46.117394Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-03T22:08:46.117562Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-03T22:08:46.117525Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-03T22:08:46.117604Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-03T22:08:46.117423Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-03T22:08:46.118719Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4a4ae660c7a9a6a7","local-member-id":"71d28e9ac1bc4e5d","cluster-version":"3.5"} {"level":"info","ts":"2026-05-03T22:08:46.118831Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-03T22:08:46.118875Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-03T22:08:46.120963Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-03T22:08:46.121839Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.205:2379"} {"level":"info","ts":"2026-05-03T22:08:51.341172Z","caller":"traceutil/trace.go:171","msg":"trace[263206642] linearizableReadLoop","detail":"{readStateIndex:225; appliedIndex:225; }","duration":"206.036455ms","start":"2026-05-03T22:08:51.135107Z","end":"2026-05-03T22:08:51.341143Z","steps":["trace[263206642] 'read index received' (duration: 206.023974ms)","trace[263206642] 'applied index is now lower than readState.Index' (duration: 8.051µs)"],"step_count":2} {"level":"warn","ts":"2026-05-03T22:08:51.341321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.200079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-03T22:08:51.341419Z","caller":"traceutil/trace.go:171","msg":"trace[244844851] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:220; }","duration":"206.335192ms","start":"2026-05-03T22:08:51.135069Z","end":"2026-05-03T22:08:51.341405Z","steps":["trace[244844851] 'agreement among raft nodes before linearized reading' (duration: 206.176108ms)"],"step_count":1} {"level":"info","ts":"2026-05-03T22:08:51.341196Z","caller":"traceutil/trace.go:171","msg":"trace[1614397887] transaction","detail":"{read_only:false; response_revision:220; number_of_response:1; }","duration":"232.071311ms","start":"2026-05-03T22:08:51.109063Z","end":"2026-05-03T22:08:51.341134Z","steps":["trace[1614397887] 'process raft request' (duration: 231.948627ms)"],"step_count":1} {"level":"info","ts":"2026-05-03T22:08:51.465089Z","caller":"traceutil/trace.go:171","msg":"trace[1635794871] linearizableReadLoop","detail":"{readStateIndex:227; appliedIndex:225; }","duration":"123.815478ms","start":"2026-05-03T22:08:51.341253Z","end":"2026-05-03T22:08:51.465068Z","steps":["trace[1635794871] 'read index received' (duration: 112.5371ms)","trace[1635794871] 'applied index is now lower than readState.Index' (duration: 11.277378ms)"],"step_count":2} {"level":"warn","ts":"2026-05-03T22:08:51.465176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.762384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-03T22:08:51.465209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.819645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-03T22:08:51.465241Z","caller":"traceutil/trace.go:171","msg":"trace[1691995752] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:220; }","duration":"215.881646ms","start":"2026-05-03T22:08:51.249348Z","end":"2026-05-03T22:08:51.46523Z","steps":["trace[1691995752] 'agreement among raft nodes before linearized reading' (duration: 215.826104ms)"],"step_count":1} {"level":"info","ts":"2026-05-03T22:08:51.465229Z","caller":"traceutil/trace.go:171","msg":"trace[182234689] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:220; }","duration":"126.874117ms","start":"2026-05-03T22:08:51.338341Z","end":"2026-05-03T22:08:51.465215Z","steps":["trace[182234689] 'agreement among raft nodes before linearized reading' (duration: 126.746544ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:08:51.465383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:08:51.135163Z","time spent":"330.209132ms","remote":"127.0.0.1:37174","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-03T22:08:51.465442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.104191ms","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-05-03T22:08:51.465517Z","caller":"traceutil/trace.go:171","msg":"trace[1857896473] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:220; }","duration":"309.260346ms","start":"2026-05-03T22:08:51.15624Z","end":"2026-05-03T22:08:51.4655Z","steps":["trace[1857896473] 'agreement among raft nodes before linearized reading' (duration: 309.077152ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:08:51.465506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.214282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-05-03T22:08:51.46553Z","caller":"traceutil/trace.go:171","msg":"trace[1871475050] transaction","detail":"{read_only:false; number_of_response:0; response_revision:220; }","duration":"232.624405ms","start":"2026-05-03T22:08:51.232859Z","end":"2026-05-03T22:08:51.465483Z","steps":["trace[1871475050] 'process raft request' (duration: 232.157543ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:08:51.465561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:08:51.156224Z","time spent":"309.329369ms","remote":"127.0.0.1:37388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-03T22:08:51.465567Z","caller":"traceutil/trace.go:171","msg":"trace[1577207662] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:220; }","duration":"330.299304ms","start":"2026-05-03T22:08:51.135252Z","end":"2026-05-03T22:08:51.465552Z","steps":["trace[1577207662] 'agreement among raft nodes before linearized reading' (duration: 330.15997ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:08:51.465601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:08:51.135163Z","time spent":"330.430768ms","remote":"127.0.0.1:37504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":686,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-05-03T22:08:51.510792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.927888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4017"} {"level":"info","ts":"2026-05-03T22:08:51.51086Z","caller":"traceutil/trace.go:171","msg":"trace[1695579139] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:221; }","duration":"166.04587ms","start":"2026-05-03T22:08:51.344799Z","end":"2026-05-03T22:08:51.510845Z","steps":["trace[1695579139] 'agreement among raft nodes before linearized reading' (duration: 165.914247ms)"],"step_count":1} {"level":"info","ts":"2026-05-03T22:08:51.511141Z","caller":"traceutil/trace.go:171","msg":"trace[196599732] transaction","detail":"{read_only:false; response_revision:221; number_of_response:1; }","duration":"162.10868ms","start":"2026-05-03T22:08:51.349019Z","end":"2026-05-03T22:08:51.511128Z","steps":["trace[196599732] 'process raft request' (duration: 161.469964ms)"],"step_count":1} {"level":"info","ts":"2026-05-03T22:08:51.77146Z","caller":"traceutil/trace.go:171","msg":"trace[846435055] transaction","detail":"{read_only:false; response_revision:224; number_of_response:1; }","duration":"303.489699ms","start":"2026-05-03T22:08:51.467941Z","end":"2026-05-03T22:08:51.771431Z","steps":["trace[846435055] 'process raft request' (duration: 303.376256ms)"],"step_count":1} {"level":"info","ts":"2026-05-03T22:08:51.771527Z","caller":"traceutil/trace.go:171","msg":"trace[2113152295] transaction","detail":"{read_only:false; response_revision:223; number_of_response:1; }","duration":"303.705934ms","start":"2026-05-03T22:08:51.4678Z","end":"2026-05-03T22:08:51.771505Z","steps":["trace[2113152295] 'process raft request' (duration: 303.471708ms)"],"step_count":1} {"level":"info","ts":"2026-05-03T22:08:51.771538Z","caller":"traceutil/trace.go:171","msg":"trace[911891324] linearizableReadLoop","detail":"{readStateIndex:236; appliedIndex:228; }","duration":"260.829406ms","start":"2026-05-03T22:08:51.510669Z","end":"2026-05-03T22:08:51.771499Z","steps":["trace[911891324] 'read index received' (duration: 244.716103ms)","trace[911891324] 'applied index is now lower than readState.Index' (duration: 16.112473ms)"],"step_count":2} {"level":"info","ts":"2026-05-03T22:08:51.771573Z","caller":"traceutil/trace.go:171","msg":"trace[2014736466] transaction","detail":"{read_only:false; response_revision:222; number_of_response:1; }","duration":"304.594157ms","start":"2026-05-03T22:08:51.466958Z","end":"2026-05-03T22:08:51.771552Z","steps":["trace[2014736466] 'process raft request' (duration: 288.269508ms)","trace[2014736466] 'compare' (duration: 15.919619ms)"],"step_count":2} {"level":"info","ts":"2026-05-03T22:08:51.771621Z","caller":"traceutil/trace.go:171","msg":"trace[1293477658] transaction","detail":"{read_only:false; response_revision:229; number_of_response:1; }","duration":"302.137094ms","start":"2026-05-03T22:08:51.469476Z","end":"2026-05-03T22:08:51.771613Z","steps":["trace[1293477658] 'process raft request' (duration: 301.993731ms)"],"step_count":1} {"level":"info","ts":"2026-05-03T22:08:51.771662Z","caller":"traceutil/trace.go:171","msg":"trace[501544301] transaction","detail":"{read_only:false; response_revision:228; number_of_response:1; }","duration":"302.562234ms","start":"2026-05-03T22:08:51.469085Z","end":"2026-05-03T22:08:51.771647Z","steps":["trace[501544301] 'process raft request' (duration: 302.354319ms)"],"step_count":1} {"level":"info","ts":"2026-05-03T22:08:51.771665Z","caller":"traceutil/trace.go:171","msg":"trace[708244287] transaction","detail":"{read_only:false; response_revision:226; number_of_response:1; }","duration":"303.357215ms","start":"2026-05-03T22:08:51.468288Z","end":"2026-05-03T22:08:51.771646Z","steps":["trace[708244287] 'process raft request' (duration: 303.091048ms)"],"step_count":1} {"level":"info","ts":"2026-05-03T22:08:51.771693Z","caller":"traceutil/trace.go:171","msg":"trace[648435098] transaction","detail":"{read_only:false; response_revision:225; number_of_response:1; }","duration":"303.507329ms","start":"2026-05-03T22:08:51.468178Z","end":"2026-05-03T22:08:51.771685Z","steps":["trace[648435098] 'process raft request' (duration: 303.173271ms)"],"step_count":1} {"level":"info","ts":"2026-05-03T22:08:51.771723Z","caller":"traceutil/trace.go:171","msg":"trace[1260596840] transaction","detail":"{read_only:false; response_revision:227; number_of_response:1; }","duration":"303.315495ms","start":"2026-05-03T22:08:51.468397Z","end":"2026-05-03T22:08:51.771712Z","steps":["trace[1260596840] 'process raft request' (duration: 303.012627ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:08:51.771727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.760792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4017"} {"level":"info","ts":"2026-05-03T22:08:51.771765Z","caller":"traceutil/trace.go:171","msg":"trace[1900734780] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:229; }","duration":"304.907645ms","start":"2026-05-03T22:08:51.466846Z","end":"2026-05-03T22:08:51.771753Z","steps":["trace[1900734780] 'agreement among raft nodes before linearized reading' (duration: 304.72888ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:08:51.771802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:08:51.466832Z","time spent":"304.962277ms","remote":"127.0.0.1:37278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4039,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-05-03T22:08:51.771829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.871298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"warn","ts":"2026-05-03T22:08:51.772097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:08:51.467927Z","time spent":"303.621872ms","remote":"127.0.0.1:37288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2905,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-03T22:08:51.771967Z","caller":"traceutil/trace.go:171","msg":"trace[984364365] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:229; }","duration":"304.089423ms","start":"2026-05-03T22:08:51.467845Z","end":"2026-05-03T22:08:51.771934Z","steps":["trace[984364365] 'agreement among raft nodes before linearized reading' (duration: 303.818526ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:08:51.772209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:08:51.46778Z","time spent":"303.809967ms","remote":"127.0.0.1:37288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3453,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-03T22:08:51.772242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:08:51.467828Z","time spent":"304.392741ms","remote":"127.0.0.1:37504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":686,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-05-03T22:08:51.77232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:08:51.466918Z","time spent":"304.700489ms","remote":"127.0.0.1:37174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-03T22:08:51.772455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:08:51.46947Z","time spent":"302.170805ms","remote":"127.0.0.1:37388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-03T22:08:51.772619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:08:51.469078Z","time spent":"302.610156ms","remote":"127.0.0.1:37388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-03T22:08:51.772683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:08:51.468275Z","time spent":"303.419967ms","remote":"127.0.0.1:37288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5882,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-03T22:08:51.772921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:08:51.468386Z","time spent":"303.359796ms","remote":"127.0.0.1:37288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3965,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-03T22:08:51.774372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:08:51.468161Z","time spent":"303.56408ms","remote":"127.0.0.1:37288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-03T22:08:51.801994Z","caller":"traceutil/trace.go:171","msg":"trace[1102934570] transaction","detail":"{read_only:false; response_revision:231; number_of_response:1; }","duration":"281.470675ms","start":"2026-05-03T22:08:51.520502Z","end":"2026-05-03T22:08:51.801973Z","steps":["trace[1102934570] 'process raft request' (duration: 281.359572ms)"],"step_count":1} {"level":"info","ts":"2026-05-03T22:08:51.802077Z","caller":"traceutil/trace.go:171","msg":"trace[2070911692] transaction","detail":"{read_only:false; response_revision:230; number_of_response:1; }","duration":"286.714449ms","start":"2026-05-03T22:08:51.515323Z","end":"2026-05-03T22:08:51.802037Z","steps":["trace[2070911692] 'process raft request' (duration: 286.427542ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:08:56.773397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.150299ms","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-05-03T22:08:56.773466Z","caller":"traceutil/trace.go:171","msg":"trace[1154271576] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:314; }","duration":"166.257532ms","start":"2026-05-03T22:08:56.607195Z","end":"2026-05-03T22:08:56.773452Z","steps":["trace[1154271576] 'range keys from in-memory index tree' (duration: 166.090088ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:08:56.773637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.045991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-05-03T22:08:56.773662Z","caller":"traceutil/trace.go:171","msg":"trace[1615582740] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:314; }","duration":"146.071662ms","start":"2026-05-03T22:08:56.627584Z","end":"2026-05-03T22:08:56.773656Z","steps":["trace[1615582740] 'range keys from in-memory index tree' (duration: 145.99033ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:08:56.773751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.272242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:1 size:536"} {"level":"info","ts":"2026-05-03T22:08:56.773772Z","caller":"traceutil/trace.go:171","msg":"trace[17887311] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:1; response_revision:314; }","duration":"140.309133ms","start":"2026-05-03T22:08:56.633458Z","end":"2026-05-03T22:08:56.773767Z","steps":["trace[17887311] 'range keys from in-memory index tree' (duration: 140.237281ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:08:56.773869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.363094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5164"} {"level":"info","ts":"2026-05-03T22:08:56.773889Z","caller":"traceutil/trace.go:171","msg":"trace[1020121374] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:314; }","duration":"140.411756ms","start":"2026-05-03T22:08:56.633472Z","end":"2026-05-03T22:08:56.773884Z","steps":["trace[1020121374] 'range keys from in-memory index tree' (duration: 140.282403ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:08:56.773987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.427156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4197"} {"level":"info","ts":"2026-05-03T22:08:56.774008Z","caller":"traceutil/trace.go:171","msg":"trace[1524763067] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:314; }","duration":"140.449127ms","start":"2026-05-03T22:08:56.633553Z","end":"2026-05-03T22:08:56.774003Z","steps":["trace[1524763067] 'range keys from in-memory index tree' (duration: 140.375795ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:08:56.774116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.500607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"info","ts":"2026-05-03T22:08:56.774138Z","caller":"traceutil/trace.go:171","msg":"trace[174172004] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:314; }","duration":"140.527988ms","start":"2026-05-03T22:08:56.633604Z","end":"2026-05-03T22:08:56.774132Z","steps":["trace[174172004] 'range keys from in-memory index tree' (duration: 140.470586ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:08:56.774221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.407715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:4664"} {"level":"info","ts":"2026-05-03T22:08:56.774241Z","caller":"traceutil/trace.go:171","msg":"trace[255749590] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:314; }","duration":"140.437646ms","start":"2026-05-03T22:08:56.633798Z","end":"2026-05-03T22:08:56.774236Z","steps":["trace[255749590] 'range keys from in-memory index tree' (duration: 140.335923ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:08:56.774327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.510538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7303"} {"level":"info","ts":"2026-05-03T22:08:56.774348Z","caller":"traceutil/trace.go:171","msg":"trace[578392784] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:314; }","duration":"140.556688ms","start":"2026-05-03T22:08:56.633786Z","end":"2026-05-03T22:08:56.774343Z","steps":["trace[578392784] 'range keys from in-memory index tree' (duration: 140.415705ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:08:56.774427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.196179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6965"} {"level":"info","ts":"2026-05-03T22:08:56.77445Z","caller":"traceutil/trace.go:171","msg":"trace[2025993173] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:314; }","duration":"140.22478ms","start":"2026-05-03T22:08:56.634218Z","end":"2026-05-03T22:08:56.774443Z","steps":["trace[2025993173] 'range keys from in-memory index tree' (duration: 140.165909ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:08:57.358452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.995631ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-03T22:08:57.358684Z","caller":"traceutil/trace.go:171","msg":"trace[429748416] linearizableReadLoop","detail":"{readStateIndex:330; appliedIndex:325; }","duration":"531.002902ms","start":"2026-05-03T22:08:56.827665Z","end":"2026-05-03T22:08:57.358668Z","steps":["trace[429748416] 'read index received' (duration: 9.341571ms)","trace[429748416] 'applied index is now lower than readState.Index' (duration: 521.660421ms)"],"step_count":2} {"level":"info","ts":"2026-05-03T22:08:57.358779Z","caller":"traceutil/trace.go:171","msg":"trace[1676320278] transaction","detail":"{read_only:false; response_revision:318; number_of_response:1; }","duration":"572.859853ms","start":"2026-05-03T22:08:56.785909Z","end":"2026-05-03T22:08:57.358769Z","steps":["trace[1676320278] 'process raft request' (duration: 572.699849ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:08:57.358841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:08:56.785901Z","time spent":"572.908574ms","remote":"127.0.0.1:37456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2094,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-03T22:08:57.358817Z","caller":"traceutil/trace.go:171","msg":"trace[605090614] transaction","detail":"{read_only:false; response_revision:317; number_of_response:1; }","duration":"573.233553ms","start":"2026-05-03T22:08:56.785548Z","end":"2026-05-03T22:08:57.358782Z","steps":["trace[605090614] 'process raft request' (duration: 573.004277ms)"],"step_count":1} {"level":"info","ts":"2026-05-03T22:08:57.359034Z","caller":"traceutil/trace.go:171","msg":"trace[2144450058] transaction","detail":"{read_only:false; response_revision:319; number_of_response:1; }","duration":"571.898339ms","start":"2026-05-03T22:08:56.787126Z","end":"2026-05-03T22:08:57.359024Z","steps":["trace[2144450058] 'process raft request' (duration: 571.511448ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:08:57.359047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:08:56.78554Z","time spent":"573.400748ms","remote":"127.0.0.1:37456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":899,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-03T22:08:57.359093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:08:56.787116Z","time spent":"571.95125ms","remote":"127.0.0.1:37456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2191,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-03T22:08:57.359255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.587327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-05-03T22:08:57.359291Z","caller":"traceutil/trace.go:171","msg":"trace[1080686606] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:319; }","duration":"531.650429ms","start":"2026-05-03T22:08:56.827631Z","end":"2026-05-03T22:08:57.359281Z","steps":["trace[1080686606] 'agreement among raft nodes before linearized reading' (duration: 531.573427ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:08:57.359319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:08:56.827617Z","time spent":"531.6952ms","remote":"127.0.0.1:37314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":219,"request content":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" "} {"level":"info","ts":"2026-05-03T22:08:57.359339Z","caller":"traceutil/trace.go:171","msg":"trace[1368871619] transaction","detail":"{read_only:false; response_revision:316; number_of_response:1; }","duration":"574.729142ms","start":"2026-05-03T22:08:56.784568Z","end":"2026-05-03T22:08:57.359298Z","steps":["trace[1368871619] 'process raft request' (duration: 228.306014ms)","trace[1368871619] 'compare' (duration: 344.389175ms)"],"step_count":2} {"level":"warn","ts":"2026-05-03T22:08:57.359438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:08:56.784555Z","time spent":"574.816655ms","remote":"127.0.0.1:37382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1251,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-03T22:08:57.35948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.154456ms","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-05-03T22:08:57.359509Z","caller":"traceutil/trace.go:171","msg":"trace[2121680830] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:319; }","duration":"481.214598ms","start":"2026-05-03T22:08:56.878286Z","end":"2026-05-03T22:08:57.359501Z","steps":["trace[2121680830] 'agreement among raft nodes before linearized reading' (duration: 481.164227ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:08:57.359543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:08:56.878271Z","time spent":"481.265819ms","remote":"127.0.0.1:37314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":229,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"warn","ts":"2026-05-03T22:08:57.359779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.325065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-05-03T22:08:57.359814Z","caller":"traceutil/trace.go:171","msg":"trace[586978932] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:319; }","duration":"281.391926ms","start":"2026-05-03T22:08:57.078414Z","end":"2026-05-03T22:08:57.359806Z","steps":["trace[586978932] 'agreement among raft nodes before linearized reading' (duration: 281.335794ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:08:57.359973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.374953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-05-03T22:08:57.360027Z","caller":"traceutil/trace.go:171","msg":"trace[283742751] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:319; }","duration":"332.455246ms","start":"2026-05-03T22:08:57.027561Z","end":"2026-05-03T22:08:57.360017Z","steps":["trace[283742751] 'agreement among raft nodes before linearized reading' (duration: 332.356013ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:08:57.360056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:08:57.02755Z","time spent":"332.498677ms","remote":"127.0.0.1:37314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":236,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"warn","ts":"2026-05-03T22:08:57.360188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.510059ms","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-05-03T22:08:57.360238Z","caller":"traceutil/trace.go:171","msg":"trace[739173283] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:319; }","duration":"382.597651ms","start":"2026-05-03T22:08:56.977627Z","end":"2026-05-03T22:08:57.360225Z","steps":["trace[739173283] 'agreement among raft nodes before linearized reading' (duration: 382.483598ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:08:57.360272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:08:56.977614Z","time spent":"382.650682ms","remote":"127.0.0.1:37314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":229,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"warn","ts":"2026-05-03T22:08:57.360501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.452315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-05-03T22:08:57.360544Z","caller":"traceutil/trace.go:171","msg":"trace[2032558690] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:319; }","duration":"433.527277ms","start":"2026-05-03T22:08:56.927008Z","end":"2026-05-03T22:08:57.360535Z","steps":["trace[2032558690] 'agreement among raft nodes before linearized reading' (duration: 433.463515ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:08:57.360571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:08:56.926992Z","time spent":"433.573208ms","remote":"127.0.0.1:37314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":217,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"info","ts":"2026-05-03T22:09:00.213646Z","caller":"traceutil/trace.go:171","msg":"trace[689830006] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"103.721463ms","start":"2026-05-03T22:09:00.10989Z","end":"2026-05-03T22:09:00.213611Z","steps":["trace[689830006] 'process raft request' (duration: 103.301413ms)"],"step_count":1} {"level":"info","ts":"2026-05-03T22:09:03.210202Z","caller":"traceutil/trace.go:171","msg":"trace[1546595080] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"148.842191ms","start":"2026-05-03T22:09:03.061333Z","end":"2026-05-03T22:09:03.210175Z","steps":["trace[1546595080] 'process raft request' (duration: 148.676755ms)"],"step_count":1} {"level":"info","ts":"2026-05-03T22:09:03.350281Z","caller":"traceutil/trace.go:171","msg":"trace[2084682250] linearizableReadLoop","detail":"{readStateIndex:385; appliedIndex:384; }","duration":"123.279484ms","start":"2026-05-03T22:09:03.226964Z","end":"2026-05-03T22:09:03.350244Z","steps":["trace[2084682250] 'read index received' (duration: 123.109889ms)","trace[2084682250] 'applied index is now lower than readState.Index' (duration: 168.625µs)"],"step_count":2} {"level":"warn","ts":"2026-05-03T22:09:03.350559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.569611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4056"} {"level":"info","ts":"2026-05-03T22:09:03.350646Z","caller":"traceutil/trace.go:171","msg":"trace[152369981] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:372; }","duration":"123.698895ms","start":"2026-05-03T22:09:03.226933Z","end":"2026-05-03T22:09:03.350632Z","steps":["trace[152369981] 'agreement among raft nodes before linearized reading' (duration: 123.49975ms)"],"step_count":1} {"level":"info","ts":"2026-05-03T22:09:03.350436Z","caller":"traceutil/trace.go:171","msg":"trace[1028084902] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"127.570225ms","start":"2026-05-03T22:09:03.222776Z","end":"2026-05-03T22:09:03.350346Z","steps":["trace[1028084902] 'process raft request' (duration: 127.287117ms)"],"step_count":1} {"level":"info","ts":"2026-05-03T22:09:04.412779Z","caller":"traceutil/trace.go:171","msg":"trace[2102226016] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"158.894276ms","start":"2026-05-03T22:09:04.253851Z","end":"2026-05-03T22:09:04.412745Z","steps":["trace[2102226016] 'process raft request' (duration: 67.05079ms)","trace[2102226016] 'compare' (duration: 91.697083ms)"],"step_count":2} {"level":"info","ts":"2026-05-03T22:09:06.087156Z","caller":"traceutil/trace.go:171","msg":"trace[634387253] linearizableReadLoop","detail":"{readStateIndex:423; appliedIndex:422; }","duration":"189.799552ms","start":"2026-05-03T22:09:05.897333Z","end":"2026-05-03T22:09:06.087132Z","steps":["trace[634387253] 'read index received' (duration: 189.634848ms)","trace[634387253] 'applied index is now lower than readState.Index' (duration: 163.794µs)"],"step_count":2} {"level":"info","ts":"2026-05-03T22:09:06.08722Z","caller":"traceutil/trace.go:171","msg":"trace[1298695377] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"190.040248ms","start":"2026-05-03T22:09:05.897144Z","end":"2026-05-03T22:09:06.087184Z","steps":["trace[1298695377] 'process raft request' (duration: 189.857683ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:06.087335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.982177ms","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-05-03T22:09:06.087383Z","caller":"traceutil/trace.go:171","msg":"trace[1198631206] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:408; }","duration":"190.070649ms","start":"2026-05-03T22:09:05.8973Z","end":"2026-05-03T22:09:06.08737Z","steps":["trace[1198631206] 'agreement among raft nodes before linearized reading' (duration: 189.968226ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:08.150491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.419464583s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-03T22:09:08.150591Z","caller":"traceutil/trace.go:171","msg":"trace[1606151490] linearizableReadLoop","detail":"{readStateIndex:424; appliedIndex:423; }","duration":"1.896300837s","start":"2026-05-03T22:09:06.254272Z","end":"2026-05-03T22:09:08.150573Z","steps":["trace[1606151490] 'read index received' (duration: 476.620608ms)","trace[1606151490] 'applied index is now lower than readState.Index' (duration: 1.419678959s)"],"step_count":2} {"level":"warn","ts":"2026-05-03T22:09:08.150925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.73471006s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-03T22:09:08.151005Z","caller":"traceutil/trace.go:171","msg":"trace[1844228951] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:409; }","duration":"1.734851203s","start":"2026-05-03T22:09:06.416134Z","end":"2026-05-03T22:09:08.150985Z","steps":["trace[1844228951] 'agreement among raft nodes before linearized reading' (duration: 1.734654738s)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:08.151045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:06.416116Z","time spent":"1.734920195s","remote":"127.0.0.1:37388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-03T22:09:08.1511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.8968184s","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-05-03T22:09:08.151123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.427658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-03T22:09:08.151102Z","caller":"traceutil/trace.go:171","msg":"trace[332649794] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"2.059975129s","start":"2026-05-03T22:09:06.091098Z","end":"2026-05-03T22:09:08.151074Z","steps":["trace[332649794] 'process raft request' (duration: 639.84779ms)","trace[332649794] 'compare' (duration: 1.419143774s)"],"step_count":2} {"level":"info","ts":"2026-05-03T22:09:08.151153Z","caller":"traceutil/trace.go:171","msg":"trace[200546115] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:409; }","duration":"1.896912222s","start":"2026-05-03T22:09:06.254228Z","end":"2026-05-03T22:09:08.151141Z","steps":["trace[200546115] 'agreement among raft nodes before linearized reading' (duration: 1.89682058s)"],"step_count":1} {"level":"info","ts":"2026-05-03T22:09:08.151164Z","caller":"traceutil/trace.go:171","msg":"trace[3249226] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:409; }","duration":"125.50017ms","start":"2026-05-03T22:09:08.025651Z","end":"2026-05-03T22:09:08.151151Z","steps":["trace[3249226] 'agreement among raft nodes before linearized reading' (duration: 125.431029ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:08.151188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:06.254207Z","time spent":"1.896972434s","remote":"127.0.0.1:37388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-03T22:09:08.151259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:06.09108Z","time spent":"2.060091172s","remote":"127.0.0.1:37388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-03T22:09:08.654945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.753883ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-03T22:09:08.655027Z","caller":"traceutil/trace.go:171","msg":"trace[123133571] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"497.949475ms","start":"2026-05-03T22:09:08.157064Z","end":"2026-05-03T22:09:08.655014Z","steps":["trace[123133571] 'process raft request' (duration: 302.030788ms)","trace[123133571] 'compare' (duration: 195.687281ms)"],"step_count":2} {"level":"warn","ts":"2026-05-03T22:09:08.65506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:08.157051Z","time spent":"497.993856ms","remote":"127.0.0.1:37388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-03T22:09:08.693132Z","caller":"traceutil/trace.go:171","msg":"trace[836992924] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"535.388768ms","start":"2026-05-03T22:09:08.157726Z","end":"2026-05-03T22:09:08.693115Z","steps":["trace[836992924] 'process raft request' (duration: 535.319346ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:08.693224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:08.157714Z","time spent":"535.471019ms","remote":"127.0.0.1:37388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-03T22:09:08.693193Z","caller":"traceutil/trace.go:171","msg":"trace[1395516959] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"535.919301ms","start":"2026-05-03T22:09:08.157239Z","end":"2026-05-03T22:09:08.693159Z","steps":["trace[1395516959] 'process raft request' (duration: 535.723505ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:08.693611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:08.15721Z","time spent":"536.212548ms","remote":"127.0.0.1:37388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-03T22:09:08.693831Z","caller":"traceutil/trace.go:171","msg":"trace[835037700] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"289.007876ms","start":"2026-05-03T22:09:08.404802Z","end":"2026-05-03T22:09:08.69381Z","steps":["trace[835037700] 'process raft request' (duration: 288.280248ms)"],"step_count":1} {"level":"info","ts":"2026-05-03T22:09:14.490126Z","caller":"traceutil/trace.go:171","msg":"trace[437700433] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"308.594357ms","start":"2026-05-03T22:09:14.181506Z","end":"2026-05-03T22:09:14.4901Z","steps":["trace[437700433] 'process raft request' (duration: 308.379212ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:14.490289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:14.181486Z","time spent":"308.724181ms","remote":"127.0.0.1:37388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-03T22:09:14.89865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.471582ms","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-05-03T22:09:14.898734Z","caller":"traceutil/trace.go:171","msg":"trace[928303246] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:424; }","duration":"215.589344ms","start":"2026-05-03T22:09:14.683126Z","end":"2026-05-03T22:09:14.898716Z","steps":["trace[928303246] 'range keys from in-memory index tree' (duration: 215.364209ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:14.898737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.457234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-03T22:09:14.898656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.092388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-03T22:09:14.89879Z","caller":"traceutil/trace.go:171","msg":"trace[2145310342] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:424; }","duration":"187.531785ms","start":"2026-05-03T22:09:14.711238Z","end":"2026-05-03T22:09:14.89877Z","steps":["trace[2145310342] 'range keys from in-memory index tree' (duration: 187.3417ms)"],"step_count":1} {"level":"info","ts":"2026-05-03T22:09:14.898846Z","caller":"traceutil/trace.go:171","msg":"trace[470115705] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:0; response_revision:424; }","duration":"142.347774ms","start":"2026-05-03T22:09:14.756467Z","end":"2026-05-03T22:09:14.898815Z","steps":["trace[470115705] 'range keys from in-memory index tree' (duration: 141.952374ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:14.898882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.59678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-03T22:09:14.898943Z","caller":"traceutil/trace.go:171","msg":"trace[580871615] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:424; }","duration":"186.677394ms","start":"2026-05-03T22:09:14.712247Z","end":"2026-05-03T22:09:14.898924Z","steps":["trace[580871615] 'range keys from in-memory index tree' (duration: 186.506228ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:15.40521Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646843161423994316,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-03T22:09:15.905592Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646843161423994316,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-03T22:09:16.405937Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646843161423994316,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-03T22:09:16.90689Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646843161423994316,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-03T22:09:17.026214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00016319s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-03T22:09:17.026347Z","caller":"traceutil/trace.go:171","msg":"trace[1563094997] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000323474s","start":"2026-05-03T22:09:15.025991Z","end":"2026-05-03T22:09:17.026314Z","steps":["trace[1563094997] 'agreement among raft nodes before linearized reading' (duration: 2.00015812s)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:17.026806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:15.025965Z","time spent":"2.000802518s","remote":"127.0.0.1:37096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/03 22:09:17 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-03T22:09:17.407671Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646843161423994316,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-03T22:09:17.441645Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.538431102s","expected-duration":"1s"} {"level":"info","ts":"2026-05-03T22:09:17.442196Z","caller":"traceutil/trace.go:171","msg":"trace[1856644778] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"2.539063918s","start":"2026-05-03T22:09:14.903096Z","end":"2026-05-03T22:09:17.44216Z","steps":["trace[1856644778] 'process raft request' (duration: 2.538847103s)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:17.44237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:14.90307Z","time spent":"2.539187042s","remote":"127.0.0.1:37388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-03T22:09:17.458425Z","caller":"traceutil/trace.go:171","msg":"trace[1095968750] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"2.554744794s","start":"2026-05-03T22:09:14.903643Z","end":"2026-05-03T22:09:17.458387Z","steps":["trace[1095968750] 'process raft request' (duration: 2.554434646s)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:17.458619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:14.903626Z","time spent":"2.554891467s","remote":"127.0.0.1:37388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-03T22:09:17.458774Z","caller":"traceutil/trace.go:171","msg":"trace[323164149] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"2.554612471s","start":"2026-05-03T22:09:14.90405Z","end":"2026-05-03T22:09:17.458663Z","steps":["trace[323164149] 'process raft request' (duration: 2.554171699s)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:17.459178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:14.903963Z","time spent":"2.55498058s","remote":"127.0.0.1:37388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-03T22:09:17.46513Z","caller":"traceutil/trace.go:171","msg":"trace[81816238] linearizableReadLoop","detail":"{readStateIndex:444; appliedIndex:441; }","duration":"2.559972693s","start":"2026-05-03T22:09:14.905135Z","end":"2026-05-03T22:09:17.465108Z","steps":["trace[81816238] 'read index received' (duration: 2.537377144s)","trace[81816238] 'applied index is now lower than readState.Index' (duration: 22.594229ms)"],"step_count":2} {"level":"warn","ts":"2026-05-03T22:09:17.465332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"970.837904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-03T22:09:17.465385Z","caller":"traceutil/trace.go:171","msg":"trace[2004472022] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:427; }","duration":"970.926196ms","start":"2026-05-03T22:09:16.494444Z","end":"2026-05-03T22:09:17.46537Z","steps":["trace[2004472022] 'agreement among raft nodes before linearized reading' (duration: 970.807733ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:17.465367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.560148077s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/cilium-config\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-03T22:09:17.465531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:16.494424Z","time spent":"971.09625ms","remote":"127.0.0.1:37388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-03T22:09:17.465477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.892212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-03T22:09:17.465641Z","caller":"traceutil/trace.go:171","msg":"trace[665191068] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:427; }","duration":"439.119039ms","start":"2026-05-03T22:09:17.0265Z","end":"2026-05-03T22:09:17.465619Z","steps":["trace[665191068] 'agreement among raft nodes before linearized reading' (duration: 438.810831ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:17.465736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:17.026477Z","time spent":"439.246961ms","remote":"127.0.0.1:37112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-03T22:09:17.465923Z","caller":"traceutil/trace.go:171","msg":"trace[1757678984] range","detail":"{range_begin:/registry/configmaps/kube-system/cilium-config; range_end:; response_count:0; response_revision:427; }","duration":"2.560407373s","start":"2026-05-03T22:09:14.905113Z","end":"2026-05-03T22:09:17.465521Z","steps":["trace[1757678984] 'agreement among raft nodes before linearized reading' (duration: 2.560111346s)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:17.466283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:14.905102Z","time spent":"2.560923107s","remote":"127.0.0.1:37202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":27,"request content":"key:\"/registry/configmaps/kube-system/cilium-config\" "} {"level":"warn","ts":"2026-05-03T22:09:19.946073Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646843161423994389,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-03T22:09:20.26986Z","caller":"traceutil/trace.go:171","msg":"trace[76277419] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"950.732474ms","start":"2026-05-03T22:09:19.3191Z","end":"2026-05-03T22:09:20.269832Z","steps":["trace[76277419] 'process raft request' (duration: 950.520399ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:20.270018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:19.319082Z","time spent":"950.860568ms","remote":"127.0.0.1:37148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-03T22:09:20.33997Z","caller":"traceutil/trace.go:171","msg":"trace[583004870] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"1.014230088s","start":"2026-05-03T22:09:19.325705Z","end":"2026-05-03T22:09:20.339935Z","steps":["trace[583004870] 'process raft request' (duration: 1.014059023s)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:20.340119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:19.325678Z","time spent":"1.014365382s","remote":"127.0.0.1:37148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-03T22:09:20.340138Z","caller":"traceutil/trace.go:171","msg":"trace[1889531049] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"990.821638ms","start":"2026-05-03T22:09:19.349269Z","end":"2026-05-03T22:09:20.340091Z","steps":["trace[1889531049] 'process raft request' (duration: 990.680714ms)"],"step_count":1} {"level":"info","ts":"2026-05-03T22:09:20.340092Z","caller":"traceutil/trace.go:171","msg":"trace[1689172045] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"1.014220467s","start":"2026-05-03T22:09:19.325796Z","end":"2026-05-03T22:09:20.340016Z","steps":["trace[1689172045] 'process raft request' (duration: 1.014078093s)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:20.340292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:19.349251Z","time spent":"990.975262ms","remote":"127.0.0.1:37148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-03T22:09:20.340374Z","caller":"traceutil/trace.go:171","msg":"trace[2049806970] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"991.010183ms","start":"2026-05-03T22:09:19.349349Z","end":"2026-05-03T22:09:20.34036Z","steps":["trace[2049806970] 'process raft request' (duration: 990.640473ms)"],"step_count":1} {"level":"info","ts":"2026-05-03T22:09:20.34018Z","caller":"traceutil/trace.go:171","msg":"trace[1964397874] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"995.228124ms","start":"2026-05-03T22:09:19.344866Z","end":"2026-05-03T22:09:20.340094Z","steps":["trace[1964397874] 'process raft request' (duration: 995.046799ms)"],"step_count":1} {"level":"info","ts":"2026-05-03T22:09:20.3406Z","caller":"traceutil/trace.go:171","msg":"trace[394008826] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"990.703704ms","start":"2026-05-03T22:09:19.349864Z","end":"2026-05-03T22:09:20.340568Z","steps":["trace[394008826] 'process raft request' (duration: 990.15861ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:20.340548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:19.325793Z","time spent":"1.014519175s","remote":"127.0.0.1:37148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-03T22:09:20.340662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:19.349859Z","time spent":"990.780776ms","remote":"127.0.0.1:37148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-03T22:09:20.34071Z","caller":"traceutil/trace.go:171","msg":"trace[328563085] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"981.514271ms","start":"2026-05-03T22:09:19.359178Z","end":"2026-05-03T22:09:20.340692Z","steps":["trace[328563085] 'process raft request' (duration: 981.271555ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:20.340799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:19.359149Z","time spent":"981.610834ms","remote":"127.0.0.1:37148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-03T22:09:20.340451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:19.349345Z","time spent":"991.073424ms","remote":"127.0.0.1:37148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-03T22:09:20.341026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:19.344828Z","time spent":"995.701316ms","remote":"127.0.0.1:37148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-03T22:09:20.341182Z","caller":"traceutil/trace.go:171","msg":"trace[1911617163] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"979.084738ms","start":"2026-05-03T22:09:19.36208Z","end":"2026-05-03T22:09:20.341165Z","steps":["trace[1911617163] 'process raft request' (duration: 978.46065ms)"],"step_count":1} {"level":"info","ts":"2026-05-03T22:09:20.341256Z","caller":"traceutil/trace.go:171","msg":"trace[1215585642] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"971.507996ms","start":"2026-05-03T22:09:19.369728Z","end":"2026-05-03T22:09:20.341236Z","steps":["trace[1215585642] 'process raft request' (duration: 971.368563ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:20.341275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:19.362066Z","time spent":"979.180411ms","remote":"127.0.0.1:37148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-03T22:09:20.341319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:19.369703Z","time spent":"971.589869ms","remote":"127.0.0.1:37148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-03T22:09:20.341393Z","caller":"traceutil/trace.go:171","msg":"trace[194226131] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"947.242654ms","start":"2026-05-03T22:09:19.394143Z","end":"2026-05-03T22:09:20.341385Z","steps":["trace[194226131] 'process raft request' (duration: 947.012868ms)"],"step_count":1} {"level":"info","ts":"2026-05-03T22:09:20.341387Z","caller":"traceutil/trace.go:171","msg":"trace[204615915] linearizableReadLoop","detail":"{readStateIndex:495; appliedIndex:483; }","duration":"895.393051ms","start":"2026-05-03T22:09:19.445963Z","end":"2026-05-03T22:09:20.341356Z","steps":["trace[204615915] 'read index received' (duration: 823.775774ms)","trace[204615915] 'applied index is now lower than readState.Index' (duration: 71.616187ms)"],"step_count":2} {"level":"info","ts":"2026-05-03T22:09:20.3414Z","caller":"traceutil/trace.go:171","msg":"trace[1303406733] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"857.618011ms","start":"2026-05-03T22:09:19.483772Z","end":"2026-05-03T22:09:20.34139Z","steps":["trace[1303406733] 'process raft request' (duration: 857.523499ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:20.341429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:19.394105Z","time spent":"947.308306ms","remote":"127.0.0.1:37148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-03T22:09:20.341453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:19.483741Z","time spent":"857.690713ms","remote":"127.0.0.1:37148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-03T22:09:20.341603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"895.615368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-03T22:09:20.341677Z","caller":"traceutil/trace.go:171","msg":"trace[935828742] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:477; }","duration":"895.71221ms","start":"2026-05-03T22:09:19.445949Z","end":"2026-05-03T22:09:20.341661Z","steps":["trace[935828742] 'agreement among raft nodes before linearized reading' (duration: 895.489185ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:20.341719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:19.445894Z","time spent":"895.815333ms","remote":"127.0.0.1:37388","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-05-03T22:09:20.347149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"883.484416ms","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-05-03T22:09:20.347212Z","caller":"traceutil/trace.go:171","msg":"trace[1924263595] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:479; }","duration":"883.581388ms","start":"2026-05-03T22:09:19.463618Z","end":"2026-05-03T22:09:20.347199Z","steps":["trace[1924263595] 'agreement among raft nodes before linearized reading' (duration: 883.451485ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:20.347246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:19.463606Z","time spent":"883.63287ms","remote":"127.0.0.1:37388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-03T22:09:20.347423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.474174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-03T22:09:20.347462Z","caller":"traceutil/trace.go:171","msg":"trace[2119659166] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:479; }","duration":"319.551485ms","start":"2026-05-03T22:09:20.027901Z","end":"2026-05-03T22:09:20.347453Z","steps":["trace[2119659166] 'agreement among raft nodes before linearized reading' (duration: 319.499814ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:20.347489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:20.027881Z","time spent":"319.603167ms","remote":"127.0.0.1:37096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-03T22:09:20.347649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"778.931587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.205\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-03T22:09:20.347686Z","caller":"traceutil/trace.go:171","msg":"trace[1928012703] range","detail":"{range_begin:/registry/masterleases/199.204.45.205; range_end:; response_count:1; response_revision:479; }","duration":"779.01371ms","start":"2026-05-03T22:09:19.568664Z","end":"2026-05-03T22:09:20.347677Z","steps":["trace[1928012703] 'agreement among raft nodes before linearized reading' (duration: 778.948109ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:20.347681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"883.976959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-03T22:09:20.347716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:19.568641Z","time spent":"779.066421ms","remote":"127.0.0.1:37130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.205\" "} {"level":"warn","ts":"2026-05-03T22:09:20.347764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"869.505685ms","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-05-03T22:09:20.347791Z","caller":"traceutil/trace.go:171","msg":"trace[2103840048] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:479; }","duration":"869.571567ms","start":"2026-05-03T22:09:19.478214Z","end":"2026-05-03T22:09:20.347786Z","steps":["trace[2103840048] 'agreement among raft nodes before linearized reading' (duration: 869.524596ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:20.347813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:19.478193Z","time spent":"869.615059ms","remote":"127.0.0.1:37388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-03T22:09:20.347721Z","caller":"traceutil/trace.go:171","msg":"trace[149861284] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:479; }","duration":"884.05802ms","start":"2026-05-03T22:09:19.463652Z","end":"2026-05-03T22:09:20.34771Z","steps":["trace[149861284] 'agreement among raft nodes before linearized reading' (duration: 883.966988ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:20.347859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:19.463633Z","time spent":"884.217406ms","remote":"127.0.0.1:37388","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-05-03T22:09:25.490204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.562329ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-05-03T22:09:25.490323Z","caller":"traceutil/trace.go:171","msg":"trace[1949552879] linearizableReadLoop","detail":"{readStateIndex:586; appliedIndex:585; }","duration":"464.675661ms","start":"2026-05-03T22:09:25.025628Z","end":"2026-05-03T22:09:25.490304Z","steps":["trace[1949552879] 'read index received' (duration: 50.086301ms)","trace[1949552879] 'applied index is now lower than readState.Index' (duration: 414.58793ms)"],"step_count":2} {"level":"warn","ts":"2026-05-03T22:09:25.490397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.773814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-03T22:09:25.490424Z","caller":"traceutil/trace.go:171","msg":"trace[1865867792] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:562; }","duration":"464.860716ms","start":"2026-05-03T22:09:25.025552Z","end":"2026-05-03T22:09:25.490413Z","steps":["trace[1865867792] 'agreement among raft nodes before linearized reading' (duration: 464.798274ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:25.490457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:25.025498Z","time spent":"464.951659ms","remote":"127.0.0.1:37096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-03T22:09:25.534674Z","caller":"traceutil/trace.go:171","msg":"trace[1146252831] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"467.537108ms","start":"2026-05-03T22:09:25.067102Z","end":"2026-05-03T22:09:25.534639Z","steps":["trace[1146252831] 'process raft request' (duration: 466.921872ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:25.534847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:25.06708Z","time spent":"467.671021ms","remote":"127.0.0.1:37192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036193,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-03T22:09:26.474133Z","caller":"traceutil/trace.go:171","msg":"trace[615073616] linearizableReadLoop","detail":"{readStateIndex:589; appliedIndex:588; }","duration":"197.251626ms","start":"2026-05-03T22:09:26.276863Z","end":"2026-05-03T22:09:26.474115Z","steps":["trace[615073616] 'read index received' (duration: 196.684791ms)","trace[615073616] 'applied index is now lower than readState.Index' (duration: 566.255µs)"],"step_count":2} {"level":"info","ts":"2026-05-03T22:09:26.474196Z","caller":"traceutil/trace.go:171","msg":"trace[503913795] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"404.671013ms","start":"2026-05-03T22:09:26.069495Z","end":"2026-05-03T22:09:26.474166Z","steps":["trace[503913795] 'process raft request' (duration: 404.229882ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:26.474266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.301437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-03T22:09:26.474291Z","caller":"traceutil/trace.go:171","msg":"trace[1068061555] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:565; }","duration":"197.343868ms","start":"2026-05-03T22:09:26.276941Z","end":"2026-05-03T22:09:26.474285Z","steps":["trace[1068061555] 'agreement among raft nodes before linearized reading' (duration: 197.287447ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:26.474325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:26.069473Z","time spent":"404.782277ms","remote":"127.0.0.1:37192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036229,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-03T22:09:26.474356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.470622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-03T22:09:26.474427Z","caller":"traceutil/trace.go:171","msg":"trace[438170059] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:565; }","duration":"197.577045ms","start":"2026-05-03T22:09:26.276835Z","end":"2026-05-03T22:09:26.474412Z","steps":["trace[438170059] 'agreement among raft nodes before linearized reading' (duration: 197.38475ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:26.47445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.103431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-03T22:09:26.47447Z","caller":"traceutil/trace.go:171","msg":"trace[338826520] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:565; }","duration":"100.155863ms","start":"2026-05-03T22:09:26.374309Z","end":"2026-05-03T22:09:26.474465Z","steps":["trace[338826520] 'agreement among raft nodes before linearized reading' (duration: 100.124362ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:26.474552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.521876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-03T22:09:26.474578Z","caller":"traceutil/trace.go:171","msg":"trace[2035707414] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:565; }","duration":"196.549877ms","start":"2026-05-03T22:09:26.278021Z","end":"2026-05-03T22:09:26.474571Z","steps":["trace[2035707414] 'agreement among raft nodes before linearized reading' (duration: 196.503275ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:26.474658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.496512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-03T22:09:26.474677Z","caller":"traceutil/trace.go:171","msg":"trace[1930561057] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:565; }","duration":"100.533913ms","start":"2026-05-03T22:09:26.374138Z","end":"2026-05-03T22:09:26.474672Z","steps":["trace[1930561057] 'agreement among raft nodes before linearized reading' (duration: 100.505423ms)"],"step_count":1} {"level":"info","ts":"2026-05-03T22:09:26.770606Z","caller":"traceutil/trace.go:171","msg":"trace[1078968029] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"291.361349ms","start":"2026-05-03T22:09:26.479217Z","end":"2026-05-03T22:09:26.770579Z","steps":["trace[1078968029] 'process raft request' (duration: 194.0251ms)","trace[1078968029] 'compare' (duration: 97.223146ms)"],"step_count":2} {"level":"info","ts":"2026-05-03T22:09:26.840075Z","caller":"traceutil/trace.go:171","msg":"trace[145472539] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"360.59333ms","start":"2026-05-03T22:09:26.479462Z","end":"2026-05-03T22:09:26.840055Z","steps":["trace[145472539] 'process raft request' (duration: 360.533069ms)"],"step_count":1} {"level":"info","ts":"2026-05-03T22:09:26.84011Z","caller":"traceutil/trace.go:171","msg":"trace[985429151] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"360.700893ms","start":"2026-05-03T22:09:26.479381Z","end":"2026-05-03T22:09:26.840082Z","steps":["trace[985429151] 'process raft request' (duration: 360.509828ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:26.840204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:26.479449Z","time spent":"360.690632ms","remote":"127.0.0.1:37388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-03T22:09:26.84021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:26.479368Z","time spent":"360.794985ms","remote":"127.0.0.1:37388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-03T22:09:26.840465Z","caller":"traceutil/trace.go:171","msg":"trace[526973491] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"356.543903ms","start":"2026-05-03T22:09:26.483864Z","end":"2026-05-03T22:09:26.840408Z","steps":["trace[526973491] 'process raft request' (duration: 356.157702ms)"],"step_count":1} {"level":"info","ts":"2026-05-03T22:09:26.840509Z","caller":"traceutil/trace.go:171","msg":"trace[1701942767] linearizableReadLoop","detail":"{readStateIndex:592; appliedIndex:589; }","duration":"358.776873ms","start":"2026-05-03T22:09:26.481712Z","end":"2026-05-03T22:09:26.840489Z","steps":["trace[1701942767] 'read index received' (duration: 191.553265ms)","trace[1701942767] 'applied index is now lower than readState.Index' (duration: 167.222578ms)"],"step_count":2} {"level":"info","ts":"2026-05-03T22:09:26.8407Z","caller":"traceutil/trace.go:171","msg":"trace[779061416] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"312.389629ms","start":"2026-05-03T22:09:26.528299Z","end":"2026-05-03T22:09:26.840689Z","steps":["trace[779061416] 'process raft request' (duration: 312.011949ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:26.84074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.70363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-03T22:09:26.840794Z","caller":"traceutil/trace.go:171","msg":"trace[1982192999] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:570; }","duration":"358.771262ms","start":"2026-05-03T22:09:26.482007Z","end":"2026-05-03T22:09:26.840779Z","steps":["trace[1982192999] 'agreement among raft nodes before linearized reading' (duration: 358.69497ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:26.840808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:26.48385Z","time spent":"356.761419ms","remote":"127.0.0.1:37388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-03T22:09:26.840886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:26.528288Z","time spent":"312.44458ms","remote":"127.0.0.1:37456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1092,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-03T22:09:26.840956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.741317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-03T22:09:26.840969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.679479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-03T22:09:26.840989Z","caller":"traceutil/trace.go:171","msg":"trace[2096011977] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:570; }","duration":"312.812349ms","start":"2026-05-03T22:09:26.528169Z","end":"2026-05-03T22:09:26.840981Z","steps":["trace[2096011977] 'agreement among raft nodes before linearized reading' (duration: 312.769638ms)"],"step_count":1} {"level":"info","ts":"2026-05-03T22:09:26.841008Z","caller":"traceutil/trace.go:171","msg":"trace[384052137] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:570; }","duration":"264.744371ms","start":"2026-05-03T22:09:26.576251Z","end":"2026-05-03T22:09:26.840995Z","steps":["trace[384052137] 'agreement among raft nodes before linearized reading' (duration: 264.67242ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:26.841019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:26.528155Z","time spent":"312.855471ms","remote":"127.0.0.1:39402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:500 "} {"level":"warn","ts":"2026-05-03T22:09:26.84107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.813946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-03T22:09:26.840834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:26.481998Z","time spent":"358.826703ms","remote":"127.0.0.1:39382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 "} {"level":"warn","ts":"2026-05-03T22:09:26.840913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.732619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-03T22:09:26.841151Z","caller":"traceutil/trace.go:171","msg":"trace[2039956855] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:570; }","duration":"237.914348ms","start":"2026-05-03T22:09:26.603214Z","end":"2026-05-03T22:09:26.841128Z","steps":["trace[2039956855] 'agreement among raft nodes before linearized reading' (duration: 237.770064ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:26.841174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.966699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-03T22:09:26.841179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.773285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-03T22:09:26.841208Z","caller":"traceutil/trace.go:171","msg":"trace[2031617507] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:570; }","duration":"237.9915ms","start":"2026-05-03T22:09:26.603199Z","end":"2026-05-03T22:09:26.84119Z","steps":["trace[2031617507] 'agreement among raft nodes before linearized reading' (duration: 237.952059ms)"],"step_count":1} {"level":"info","ts":"2026-05-03T22:09:26.841218Z","caller":"traceutil/trace.go:171","msg":"trace[915839143] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:570; }","duration":"316.823606ms","start":"2026-05-03T22:09:26.524385Z","end":"2026-05-03T22:09:26.841208Z","steps":["trace[915839143] 'agreement among raft nodes before linearized reading' (duration: 316.772135ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:26.841256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:26.524382Z","time spent":"316.865227ms","remote":"127.0.0.1:39402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:10000 "} {"level":"warn","ts":"2026-05-03T22:09:26.841307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.17186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-03T22:09:26.841362Z","caller":"traceutil/trace.go:171","msg":"trace[956175364] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:570; }","duration":"101.266633ms","start":"2026-05-03T22:09:26.740082Z","end":"2026-05-03T22:09:26.841349Z","steps":["trace[956175364] 'agreement among raft nodes before linearized reading' (duration: 101.189081ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:26.841383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.915436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-03T22:09:26.840876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.158973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-03T22:09:26.841416Z","caller":"traceutil/trace.go:171","msg":"trace[698330268] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:570; }","duration":"264.992717ms","start":"2026-05-03T22:09:26.576413Z","end":"2026-05-03T22:09:26.841406Z","steps":["trace[698330268] 'agreement among raft nodes before linearized reading' (duration: 264.929235ms)"],"step_count":1} {"level":"info","ts":"2026-05-03T22:09:26.841425Z","caller":"traceutil/trace.go:171","msg":"trace[1036920091] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:570; }","duration":"359.730907ms","start":"2026-05-03T22:09:26.48143Z","end":"2026-05-03T22:09:26.841413Z","steps":["trace[1036920091] 'agreement among raft nodes before linearized reading' (duration: 359.171273ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:26.841433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.246135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-03T22:09:26.841465Z","caller":"traceutil/trace.go:171","msg":"trace[1262813506] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:570; }","duration":"341.309417ms","start":"2026-05-03T22:09:26.500144Z","end":"2026-05-03T22:09:26.841454Z","steps":["trace[1262813506] 'agreement among raft nodes before linearized reading' (duration: 341.258246ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:26.841497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:26.48142Z","time spent":"360.058486ms","remote":"127.0.0.1:39382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"warn","ts":"2026-05-03T22:09:26.841506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:26.500132Z","time spent":"341.362098ms","remote":"127.0.0.1:39388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":131,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 "} {"level":"warn","ts":"2026-05-03T22:09:26.841384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.000181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-03T22:09:26.841588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.942048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-03T22:09:26.841645Z","caller":"traceutil/trace.go:171","msg":"trace[1119700329] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:570; }","duration":"238.0015ms","start":"2026-05-03T22:09:26.60363Z","end":"2026-05-03T22:09:26.841632Z","steps":["trace[1119700329] 'agreement among raft nodes before linearized reading' (duration: 237.919158ms)"],"step_count":1} {"level":"info","ts":"2026-05-03T22:09:26.841215Z","caller":"traceutil/trace.go:171","msg":"trace[1635973086] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:570; }","duration":"101.034327ms","start":"2026-05-03T22:09:26.74014Z","end":"2026-05-03T22:09:26.841174Z","steps":["trace[1635973086] 'agreement among raft nodes before linearized reading' (duration: 100.75428ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:26.841695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.097378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-03T22:09:26.841603Z","caller":"traceutil/trace.go:171","msg":"trace[845275179] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:570; }","duration":"317.217367ms","start":"2026-05-03T22:09:26.524374Z","end":"2026-05-03T22:09:26.841592Z","steps":["trace[845275179] 'agreement among raft nodes before linearized reading' (duration: 316.985861ms)"],"step_count":1} {"level":"info","ts":"2026-05-03T22:09:26.841756Z","caller":"traceutil/trace.go:171","msg":"trace[979494211] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:570; }","duration":"342.163311ms","start":"2026-05-03T22:09:26.499581Z","end":"2026-05-03T22:09:26.841744Z","steps":["trace[979494211] 'agreement among raft nodes before linearized reading' (duration: 342.094588ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:26.841783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:26.524364Z","time spent":"317.400512ms","remote":"127.0.0.1:39402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"warn","ts":"2026-05-03T22:09:26.841815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:26.499577Z","time spent":"342.207012ms","remote":"127.0.0.1:39388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-03T22:09:26.841652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.47861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-03T22:09:26.842001Z","caller":"traceutil/trace.go:171","msg":"trace[1450129536] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:570; }","duration":"359.858041ms","start":"2026-05-03T22:09:26.482127Z","end":"2026-05-03T22:09:26.841985Z","steps":["trace[1450129536] 'agreement among raft nodes before linearized reading' (duration: 359.46633ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:26.84209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:26.482118Z","time spent":"359.952853ms","remote":"127.0.0.1:39382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 "} {"level":"warn","ts":"2026-05-03T22:09:26.842146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.593273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-03T22:09:26.842233Z","caller":"traceutil/trace.go:171","msg":"trace[502124929] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:570; }","duration":"342.697545ms","start":"2026-05-03T22:09:26.499524Z","end":"2026-05-03T22:09:26.842222Z","steps":["trace[502124929] 'agreement among raft nodes before linearized reading' (duration: 342.464509ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:26.841326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.903149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-03T22:09:26.842313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:26.499517Z","time spent":"342.782017ms","remote":"127.0.0.1:39388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":131,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 "} {"level":"info","ts":"2026-05-03T22:09:26.842335Z","caller":"traceutil/trace.go:171","msg":"trace[1879412496] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:570; }","duration":"264.932396ms","start":"2026-05-03T22:09:26.57739Z","end":"2026-05-03T22:09:26.842322Z","steps":["trace[1879412496] 'agreement among raft nodes before linearized reading' (duration: 263.919359ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:27.256855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.411298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-03T22:09:27.256849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.852524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-03T22:09:27.256943Z","caller":"traceutil/trace.go:171","msg":"trace[745285769] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:570; }","duration":"365.981487ms","start":"2026-05-03T22:09:26.890952Z","end":"2026-05-03T22:09:27.256933Z","steps":["trace[745285769] 'range keys from in-memory index tree' (duration: 365.70624ms)"],"step_count":1} {"level":"info","ts":"2026-05-03T22:09:27.256939Z","caller":"traceutil/trace.go:171","msg":"trace[175430167] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:570; }","duration":"110.510251ms","start":"2026-05-03T22:09:27.14641Z","end":"2026-05-03T22:09:27.25692Z","steps":["trace[175430167] 'range keys from in-memory index tree' (duration: 110.365597ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:27.256977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:26.890938Z","time spent":"366.030018ms","remote":"127.0.0.1:39444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 "} {"level":"warn","ts":"2026-05-03T22:09:27.257008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.856386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-03T22:09:27.257179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.112721ms","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":"warn","ts":"2026-05-03T22:09:27.257187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.88986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-03T22:09:27.257216Z","caller":"traceutil/trace.go:171","msg":"trace[1458592768] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:570; }","duration":"366.151132ms","start":"2026-05-03T22:09:26.891056Z","end":"2026-05-03T22:09:27.257208Z","steps":["trace[1458592768] 'count revisions from in-memory index tree' (duration: 366.072829ms)"],"step_count":1} {"level":"info","ts":"2026-05-03T22:09:27.25722Z","caller":"traceutil/trace.go:171","msg":"trace[132341465] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:570; }","duration":"143.930841ms","start":"2026-05-03T22:09:27.113279Z","end":"2026-05-03T22:09:27.25721Z","steps":["trace[132341465] 'range keys from in-memory index tree' (duration: 143.847998ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:27.257242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:26.891034Z","time spent":"366.201763ms","remote":"127.0.0.1:39444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"warn","ts":"2026-05-03T22:09:27.25728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.901215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-03T22:09:27.257388Z","caller":"traceutil/trace.go:171","msg":"trace[1460870205] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:570; }","duration":"366.06997ms","start":"2026-05-03T22:09:26.891304Z","end":"2026-05-03T22:09:27.257374Z","steps":["trace[1460870205] 'range keys from in-memory index tree' (duration: 365.861884ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:27.257446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.09369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-03T22:09:27.257473Z","caller":"traceutil/trace.go:171","msg":"trace[274924626] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:570; }","duration":"381.158061ms","start":"2026-05-03T22:09:26.876308Z","end":"2026-05-03T22:09:27.257466Z","steps":["trace[274924626] 'range keys from in-memory index tree' (duration: 381.029307ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:27.257498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:26.876282Z","time spent":"381.208612ms","remote":"127.0.0.1:39432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 "} {"level":"warn","ts":"2026-05-03T22:09:27.257647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.810506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-03T22:09:27.257671Z","caller":"traceutil/trace.go:171","msg":"trace[2069548488] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:570; }","duration":"111.872467ms","start":"2026-05-03T22:09:27.145792Z","end":"2026-05-03T22:09:27.257665Z","steps":["trace[2069548488] 'count revisions from in-memory index tree' (duration: 111.760305ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:27.257762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.372182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-03T22:09:27.257785Z","caller":"traceutil/trace.go:171","msg":"trace[1946691045] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:570; }","duration":"144.401692ms","start":"2026-05-03T22:09:27.113376Z","end":"2026-05-03T22:09:27.257778Z","steps":["trace[1946691045] 'count revisions from in-memory index tree' (duration: 144.354872ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:27.258061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.304869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-03T22:09:27.258168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.089158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-03T22:09:27.258199Z","caller":"traceutil/trace.go:171","msg":"trace[979815125] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:570; }","duration":"190.124289ms","start":"2026-05-03T22:09:27.068066Z","end":"2026-05-03T22:09:27.258191Z","steps":["trace[979815125] 'range keys from in-memory index tree' (duration: 190.034427ms)"],"step_count":1} {"level":"info","ts":"2026-05-03T22:09:27.258194Z","caller":"traceutil/trace.go:171","msg":"trace[372695044] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:570; }","duration":"112.481363ms","start":"2026-05-03T22:09:27.145691Z","end":"2026-05-03T22:09:27.258172Z","steps":["trace[372695044] 'range keys from in-memory index tree' (duration: 112.115994ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:27.258373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.291134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-03T22:09:27.258404Z","caller":"traceutil/trace.go:171","msg":"trace[761615244] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:570; }","duration":"190.335295ms","start":"2026-05-03T22:09:27.068059Z","end":"2026-05-03T22:09:27.258395Z","steps":["trace[761615244] 'count revisions from in-memory index tree' (duration: 190.233963ms)"],"step_count":1} {"level":"info","ts":"2026-05-03T22:09:27.257136Z","caller":"traceutil/trace.go:171","msg":"trace[1236479644] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:570; }","duration":"230.037811ms","start":"2026-05-03T22:09:27.027074Z","end":"2026-05-03T22:09:27.257112Z","steps":["trace[1236479644] 'range keys from in-memory index tree' (duration: 229.784595ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:27.259461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.688111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-03T22:09:27.25956Z","caller":"traceutil/trace.go:171","msg":"trace[1854140107] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:570; }","duration":"191.467495ms","start":"2026-05-03T22:09:27.068083Z","end":"2026-05-03T22:09:27.25955Z","steps":["trace[1854140107] 'range keys from in-memory index tree' (duration: 188.63256ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:27.257517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:26.891296Z","time spent":"366.204453ms","remote":"127.0.0.1:39444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 "} {"level":"warn","ts":"2026-05-03T22:09:27.25982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.432562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-03T22:09:27.259936Z","caller":"traceutil/trace.go:171","msg":"trace[1447475198] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:570; }","duration":"383.612887ms","start":"2026-05-03T22:09:26.876302Z","end":"2026-05-03T22:09:27.259915Z","steps":["trace[1447475198] 'count revisions from in-memory index tree' (duration: 383.32965ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:27.260019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:26.876284Z","time spent":"383.718289ms","remote":"127.0.0.1:39432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"info","ts":"2026-05-03T22:09:27.420755Z","caller":"traceutil/trace.go:171","msg":"trace[1869112613] linearizableReadLoop","detail":"{readStateIndex:595; appliedIndex:594; }","duration":"177.72682ms","start":"2026-05-03T22:09:27.243007Z","end":"2026-05-03T22:09:27.420733Z","steps":["trace[1869112613] 'read index received' (duration: 176.992041ms)","trace[1869112613] 'applied index is now lower than readState.Index' (duration: 734.069µs)"],"step_count":2} {"level":"info","ts":"2026-05-03T22:09:27.420833Z","caller":"traceutil/trace.go:171","msg":"trace[1461318402] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"183.312698ms","start":"2026-05-03T22:09:27.237482Z","end":"2026-05-03T22:09:27.420795Z","steps":["trace[1461318402] 'process raft request' (duration: 182.710103ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:27.420919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.885674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-03T22:09:27.420951Z","caller":"traceutil/trace.go:171","msg":"trace[316723648] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:571; }","duration":"177.975556ms","start":"2026-05-03T22:09:27.242967Z","end":"2026-05-03T22:09:27.420942Z","steps":["trace[316723648] 'agreement among raft nodes before linearized reading' (duration: 177.880334ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:27.421131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.866004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-03T22:09:27.421264Z","caller":"traceutil/trace.go:171","msg":"trace[291912654] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:571; }","duration":"178.054008ms","start":"2026-05-03T22:09:27.243183Z","end":"2026-05-03T22:09:27.421237Z","steps":["trace[291912654] 'agreement among raft nodes before linearized reading' (duration: 177.835702ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:27.421582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.16542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-03T22:09:27.42168Z","caller":"traceutil/trace.go:171","msg":"trace[1407058734] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:571; }","duration":"131.292504ms","start":"2026-05-03T22:09:27.290368Z","end":"2026-05-03T22:09:27.421661Z","steps":["trace[1407058734] 'agreement among raft nodes before linearized reading' (duration: 131.174861ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:27.421828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.282045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-03T22:09:27.421906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.52163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-03T22:09:27.421921Z","caller":"traceutil/trace.go:171","msg":"trace[1412366941] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:571; }","duration":"178.353857ms","start":"2026-05-03T22:09:27.24352Z","end":"2026-05-03T22:09:27.421874Z","steps":["trace[1412366941] 'agreement among raft nodes before linearized reading' (duration: 178.264245ms)"],"step_count":1} {"level":"info","ts":"2026-05-03T22:09:27.421943Z","caller":"traceutil/trace.go:171","msg":"trace[819775458] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:571; }","duration":"131.585882ms","start":"2026-05-03T22:09:27.290347Z","end":"2026-05-03T22:09:27.421933Z","steps":["trace[819775458] 'agreement among raft nodes before linearized reading' (duration: 131.536891ms)"],"step_count":1} {"level":"info","ts":"2026-05-03T22:09:28.278154Z","caller":"traceutil/trace.go:171","msg":"trace[965397109] linearizableReadLoop","detail":"{readStateIndex:597; appliedIndex:596; }","duration":"251.132656ms","start":"2026-05-03T22:09:28.026991Z","end":"2026-05-03T22:09:28.278124Z","steps":["trace[965397109] 'read index received' (duration: 250.200782ms)","trace[965397109] 'applied index is now lower than readState.Index' (duration: 930.504µs)"],"step_count":2} {"level":"info","ts":"2026-05-03T22:09:28.278207Z","caller":"traceutil/trace.go:171","msg":"trace[1323744453] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"360.041295ms","start":"2026-05-03T22:09:27.91813Z","end":"2026-05-03T22:09:28.278172Z","steps":["trace[1323744453] 'process raft request' (duration: 359.306666ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:28.278322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.283712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-03T22:09:28.278365Z","caller":"traceutil/trace.go:171","msg":"trace[1209577576] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:573; }","duration":"251.437605ms","start":"2026-05-03T22:09:28.026914Z","end":"2026-05-03T22:09:28.278351Z","steps":["trace[1209577576] 'agreement among raft nodes before linearized reading' (duration: 251.315741ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:28.278431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:27.918104Z","time spent":"360.231942ms","remote":"127.0.0.1:37192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036293,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-03T22:09:28.638581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.606872ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-03T22:09:28.638859Z","caller":"traceutil/trace.go:171","msg":"trace[1843695243] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"298.773985ms","start":"2026-05-03T22:09:28.34004Z","end":"2026-05-03T22:09:28.638814Z","steps":["trace[1843695243] 'process raft request' (duration: 11.859995ms)","trace[1843695243] 'compare' (duration: 286.318754ms)"],"step_count":2} {"level":"info","ts":"2026-05-03T22:09:28.639082Z","caller":"traceutil/trace.go:171","msg":"trace[1912892818] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"152.921772ms","start":"2026-05-03T22:09:28.486135Z","end":"2026-05-03T22:09:28.639057Z","steps":["trace[1912892818] 'process raft request' (duration: 152.84554ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:29.75935Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646843161423994613,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-03T22:09:30.177814Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.111820893s","expected-duration":"1s"} {"level":"info","ts":"2026-05-03T22:09:30.178879Z","caller":"traceutil/trace.go:171","msg":"trace[565195149] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"1.113886978s","start":"2026-05-03T22:09:29.06497Z","end":"2026-05-03T22:09:30.178857Z","steps":["trace[565195149] 'process raft request' (duration: 1.113298893s)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:30.179066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:29.064938Z","time spent":"1.114005031s","remote":"127.0.0.1:37192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036317,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-03T22:09:30.504405Z","caller":"traceutil/trace.go:171","msg":"trace[1284689425] linearizableReadLoop","detail":"{readStateIndex:607; appliedIndex:605; }","duration":"1.24560758s","start":"2026-05-03T22:09:29.258774Z","end":"2026-05-03T22:09:30.504382Z","steps":["trace[1284689425] 'read index received' (duration: 919.231431ms)","trace[1284689425] 'applied index is now lower than readState.Index' (duration: 326.375169ms)"],"step_count":2} {"level":"info","ts":"2026-05-03T22:09:30.504497Z","caller":"traceutil/trace.go:171","msg":"trace[173734604] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"1.14615511s","start":"2026-05-03T22:09:29.358309Z","end":"2026-05-03T22:09:30.504464Z","steps":["trace[173734604] 'process raft request' (duration: 1.145929835s)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:30.504763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:29.358284Z","time spent":"1.146358927s","remote":"127.0.0.1:37388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-03T22:09:30.504895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.246062653s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-c5x7n\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-03T22:09:30.504989Z","caller":"traceutil/trace.go:171","msg":"trace[1888472571] range","detail":"{range_begin:/registry/pods/kube-system/cilium-c5x7n; range_end:; response_count:1; response_revision:583; }","duration":"1.246224668s","start":"2026-05-03T22:09:29.258744Z","end":"2026-05-03T22:09:30.504969Z","steps":["trace[1888472571] 'agreement among raft nodes before linearized reading' (duration: 1.245858298s)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:30.505062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:29.258733Z","time spent":"1.24631997s","remote":"127.0.0.1:37288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-c5x7n\" "} {"level":"warn","ts":"2026-05-03T22:09:31.004959Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646843161423994617,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-03T22:09:31.505369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.805425ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-03T22:09:31.505577Z","caller":"traceutil/trace.go:171","msg":"trace[673310647] linearizableReadLoop","detail":"{readStateIndex:609; appliedIndex:607; }","duration":"1.001035709s","start":"2026-05-03T22:09:30.504482Z","end":"2026-05-03T22:09:31.505518Z","steps":["trace[673310647] 'read index received' (duration: 716.009578ms)","trace[673310647] 'applied index is now lower than readState.Index' (duration: 285.025461ms)"],"step_count":2} {"level":"warn","ts":"2026-05-03T22:09:31.505826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"710.66808ms","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-05-03T22:09:31.505855Z","caller":"traceutil/trace.go:171","msg":"trace[1944071886] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:585; }","duration":"710.700181ms","start":"2026-05-03T22:09:30.795148Z","end":"2026-05-03T22:09:31.505848Z","steps":["trace[1944071886] 'agreement among raft nodes before linearized reading' (duration: 710.65166ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:31.505878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:30.795092Z","time spent":"710.780783ms","remote":"127.0.0.1:37388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-03T22:09:31.505855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.936876469s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.205\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-03T22:09:31.505929Z","caller":"traceutil/trace.go:171","msg":"trace[1875992946] range","detail":"{range_begin:/registry/masterleases/199.204.45.205; range_end:; response_count:1; response_revision:585; }","duration":"1.937013023s","start":"2026-05-03T22:09:29.568901Z","end":"2026-05-03T22:09:31.505914Z","steps":["trace[1875992946] 'agreement among raft nodes before linearized reading' (duration: 1.936861979s)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:31.506022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:29.568879Z","time spent":"1.937131905s","remote":"127.0.0.1:37130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.205\" "} {"level":"info","ts":"2026-05-03T22:09:31.505571Z","caller":"traceutil/trace.go:171","msg":"trace[1885786108] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"1.269221978s","start":"2026-05-03T22:09:30.236304Z","end":"2026-05-03T22:09:31.505526Z","steps":["trace[1885786108] 'process raft request' (duration: 984.205907ms)","trace[1885786108] 'compare' (duration: 284.550119ms)"],"step_count":2} {"level":"warn","ts":"2026-05-03T22:09:31.506239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.480331405s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-03T22:09:31.50626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"647.233248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-03T22:09:31.506269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:30.23628Z","time spent":"1.269921057s","remote":"127.0.0.1:37350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-03T22:09:31.506371Z","caller":"traceutil/trace.go:171","msg":"trace[1061147389] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"986.44483ms","start":"2026-05-03T22:09:30.519915Z","end":"2026-05-03T22:09:31.50636Z","steps":["trace[1061147389] 'process raft request' (duration: 985.531766ms)"],"step_count":1} {"level":"info","ts":"2026-05-03T22:09:31.506308Z","caller":"traceutil/trace.go:171","msg":"trace[655988404] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:585; }","duration":"1.480437358s","start":"2026-05-03T22:09:30.025857Z","end":"2026-05-03T22:09:31.506294Z","steps":["trace[655988404] 'agreement among raft nodes before linearized reading' (duration: 1.480346665s)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:31.506444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.616512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-03T22:09:31.506479Z","caller":"traceutil/trace.go:171","msg":"trace[1723959647] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:585; }","duration":"646.668173ms","start":"2026-05-03T22:09:30.859802Z","end":"2026-05-03T22:09:31.50647Z","steps":["trace[1723959647] 'agreement among raft nodes before linearized reading' (duration: 646.55603ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:31.506516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:30.859793Z","time spent":"646.715665ms","remote":"127.0.0.1:37388","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-05-03T22:09:31.506477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:30.519892Z","time spent":"986.539872ms","remote":"127.0.0.1:37288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-03T22:09:31.506388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.977584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-03T22:09:31.506466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:30.025834Z","time spent":"1.480615093s","remote":"127.0.0.1:37112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-03T22:09:31.506618Z","caller":"traceutil/trace.go:171","msg":"trace[1887607629] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:585; }","duration":"646.277762ms","start":"2026-05-03T22:09:30.860316Z","end":"2026-05-03T22:09:31.506594Z","steps":["trace[1887607629] 'agreement among raft nodes before linearized reading' (duration: 645.742489ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:31.506662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:30.860305Z","time spent":"646.345074ms","remote":"127.0.0.1:37388","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":"info","ts":"2026-05-03T22:09:31.506332Z","caller":"traceutil/trace.go:171","msg":"trace[1022024949] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:585; }","duration":"647.356071ms","start":"2026-05-03T22:09:30.858958Z","end":"2026-05-03T22:09:31.506314Z","steps":["trace[1022024949] 'agreement among raft nodes before linearized reading' (duration: 647.141746ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:31.506752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:30.858937Z","time spent":"647.809383ms","remote":"127.0.0.1:37388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-03T22:09:32.078353Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646843161423994627,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-03T22:09:32.57921Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646843161423994627,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-03T22:09:33.080214Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646843161423994627,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-03T22:09:33.517659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000255687s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-03T22:09:33.517727Z","caller":"traceutil/trace.go:171","msg":"trace[1478490256] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000357619s","start":"2026-05-03T22:09:31.517354Z","end":"2026-05-03T22:09:33.517711Z","steps":["trace[1478490256] 'agreement among raft nodes before linearized reading' (duration: 2.000271477s)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:33.517765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:31.517347Z","time spent":"2.00041041s","remote":"127.0.0.1:37096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/03 22:09:33 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-03T22:09:33.581338Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646843161423994627,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-03T22:09:34.081812Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646843161423994627,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-03T22:09:34.184085Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.606332192s","expected-duration":"1s"} {"level":"info","ts":"2026-05-03T22:09:34.243887Z","caller":"traceutil/trace.go:171","msg":"trace[1087713645] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"2.729724508s","start":"2026-05-03T22:09:31.514139Z","end":"2026-05-03T22:09:34.243864Z","steps":["trace[1087713645] 'process raft request' (duration: 2.729609057s)"],"step_count":1} {"level":"info","ts":"2026-05-03T22:09:34.243907Z","caller":"traceutil/trace.go:171","msg":"trace[1026756839] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"2.732719408s","start":"2026-05-03T22:09:31.511157Z","end":"2026-05-03T22:09:34.243876Z","steps":["trace[1026756839] 'process raft request' (duration: 2.673135049s)","trace[1026756839] 'compare' (duration: 58.613413ms)"],"step_count":2} {"level":"warn","ts":"2026-05-03T22:09:34.244055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:31.511133Z","time spent":"2.732834259s","remote":"127.0.0.1:37388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-03T22:09:34.244084Z","caller":"traceutil/trace.go:171","msg":"trace[1900236039] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"2.729652602s","start":"2026-05-03T22:09:31.514413Z","end":"2026-05-03T22:09:34.244065Z","steps":["trace[1900236039] 'process raft request' (duration: 2.729416051s)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:34.24405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:31.514113Z","time spent":"2.729845488s","remote":"127.0.0.1:37388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-03T22:09:34.244168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:31.514403Z","time spent":"2.729719342s","remote":"127.0.0.1:37388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-03T22:09:34.244372Z","caller":"traceutil/trace.go:171","msg":"trace[1515864380] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"2.729657447s","start":"2026-05-03T22:09:31.514705Z","end":"2026-05-03T22:09:34.244363Z","steps":["trace[1515864380] 'process raft request' (duration: 2.729303584s)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:34.244427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:31.514694Z","time spent":"2.729702347s","remote":"127.0.0.1:37388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-03T22:09:34.244667Z","caller":"traceutil/trace.go:171","msg":"trace[1027248213] linearizableReadLoop","detail":"{readStateIndex:616; appliedIndex:610; }","duration":"2.666726683s","start":"2026-05-03T22:09:31.577928Z","end":"2026-05-03T22:09:34.244655Z","steps":["trace[1027248213] 'read index received' (duration: 2.606335638s)","trace[1027248213] 'applied index is now lower than readState.Index' (duration: 60.390115ms)"],"step_count":2} {"level":"info","ts":"2026-05-03T22:09:34.244759Z","caller":"traceutil/trace.go:171","msg":"trace[1706144222] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"2.66596703s","start":"2026-05-03T22:09:31.578781Z","end":"2026-05-03T22:09:34.244748Z","steps":["trace[1706144222] 'process raft request' (duration: 2.665546088s)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:34.244824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:31.578759Z","time spent":"2.666027432s","remote":"127.0.0.1:37130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-03T22:09:34.244893Z","caller":"traceutil/trace.go:171","msg":"trace[1787053457] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"1.347768792s","start":"2026-05-03T22:09:32.897107Z","end":"2026-05-03T22:09:34.244876Z","steps":["trace[1787053457] 'process raft request' (duration: 1.34743704s)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:34.244991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:32.897062Z","time spent":"1.347879135s","remote":"127.0.0.1:37388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-03T22:09:34.245029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.663855909s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-05-03T22:09:34.245064Z","caller":"traceutil/trace.go:171","msg":"trace[577012338] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:591; }","duration":"2.66392897s","start":"2026-05-03T22:09:31.581125Z","end":"2026-05-03T22:09:34.245054Z","steps":["trace[577012338] 'agreement among raft nodes before linearized reading' (duration: 2.66386229s)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:34.245107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:31.581102Z","time spent":"2.663990062s","remote":"127.0.0.1:37314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":215,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"warn","ts":"2026-05-03T22:09:34.245282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"727.118983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-03T22:09:34.245311Z","caller":"traceutil/trace.go:171","msg":"trace[2122033405] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:591; }","duration":"727.180244ms","start":"2026-05-03T22:09:33.518121Z","end":"2026-05-03T22:09:34.245301Z","steps":["trace[2122033405] 'agreement among raft nodes before linearized reading' (duration: 727.133354ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:34.245336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:33.518101Z","time spent":"727.229894ms","remote":"127.0.0.1:37112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-03T22:09:34.245513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.259824482s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-03T22:09:34.245552Z","caller":"traceutil/trace.go:171","msg":"trace[1948544687] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:591; }","duration":"1.259900473s","start":"2026-05-03T22:09:32.985641Z","end":"2026-05-03T22:09:34.245542Z","steps":["trace[1948544687] 'agreement among raft nodes before linearized reading' (duration: 1.259844633s)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:34.245578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:32.985605Z","time spent":"1.259967615s","remote":"127.0.0.1:37096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-03T22:09:34.486077Z","caller":"traceutil/trace.go:171","msg":"trace[2007182407] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"176.693722ms","start":"2026-05-03T22:09:34.309331Z","end":"2026-05-03T22:09:34.486025Z","steps":["trace[2007182407] 'process raft request' (duration: 86.661319ms)","trace[2007182407] 'compare' (duration: 89.42996ms)"],"step_count":2} {"level":"info","ts":"2026-05-03T22:09:36.913104Z","caller":"traceutil/trace.go:171","msg":"trace[525214492] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"478.821761ms","start":"2026-05-03T22:09:36.43426Z","end":"2026-05-03T22:09:36.913081Z","steps":["trace[525214492] 'process raft request' (duration: 478.64851ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:36.91328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:36.434233Z","time spent":"478.953232ms","remote":"127.0.0.1:37148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4318,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-03T22:09:37.143921Z","caller":"traceutil/trace.go:171","msg":"trace[1022432804] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"327.47341ms","start":"2026-05-03T22:09:36.816424Z","end":"2026-05-03T22:09:37.143897Z","steps":["trace[1022432804] 'process raft request' (duration: 327.436879ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:37.144049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:36.816395Z","time spent":"327.597701ms","remote":"127.0.0.1:37148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-03T22:09:37.144143Z","caller":"traceutil/trace.go:171","msg":"trace[1017912324] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"529.390123ms","start":"2026-05-03T22:09:36.614565Z","end":"2026-05-03T22:09:37.143955Z","steps":["trace[1017912324] 'process raft request' (duration: 528.977509ms)"],"step_count":1} {"level":"info","ts":"2026-05-03T22:09:37.144236Z","caller":"traceutil/trace.go:171","msg":"trace[573963400] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"500.298741ms","start":"2026-05-03T22:09:36.643894Z","end":"2026-05-03T22:09:37.144193Z","steps":["trace[573963400] 'process raft request' (duration: 499.910747ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:37.144478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:36.614548Z","time spent":"529.822746ms","remote":"127.0.0.1:37148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3202,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-03T22:09:37.144528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:36.64386Z","time spent":"500.590803ms","remote":"127.0.0.1:37174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":700,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-03T22:09:37.149175Z","caller":"traceutil/trace.go:171","msg":"trace[307343467] linearizableReadLoop","detail":"{readStateIndex:664; appliedIndex:660; }","duration":"121.501284ms","start":"2026-05-03T22:09:37.027651Z","end":"2026-05-03T22:09:37.149152Z","steps":["trace[307343467] 'read index received' (duration: 115.89953ms)","trace[307343467] 'applied index is now lower than readState.Index' (duration: 5.600824ms)"],"step_count":2} {"level":"info","ts":"2026-05-03T22:09:37.149269Z","caller":"traceutil/trace.go:171","msg":"trace[1140648771] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"132.481129ms","start":"2026-05-03T22:09:37.016769Z","end":"2026-05-03T22:09:37.14925Z","steps":["trace[1140648771] 'process raft request' (duration: 132.260298ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:37.149515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.858946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-03T22:09:37.149566Z","caller":"traceutil/trace.go:171","msg":"trace[1831790537] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:638; }","duration":"121.948209ms","start":"2026-05-03T22:09:37.027604Z","end":"2026-05-03T22:09:37.149552Z","steps":["trace[1831790537] 'agreement among raft nodes before linearized reading' (duration: 121.630635ms)"],"step_count":1} {"level":"info","ts":"2026-05-03T22:09:48.667428Z","caller":"traceutil/trace.go:171","msg":"trace[8755447] linearizableReadLoop","detail":"{readStateIndex:764; appliedIndex:763; }","duration":"353.441432ms","start":"2026-05-03T22:09:48.313963Z","end":"2026-05-03T22:09:48.667404Z","steps":["trace[8755447] 'read index received' (duration: 353.29144ms)","trace[8755447] 'applied index is now lower than readState.Index' (duration: 148.982µs)"],"step_count":2} {"level":"info","ts":"2026-05-03T22:09:48.667485Z","caller":"traceutil/trace.go:171","msg":"trace[648564444] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"354.30026ms","start":"2026-05-03T22:09:48.313151Z","end":"2026-05-03T22:09:48.667451Z","steps":["trace[648564444] 'process raft request' (duration: 354.081698ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:48.667649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:48.313141Z","time spent":"354.424821ms","remote":"127.0.0.1:37388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-03T22:09:48.667673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.444869ms","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-05-03T22:09:48.667709Z","caller":"traceutil/trace.go:171","msg":"trace[987805285] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:735; }","duration":"348.51071ms","start":"2026-05-03T22:09:48.319188Z","end":"2026-05-03T22:09:48.667699Z","steps":["trace[987805285] 'agreement among raft nodes before linearized reading' (duration: 348.363778ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:48.667704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.652662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-03T22:09:48.667712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.724865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-03T22:09:48.667745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:48.319182Z","time spent":"348.55489ms","remote":"127.0.0.1:37388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-03T22:09:48.667751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.582449ms","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-05-03T22:09:48.667782Z","caller":"traceutil/trace.go:171","msg":"trace[945861129] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:735; }","duration":"100.782264ms","start":"2026-05-03T22:09:48.566983Z","end":"2026-05-03T22:09:48.667766Z","steps":["trace[945861129] 'agreement among raft nodes before linearized reading' (duration: 100.636663ms)"],"step_count":1} {"level":"info","ts":"2026-05-03T22:09:48.667799Z","caller":"traceutil/trace.go:171","msg":"trace[919198596] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:735; }","duration":"353.848555ms","start":"2026-05-03T22:09:48.313928Z","end":"2026-05-03T22:09:48.667777Z","steps":["trace[919198596] 'agreement among raft nodes before linearized reading' (duration: 353.619433ms)"],"step_count":1} {"level":"info","ts":"2026-05-03T22:09:48.667821Z","caller":"traceutil/trace.go:171","msg":"trace[55370379] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:735; }","duration":"346.69204ms","start":"2026-05-03T22:09:48.321112Z","end":"2026-05-03T22:09:48.667804Z","steps":["trace[55370379] 'agreement among raft nodes before linearized reading' (duration: 346.510918ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:48.66786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:48.321088Z","time spent":"346.76364ms","remote":"127.0.0.1:37388","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-05-03T22:09:48.667858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:48.313914Z","time spent":"353.931327ms","remote":"127.0.0.1:37388","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-05-03T22:09:48.856464Z","caller":"traceutil/trace.go:171","msg":"trace[724258113] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"184.762341ms","start":"2026-05-03T22:09:48.671675Z","end":"2026-05-03T22:09:48.856438Z","steps":["trace[724258113] 'process raft request' (duration: 142.343433ms)","trace[724258113] 'compare' (duration: 42.319927ms)"],"step_count":2} {"level":"info","ts":"2026-05-03T22:09:48.867357Z","caller":"traceutil/trace.go:171","msg":"trace[719039017] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"193.079808ms","start":"2026-05-03T22:09:48.674252Z","end":"2026-05-03T22:09:48.867332Z","steps":["trace[719039017] 'process raft request' (duration: 193.038428ms)"],"step_count":1} {"level":"info","ts":"2026-05-03T22:09:48.867448Z","caller":"traceutil/trace.go:171","msg":"trace[1603101690] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"194.472263ms","start":"2026-05-03T22:09:48.672952Z","end":"2026-05-03T22:09:48.867424Z","steps":["trace[1603101690] 'process raft request' (duration: 194.211631ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:51.17195Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646843161423994943,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-03T22:09:51.672718Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646843161423994943,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-03T22:09:51.821097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.794295425s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-03T22:09:51.821188Z","caller":"traceutil/trace.go:171","msg":"trace[669367810] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:748; }","duration":"1.794431226s","start":"2026-05-03T22:09:50.026734Z","end":"2026-05-03T22:09:51.821166Z","steps":["trace[669367810] 'range keys from in-memory index tree' (duration: 1.794211403s)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:51.821239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:50.026715Z","time spent":"1.794511316s","remote":"127.0.0.1:37112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-03T22:09:51.82175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.523368364s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-03T22:09:51.821803Z","caller":"traceutil/trace.go:171","msg":"trace[1493674009] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:748; }","duration":"1.523479786s","start":"2026-05-03T22:09:50.29831Z","end":"2026-05-03T22:09:51.82179Z","steps":["trace[1493674009] 'range keys from in-memory index tree' (duration: 1.523217864s)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:51.821849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:50.298291Z","time spent":"1.523549807s","remote":"127.0.0.1:37388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-03T22:09:52.003284Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.610137678s","expected-duration":"1s"} {"level":"info","ts":"2026-05-03T22:09:52.00371Z","caller":"traceutil/trace.go:171","msg":"trace[639173904] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"1.610659276s","start":"2026-05-03T22:09:50.393027Z","end":"2026-05-03T22:09:52.003686Z","steps":["trace[639173904] 'process raft request' (duration: 1.610513444s)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:52.003859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:50.393007Z","time spent":"1.610774417s","remote":"127.0.0.1:37350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-03T22:09:52.183089Z","caller":"traceutil/trace.go:171","msg":"trace[1968395029] linearizableReadLoop","detail":"{readStateIndex:780; appliedIndex:778; }","duration":"1.511375949s","start":"2026-05-03T22:09:50.671695Z","end":"2026-05-03T22:09:52.183071Z","steps":["trace[1968395029] 'read index received' (duration: 1.331830278s)","trace[1968395029] 'applied index is now lower than readState.Index' (duration: 179.544831ms)"],"step_count":2} {"level":"info","ts":"2026-05-03T22:09:52.183123Z","caller":"traceutil/trace.go:171","msg":"trace[867983596] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"1.218131493s","start":"2026-05-03T22:09:50.964958Z","end":"2026-05-03T22:09:52.183089Z","steps":["trace[867983596] 'process raft request' (duration: 1.217963511s)"],"step_count":1} {"level":"info","ts":"2026-05-03T22:09:52.183154Z","caller":"traceutil/trace.go:171","msg":"trace[1847239429] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"357.239385ms","start":"2026-05-03T22:09:51.825887Z","end":"2026-05-03T22:09:52.183127Z","steps":["trace[1847239429] 'process raft request' (duration: 357.148774ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:52.183248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.511547321s","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-05-03T22:09:52.18328Z","caller":"traceutil/trace.go:171","msg":"trace[932162833] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:751; }","duration":"1.511627503s","start":"2026-05-03T22:09:50.671645Z","end":"2026-05-03T22:09:52.183273Z","steps":["trace[932162833] 'agreement among raft nodes before linearized reading' (duration: 1.511526962s)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:52.183304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:50.671626Z","time spent":"1.511671533s","remote":"127.0.0.1:37388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-03T22:09:52.183393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:51.825866Z","time spent":"357.379366ms","remote":"127.0.0.1:37388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-03T22:09:52.183429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:50.964937Z","time spent":"1.218259235s","remote":"127.0.0.1:37388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-03T22:09:52.183472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"759.549597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-03T22:09:52.183542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.311962125s","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-05-03T22:09:52.183535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.323413951s","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-05-03T22:09:52.183604Z","caller":"traceutil/trace.go:171","msg":"trace[1292262393] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:751; }","duration":"1.312028126s","start":"2026-05-03T22:09:50.871551Z","end":"2026-05-03T22:09:52.183579Z","steps":["trace[1292262393] 'agreement among raft nodes before linearized reading' (duration: 1.311862754s)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:52.183617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.895388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-03T22:09:52.183641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:50.871545Z","time spent":"1.312086606s","remote":"127.0.0.1:37388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-03T22:09:52.18365Z","caller":"traceutil/trace.go:171","msg":"trace[192794450] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:751; }","duration":"354.947649ms","start":"2026-05-03T22:09:51.828694Z","end":"2026-05-03T22:09:52.183642Z","steps":["trace[192794450] 'agreement among raft nodes before linearized reading' (duration: 354.900788ms)"],"step_count":1} {"level":"info","ts":"2026-05-03T22:09:52.183658Z","caller":"traceutil/trace.go:171","msg":"trace[988194717] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:751; }","duration":"1.323603253s","start":"2026-05-03T22:09:50.86003Z","end":"2026-05-03T22:09:52.183634Z","steps":["trace[988194717] 'agreement among raft nodes before linearized reading' (duration: 1.32329776s)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:52.183694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:51.828685Z","time spent":"355.001689ms","remote":"127.0.0.1:37096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-03T22:09:52.183749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:50.859999Z","time spent":"1.323717564s","remote":"127.0.0.1:37388","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":"info","ts":"2026-05-03T22:09:52.183566Z","caller":"traceutil/trace.go:171","msg":"trace[467003550] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:751; }","duration":"759.708079ms","start":"2026-05-03T22:09:51.423839Z","end":"2026-05-03T22:09:52.183547Z","steps":["trace[467003550] 'agreement among raft nodes before linearized reading' (duration: 759.522927ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:52.183814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:51.423817Z","time spent":"759.983261ms","remote":"127.0.0.1:37214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":5,"response size":29,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-05-03T22:09:52.183878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.31245153s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-03T22:09:52.183973Z","caller":"traceutil/trace.go:171","msg":"trace[719480876] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:751; }","duration":"1.312548621s","start":"2026-05-03T22:09:50.87141Z","end":"2026-05-03T22:09:52.183959Z","steps":["trace[719480876] 'agreement among raft nodes before linearized reading' (duration: 1.312277038s)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:09:52.184047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:09:50.87134Z","time spent":"1.312694884s","remote":"127.0.0.1:37388","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-05-03T22:10:05.969777Z","caller":"traceutil/trace.go:171","msg":"trace[534398776] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"114.393042ms","start":"2026-05-03T22:10:05.855366Z","end":"2026-05-03T22:10:05.969759Z","steps":["trace[534398776] 'process raft request' (duration: 114.276301ms)"],"step_count":1} {"level":"info","ts":"2026-05-03T22:10:18.548733Z","caller":"traceutil/trace.go:171","msg":"trace[792661900] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"109.944494ms","start":"2026-05-03T22:10:18.438756Z","end":"2026-05-03T22:10:18.548701Z","steps":["trace[792661900] 'process raft request' (duration: 58.2754ms)","trace[792661900] 'compare' (duration: 51.481511ms)"],"step_count":2} {"level":"info","ts":"2026-05-03T22:10:26.557389Z","caller":"traceutil/trace.go:171","msg":"trace[1902005413] linearizableReadLoop","detail":"{readStateIndex:1035; appliedIndex:1034; }","duration":"110.236994ms","start":"2026-05-03T22:10:26.447133Z","end":"2026-05-03T22:10:26.55737Z","steps":["trace[1902005413] 'read index received' (duration: 110.123352ms)","trace[1902005413] 'applied index is now lower than readState.Index' (duration: 113.082µs)"],"step_count":2} {"level":"info","ts":"2026-05-03T22:10:26.557473Z","caller":"traceutil/trace.go:171","msg":"trace[1135161507] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"112.051475ms","start":"2026-05-03T22:10:26.445417Z","end":"2026-05-03T22:10:26.557468Z","steps":["trace[1135161507] 'process raft request' (duration: 111.854422ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:10:26.55763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.493708ms","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-05-03T22:10:26.557664Z","caller":"traceutil/trace.go:171","msg":"trace[400622116] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:995; }","duration":"110.58295ms","start":"2026-05-03T22:10:26.447075Z","end":"2026-05-03T22:10:26.557658Z","steps":["trace[400622116] 'agreement among raft nodes before linearized reading' (duration: 110.478898ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:10:26.557809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.867765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-03T22:10:26.557923Z","caller":"traceutil/trace.go:171","msg":"trace[1835363213] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:995; }","duration":"101.002757ms","start":"2026-05-03T22:10:26.456896Z","end":"2026-05-03T22:10:26.557899Z","steps":["trace[1835363213] 'agreement among raft nodes before linearized reading' (duration: 100.721472ms)"],"step_count":1} {"level":"info","ts":"2026-05-03T22:10:26.761811Z","caller":"traceutil/trace.go:171","msg":"trace[648713123] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"201.542466ms","start":"2026-05-03T22:10:26.560239Z","end":"2026-05-03T22:10:26.761782Z","steps":["trace[648713123] 'process raft request' (duration: 149.153114ms)","trace[648713123] 'compare' (duration: 52.27364ms)"],"step_count":2} {"level":"info","ts":"2026-05-03T22:10:26.764903Z","caller":"traceutil/trace.go:171","msg":"trace[469497901] linearizableReadLoop","detail":"{readStateIndex:1038; appliedIndex:1035; }","duration":"185.991471ms","start":"2026-05-03T22:10:26.578889Z","end":"2026-05-03T22:10:26.764881Z","steps":["trace[469497901] 'read index received' (duration: 130.513748ms)","trace[469497901] 'applied index is now lower than readState.Index' (duration: 55.476533ms)"],"step_count":2} {"level":"info","ts":"2026-05-03T22:10:26.764949Z","caller":"traceutil/trace.go:171","msg":"trace[740809336] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"203.405668ms","start":"2026-05-03T22:10:26.56151Z","end":"2026-05-03T22:10:26.764915Z","steps":["trace[740809336] 'process raft request' (duration: 203.297436ms)"],"step_count":1} {"level":"info","ts":"2026-05-03T22:10:26.765101Z","caller":"traceutil/trace.go:171","msg":"trace[476315798] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"203.672192ms","start":"2026-05-03T22:10:26.561419Z","end":"2026-05-03T22:10:26.765091Z","steps":["trace[476315798] 'process raft request' (duration: 203.266885ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:10:26.765116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.214546ms","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-05-03T22:10:26.765152Z","caller":"traceutil/trace.go:171","msg":"trace[294145703] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:999; }","duration":"186.306316ms","start":"2026-05-03T22:10:26.578836Z","end":"2026-05-03T22:10:26.765142Z","steps":["trace[294145703] 'agreement among raft nodes before linearized reading' (duration: 186.164154ms)"],"step_count":1} {"level":"info","ts":"2026-05-03T22:11:15.449318Z","caller":"traceutil/trace.go:171","msg":"trace[2053568890] linearizableReadLoop","detail":"{readStateIndex:1204; appliedIndex:1203; }","duration":"409.006801ms","start":"2026-05-03T22:11:15.040289Z","end":"2026-05-03T22:11:15.449296Z","steps":["trace[2053568890] 'read index received' (duration: 408.815907ms)","trace[2053568890] 'applied index is now lower than readState.Index' (duration: 190.014µs)"],"step_count":2} {"level":"info","ts":"2026-05-03T22:11:15.449378Z","caller":"traceutil/trace.go:171","msg":"trace[619329259] transaction","detail":"{read_only:false; response_revision:1154; number_of_response:1; }","duration":"418.096203ms","start":"2026-05-03T22:11:15.031247Z","end":"2026-05-03T22:11:15.449344Z","steps":["trace[619329259] 'process raft request' (duration: 417.91907ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:11:15.449538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.815597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-05-03T22:11:15.449551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:11:15.031218Z","time spent":"418.243547ms","remote":"127.0.0.1:37388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-03T22:11:15.449571Z","caller":"traceutil/trace.go:171","msg":"trace[1682439098] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1154; }","duration":"343.909379ms","start":"2026-05-03T22:11:15.105653Z","end":"2026-05-03T22:11:15.449562Z","steps":["trace[1682439098] 'agreement among raft nodes before linearized reading' (duration: 343.770426ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:11:15.449572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.239311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-03T22:11:15.449608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:11:15.105629Z","time spent":"343.97103ms","remote":"127.0.0.1:37388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-03T22:11:15.449563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.249726ms","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-05-03T22:11:15.449648Z","caller":"traceutil/trace.go:171","msg":"trace[371109635] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1154; }","duration":"266.338043ms","start":"2026-05-03T22:11:15.183292Z","end":"2026-05-03T22:11:15.44963Z","steps":["trace[371109635] 'agreement among raft nodes before linearized reading' (duration: 266.149899ms)"],"step_count":1} {"level":"info","ts":"2026-05-03T22:11:15.449667Z","caller":"traceutil/trace.go:171","msg":"trace[1546291647] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1154; }","duration":"409.381599ms","start":"2026-05-03T22:11:15.040262Z","end":"2026-05-03T22:11:15.449644Z","steps":["trace[1546291647] 'agreement among raft nodes before linearized reading' (duration: 409.152824ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:11:15.449713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:11:15.040253Z","time spent":"409.45101ms","remote":"127.0.0.1:37388","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-05-03T22:11:15.449805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.132339ms","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-05-03T22:11:15.449829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.718885ms","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:7"} {"level":"info","ts":"2026-05-03T22:11:15.449833Z","caller":"traceutil/trace.go:171","msg":"trace[1315665880] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1154; }","duration":"266.1851ms","start":"2026-05-03T22:11:15.18364Z","end":"2026-05-03T22:11:15.449825Z","steps":["trace[1315665880] 'agreement among raft nodes before linearized reading' (duration: 266.096867ms)"],"step_count":1} {"level":"info","ts":"2026-05-03T22:11:15.449859Z","caller":"traceutil/trace.go:171","msg":"trace[2132817712] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1154; }","duration":"277.785937ms","start":"2026-05-03T22:11:15.172064Z","end":"2026-05-03T22:11:15.44985Z","steps":["trace[2132817712] 'agreement among raft nodes before linearized reading' (duration: 277.738746ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:11:15.45049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.604477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:5 size:1050268"} {"level":"info","ts":"2026-05-03T22:11:15.450534Z","caller":"traceutil/trace.go:171","msg":"trace[1820650026] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:5; response_revision:1154; }","duration":"272.662578ms","start":"2026-05-03T22:11:15.177859Z","end":"2026-05-03T22:11:15.450522Z","steps":["trace[1820650026] 'agreement among raft nodes before linearized reading' (duration: 271.85785ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:11:21.696135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.615315ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-03T22:11:21.696283Z","caller":"traceutil/trace.go:171","msg":"trace[808223760] transaction","detail":"{read_only:false; response_revision:1176; number_of_response:1; }","duration":"160.261075ms","start":"2026-05-03T22:11:21.535995Z","end":"2026-05-03T22:11:21.696256Z","steps":["trace[808223760] 'process raft request' (duration: 36.414705ms)","trace[808223760] 'compare' (duration: 123.346738ms)"],"step_count":2} {"level":"info","ts":"2026-05-03T22:11:21.701036Z","caller":"traceutil/trace.go:171","msg":"trace[825817393] transaction","detail":"{read_only:false; response_revision:1178; number_of_response:1; }","duration":"163.444953ms","start":"2026-05-03T22:11:21.537568Z","end":"2026-05-03T22:11:21.701013Z","steps":["trace[825817393] 'process raft request' (duration: 163.412272ms)"],"step_count":1} {"level":"info","ts":"2026-05-03T22:11:21.701073Z","caller":"traceutil/trace.go:171","msg":"trace[742341973] transaction","detail":"{read_only:false; response_revision:1177; number_of_response:1; }","duration":"163.901242ms","start":"2026-05-03T22:11:21.537136Z","end":"2026-05-03T22:11:21.701037Z","steps":["trace[742341973] 'process raft request' (duration: 163.765339ms)"],"step_count":1} {"level":"info","ts":"2026-05-03T22:11:23.84735Z","caller":"traceutil/trace.go:171","msg":"trace[858406672] transaction","detail":"{read_only:false; response_revision:1183; number_of_response:1; }","duration":"134.399861ms","start":"2026-05-03T22:11:23.712919Z","end":"2026-05-03T22:11:23.847319Z","steps":["trace[858406672] 'process raft request' (duration: 109.931051ms)","trace[858406672] 'compare' (duration: 24.356088ms)"],"step_count":2} {"level":"warn","ts":"2026-05-03T22:11:38.063396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.67098ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-03T22:11:38.063502Z","caller":"traceutil/trace.go:171","msg":"trace[539743305] linearizableReadLoop","detail":"{readStateIndex:1314; appliedIndex:1313; }","duration":"162.654109ms","start":"2026-05-03T22:11:37.90083Z","end":"2026-05-03T22:11:38.063484Z","steps":["trace[539743305] 'read index received' (duration: 54.022µs)","trace[539743305] 'applied index is now lower than readState.Index' (duration: 162.598417ms)"],"step_count":2} {"level":"info","ts":"2026-05-03T22:11:38.063608Z","caller":"traceutil/trace.go:171","msg":"trace[2088255886] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"442.2567ms","start":"2026-05-03T22:11:37.621304Z","end":"2026-05-03T22:11:38.063561Z","steps":["trace[2088255886] 'process raft request' (duration: 112.341384ms)","trace[2088255886] 'compare' (duration: 329.353323ms)"],"step_count":2} {"level":"warn","ts":"2026-05-03T22:11:38.063797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.962686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-03T22:11:38.063804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:11:37.621277Z","time spent":"442.446254ms","remote":"127.0.0.1:37388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-03T22:11:38.063835Z","caller":"traceutil/trace.go:171","msg":"trace[272647878] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1259; }","duration":"163.028607ms","start":"2026-05-03T22:11:37.900793Z","end":"2026-05-03T22:11:38.063822Z","steps":["trace[272647878] 'agreement among raft nodes before linearized reading' (duration: 162.777371ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:11:38.063875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.801124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-03T22:11:38.063921Z","caller":"traceutil/trace.go:171","msg":"trace[348503405] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1259; }","duration":"146.893196ms","start":"2026-05-03T22:11:37.917014Z","end":"2026-05-03T22:11:38.063907Z","steps":["trace[348503405] 'agreement among raft nodes before linearized reading' (duration: 146.726263ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:11:38.063979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.23175ms","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-05-03T22:11:38.064052Z","caller":"traceutil/trace.go:171","msg":"trace[327188797] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1259; }","duration":"145.366223ms","start":"2026-05-03T22:11:37.918666Z","end":"2026-05-03T22:11:38.064032Z","steps":["trace[327188797] 'agreement among raft nodes before linearized reading' (duration: 145.123328ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:11:38.526577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.110204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-03T22:11:38.52667Z","caller":"traceutil/trace.go:171","msg":"trace[358164576] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1264; }","duration":"216.262608ms","start":"2026-05-03T22:11:38.310379Z","end":"2026-05-03T22:11:38.526641Z","steps":["trace[358164576] 'count revisions from in-memory index tree' (duration: 216.015973ms)"],"step_count":1} {"level":"info","ts":"2026-05-03T22:11:38.705268Z","caller":"traceutil/trace.go:171","msg":"trace[80297754] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"149.281734ms","start":"2026-05-03T22:11:38.555962Z","end":"2026-05-03T22:11:38.705244Z","steps":["trace[80297754] 'process raft request' (duration: 149.130401ms)"],"step_count":1} {"level":"info","ts":"2026-05-03T22:11:38.989966Z","caller":"traceutil/trace.go:171","msg":"trace[899130530] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"132.645501ms","start":"2026-05-03T22:11:38.857294Z","end":"2026-05-03T22:11:38.989939Z","steps":["trace[899130530] 'process raft request' (duration: 132.472628ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:11:42.932527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.327724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-03T22:11:42.932654Z","caller":"traceutil/trace.go:171","msg":"trace[975100086] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1281; }","duration":"103.514397ms","start":"2026-05-03T22:11:42.829116Z","end":"2026-05-03T22:11:42.932631Z","steps":["trace[975100086] 'count revisions from in-memory index tree' (duration: 103.244661ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:11:43.970827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.616735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-03T22:11:43.970939Z","caller":"traceutil/trace.go:171","msg":"trace[260606830] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1283; }","duration":"175.790699ms","start":"2026-05-03T22:11:43.795125Z","end":"2026-05-03T22:11:43.970916Z","steps":["trace[260606830] 'count revisions from in-memory index tree' (duration: 175.513473ms)"],"step_count":1} {"level":"info","ts":"2026-05-03T22:11:44.497741Z","caller":"traceutil/trace.go:171","msg":"trace[769334028] linearizableReadLoop","detail":"{readStateIndex:1340; appliedIndex:1339; }","duration":"401.67521ms","start":"2026-05-03T22:11:44.096042Z","end":"2026-05-03T22:11:44.497717Z","steps":["trace[769334028] 'read index received' (duration: 401.579028ms)","trace[769334028] 'applied index is now lower than readState.Index' (duration: 95.152µs)"],"step_count":2} {"level":"info","ts":"2026-05-03T22:11:44.497912Z","caller":"traceutil/trace.go:171","msg":"trace[1273016242] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"403.42904ms","start":"2026-05-03T22:11:44.094372Z","end":"2026-05-03T22:11:44.497801Z","steps":["trace[1273016242] 'process raft request' (duration: 403.202385ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:11:44.497963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.899946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-03T22:11:44.498007Z","caller":"traceutil/trace.go:171","msg":"trace[23291654] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1284; }","duration":"401.979008ms","start":"2026-05-03T22:11:44.096018Z","end":"2026-05-03T22:11:44.497997Z","steps":["trace[23291654] 'agreement among raft nodes before linearized reading' (duration: 401.821034ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:11:44.498042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:11:44.09601Z","time spent":"402.024078ms","remote":"127.0.0.1:37388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-03T22:11:44.498113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:11:44.094331Z","time spent":"403.674966ms","remote":"127.0.0.1:37388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-03T22:11:44.526375Z","caller":"traceutil/trace.go:171","msg":"trace[1106273016] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"351.556851ms","start":"2026-05-03T22:11:44.174789Z","end":"2026-05-03T22:11:44.526346Z","steps":["trace[1106273016] 'process raft request' (duration: 351.345056ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:11:44.526429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.079849ms","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-05-03T22:11:44.526506Z","caller":"traceutil/trace.go:171","msg":"trace[492961464] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1285; }","duration":"370.174262ms","start":"2026-05-03T22:11:44.156314Z","end":"2026-05-03T22:11:44.526488Z","steps":["trace[492961464] 'agreement among raft nodes before linearized reading' (duration: 369.961286ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:11:44.526528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:11:44.174759Z","time spent":"351.686815ms","remote":"127.0.0.1:37388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-03T22:11:44.526557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:11:44.156262Z","time spent":"370.286914ms","remote":"127.0.0.1:37388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-03T22:11:44.526839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.422167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-03T22:11:44.526914Z","caller":"traceutil/trace.go:171","msg":"trace[177813075] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1285; }","duration":"370.54796ms","start":"2026-05-03T22:11:44.156349Z","end":"2026-05-03T22:11:44.526897Z","steps":["trace[177813075] 'agreement among raft nodes before linearized reading' (duration: 370.302274ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:11:44.526958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-03T22:11:44.15632Z","time spent":"370.624721ms","remote":"127.0.0.1:37388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-03T22:12:08.823908Z","caller":"traceutil/trace.go:171","msg":"trace[1641338149] linearizableReadLoop","detail":"{readStateIndex:1430; appliedIndex:1429; }","duration":"118.15063ms","start":"2026-05-03T22:12:08.705729Z","end":"2026-05-03T22:12:08.823879Z","steps":["trace[1641338149] 'read index received' (duration: 117.881344ms)","trace[1641338149] 'applied index is now lower than readState.Index' (duration: 268.346µs)"],"step_count":2} {"level":"warn","ts":"2026-05-03T22:12:08.824162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.408287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-system/system:controller:cloud-provider\" ","response":"range_response_count:1 size:625"} {"level":"info","ts":"2026-05-03T22:12:08.824256Z","caller":"traceutil/trace.go:171","msg":"trace[1189792214] range","detail":"{range_begin:/registry/roles/kube-system/system:controller:cloud-provider; range_end:; response_count:1; response_revision:1369; }","duration":"118.522089ms","start":"2026-05-03T22:12:08.705707Z","end":"2026-05-03T22:12:08.82423Z","steps":["trace[1189792214] 'agreement among raft nodes before linearized reading' (duration: 118.324485ms)"],"step_count":1} {"level":"info","ts":"2026-05-03T22:12:08.824782Z","caller":"traceutil/trace.go:171","msg":"trace[1291541503] transaction","detail":"{read_only:false; response_revision:1369; number_of_response:1; }","duration":"137.45463ms","start":"2026-05-03T22:12:08.686544Z","end":"2026-05-03T22:12:08.823999Z","steps":["trace[1291541503] 'process raft request' (duration: 137.189294ms)"],"step_count":1} {"level":"warn","ts":"2026-05-03T22:12:11.806259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.146622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/persistent-volume-binder\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-05-03T22:12:11.806353Z","caller":"traceutil/trace.go:171","msg":"trace[1024664902] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/persistent-volume-binder; range_end:; response_count:1; response_revision:1380; }","duration":"113.287026ms","start":"2026-05-03T22:12:11.693046Z","end":"2026-05-03T22:12:11.806333Z","steps":["trace[1024664902] 'range keys from in-memory index tree' (duration: 113.05239ms)"],"step_count":1}