{"level":"warn","ts":"2026-07-09T14:38:31.036305Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-09T14:38:31.036435Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.232:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.232:2380","--initial-cluster=instance=https://199.19.213.232:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.232:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.232: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-07-09T14:38:31.036638Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-09T14:38:31.036658Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.232:2380"]} {"level":"info","ts":"2026-07-09T14:38:31.036726Z","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-07-09T14:38:31.037867Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.232:2379"]} {"level":"info","ts":"2026-07-09T14:38:31.038086Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.232:2380"],"listen-peer-urls":["https://199.19.213.232:2380"],"advertise-client-urls":["https://199.19.213.232:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.232:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.232: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-07-09T14:38:31.044476Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.06377ms"} {"level":"info","ts":"2026-07-09T14:38:31.051079Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"20a2700e013b1fa5","cluster-id":"b7b35e617ebc7ac7"} {"level":"info","ts":"2026-07-09T14:38:31.051304Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"20a2700e013b1fa5 switched to configuration voters=()"} {"level":"info","ts":"2026-07-09T14:38:31.051701Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"20a2700e013b1fa5 became follower at term 0"} {"level":"info","ts":"2026-07-09T14:38:31.051756Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 20a2700e013b1fa5 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-09T14:38:31.051777Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"20a2700e013b1fa5 became follower at term 1"} {"level":"info","ts":"2026-07-09T14:38:31.051886Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"20a2700e013b1fa5 switched to configuration voters=(2351565160893325221)"} {"level":"warn","ts":"2026-07-09T14:38:31.056661Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-09T14:38:31.058007Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-09T14:38:31.060174Z","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-07-09T14:38:31.063013Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"20a2700e013b1fa5","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-09T14:38:31.063235Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"20a2700e013b1fa5","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-09T14:38:31.063447Z","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-07-09T14:38:31.063936Z","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-07-09T14:38:31.063957Z","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-07-09T14:38:31.065125Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"20a2700e013b1fa5 switched to configuration voters=(2351565160893325221)"} {"level":"info","ts":"2026-07-09T14:38:31.065416Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b7b35e617ebc7ac7","local-member-id":"20a2700e013b1fa5","added-peer-id":"20a2700e013b1fa5","added-peer-peer-urls":["https://199.19.213.232:2380"]} {"level":"info","ts":"2026-07-09T14:38:31.069268Z","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-07-09T14:38:31.069468Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.232:2380"} {"level":"info","ts":"2026-07-09T14:38:31.069603Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.232:2380"} {"level":"info","ts":"2026-07-09T14:38:31.069518Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"20a2700e013b1fa5","initial-advertise-peer-urls":["https://199.19.213.232:2380"],"listen-peer-urls":["https://199.19.213.232:2380"],"advertise-client-urls":["https://199.19.213.232:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.232:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-09T14:38:31.069564Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-09T14:38:31.752375Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"20a2700e013b1fa5 is starting a new election at term 1"} {"level":"info","ts":"2026-07-09T14:38:31.752432Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"20a2700e013b1fa5 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-09T14:38:31.752469Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"20a2700e013b1fa5 received MsgPreVoteResp from 20a2700e013b1fa5 at term 1"} {"level":"info","ts":"2026-07-09T14:38:31.752483Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"20a2700e013b1fa5 became candidate at term 2"} {"level":"info","ts":"2026-07-09T14:38:31.752496Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"20a2700e013b1fa5 received MsgVoteResp from 20a2700e013b1fa5 at term 2"} {"level":"info","ts":"2026-07-09T14:38:31.752507Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"20a2700e013b1fa5 became leader at term 2"} {"level":"info","ts":"2026-07-09T14:38:31.752521Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 20a2700e013b1fa5 elected leader 20a2700e013b1fa5 at term 2"} {"level":"info","ts":"2026-07-09T14:38:31.759856Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T14:38:31.785746Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"20a2700e013b1fa5","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.232:2379]}","request-path":"/0/members/20a2700e013b1fa5/attributes","cluster-id":"b7b35e617ebc7ac7","publish-timeout":"7s"} {"level":"info","ts":"2026-07-09T14:38:31.785813Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-09T14:38:31.785768Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-09T14:38:31.78681Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b7b35e617ebc7ac7","local-member-id":"20a2700e013b1fa5","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T14:38:31.786772Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-09T14:38:31.786926Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-09T14:38:31.787033Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T14:38:31.787179Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T14:38:31.790376Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.232:2379"} {"level":"info","ts":"2026-07-09T14:38:31.791704Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-09T14:39:15.820524Z","caller":"traceutil/trace.go:171","msg":"trace[547570644] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"128.422496ms","start":"2026-07-09T14:39:15.692073Z","end":"2026-07-09T14:39:15.820496Z","steps":["trace[547570644] 'process raft request' (duration: 128.227751ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T14:39:15.821636Z","caller":"traceutil/trace.go:171","msg":"trace[1771429835] linearizableReadLoop","detail":"{readStateIndex:622; appliedIndex:622; }","duration":"127.636188ms","start":"2026-07-09T14:39:15.693945Z","end":"2026-07-09T14:39:15.821581Z","steps":["trace[1771429835] 'read index received' (duration: 127.621718ms)","trace[1771429835] 'applied index is now lower than readState.Index' (duration: 11.83µs)"],"step_count":2} {"level":"warn","ts":"2026-07-09T14:39:15.822841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.788825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-09T14:39:15.82296Z","caller":"traceutil/trace.go:171","msg":"trace[1647306543] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:594; }","duration":"129.032821ms","start":"2026-07-09T14:39:15.693914Z","end":"2026-07-09T14:39:15.822947Z","steps":["trace[1647306543] 'agreement among raft nodes before linearized reading' (duration: 127.849914ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T14:39:15.830625Z","caller":"traceutil/trace.go:171","msg":"trace[617900471] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"133.168977ms","start":"2026-07-09T14:39:15.69743Z","end":"2026-07-09T14:39:15.830599Z","steps":["trace[617900471] 'process raft request' (duration: 131.729284ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:39:16.072149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.445192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:80703"} {"level":"info","ts":"2026-07-09T14:39:16.072218Z","caller":"traceutil/trace.go:171","msg":"trace[26940708] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:598; }","duration":"139.546074ms","start":"2026-07-09T14:39:15.932656Z","end":"2026-07-09T14:39:16.072202Z","steps":["trace[26940708] 'range keys from in-memory index tree' (duration: 139.319509ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T14:39:16.072387Z","caller":"traceutil/trace.go:171","msg":"trace[951623033] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"128.903208ms","start":"2026-07-09T14:39:15.943453Z","end":"2026-07-09T14:39:16.072356Z","steps":["trace[951623033] 'process raft request' (duration: 128.483508ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:39:16.072458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.104364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-09T14:39:16.072528Z","caller":"traceutil/trace.go:171","msg":"trace[1088420830] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:598; }","duration":"133.179236ms","start":"2026-07-09T14:39:15.939305Z","end":"2026-07-09T14:39:16.072484Z","steps":["trace[1088420830] 'range keys from in-memory index tree' (duration: 132.965012ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T14:39:16.963363Z","caller":"traceutil/trace.go:171","msg":"trace[1293996651] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"230.112022ms","start":"2026-07-09T14:39:16.733228Z","end":"2026-07-09T14:39:16.96334Z","steps":["trace[1293996651] 'process raft request' (duration: 229.647572ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:39:23.927592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.018283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-09T14:39:23.927703Z","caller":"traceutil/trace.go:171","msg":"trace[743290232] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:667; }","duration":"256.184347ms","start":"2026-07-09T14:39:23.671494Z","end":"2026-07-09T14:39:23.927678Z","steps":["trace[743290232] 'range keys from in-memory index tree' (duration: 255.766497ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:39:39.645944Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2280403914981544576,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T14:39:40.14685Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2280403914981544576,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T14:39:40.474148Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.344145301s","expected-duration":"1s"} {"level":"info","ts":"2026-07-09T14:39:40.476307Z","caller":"traceutil/trace.go:171","msg":"trace[476714760] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"1.346347493s","start":"2026-07-09T14:39:39.129919Z","end":"2026-07-09T14:39:40.476266Z","steps":["trace[476714760] 'process raft request' (duration: 1.345292098s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:39:40.476746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T14:39:39.129905Z","time spent":"1.346459935s","remote":"127.0.0.1:56434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4025,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T14:39:40.481713Z","caller":"traceutil/trace.go:171","msg":"trace[1483215238] linearizableReadLoop","detail":"{readStateIndex:818; appliedIndex:816; }","duration":"1.336690098s","start":"2026-07-09T14:39:39.145Z","end":"2026-07-09T14:39:40.48169Z","steps":["trace[1483215238] 'read index received' (duration: 1.329548063s)","trace[1483215238] 'applied index is now lower than readState.Index' (duration: 7.140535ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T14:39:40.481957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.336937404s","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-07-09T14:39:40.482015Z","caller":"traceutil/trace.go:171","msg":"trace[279524836] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:783; }","duration":"1.337040728s","start":"2026-07-09T14:39:39.144966Z","end":"2026-07-09T14:39:40.482007Z","steps":["trace[279524836] 'agreement among raft nodes before linearized reading' (duration: 1.336871124s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:39:40.482079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T14:39:39.14495Z","time spent":"1.337120399s","remote":"127.0.0.1:56502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-09T14:39:40.482448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.530051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T14:39:40.482518Z","caller":"traceutil/trace.go:171","msg":"trace[1803313871] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:783; }","duration":"173.623534ms","start":"2026-07-09T14:39:40.308883Z","end":"2026-07-09T14:39:40.482507Z","steps":["trace[1803313871] 'agreement among raft nodes before linearized reading' (duration: 173.535022ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:39:40.48257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.84051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T14:39:40.482611Z","caller":"traceutil/trace.go:171","msg":"trace[649220998] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:783; }","duration":"224.928542ms","start":"2026-07-09T14:39:40.257667Z","end":"2026-07-09T14:39:40.482595Z","steps":["trace[649220998] 'agreement among raft nodes before linearized reading' (duration: 224.885081ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:39:40.482787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.169438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T14:39:40.482811Z","caller":"traceutil/trace.go:171","msg":"trace[127105966] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:783; }","duration":"225.25186ms","start":"2026-07-09T14:39:40.257553Z","end":"2026-07-09T14:39:40.482805Z","steps":["trace[127105966] 'agreement among raft nodes before linearized reading' (duration: 225.215079ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:39:40.482895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.666249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T14:39:40.482916Z","caller":"traceutil/trace.go:171","msg":"trace[694085260] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:783; }","duration":"225.72441ms","start":"2026-07-09T14:39:40.257186Z","end":"2026-07-09T14:39:40.482911Z","steps":["trace[694085260] 'agreement among raft nodes before linearized reading' (duration: 225.692909ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:39:40.483011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.527293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T14:39:40.483033Z","caller":"traceutil/trace.go:171","msg":"trace[876756131] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:783; }","duration":"243.549754ms","start":"2026-07-09T14:39:40.239478Z","end":"2026-07-09T14:39:40.483028Z","steps":["trace[876756131] 'agreement among raft nodes before linearized reading' (duration: 243.517073ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:39:40.483164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.698758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T14:39:40.483187Z","caller":"traceutil/trace.go:171","msg":"trace[746691398] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:783; }","duration":"243.727708ms","start":"2026-07-09T14:39:40.239454Z","end":"2026-07-09T14:39:40.483182Z","steps":["trace[746691398] 'agreement among raft nodes before linearized reading' (duration: 243.689587ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:39:40.48327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.619307ms","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-07-09T14:39:40.483302Z","caller":"traceutil/trace.go:171","msg":"trace[1905421726] 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:783; }","duration":"294.748191ms","start":"2026-07-09T14:39:40.188549Z","end":"2026-07-09T14:39:40.483297Z","steps":["trace[1905421726] 'agreement among raft nodes before linearized reading' (duration: 294.70515ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:39:40.483308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.757016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T14:39:40.48339Z","caller":"traceutil/trace.go:171","msg":"trace[504241229] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:783; }","duration":"173.879389ms","start":"2026-07-09T14:39:40.309486Z","end":"2026-07-09T14:39:40.483365Z","steps":["trace[504241229] 'agreement among raft nodes before linearized reading' (duration: 173.750517ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:39:40.483401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.065361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T14:39:40.483482Z","caller":"traceutil/trace.go:171","msg":"trace[293473081] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:783; }","duration":"365.187303ms","start":"2026-07-09T14:39:40.118278Z","end":"2026-07-09T14:39:40.483466Z","steps":["trace[293473081] 'agreement among raft nodes before linearized reading' (duration: 365.04741ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:39:40.483507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.604327ms","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-07-09T14:39:40.482538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.253734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-09T14:39:40.483527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T14:39:40.118265Z","time spent":"365.252604ms","remote":"127.0.0.1:56296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-09T14:39:40.483538Z","caller":"traceutil/trace.go:171","msg":"trace[656527650] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:783; }","duration":"149.636977ms","start":"2026-07-09T14:39:40.333892Z","end":"2026-07-09T14:39:40.483529Z","steps":["trace[656527650] 'agreement among raft nodes before linearized reading' (duration: 149.590376ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:39:40.483741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.351554ms","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":"info","ts":"2026-07-09T14:39:40.483783Z","caller":"traceutil/trace.go:171","msg":"trace[760699309] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:783; }","duration":"150.429216ms","start":"2026-07-09T14:39:40.333345Z","end":"2026-07-09T14:39:40.483774Z","steps":["trace[760699309] 'agreement among raft nodes before linearized reading' (duration: 150.370244ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:39:40.483798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.791953ms","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-07-09T14:39:40.483828Z","caller":"traceutil/trace.go:171","msg":"trace[558219251] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:783; }","duration":"323.853315ms","start":"2026-07-09T14:39:40.159969Z","end":"2026-07-09T14:39:40.483822Z","steps":["trace[558219251] 'agreement among raft nodes before linearized reading' (duration: 323.806174ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:39:40.483856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T14:39:40.15995Z","time spent":"323.899786ms","remote":"127.0.0.1:51446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-09T14:39:40.483916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.569669ms","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-07-09T14:39:40.483953Z","caller":"traceutil/trace.go:171","msg":"trace[1685255706] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:783; }","duration":"150.650421ms","start":"2026-07-09T14:39:40.333294Z","end":"2026-07-09T14:39:40.483945Z","steps":["trace[1685255706] 'agreement among raft nodes before linearized reading' (duration: 150.59892ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:39:40.483971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.908947ms","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-07-09T14:39:40.484025Z","caller":"traceutil/trace.go:171","msg":"trace[1978818676] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:783; }","duration":"324.025818ms","start":"2026-07-09T14:39:40.159989Z","end":"2026-07-09T14:39:40.484015Z","steps":["trace[1978818676] 'agreement among raft nodes before linearized reading' (duration: 323.929726ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:39:40.484059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T14:39:40.159964Z","time spent":"324.088271ms","remote":"127.0.0.1:51446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":115,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-07-09T14:39:40.484064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.428536ms","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-07-09T14:39:40.483435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.671747ms","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-07-09T14:39:40.483269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.237218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T14:39:40.484111Z","caller":"traceutil/trace.go:171","msg":"trace[993291125] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:783; }","duration":"323.476067ms","start":"2026-07-09T14:39:40.160624Z","end":"2026-07-09T14:39:40.4841Z","steps":["trace[993291125] 'agreement among raft nodes before linearized reading' (duration: 323.415306ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T14:39:40.484136Z","caller":"traceutil/trace.go:171","msg":"trace[1985500138] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:783; }","duration":"175.120949ms","start":"2026-07-09T14:39:40.309008Z","end":"2026-07-09T14:39:40.484129Z","steps":["trace[1985500138] 'agreement among raft nodes before linearized reading' (duration: 174.244858ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:39:40.484153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T14:39:40.160591Z","time spent":"323.551579ms","remote":"127.0.0.1:51446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":115,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 "} {"level":"info","ts":"2026-07-09T14:39:40.484152Z","caller":"traceutil/trace.go:171","msg":"trace[258717799] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:783; }","duration":"455.349633ms","start":"2026-07-09T14:39:40.028758Z","end":"2026-07-09T14:39:40.484107Z","steps":["trace[258717799] 'agreement among raft nodes before linearized reading' (duration: 454.632736ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:39:40.48419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T14:39:40.028712Z","time spent":"455.467036ms","remote":"127.0.0.1:56502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-09T14:39:40.483746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.741823ms","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":"info","ts":"2026-07-09T14:39:40.484318Z","caller":"traceutil/trace.go:171","msg":"trace[338300867] 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:783; }","duration":"296.360058ms","start":"2026-07-09T14:39:40.187949Z","end":"2026-07-09T14:39:40.484309Z","steps":["trace[338300867] 'agreement among raft nodes before linearized reading' (duration: 295.764363ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T14:39:40.483546Z","caller":"traceutil/trace.go:171","msg":"trace[946830676] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:783; }","duration":"183.296877ms","start":"2026-07-09T14:39:40.300243Z","end":"2026-07-09T14:39:40.48354Z","steps":["trace[946830676] 'agreement among raft nodes before linearized reading' (duration: 182.233193ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:39:40.482436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.11861ms","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-07-09T14:39:40.484409Z","caller":"traceutil/trace.go:171","msg":"trace[1775346644] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:783; }","duration":"464.151677ms","start":"2026-07-09T14:39:40.020253Z","end":"2026-07-09T14:39:40.484404Z","steps":["trace[1775346644] 'agreement among raft nodes before linearized reading' (duration: 462.035718ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:39:40.484429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T14:39:40.020233Z","time spent":"464.189938ms","remote":"127.0.0.1:56502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-09T14:39:40.48379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.887877ms","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":"info","ts":"2026-07-09T14:39:40.484555Z","caller":"traceutil/trace.go:171","msg":"trace[916080578] 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:783; }","duration":"296.703466ms","start":"2026-07-09T14:39:40.187838Z","end":"2026-07-09T14:39:40.484542Z","steps":["trace[916080578] 'agreement among raft nodes before linearized reading' (duration: 295.928488ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T14:40:16.033745Z","caller":"traceutil/trace.go:171","msg":"trace[310928404] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"680.649057ms","start":"2026-07-09T14:40:15.353067Z","end":"2026-07-09T14:40:16.033716Z","steps":["trace[310928404] 'process raft request' (duration: 680.484333ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:40:16.033914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T14:40:15.353048Z","time spent":"680.784901ms","remote":"127.0.0.1:56502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T14:40:16.035637Z","caller":"traceutil/trace.go:171","msg":"trace[1233076229] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"405.92351ms","start":"2026-07-09T14:40:15.629694Z","end":"2026-07-09T14:40:16.035617Z","steps":["trace[1233076229] 'process raft request' (duration: 405.821248ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:40:16.035745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T14:40:15.629659Z","time spent":"406.033303ms","remote":"127.0.0.1:56502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T14:40:16.588867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.822817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T14:40:16.58901Z","caller":"traceutil/trace.go:171","msg":"trace[1896657862] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1000; }","duration":"470.006201ms","start":"2026-07-09T14:40:16.118988Z","end":"2026-07-09T14:40:16.588994Z","steps":["trace[1896657862] 'range keys from in-memory index tree' (duration: 469.664243ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:40:16.589042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T14:40:16.118971Z","time spent":"470.062263ms","remote":"127.0.0.1:56296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-09T14:40:40.560235Z","caller":"traceutil/trace.go:171","msg":"trace[1128404047] linearizableReadLoop","detail":"{readStateIndex:1127; appliedIndex:1126; }","duration":"441.416738ms","start":"2026-07-09T14:40:40.118794Z","end":"2026-07-09T14:40:40.560211Z","steps":["trace[1128404047] 'read index received' (duration: 441.221803ms)","trace[1128404047] 'applied index is now lower than readState.Index' (duration: 193.884µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T14:40:40.560369Z","caller":"traceutil/trace.go:171","msg":"trace[1115807455] transaction","detail":"{read_only:false; response_revision:1079; number_of_response:1; }","duration":"445.883702ms","start":"2026-07-09T14:40:40.114454Z","end":"2026-07-09T14:40:40.560338Z","steps":["trace[1115807455] 'process raft request' (duration: 445.589186ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:40:40.560422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.597302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T14:40:40.560486Z","caller":"traceutil/trace.go:171","msg":"trace[1079847947] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1079; }","duration":"441.696283ms","start":"2026-07-09T14:40:40.118769Z","end":"2026-07-09T14:40:40.560465Z","steps":["trace[1079847947] 'agreement among raft nodes before linearized reading' (duration: 441.53762ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:40:40.560502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T14:40:40.114432Z","time spent":"445.996645ms","remote":"127.0.0.1:56502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T14:40:40.560537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T14:40:40.11876Z","time spent":"441.765936ms","remote":"127.0.0.1:56296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-09T14:40:41.146912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.257409ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-09T14:40:41.147027Z","caller":"traceutil/trace.go:171","msg":"trace[1576541812] linearizableReadLoop","detail":"{readStateIndex:1128; appliedIndex:1127; }","duration":"584.89948ms","start":"2026-07-09T14:40:40.562108Z","end":"2026-07-09T14:40:41.147007Z","steps":["trace[1576541812] 'read index received' (duration: 284.282282ms)","trace[1576541812] 'applied index is now lower than readState.Index' (duration: 300.615317ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T14:40:41.147191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.081814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T14:40:41.147228Z","caller":"traceutil/trace.go:171","msg":"trace[1298399778] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1079; }","duration":"585.126325ms","start":"2026-07-09T14:40:40.562092Z","end":"2026-07-09T14:40:41.147218Z","steps":["trace[1298399778] 'agreement among raft nodes before linearized reading' (duration: 585.049484ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:40:41.147265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T14:40:40.562039Z","time spent":"585.217397ms","remote":"127.0.0.1:56300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-09T14:40:41.147309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.387297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T14:40:41.147348Z","caller":"traceutil/trace.go:171","msg":"trace[1749079254] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1079; }","duration":"419.53017ms","start":"2026-07-09T14:40:40.727809Z","end":"2026-07-09T14:40:41.147339Z","steps":["trace[1749079254] 'agreement among raft nodes before linearized reading' (duration: 419.455748ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:40:41.147373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T14:40:40.727787Z","time spent":"419.577921ms","remote":"127.0.0.1:56528","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":28,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-07-09T14:40:41.147505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.511873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T14:40:41.147497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.945832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-09T14:40:41.147562Z","caller":"traceutil/trace.go:171","msg":"trace[1808989880] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1079; }","duration":"263.598565ms","start":"2026-07-09T14:40:40.883949Z","end":"2026-07-09T14:40:41.147548Z","steps":["trace[1808989880] 'agreement among raft nodes before linearized reading' (duration: 263.37708ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T14:40:41.147609Z","caller":"traceutil/trace.go:171","msg":"trace[231277852] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1079; }","duration":"222.121507ms","start":"2026-07-09T14:40:40.925463Z","end":"2026-07-09T14:40:41.147584Z","steps":["trace[231277852] 'agreement among raft nodes before linearized reading' (duration: 221.876811ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:40:41.147631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.88006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-09T14:40:41.147657Z","caller":"traceutil/trace.go:171","msg":"trace[193623610] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1079; }","duration":"296.94161ms","start":"2026-07-09T14:40:40.850708Z","end":"2026-07-09T14:40:41.147649Z","steps":["trace[193623610] 'agreement among raft nodes before linearized reading' (duration: 296.838798ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:40:41.147664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.240916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-09T14:40:41.147728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.920158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-09T14:40:41.147761Z","caller":"traceutil/trace.go:171","msg":"trace[1254584296] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1079; }","duration":"291.374739ms","start":"2026-07-09T14:40:40.856361Z","end":"2026-07-09T14:40:41.147736Z","steps":["trace[1254584296] 'agreement among raft nodes before linearized reading' (duration: 291.038082ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T14:40:41.147773Z","caller":"traceutil/trace.go:171","msg":"trace[1582074577] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1079; }","duration":"333.00807ms","start":"2026-07-09T14:40:40.81475Z","end":"2026-07-09T14:40:41.147758Z","steps":["trace[1582074577] 'agreement among raft nodes before linearized reading' (duration: 332.945288ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:40:41.147814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T14:40:40.814727Z","time spent":"333.079133ms","remote":"127.0.0.1:56770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":30,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-07-09T14:40:41.147823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.261399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-09T14:40:41.147854Z","caller":"traceutil/trace.go:171","msg":"trace[510067363] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1079; }","duration":"258.332471ms","start":"2026-07-09T14:40:40.889513Z","end":"2026-07-09T14:40:41.147845Z","steps":["trace[510067363] 'agreement among raft nodes before linearized reading' (duration: 258.239568ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:40:41.147989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.49322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-09T14:40:41.148021Z","caller":"traceutil/trace.go:171","msg":"trace[604307753] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1079; }","duration":"265.561241ms","start":"2026-07-09T14:40:40.88245Z","end":"2026-07-09T14:40:41.148012Z","steps":["trace[604307753] 'agreement among raft nodes before linearized reading' (duration: 265.471889ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T14:41:04.84427Z","caller":"traceutil/trace.go:171","msg":"trace[616069214] transaction","detail":"{read_only:false; response_revision:1210; number_of_response:1; }","duration":"132.375763ms","start":"2026-07-09T14:41:04.711858Z","end":"2026-07-09T14:41:04.844234Z","steps":["trace[616069214] 'process raft request' (duration: 132.157728ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:41:05.417799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.924261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.232\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-09T14:41:05.417854Z","caller":"traceutil/trace.go:171","msg":"trace[2111709069] range","detail":"{range_begin:/registry/masterleases/199.19.213.232; range_end:; response_count:1; response_revision:1210; }","duration":"509.027723ms","start":"2026-07-09T14:41:04.908814Z","end":"2026-07-09T14:41:05.417842Z","steps":["trace[2111709069] 'range keys from in-memory index tree' (duration: 508.769827ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:41:05.417846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.270372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-09T14:41:05.417883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T14:41:04.908792Z","time spent":"509.086014ms","remote":"127.0.0.1:56310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.232\" "} {"level":"info","ts":"2026-07-09T14:41:05.417896Z","caller":"traceutil/trace.go:171","msg":"trace[1433526076] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1210; }","duration":"121.373414ms","start":"2026-07-09T14:41:05.296509Z","end":"2026-07-09T14:41:05.417883Z","steps":["trace[1433526076] 'range keys from in-memory index tree' (duration: 121.16381ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:41:05.418146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.015409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T14:41:05.418168Z","caller":"traceutil/trace.go:171","msg":"trace[28373664] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1210; }","duration":"298.083771ms","start":"2026-07-09T14:41:05.120077Z","end":"2026-07-09T14:41:05.418161Z","steps":["trace[28373664] 'range keys from in-memory index tree' (duration: 297.857575ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:41:18.017749Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2280403914981545707,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T14:41:18.518968Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2280403914981545707,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T14:41:18.832Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.316494484s","expected-duration":"1s"} {"level":"info","ts":"2026-07-09T14:41:18.832449Z","caller":"traceutil/trace.go:171","msg":"trace[1024044545] linearizableReadLoop","detail":"{readStateIndex:1310; appliedIndex:1309; }","duration":"1.315070501s","start":"2026-07-09T14:41:17.51734Z","end":"2026-07-09T14:41:18.83241Z","steps":["trace[1024044545] 'read index received' (duration: 1.314859936s)","trace[1024044545] 'applied index is now lower than readState.Index' (duration: 209.425µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T14:41:18.832504Z","caller":"traceutil/trace.go:171","msg":"trace[326638169] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"1.317075537s","start":"2026-07-09T14:41:17.515396Z","end":"2026-07-09T14:41:18.832471Z","steps":["trace[326638169] 'process raft request' (duration: 1.316837652s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:41:18.832848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.313998736s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-09T14:41:18.832848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.306330504s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-09T14:41:18.832849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.3154758s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-09T14:41:18.832912Z","caller":"traceutil/trace.go:171","msg":"trace[1382737961] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1252; }","duration":"1.314086787s","start":"2026-07-09T14:41:17.518809Z","end":"2026-07-09T14:41:18.832896Z","steps":["trace[1382737961] 'agreement among raft nodes before linearized reading' (duration: 1.31378062s)"],"step_count":1} {"level":"info","ts":"2026-07-09T14:41:18.832942Z","caller":"traceutil/trace.go:171","msg":"trace[254290489] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1252; }","duration":"1.306457428s","start":"2026-07-09T14:41:17.526467Z","end":"2026-07-09T14:41:18.832924Z","steps":["trace[254290489] 'agreement among raft nodes before linearized reading' (duration: 1.306172111s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:41:18.832942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T14:41:17.51537Z","time spent":"1.317207661s","remote":"127.0.0.1:56502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T14:41:18.832975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T14:41:17.518804Z","time spent":"1.314161139s","remote":"127.0.0.1:56502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-09T14:41:18.832972Z","caller":"traceutil/trace.go:171","msg":"trace[2068935735] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1252; }","duration":"1.315652184s","start":"2026-07-09T14:41:17.517293Z","end":"2026-07-09T14:41:18.832945Z","steps":["trace[2068935735] 'agreement among raft nodes before linearized reading' (duration: 1.315250525s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:41:18.832987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T14:41:17.526458Z","time spent":"1.30652095s","remote":"127.0.0.1:56502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-09T14:41:18.832893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.309791087s","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-07-09T14:41:18.83304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T14:41:17.517273Z","time spent":"1.315751147s","remote":"127.0.0.1:56502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-09T14:41:18.833073Z","caller":"traceutil/trace.go:171","msg":"trace[1876446050] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1252; }","duration":"1.309982431s","start":"2026-07-09T14:41:17.523073Z","end":"2026-07-09T14:41:18.833056Z","steps":["trace[1876446050] 'agreement among raft nodes before linearized reading' (duration: 1.309600772s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:41:18.833121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T14:41:17.523064Z","time spent":"1.310044893s","remote":"127.0.0.1:56502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-09T14:41:18.984242Z","caller":"traceutil/trace.go:171","msg":"trace[985364914] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"145.723878ms","start":"2026-07-09T14:41:18.838501Z","end":"2026-07-09T14:41:18.984225Z","steps":["trace[985364914] 'process raft request' (duration: 145.695227ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T14:41:18.9845Z","caller":"traceutil/trace.go:171","msg":"trace[905275880] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"146.741081ms","start":"2026-07-09T14:41:18.837749Z","end":"2026-07-09T14:41:18.98449Z","steps":["trace[905275880] 'process raft request' (duration: 136.805517ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T14:41:18.98465Z","caller":"traceutil/trace.go:171","msg":"trace[1908354294] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"146.820493ms","start":"2026-07-09T14:41:18.837806Z","end":"2026-07-09T14:41:18.984626Z","steps":["trace[1908354294] 'process raft request' (duration: 146.364192ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:41:19.239814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.513428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T14:41:19.239917Z","caller":"traceutil/trace.go:171","msg":"trace[1796573632] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1257; }","duration":"121.684122ms","start":"2026-07-09T14:41:19.118209Z","end":"2026-07-09T14:41:19.239893Z","steps":["trace[1796573632] 'range keys from in-memory index tree' (duration: 121.354944ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T14:41:20.239298Z","caller":"traceutil/trace.go:171","msg":"trace[417743130] transaction","detail":"{read_only:false; response_revision:1258; number_of_response:1; }","duration":"155.256793ms","start":"2026-07-09T14:41:20.084015Z","end":"2026-07-09T14:41:20.239272Z","steps":["trace[417743130] 'process raft request' (duration: 155.090359ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:41:21.338804Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2280403914981545721,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T14:41:21.839742Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2280403914981545721,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T14:41:22.04098Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.273763181s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-09T14:41:22.151978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.630242444s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T14:41:22.152049Z","caller":"traceutil/trace.go:171","msg":"trace[1086457549] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1258; }","duration":"1.630364667s","start":"2026-07-09T14:41:20.521668Z","end":"2026-07-09T14:41:22.152033Z","steps":["trace[1086457549] 'range keys from in-memory index tree' (duration: 1.630163102s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:41:22.152106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.499675264s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-09T14:41:22.152136Z","caller":"traceutil/trace.go:171","msg":"trace[1936706594] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1258; }","duration":"1.499710455s","start":"2026-07-09T14:41:20.652417Z","end":"2026-07-09T14:41:22.152128Z","steps":["trace[1936706594] 'count revisions from in-memory index tree' (duration: 1.499565031s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:41:22.152157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T14:41:20.652358Z","time spent":"1.499793767s","remote":"127.0.0.1:56434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":13,"response size":30,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-07-09T14:41:22.152086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T14:41:20.521644Z","time spent":"1.630434289s","remote":"127.0.0.1:56296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-09T14:41:23.119547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000119604s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-07-09T14:41:23.11966Z","caller":"traceutil/trace.go:171","msg":"trace[889281832] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000301108s","start":"2026-07-09T14:41:21.11934Z","end":"2026-07-09T14:41:23.119641Z","steps":["trace[889281832] 'agreement among raft nodes before linearized reading' (duration: 2.000148985s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:41:23.119782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T14:41:21.11932Z","time spent":"2.000449991s","remote":"127.0.0.1:56300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/09 14:41:23 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-09T14:41:23.143819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.10241268s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-07-09T14:41:23.25721Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.215910709s","expected-duration":"1s"} {"level":"info","ts":"2026-07-09T14:41:23.257574Z","caller":"traceutil/trace.go:171","msg":"trace[1124857057] linearizableReadLoop","detail":"{readStateIndex:1318; appliedIndex:1316; }","duration":"2.419304723s","start":"2026-07-09T14:41:20.838248Z","end":"2026-07-09T14:41:23.257553Z","steps":["trace[1124857057] 'read index received' (duration: 1.203177889s)","trace[1124857057] 'applied index is now lower than readState.Index' (duration: 1.216126034s)"],"step_count":2} {"level":"info","ts":"2026-07-09T14:41:23.257643Z","caller":"traceutil/trace.go:171","msg":"trace[1046511835] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"2.268480695s","start":"2026-07-09T14:41:20.989146Z","end":"2026-07-09T14:41:23.257626Z","steps":["trace[1046511835] 'process raft request' (duration: 2.268257329s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:41:23.257732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.419483837s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-09T14:41:23.257769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T14:41:20.989135Z","time spent":"2.268550007s","remote":"127.0.0.1:56502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T14:41:23.257778Z","caller":"traceutil/trace.go:171","msg":"trace[1583606597] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1259; }","duration":"2.419581979s","start":"2026-07-09T14:41:20.838185Z","end":"2026-07-09T14:41:23.257767Z","steps":["trace[1583606597] 'agreement among raft nodes before linearized reading' (duration: 2.419453396s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:41:23.257812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T14:41:20.838164Z","time spent":"2.419639891s","remote":"127.0.0.1:56502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-09T14:41:24.152584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"782.042893ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-09T14:41:24.152706Z","caller":"traceutil/trace.go:171","msg":"trace[379909431] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"1.025328876s","start":"2026-07-09T14:41:23.127342Z","end":"2026-07-09T14:41:24.152671Z","steps":["trace[379909431] 'process raft request' (duration: 243.148649ms)","trace[379909431] 'compare' (duration: 781.227515ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T14:41:24.152772Z","caller":"traceutil/trace.go:171","msg":"trace[621521560] linearizableReadLoop","detail":"{readStateIndex:1319; appliedIndex:1318; }","duration":"895.145525ms","start":"2026-07-09T14:41:23.257621Z","end":"2026-07-09T14:41:24.152766Z","steps":["trace[621521560] 'read index received' (duration: 112.880396ms)","trace[621521560] 'applied index is now lower than readState.Index' (duration: 782.264439ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T14:41:24.152852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T14:41:23.127323Z","time spent":"1.025458809s","remote":"127.0.0.1:56340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T14:41:24.152892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.255044951s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-09T14:41:24.152913Z","caller":"traceutil/trace.go:171","msg":"trace[1892600360] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1261; }","duration":"3.255114062s","start":"2026-07-09T14:41:20.897793Z","end":"2026-07-09T14:41:24.152907Z","steps":["trace[1892600360] 'agreement among raft nodes before linearized reading' (duration: 3.25499795s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:41:24.152935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T14:41:20.89777Z","time spent":"3.255159943s","remote":"127.0.0.1:56502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-09T14:41:24.152744Z","caller":"traceutil/trace.go:171","msg":"trace[122932583] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"890.578557ms","start":"2026-07-09T14:41:23.262152Z","end":"2026-07-09T14:41:24.152731Z","steps":["trace[122932583] 'process raft request' (duration: 890.519936ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:41:24.153088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.558899726s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-09T14:41:24.15312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T14:41:23.262051Z","time spent":"891.027357ms","remote":"127.0.0.1:56502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T14:41:24.153131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"935.004512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-09T14:41:24.153163Z","caller":"traceutil/trace.go:171","msg":"trace[350812149] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1261; }","duration":"2.5590488s","start":"2026-07-09T14:41:21.594096Z","end":"2026-07-09T14:41:24.153145Z","steps":["trace[350812149] 'agreement among raft nodes before linearized reading' (duration: 2.558934137s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:41:24.153164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.164498441s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-09T14:41:24.153164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.164720806s","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-07-09T14:41:24.153208Z","caller":"traceutil/trace.go:171","msg":"trace[1821951780] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1261; }","duration":"935.125365ms","start":"2026-07-09T14:41:23.218065Z","end":"2026-07-09T14:41:24.15319Z","steps":["trace[1821951780] 'agreement among raft nodes before linearized reading' (duration: 934.968921ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T14:41:24.153214Z","caller":"traceutil/trace.go:171","msg":"trace[1371745282] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1261; }","duration":"3.164578843s","start":"2026-07-09T14:41:20.988621Z","end":"2026-07-09T14:41:24.1532Z","steps":["trace[1371745282] 'agreement among raft nodes before linearized reading' (duration: 3.16445168s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:41:24.153222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T14:41:21.594066Z","time spent":"2.559134571s","remote":"127.0.0.1:56432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":30,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"info","ts":"2026-07-09T14:41:24.153228Z","caller":"traceutil/trace.go:171","msg":"trace[1292212237] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1261; }","duration":"3.164792307s","start":"2026-07-09T14:41:20.988418Z","end":"2026-07-09T14:41:24.15321Z","steps":["trace[1292212237] 'agreement among raft nodes before linearized reading' (duration: 3.164636694s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:41:24.153252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T14:41:20.988604Z","time spent":"3.164639354s","remote":"127.0.0.1:56502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-09T14:41:24.153252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T14:41:23.218048Z","time spent":"935.194386ms","remote":"127.0.0.1:44820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":4,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-07-09T14:41:24.153271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T14:41:20.988415Z","time spent":"3.164845028s","remote":"127.0.0.1:56502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-09T14:41:24.153115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.164713836s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-09T14:41:24.153317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.023773499s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T14:41:24.153339Z","caller":"traceutil/trace.go:171","msg":"trace[1307671253] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1261; }","duration":"1.02381883s","start":"2026-07-09T14:41:23.129515Z","end":"2026-07-09T14:41:24.153333Z","steps":["trace[1307671253] 'agreement among raft nodes before linearized reading' (duration: 1.023780189s)"],"step_count":1} {"level":"info","ts":"2026-07-09T14:41:24.153333Z","caller":"traceutil/trace.go:171","msg":"trace[283133666] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1261; }","duration":"3.164956321s","start":"2026-07-09T14:41:20.988368Z","end":"2026-07-09T14:41:24.153324Z","steps":["trace[283133666] 'agreement among raft nodes before linearized reading' (duration: 3.164706685s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:41:24.153357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T14:41:23.129506Z","time spent":"1.023847181s","remote":"127.0.0.1:56296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-09T14:41:24.153366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T14:41:20.988353Z","time spent":"3.165005243s","remote":"127.0.0.1:56502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-09T14:41:25.216873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"923.40223ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T14:41:25.216997Z","caller":"traceutil/trace.go:171","msg":"trace[1391331214] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"1.059459409s","start":"2026-07-09T14:41:24.157519Z","end":"2026-07-09T14:41:25.216979Z","steps":["trace[1391331214] 'process raft request' (duration: 135.829214ms)","trace[1391331214] 'compare' (duration: 922.765694ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T14:41:25.21709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T14:41:24.1575Z","time spent":"1.059543581s","remote":"127.0.0.1:56502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T14:41:25.219633Z","caller":"traceutil/trace.go:171","msg":"trace[1342135264] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"1.06207228s","start":"2026-07-09T14:41:24.157544Z","end":"2026-07-09T14:41:25.219616Z","steps":["trace[1342135264] 'process raft request' (duration: 1.061762333s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:41:25.219821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T14:41:24.157525Z","time spent":"1.062157093s","remote":"127.0.0.1:56502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T14:41:25.219864Z","caller":"traceutil/trace.go:171","msg":"trace[1672835571] linearizableReadLoop","detail":"{readStateIndex:1324; appliedIndex:1320; }","duration":"1.058370044s","start":"2026-07-09T14:41:24.161471Z","end":"2026-07-09T14:41:25.219841Z","steps":["trace[1672835571] 'read index received' (duration: 131.887171ms)","trace[1672835571] 'applied index is now lower than readState.Index' (duration: 926.481733ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T14:41:25.219907Z","caller":"traceutil/trace.go:171","msg":"trace[721791016] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"1.061398175s","start":"2026-07-09T14:41:24.158493Z","end":"2026-07-09T14:41:25.219891Z","steps":["trace[721791016] 'process raft request' (duration: 1.061238691s)"],"step_count":1} {"level":"info","ts":"2026-07-09T14:41:25.21988Z","caller":"traceutil/trace.go:171","msg":"trace[1109291317] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"1.061424166s","start":"2026-07-09T14:41:24.158393Z","end":"2026-07-09T14:41:25.219817Z","steps":["trace[1109291317] 'process raft request' (duration: 1.06114524s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:41:25.22001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.058520747s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T14:41:25.220009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T14:41:24.158476Z","time spent":"1.061480187s","remote":"127.0.0.1:56502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T14:41:25.220053Z","caller":"traceutil/trace.go:171","msg":"trace[559033771] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1265; }","duration":"1.058599059s","start":"2026-07-09T14:41:24.161444Z","end":"2026-07-09T14:41:25.220043Z","steps":["trace[559033771] 'agreement among raft nodes before linearized reading' (duration: 1.058517867s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:41:25.220047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"963.242457ms","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-07-09T14:41:25.220086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T14:41:24.161435Z","time spent":"1.05864273s","remote":"127.0.0.1:56296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-09T14:41:25.220106Z","caller":"traceutil/trace.go:171","msg":"trace[413548716] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1265; }","duration":"963.351819ms","start":"2026-07-09T14:41:24.256741Z","end":"2026-07-09T14:41:25.220093Z","steps":["trace[413548716] 'agreement among raft nodes before linearized reading' (duration: 963.250977ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:41:25.220143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T14:41:24.256719Z","time spent":"963.41748ms","remote":"127.0.0.1:51568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-07-09T14:41:25.220126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T14:41:24.158379Z","time spent":"1.06160518s","remote":"127.0.0.1:56502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T14:41:25.220188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.476623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.232\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-09T14:41:25.220304Z","caller":"traceutil/trace.go:171","msg":"trace[566851733] range","detail":"{range_begin:/registry/masterleases/199.19.213.232; range_end:; response_count:1; response_revision:1265; }","duration":"310.642967ms","start":"2026-07-09T14:41:24.909649Z","end":"2026-07-09T14:41:25.220292Z","steps":["trace[566851733] 'agreement among raft nodes before linearized reading' (duration: 310.448572ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:41:25.220342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T14:41:24.909627Z","time spent":"310.704639ms","remote":"127.0.0.1:56310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.232\" "} {"level":"info","ts":"2026-07-09T14:41:27.643194Z","caller":"traceutil/trace.go:171","msg":"trace[1880567632] linearizableReadLoop","detail":"{readStateIndex:1342; appliedIndex:1341; }","duration":"278.12306ms","start":"2026-07-09T14:41:27.36497Z","end":"2026-07-09T14:41:27.643093Z","steps":["trace[1880567632] 'read index received' (duration: 218.544739ms)","trace[1880567632] 'applied index is now lower than readState.Index' (duration: 59.577041ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T14:41:27.643237Z","caller":"traceutil/trace.go:171","msg":"trace[2055145307] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"344.491661ms","start":"2026-07-09T14:41:27.2987Z","end":"2026-07-09T14:41:27.643192Z","steps":["trace[2055145307] 'process raft request' (duration: 284.804318ms)","trace[2055145307] 'compare' (duration: 59.412977ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T14:41:27.643608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T14:41:27.298672Z","time spent":"344.711227ms","remote":"127.0.0.1:56502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T14:41:27.643596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.568981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T14:41:27.643845Z","caller":"traceutil/trace.go:171","msg":"trace[1969199983] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1282; }","duration":"278.877718ms","start":"2026-07-09T14:41:27.364914Z","end":"2026-07-09T14:41:27.643792Z","steps":["trace[1969199983] 'agreement among raft nodes before linearized reading' (duration: 278.366446ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:41:31.257843Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2280403914981545806,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-09T14:41:31.314756Z","caller":"traceutil/trace.go:171","msg":"trace[975135909] linearizableReadLoop","detail":"{readStateIndex:1363; appliedIndex:1362; }","duration":"557.88493ms","start":"2026-07-09T14:41:30.756848Z","end":"2026-07-09T14:41:31.314733Z","steps":["trace[975135909] 'read index received' (duration: 557.675055ms)","trace[975135909] 'applied index is now lower than readState.Index' (duration: 208.835µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T14:41:31.314834Z","caller":"traceutil/trace.go:171","msg":"trace[2120508975] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"744.891688ms","start":"2026-07-09T14:41:30.569899Z","end":"2026-07-09T14:41:31.314791Z","steps":["trace[2120508975] 'process raft request' (duration: 744.675143ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:41:31.314991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.920238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T14:41:31.31503Z","caller":"traceutil/trace.go:171","msg":"trace[1636160562] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1302; }","duration":"196.01313ms","start":"2026-07-09T14:41:31.119007Z","end":"2026-07-09T14:41:31.31502Z","steps":["trace[1636160562] 'agreement among raft nodes before linearized reading' (duration: 195.928188ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:41:31.315048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T14:41:30.569869Z","time spent":"745.082103ms","remote":"127.0.0.1:56502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T14:41:31.314963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.083116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T14:41:31.315831Z","caller":"traceutil/trace.go:171","msg":"trace[24601087] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1302; }","duration":"558.972686ms","start":"2026-07-09T14:41:30.756841Z","end":"2026-07-09T14:41:31.315813Z","steps":["trace[24601087] 'agreement among raft nodes before linearized reading' (duration: 558.031235ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:41:31.315874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T14:41:30.756778Z","time spent":"559.081549ms","remote":"127.0.0.1:56548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"info","ts":"2026-07-09T14:41:31.566235Z","caller":"traceutil/trace.go:171","msg":"trace[1729397029] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"246.680502ms","start":"2026-07-09T14:41:31.319525Z","end":"2026-07-09T14:41:31.566205Z","steps":["trace[1729397029] 'process raft request' (duration: 246.480247ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T14:41:31.59469Z","caller":"traceutil/trace.go:171","msg":"trace[2106621419] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"274.895196ms","start":"2026-07-09T14:41:31.319776Z","end":"2026-07-09T14:41:31.594671Z","steps":["trace[2106621419] 'process raft request' (duration: 274.806404ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T14:41:31.594684Z","caller":"traceutil/trace.go:171","msg":"trace[1247575782] linearizableReadLoop","detail":"{readStateIndex:1367; appliedIndex:1364; }","duration":"100.844882ms","start":"2026-07-09T14:41:31.493814Z","end":"2026-07-09T14:41:31.594659Z","steps":["trace[1247575782] 'read index received' (duration: 72.358692ms)","trace[1247575782] 'applied index is now lower than readState.Index' (duration: 28.48533ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T14:41:31.594794Z","caller":"traceutil/trace.go:171","msg":"trace[1044035688] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"275.224683ms","start":"2026-07-09T14:41:31.319562Z","end":"2026-07-09T14:41:31.594787Z","steps":["trace[1044035688] 'process raft request' (duration: 274.860505ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:41:31.5948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.968815ms","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-07-09T14:41:31.594847Z","caller":"traceutil/trace.go:171","msg":"trace[1903144618] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1306; }","duration":"101.075487ms","start":"2026-07-09T14:41:31.49376Z","end":"2026-07-09T14:41:31.594835Z","steps":["trace[1903144618] 'agreement among raft nodes before linearized reading' (duration: 100.984315ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T14:41:31.594819Z","caller":"traceutil/trace.go:171","msg":"trace[1341754662] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"274.979608ms","start":"2026-07-09T14:41:31.319794Z","end":"2026-07-09T14:41:31.594774Z","steps":["trace[1341754662] 'process raft request' (duration: 274.827834ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T14:41:31.843232Z","caller":"traceutil/trace.go:171","msg":"trace[22512343] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"182.642206ms","start":"2026-07-09T14:41:31.660565Z","end":"2026-07-09T14:41:31.843207Z","steps":["trace[22512343] 'process raft request' (duration: 87.202411ms)","trace[22512343] 'compare' (duration: 95.320542ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T14:41:32.16705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.003089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T14:41:32.167163Z","caller":"traceutil/trace.go:171","msg":"trace[1071497584] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1307; }","duration":"210.171733ms","start":"2026-07-09T14:41:31.956972Z","end":"2026-07-09T14:41:32.167143Z","steps":["trace[1071497584] 'count revisions from in-memory index tree' (duration: 209.931667ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T14:41:32.375211Z","caller":"traceutil/trace.go:171","msg":"trace[904154312] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"177.31923ms","start":"2026-07-09T14:41:32.197869Z","end":"2026-07-09T14:41:32.375188Z","steps":["trace[904154312] 'process raft request' (duration: 177.138106ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T14:41:32.827342Z","caller":"traceutil/trace.go:171","msg":"trace[1051883847] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"106.000172ms","start":"2026-07-09T14:41:32.721306Z","end":"2026-07-09T14:41:32.827306Z","steps":["trace[1051883847] 'process raft request' (duration: 105.857369ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:41:33.379043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.811932ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T14:41:33.37916Z","caller":"traceutil/trace.go:171","msg":"trace[1553422379] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1309; }","duration":"296.928384ms","start":"2026-07-09T14:41:33.082205Z","end":"2026-07-09T14:41:33.379133Z","steps":["trace[1553422379] 'range keys from in-memory index tree' (duration: 296.797211ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:41:33.37937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.090115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-07-09T14:41:33.379386Z","caller":"traceutil/trace.go:171","msg":"trace[1835561819] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1309; }","duration":"248.144356ms","start":"2026-07-09T14:41:33.131236Z","end":"2026-07-09T14:41:33.379381Z","steps":["trace[1835561819] 'range keys from in-memory index tree' (duration: 247.938541ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:41:33.379356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.19339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:103573"} {"level":"info","ts":"2026-07-09T14:41:33.379439Z","caller":"traceutil/trace.go:171","msg":"trace[694416686] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1309; }","duration":"246.330563ms","start":"2026-07-09T14:41:33.133087Z","end":"2026-07-09T14:41:33.379417Z","steps":["trace[694416686] 'range keys from in-memory index tree' (duration: 245.951004ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:41:34.100913Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2280403914981545829,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-09T14:41:34.271141Z","caller":"traceutil/trace.go:171","msg":"trace[2068176775] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"695.597914ms","start":"2026-07-09T14:41:33.575495Z","end":"2026-07-09T14:41:34.271093Z","steps":["trace[2068176775] 'process raft request' (duration: 695.42105ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:41:34.271311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T14:41:33.575468Z","time spent":"695.751847ms","remote":"127.0.0.1:56502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T14:41:34.408288Z","caller":"traceutil/trace.go:171","msg":"trace[1459504615] linearizableReadLoop","detail":"{readStateIndex:1372; appliedIndex:1370; }","duration":"808.363527ms","start":"2026-07-09T14:41:33.599902Z","end":"2026-07-09T14:41:34.408265Z","steps":["trace[1459504615] 'read index received' (duration: 671.006426ms)","trace[1459504615] 'applied index is now lower than readState.Index' (duration: 137.356061ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T14:41:34.408522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"808.5318ms","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-07-09T14:41:34.408564Z","caller":"traceutil/trace.go:171","msg":"trace[490282281] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1311; }","duration":"808.602052ms","start":"2026-07-09T14:41:33.599951Z","end":"2026-07-09T14:41:34.408553Z","steps":["trace[490282281] 'agreement among raft nodes before linearized reading' (duration: 808.464798ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:41:34.408562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.031943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T14:41:34.408601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T14:41:33.599946Z","time spent":"808.646914ms","remote":"127.0.0.1:56502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-09T14:41:34.408653Z","caller":"traceutil/trace.go:171","msg":"trace[698793881] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1311; }","duration":"290.147805ms","start":"2026-07-09T14:41:34.118473Z","end":"2026-07-09T14:41:34.408621Z","steps":["trace[698793881] 'agreement among raft nodes before linearized reading' (duration: 289.999002ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T14:41:34.408398Z","caller":"traceutil/trace.go:171","msg":"trace[1262654423] transaction","detail":"{read_only:false; response_revision:1311; number_of_response:1; }","duration":"558.797175ms","start":"2026-07-09T14:41:33.84957Z","end":"2026-07-09T14:41:34.408368Z","steps":["trace[1262654423] 'process raft request' (duration: 472.754061ms)","trace[1262654423] 'compare' (duration: 85.85764ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T14:41:34.408704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"808.789177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-09T14:41:34.408759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"808.186313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-09T14:41:34.408834Z","caller":"traceutil/trace.go:171","msg":"trace[560155700] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1311; }","duration":"808.286755ms","start":"2026-07-09T14:41:33.600529Z","end":"2026-07-09T14:41:34.408816Z","steps":["trace[560155700] 'agreement among raft nodes before linearized reading' (duration: 808.113261ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T14:41:34.408806Z","caller":"traceutil/trace.go:171","msg":"trace[2012791990] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1311; }","duration":"808.9326ms","start":"2026-07-09T14:41:33.599854Z","end":"2026-07-09T14:41:34.408787Z","steps":["trace[2012791990] 'agreement among raft nodes before linearized reading' (duration: 808.543181ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:41:34.408853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T14:41:33.849494Z","time spent":"559.277947ms","remote":"127.0.0.1:56502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T14:41:34.408879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T14:41:33.600521Z","time spent":"808.349456ms","remote":"127.0.0.1:56502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-09T14:41:34.408939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T14:41:33.599835Z","time spent":"809.049492ms","remote":"127.0.0.1:56502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-09T14:41:34.714321Z","caller":"traceutil/trace.go:171","msg":"trace[1654590101] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"300.321895ms","start":"2026-07-09T14:41:34.413958Z","end":"2026-07-09T14:41:34.71428Z","steps":["trace[1654590101] 'process raft request' (duration: 300.153171ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:41:34.714637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T14:41:34.413944Z","time spent":"300.633901ms","remote":"127.0.0.1:56502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T14:41:34.714492Z","caller":"traceutil/trace.go:171","msg":"trace[62206623] transaction","detail":"{read_only:false; response_revision:1315; number_of_response:1; }","duration":"299.413753ms","start":"2026-07-09T14:41:34.415069Z","end":"2026-07-09T14:41:34.714482Z","steps":["trace[62206623] 'process raft request' (duration: 299.326271ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T14:41:34.714517Z","caller":"traceutil/trace.go:171","msg":"trace[85693548] transaction","detail":"{read_only:false; response_revision:1314; number_of_response:1; }","duration":"299.563077ms","start":"2026-07-09T14:41:34.414948Z","end":"2026-07-09T14:41:34.714511Z","steps":["trace[85693548] 'process raft request' (duration: 299.300301ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:41:35.253927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.428251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T14:41:35.254022Z","caller":"traceutil/trace.go:171","msg":"trace[946317853] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1316; }","duration":"134.577165ms","start":"2026-07-09T14:41:35.119424Z","end":"2026-07-09T14:41:35.254001Z","steps":["trace[946317853] 'range keys from in-memory index tree' (duration: 134.306449ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:41:35.254028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.205088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-09T14:41:35.254099Z","caller":"traceutil/trace.go:171","msg":"trace[501967688] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1316; }","duration":"211.31091ms","start":"2026-07-09T14:41:35.042771Z","end":"2026-07-09T14:41:35.254082Z","steps":["trace[501967688] 'range keys from in-memory index tree' (duration: 210.989503ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:41:36.557599Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2280403914981545850,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-09T14:41:36.557908Z","caller":"traceutil/trace.go:171","msg":"trace[728728131] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"539.693827ms","start":"2026-07-09T14:41:36.018195Z","end":"2026-07-09T14:41:36.557889Z","steps":["trace[728728131] 'process raft request' (duration: 539.584935ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:41:36.557999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T14:41:36.018167Z","time spent":"539.774078ms","remote":"127.0.0.1:56340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T14:41:37.063673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.463781ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-09T14:41:37.063999Z","caller":"traceutil/trace.go:171","msg":"trace[1906774255] transaction","detail":"{read_only:false; response_revision:1322; number_of_response:1; }","duration":"502.390778ms","start":"2026-07-09T14:41:36.561585Z","end":"2026-07-09T14:41:37.063976Z","steps":["trace[1906774255] 'process raft request' (duration: 502.334777ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:41:37.064091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T14:41:36.561568Z","time spent":"502.479ms","remote":"127.0.0.1:56340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":836,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T14:41:37.064349Z","caller":"traceutil/trace.go:171","msg":"trace[1191966466] linearizableReadLoop","detail":"{readStateIndex:1383; appliedIndex:1380; }","duration":"1.006812795s","start":"2026-07-09T14:41:36.057518Z","end":"2026-07-09T14:41:37.064331Z","steps":["trace[1191966466] 'read index received' (duration: 500.328691ms)","trace[1191966466] 'applied index is now lower than readState.Index' (duration: 506.482504ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T14:41:37.064355Z","caller":"traceutil/trace.go:171","msg":"trace[882272324] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"1.005557745s","start":"2026-07-09T14:41:36.058768Z","end":"2026-07-09T14:41:37.064326Z","steps":["trace[882272324] 'process raft request' (duration: 615.355707ms)","trace[882272324] 'compare' (duration: 388.887537ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T14:41:37.064356Z","caller":"traceutil/trace.go:171","msg":"trace[108250359] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"650.580644ms","start":"2026-07-09T14:41:36.413741Z","end":"2026-07-09T14:41:37.064321Z","steps":["trace[108250359] 'process raft request' (duration: 650.051032ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:41:37.064446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T14:41:36.058751Z","time spent":"1.005655258s","remote":"127.0.0.1:56340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":770,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T14:41:37.064524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"961.12453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-09T14:41:37.064536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T14:41:36.413717Z","time spent":"650.720198ms","remote":"127.0.0.1:56502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T14:41:37.064538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"946.791013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T14:41:37.064574Z","caller":"traceutil/trace.go:171","msg":"trace[37419705] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1322; }","duration":"961.209422ms","start":"2026-07-09T14:41:36.103346Z","end":"2026-07-09T14:41:37.064555Z","steps":["trace[37419705] 'agreement among raft nodes before linearized reading' (duration: 961.118ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:41:37.064571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.007056791s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"warn","ts":"2026-07-09T14:41:37.064594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.162803ms","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-07-09T14:41:37.064626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T14:41:36.10333Z","time spent":"961.278264ms","remote":"127.0.0.1:56692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-07-09T14:41:37.064606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"788.778217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-09T14:41:37.06465Z","caller":"traceutil/trace.go:171","msg":"trace[1985279700] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1322; }","duration":"346.251635ms","start":"2026-07-09T14:41:36.718383Z","end":"2026-07-09T14:41:37.064635Z","steps":["trace[1985279700] 'agreement among raft nodes before linearized reading' (duration: 346.088101ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:41:37.064662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.534898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-09T14:41:37.064682Z","caller":"traceutil/trace.go:171","msg":"trace[1558437961] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1322; }","duration":"589.599669ms","start":"2026-07-09T14:41:36.475077Z","end":"2026-07-09T14:41:37.064676Z","steps":["trace[1558437961] 'agreement among raft nodes before linearized reading' (duration: 589.539918ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:41:37.064689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T14:41:36.718363Z","time spent":"346.316807ms","remote":"127.0.0.1:56502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-09T14:41:37.064688Z","caller":"traceutil/trace.go:171","msg":"trace[715929073] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1322; }","duration":"788.893639ms","start":"2026-07-09T14:41:36.275776Z","end":"2026-07-09T14:41:37.06467Z","steps":["trace[715929073] 'agreement among raft nodes before linearized reading' (duration: 788.704755ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:41:37.0647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T14:41:36.475062Z","time spent":"589.63428ms","remote":"127.0.0.1:56502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-09T14:41:37.064728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T14:41:36.27576Z","time spent":"788.960351ms","remote":"127.0.0.1:56502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-09T14:41:37.064635Z","caller":"traceutil/trace.go:171","msg":"trace[654931573] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1322; }","duration":"1.007162143s","start":"2026-07-09T14:41:36.057457Z","end":"2026-07-09T14:41:37.064619Z","steps":["trace[654931573] 'agreement among raft nodes before linearized reading' (duration: 1.006955448s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:41:37.064844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T14:41:36.057436Z","time spent":"1.007346028s","remote":"127.0.0.1:56502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":496,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-09T14:41:37.064861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.401659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-09T14:41:37.064867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.863075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-09T14:41:37.064592Z","caller":"traceutil/trace.go:171","msg":"trace[839948274] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1322; }","duration":"946.852214ms","start":"2026-07-09T14:41:36.117727Z","end":"2026-07-09T14:41:37.064579Z","steps":["trace[839948274] 'agreement among raft nodes before linearized reading' (duration: 946.768692ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T14:41:37.064892Z","caller":"traceutil/trace.go:171","msg":"trace[469522739] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1322; }","duration":"346.46398ms","start":"2026-07-09T14:41:36.718416Z","end":"2026-07-09T14:41:37.06488Z","steps":["trace[469522739] 'agreement among raft nodes before linearized reading' (duration: 346.347237ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T14:41:37.064921Z","caller":"traceutil/trace.go:171","msg":"trace[1735429463] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1322; }","duration":"345.941287ms","start":"2026-07-09T14:41:36.718965Z","end":"2026-07-09T14:41:37.064906Z","steps":["trace[1735429463] 'agreement among raft nodes before linearized reading' (duration: 345.718412ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:41:37.064927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T14:41:36.718396Z","time spent":"346.523901ms","remote":"127.0.0.1:56502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-09T14:41:37.06497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T14:41:36.718954Z","time spent":"346.000028ms","remote":"127.0.0.1:56502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-09T14:41:37.065037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T14:41:36.117639Z","time spent":"947.378036ms","remote":"127.0.0.1:56300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-09T14:41:37.619388Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2280403914981545857,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-09T14:41:37.993414Z","caller":"traceutil/trace.go:171","msg":"trace[1305159969] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"922.953329ms","start":"2026-07-09T14:41:37.070424Z","end":"2026-07-09T14:41:37.993377Z","steps":["trace[1305159969] 'process raft request' (duration: 921.894223ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T14:41:37.993542Z","caller":"traceutil/trace.go:171","msg":"trace[1145470097] transaction","detail":"{read_only:false; response_revision:1328; number_of_response:1; }","duration":"922.484888ms","start":"2026-07-09T14:41:37.071033Z","end":"2026-07-09T14:41:37.993518Z","steps":["trace[1145470097] 'process raft request' (duration: 922.438357ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:41:37.993618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T14:41:37.070409Z","time spent":"923.106602ms","remote":"127.0.0.1:56502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T14:41:37.993677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T14:41:37.071024Z","time spent":"922.58025ms","remote":"127.0.0.1:56502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T14:41:37.993795Z","caller":"traceutil/trace.go:171","msg":"trace[629206617] transaction","detail":"{read_only:false; response_revision:1325; number_of_response:1; }","duration":"923.274056ms","start":"2026-07-09T14:41:37.070511Z","end":"2026-07-09T14:41:37.993785Z","steps":["trace[629206617] 'process raft request' (duration: 922.698252ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:41:37.993843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T14:41:37.070474Z","time spent":"923.341857ms","remote":"127.0.0.1:56502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T14:41:37.994021Z","caller":"traceutil/trace.go:171","msg":"trace[1802614726] transaction","detail":"{read_only:false; response_revision:1326; number_of_response:1; }","duration":"923.326178ms","start":"2026-07-09T14:41:37.070642Z","end":"2026-07-09T14:41:37.993968Z","steps":["trace[1802614726] 'process raft request' (duration: 922.62475ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T14:41:37.994061Z","caller":"traceutil/trace.go:171","msg":"trace[136999352] transaction","detail":"{read_only:false; response_revision:1327; number_of_response:1; }","duration":"923.362108ms","start":"2026-07-09T14:41:37.070665Z","end":"2026-07-09T14:41:37.994027Z","steps":["trace[136999352] 'process raft request' (duration: 922.767594ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:41:37.994118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T14:41:37.07062Z","time spent":"923.4661ms","remote":"127.0.0.1:56502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T14:41:37.994195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T14:41:37.070655Z","time spent":"923.4695ms","remote":"127.0.0.1:56502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":457,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T14:41:37.997443Z","caller":"traceutil/trace.go:171","msg":"trace[199780347] linearizableReadLoop","detail":"{readStateIndex:1391; appliedIndex:1385; }","duration":"878.910973ms","start":"2026-07-09T14:41:37.118506Z","end":"2026-07-09T14:41:37.997417Z","steps":["trace[199780347] 'read index received' (duration: 873.823763ms)","trace[199780347] 'applied index is now lower than readState.Index' (duration: 5.08612ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T14:41:37.997548Z","caller":"traceutil/trace.go:171","msg":"trace[1440312127] transaction","detail":"{read_only:false; response_revision:1329; number_of_response:1; }","duration":"767.397688ms","start":"2026-07-09T14:41:37.230127Z","end":"2026-07-09T14:41:37.997524Z","steps":["trace[1440312127] 'process raft request' (duration: 767.121112ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:41:37.997582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"879.069566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T14:41:37.997627Z","caller":"traceutil/trace.go:171","msg":"trace[1545322914] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1329; }","duration":"879.148658ms","start":"2026-07-09T14:41:37.118462Z","end":"2026-07-09T14:41:37.997611Z","steps":["trace[1545322914] 'agreement among raft nodes before linearized reading' (duration: 879.081447ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:41:37.997651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T14:41:37.118443Z","time spent":"879.203509ms","remote":"127.0.0.1:56296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-09T14:41:37.997651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T14:41:37.230106Z","time spent":"767.490211ms","remote":"127.0.0.1:56502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T14:41:37.997693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.42598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-09T14:41:37.99774Z","caller":"traceutil/trace.go:171","msg":"trace[331796320] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1329; }","duration":"199.490162ms","start":"2026-07-09T14:41:37.798234Z","end":"2026-07-09T14:41:37.997724Z","steps":["trace[331796320] 'agreement among raft nodes before linearized reading' (duration: 199.351688ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:41:46.430169Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2280403914981545975,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-09T14:41:46.440245Z","caller":"traceutil/trace.go:171","msg":"trace[1643365081] linearizableReadLoop","detail":"{readStateIndex:1446; appliedIndex:1445; }","duration":"510.171533ms","start":"2026-07-09T14:41:45.930055Z","end":"2026-07-09T14:41:46.440227Z","steps":["trace[1643365081] 'read index received' (duration: 510.007439ms)","trace[1643365081] 'applied index is now lower than readState.Index' (duration: 161.524µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T14:41:46.440447Z","caller":"traceutil/trace.go:171","msg":"trace[492859549] transaction","detail":"{read_only:false; response_revision:1382; number_of_response:1; }","duration":"824.545117ms","start":"2026-07-09T14:41:45.61588Z","end":"2026-07-09T14:41:46.440425Z","steps":["trace[492859549] 'process raft request' (duration: 824.24294ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:41:46.440522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.422159ms","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":"warn","ts":"2026-07-09T14:41:46.440553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.319432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-09T14:41:46.440595Z","caller":"traceutil/trace.go:171","msg":"trace[310261713] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1382; }","duration":"510.575382ms","start":"2026-07-09T14:41:45.930005Z","end":"2026-07-09T14:41:46.440581Z","steps":["trace[310261713] 'agreement among raft nodes before linearized reading' (duration: 510.316826ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:41:46.440616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.007043ms","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-07-09T14:41:46.440623Z","caller":"traceutil/trace.go:171","msg":"trace[163183808] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1382; }","duration":"489.429925ms","start":"2026-07-09T14:41:45.951175Z","end":"2026-07-09T14:41:46.440605Z","steps":["trace[163183808] 'agreement among raft nodes before linearized reading' (duration: 489.294592ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T14:41:46.440642Z","caller":"traceutil/trace.go:171","msg":"trace[1426800781] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1382; }","duration":"372.092905ms","start":"2026-07-09T14:41:46.068542Z","end":"2026-07-09T14:41:46.440634Z","steps":["trace[1426800781] 'agreement among raft nodes before linearized reading' (duration: 371.990932ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:41:46.440626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T14:41:45.929986Z","time spent":"510.632173ms","remote":"127.0.0.1:51530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"warn","ts":"2026-07-09T14:41:46.440662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T14:41:46.068517Z","time spent":"372.141175ms","remote":"127.0.0.1:56502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-09T14:41:46.440679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T14:41:45.951157Z","time spent":"489.503916ms","remote":"127.0.0.1:56578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":70,"response size":30,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-07-09T14:41:46.440734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.414462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T14:41:46.440796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.024417ms","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-07-09T14:41:46.440865Z","caller":"traceutil/trace.go:171","msg":"trace[130381174] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1382; }","duration":"376.122448ms","start":"2026-07-09T14:41:46.064725Z","end":"2026-07-09T14:41:46.440848Z","steps":["trace[130381174] 'agreement among raft nodes before linearized reading' (duration: 375.904903ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T14:41:46.440852Z","caller":"traceutil/trace.go:171","msg":"trace[1948507356] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1382; }","duration":"321.596776ms","start":"2026-07-09T14:41:46.119208Z","end":"2026-07-09T14:41:46.440804Z","steps":["trace[1948507356] 'agreement among raft nodes before linearized reading' (duration: 321.353801ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:41:46.440917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T14:41:46.064711Z","time spent":"376.193801ms","remote":"127.0.0.1:56502","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-07-09T14:41:46.44096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.327291ms","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-07-09T14:41:46.441017Z","caller":"traceutil/trace.go:171","msg":"trace[193748981] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1382; }","duration":"395.425883ms","start":"2026-07-09T14:41:46.045578Z","end":"2026-07-09T14:41:46.441004Z","steps":["trace[193748981] 'agreement among raft nodes before linearized reading' (duration: 395.209058ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:41:46.441049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T14:41:46.045468Z","time spent":"395.573746ms","remote":"127.0.0.1:56502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-09T14:41:46.441202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.381881ms","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-07-09T14:41:46.441357Z","caller":"traceutil/trace.go:171","msg":"trace[1750900537] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1382; }","duration":"372.513274ms","start":"2026-07-09T14:41:46.068779Z","end":"2026-07-09T14:41:46.441292Z","steps":["trace[1750900537] 'agreement among raft nodes before linearized reading' (duration: 371.958621ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:41:46.441604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T14:41:45.615861Z","time spent":"824.70155ms","remote":"127.0.0.1:56434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13709,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T14:41:46.441781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T14:41:46.119191Z","time spent":"322.57085ms","remote":"127.0.0.1:56296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-09T14:41:46.443428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T14:41:46.068762Z","time spent":"372.856562ms","remote":"127.0.0.1:56502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-09T14:41:50.162432Z","caller":"traceutil/trace.go:171","msg":"trace[726536004] transaction","detail":"{read_only:false; response_revision:1405; number_of_response:1; }","duration":"101.178389ms","start":"2026-07-09T14:41:50.061221Z","end":"2026-07-09T14:41:50.1624Z","steps":["trace[726536004] 'process raft request' (duration: 100.987545ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:41:50.805341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.408133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-07-09T14:41:50.805455Z","caller":"traceutil/trace.go:171","msg":"trace[1215938691] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1409; }","duration":"106.591857ms","start":"2026-07-09T14:41:50.698839Z","end":"2026-07-09T14:41:50.80543Z","steps":["trace[1215938691] 'range keys from in-memory index tree' (duration: 106.160778ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T14:41:51.349509Z","caller":"traceutil/trace.go:171","msg":"trace[763284923] transaction","detail":"{read_only:false; response_revision:1412; number_of_response:1; }","duration":"108.691356ms","start":"2026-07-09T14:41:51.24079Z","end":"2026-07-09T14:41:51.349481Z","steps":["trace[763284923] 'process raft request' (duration: 108.490392ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:41:52.723713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.71198ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T14:41:52.723919Z","caller":"traceutil/trace.go:171","msg":"trace[1842363891] linearizableReadLoop","detail":"{readStateIndex:1480; appliedIndex:1479; }","duration":"196.586344ms","start":"2026-07-09T14:41:52.527316Z","end":"2026-07-09T14:41:52.723903Z","steps":["trace[1842363891] 'read index received' (duration: 24.500615ms)","trace[1842363891] 'applied index is now lower than readState.Index' (duration: 172.084419ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T14:41:52.724085Z","caller":"traceutil/trace.go:171","msg":"trace[271686019] transaction","detail":"{read_only:false; response_revision:1415; number_of_response:1; }","duration":"208.212357ms","start":"2026-07-09T14:41:52.515781Z","end":"2026-07-09T14:41:52.723993Z","steps":["trace[271686019] 'process raft request' (duration: 36.132859ms)","trace[271686019] 'compare' (duration: 171.621288ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T14:41:52.724237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.904671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-09T14:41:52.724324Z","caller":"traceutil/trace.go:171","msg":"trace[1550055697] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1415; }","duration":"197.029954ms","start":"2026-07-09T14:41:52.527273Z","end":"2026-07-09T14:41:52.724303Z","steps":["trace[1550055697] 'agreement among raft nodes before linearized reading' (duration: 196.796969ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:41:52.724636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.132737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-09T14:41:52.724707Z","caller":"traceutil/trace.go:171","msg":"trace[582819930] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1415; }","duration":"166.23871ms","start":"2026-07-09T14:41:52.558451Z","end":"2026-07-09T14:41:52.72469Z","steps":["trace[582819930] 'agreement among raft nodes before linearized reading' (duration: 166.068986ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T14:41:53.35086Z","caller":"traceutil/trace.go:171","msg":"trace[1821358967] transaction","detail":"{read_only:false; response_revision:1421; number_of_response:1; }","duration":"108.121324ms","start":"2026-07-09T14:41:53.242707Z","end":"2026-07-09T14:41:53.350828Z","steps":["trace[1821358967] 'process raft request' (duration: 107.95827ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T14:41:54.94122Z","caller":"traceutil/trace.go:171","msg":"trace[365701106] linearizableReadLoop","detail":"{readStateIndex:1492; appliedIndex:1490; }","duration":"123.200568ms","start":"2026-07-09T14:41:54.817997Z","end":"2026-07-09T14:41:54.941197Z","steps":["trace[365701106] 'read index received' (duration: 122.967692ms)","trace[365701106] 'applied index is now lower than readState.Index' (duration: 232.066µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T14:41:54.941297Z","caller":"traceutil/trace.go:171","msg":"trace[960922805] transaction","detail":"{read_only:false; response_revision:1427; number_of_response:1; }","duration":"129.009194ms","start":"2026-07-09T14:41:54.812228Z","end":"2026-07-09T14:41:54.941237Z","steps":["trace[960922805] 'process raft request' (duration: 128.921652ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T14:41:54.941393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.376511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-09T14:41:54.941641Z","caller":"traceutil/trace.go:171","msg":"trace[804843400] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1427; }","duration":"123.666499ms","start":"2026-07-09T14:41:54.817964Z","end":"2026-07-09T14:41:54.94163Z","steps":["trace[804843400] 'agreement among raft nodes before linearized reading' (duration: 123.34723ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T14:41:54.942333Z","caller":"traceutil/trace.go:171","msg":"trace[1828932149] transaction","detail":"{read_only:false; response_revision:1426; number_of_response:1; }","duration":"130.08484ms","start":"2026-07-09T14:41:54.81223Z","end":"2026-07-09T14:41:54.942315Z","steps":["trace[1828932149] 'process raft request' (duration: 128.781868ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T14:41:55.471505Z","caller":"traceutil/trace.go:171","msg":"trace[381011287] transaction","detail":"{read_only:false; response_revision:1431; number_of_response:1; }","duration":"115.331533ms","start":"2026-07-09T14:41:55.356137Z","end":"2026-07-09T14:41:55.471469Z","steps":["trace[381011287] 'process raft request' (duration: 115.16257ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T14:41:59.0769Z","caller":"traceutil/trace.go:171","msg":"trace[1576595070] transaction","detail":"{read_only:false; response_revision:1446; number_of_response:1; }","duration":"100.223348ms","start":"2026-07-09T14:41:58.976649Z","end":"2026-07-09T14:41:59.076872Z","steps":["trace[1576595070] 'process raft request' (duration: 56.950159ms)","trace[1576595070] 'compare' (duration: 43.065054ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T14:41:59.989456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.703366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-09T14:41:59.989528Z","caller":"traceutil/trace.go:171","msg":"trace[691558685] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1450; }","duration":"139.832939ms","start":"2026-07-09T14:41:59.849677Z","end":"2026-07-09T14:41:59.98951Z","steps":["trace[691558685] 'count revisions from in-memory index tree' (duration: 139.581292ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T14:42:01.6349Z","caller":"traceutil/trace.go:171","msg":"trace[1019349022] transaction","detail":"{read_only:false; response_revision:1457; number_of_response:1; }","duration":"102.340698ms","start":"2026-07-09T14:42:01.53249Z","end":"2026-07-09T14:42:01.63483Z","steps":["trace[1019349022] 'process raft request' (duration: 102.077652ms)"],"step_count":1}