{"level":"warn","ts":"2026-06-09T17:02:45.209529Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-09T17:02:45.209655Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.89:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.89:2380","--initial-cluster=instance=https://199.204.45.89:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.89:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.89:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-06-09T17:02:45.209748Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-09T17:02:45.20977Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.89:2380"]} {"level":"info","ts":"2026-06-09T17:02:45.209798Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-06-09T17:02:45.210865Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"]} {"level":"info","ts":"2026-06-09T17:02:45.210987Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.89:2380"],"listen-peer-urls":["https://199.204.45.89:2380"],"advertise-client-urls":["https://199.204.45.89:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.89:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-06-09T17:02:45.249606Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"38.240476ms"} {"level":"info","ts":"2026-06-09T17:02:45.26896Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c3b8446219deb8c1","cluster-id":"fe02a46d8aa9751f"} {"level":"info","ts":"2026-06-09T17:02:45.269061Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=()"} {"level":"info","ts":"2026-06-09T17:02:45.269085Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became follower at term 0"} {"level":"info","ts":"2026-06-09T17:02:45.273473Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c3b8446219deb8c1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-09T17:02:45.273536Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became follower at term 1"} {"level":"info","ts":"2026-06-09T17:02:45.273612Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=(14103097421242218689)"} {"level":"warn","ts":"2026-06-09T17:02:45.628634Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-09T17:02:45.632619Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-09T17:02:45.636072Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-06-09T17:02:45.640353Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c3b8446219deb8c1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-09T17:02:45.64066Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c3b8446219deb8c1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-09T17:02:45.640893Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-09T17:02:45.641031Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-09T17:02:45.641088Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-09T17:02:45.644711Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-06-09T17:02:45.644836Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=(14103097421242218689)"} {"level":"info","ts":"2026-06-09T17:02:45.645011Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c3b8446219deb8c1","initial-advertise-peer-urls":["https://199.204.45.89:2380"],"listen-peer-urls":["https://199.204.45.89:2380"],"advertise-client-urls":["https://199.204.45.89:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-09T17:02:45.645056Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-09T17:02:45.645058Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"fe02a46d8aa9751f","local-member-id":"c3b8446219deb8c1","added-peer-id":"c3b8446219deb8c1","added-peer-peer-urls":["https://199.204.45.89:2380"]} {"level":"info","ts":"2026-06-09T17:02:45.645137Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.89:2380"} {"level":"info","ts":"2026-06-09T17:02:45.645175Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.89:2380"} {"level":"info","ts":"2026-06-09T17:02:45.67413Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 is starting a new election at term 1"} {"level":"info","ts":"2026-06-09T17:02:45.67419Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-09T17:02:45.674225Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 received MsgPreVoteResp from c3b8446219deb8c1 at term 1"} {"level":"info","ts":"2026-06-09T17:02:45.674246Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became candidate at term 2"} {"level":"info","ts":"2026-06-09T17:02:45.674272Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 received MsgVoteResp from c3b8446219deb8c1 at term 2"} {"level":"info","ts":"2026-06-09T17:02:45.674292Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became leader at term 2"} {"level":"info","ts":"2026-06-09T17:02:45.674312Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c3b8446219deb8c1 elected leader c3b8446219deb8c1 at term 2"} {"level":"info","ts":"2026-06-09T17:02:45.680996Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-09T17:02:45.682161Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c3b8446219deb8c1","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.89:2379]}","request-path":"/0/members/c3b8446219deb8c1/attributes","cluster-id":"fe02a46d8aa9751f","publish-timeout":"7s"} {"level":"info","ts":"2026-06-09T17:02:45.682304Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-09T17:02:45.682397Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-09T17:02:45.683383Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"fe02a46d8aa9751f","local-member-id":"c3b8446219deb8c1","cluster-version":"3.5"} {"level":"info","ts":"2026-06-09T17:02:45.683447Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-09T17:02:45.683465Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-09T17:02:45.68433Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-09T17:02:45.684448Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-09T17:02:45.685501Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-09T17:02:45.685519Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.89:2379"} {"level":"warn","ts":"2026-06-09T17:02:48.392434Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313096440795896280,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-09T17:02:48.857116Z","caller":"traceutil/trace.go:171","msg":"trace[2090487629] linearizableReadLoop","detail":"{readStateIndex:80; appliedIndex:79; }","duration":"965.967257ms","start":"2026-06-09T17:02:47.891111Z","end":"2026-06-09T17:02:48.857078Z","steps":["trace[2090487629] 'read index received' (duration: 965.734345ms)","trace[2090487629] 'applied index is now lower than readState.Index' (duration: 231.752µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:02:48.857152Z","caller":"traceutil/trace.go:171","msg":"trace[825524114] transaction","detail":"{read_only:false; response_revision:76; number_of_response:1; }","duration":"966.480648ms","start":"2026-06-09T17:02:47.890619Z","end":"2026-06-09T17:02:48.8571Z","steps":["trace[825524114] 'process raft request' (duration: 966.309181ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:02:48.857405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"966.208619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-09T17:02:48.857502Z","caller":"traceutil/trace.go:171","msg":"trace[1600767890] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:76; }","duration":"966.402444ms","start":"2026-06-09T17:02:47.891088Z","end":"2026-06-09T17:02:48.85749Z","steps":["trace[1600767890] 'agreement among raft nodes before linearized reading' (duration: 966.151713ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:02:48.857555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:02:47.891078Z","time spent":"966.467769ms","remote":"127.0.0.1:47544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/clusterroles/system:aggregate-to-admin\" "} {"level":"warn","ts":"2026-06-09T17:02:48.857683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"854.395438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-09T17:02:48.857886Z","caller":"traceutil/trace.go:171","msg":"trace[156677446] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:76; }","duration":"854.63616ms","start":"2026-06-09T17:02:48.003172Z","end":"2026-06-09T17:02:48.857808Z","steps":["trace[156677446] 'agreement among raft nodes before linearized reading' (duration: 854.133888ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:02:48.85799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:02:48.003159Z","time spent":"854.816106ms","remote":"127.0.0.1:47186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-09T17:02:48.858016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:02:47.890603Z","time spent":"966.730379ms","remote":"127.0.0.1:47570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":429,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-09T17:02:50.000292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"683.184245ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-06-09T17:02:50.000565Z","caller":"traceutil/trace.go:171","msg":"trace[513221350] linearizableReadLoop","detail":"{readStateIndex:81; appliedIndex:80; }","duration":"1.134954759s","start":"2026-06-09T17:02:48.865541Z","end":"2026-06-09T17:02:50.000496Z","steps":["trace[513221350] 'read index received' (duration: 449.133675ms)","trace[513221350] 'applied index is now lower than readState.Index' (duration: 685.816835ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:02:50.000711Z","caller":"traceutil/trace.go:171","msg":"trace[765182421] transaction","detail":"{read_only:false; response_revision:77; number_of_response:1; }","duration":"1.135403056s","start":"2026-06-09T17:02:48.865248Z","end":"2026-06-09T17:02:50.000651Z","steps":["trace[765182421] 'process raft request' (duration: 449.440781ms)","trace[765182421] 'compare' (duration: 683.000198ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:02:50.000951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:02:48.865232Z","time spent":"1.135563524s","remote":"127.0.0.1:47570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-09T17:02:50.00118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.135614191s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-view\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-09T17:02:50.001227Z","caller":"traceutil/trace.go:171","msg":"trace[2048177293] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-view; range_end:; response_count:0; response_revision:77; }","duration":"1.135687376s","start":"2026-06-09T17:02:48.865519Z","end":"2026-06-09T17:02:50.001206Z","steps":["trace[2048177293] 'agreement among raft nodes before linearized reading' (duration: 1.135193382s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:02:50.001276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:02:48.865509Z","time spent":"1.135755931s","remote":"127.0.0.1:47544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":0,"response size":28,"request content":"key:\"/registry/clusterroles/system:aggregate-to-view\" "} {"level":"warn","ts":"2026-06-09T17:02:50.001538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"721.816052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:429"} {"level":"warn","ts":"2026-06-09T17:02:50.001552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"898.179764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-09T17:02:50.001624Z","caller":"traceutil/trace.go:171","msg":"trace[1925724333] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:77; }","duration":"721.926255ms","start":"2026-06-09T17:02:49.27966Z","end":"2026-06-09T17:02:50.001586Z","steps":["trace[1925724333] 'agreement among raft nodes before linearized reading' (duration: 721.809653ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:02:50.001619Z","caller":"traceutil/trace.go:171","msg":"trace[1399428497] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:77; }","duration":"898.310725ms","start":"2026-06-09T17:02:49.103291Z","end":"2026-06-09T17:02:50.001601Z","steps":["trace[1399428497] 'agreement among raft nodes before linearized reading' (duration: 898.171945ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:02:50.001664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:02:49.279586Z","time spent":"722.070095ms","remote":"127.0.0.1:47460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":453,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-09T17:02:50.001668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:02:49.103168Z","time spent":"898.492212ms","remote":"127.0.0.1:47186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-09T17:02:50.001632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"722.168698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-09T17:02:50.001791Z","caller":"traceutil/trace.go:171","msg":"trace[1900210260] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:77; }","duration":"722.481665ms","start":"2026-06-09T17:02:49.279262Z","end":"2026-06-09T17:02:50.001743Z","steps":["trace[1900210260] 'agreement among raft nodes before linearized reading' (duration: 722.232953ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:02:50.001876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:02:49.279218Z","time spent":"722.645262ms","remote":"127.0.0.1:47460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-09T17:02:50.246422Z","caller":"traceutil/trace.go:171","msg":"trace[1494454221] linearizableReadLoop","detail":"{readStateIndex:82; appliedIndex:81; }","duration":"239.170286ms","start":"2026-06-09T17:02:50.007226Z","end":"2026-06-09T17:02:50.246396Z","steps":["trace[1494454221] 'read index received' (duration: 229.021727ms)","trace[1494454221] 'applied index is now lower than readState.Index' (duration: 10.147679ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:02:50.246506Z","caller":"traceutil/trace.go:171","msg":"trace[1029060481] transaction","detail":"{read_only:false; response_revision:78; number_of_response:1; }","duration":"239.626414ms","start":"2026-06-09T17:02:50.006835Z","end":"2026-06-09T17:02:50.246461Z","steps":["trace[1029060481] 'process raft request' (duration: 229.529991ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:02:50.246734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.069548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-09T17:02:50.246781Z","caller":"traceutil/trace.go:171","msg":"trace[1915048584] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:79; }","duration":"114.171841ms","start":"2026-06-09T17:02:50.132597Z","end":"2026-06-09T17:02:50.246769Z","steps":["trace[1915048584] 'agreement among raft nodes before linearized reading' (duration: 114.023012ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:02:50.246883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.646342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:discovery\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-09T17:02:50.246932Z","caller":"traceutil/trace.go:171","msg":"trace[939871611] range","detail":"{range_begin:/registry/clusterrolebindings/system:discovery; range_end:; response_count:0; response_revision:79; }","duration":"239.697658ms","start":"2026-06-09T17:02:50.007222Z","end":"2026-06-09T17:02:50.24692Z","steps":["trace[939871611] 'agreement among raft nodes before linearized reading' (duration: 239.289807ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:02:50.24709Z","caller":"traceutil/trace.go:171","msg":"trace[189379495] transaction","detail":"{read_only:false; response_revision:79; number_of_response:1; }","duration":"239.652903ms","start":"2026-06-09T17:02:50.007363Z","end":"2026-06-09T17:02:50.247016Z","steps":["trace[189379495] 'process raft request' (duration: 239.005147ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:02:50.992341Z","caller":"traceutil/trace.go:171","msg":"trace[589688923] transaction","detail":"{read_only:false; response_revision:127; number_of_response:1; }","duration":"105.031251ms","start":"2026-06-09T17:02:50.887277Z","end":"2026-06-09T17:02:50.992309Z","steps":["trace[589688923] 'process raft request' (duration: 25.909906ms)","trace[589688923] 'compare' (duration: 78.91123ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:02:51.242076Z","caller":"traceutil/trace.go:171","msg":"trace[335583736] transaction","detail":"{read_only:false; response_revision:130; number_of_response:1; }","duration":"155.410681ms","start":"2026-06-09T17:02:51.08663Z","end":"2026-06-09T17:02:51.242041Z","steps":["trace[335583736] 'process raft request' (duration: 60.308718ms)","trace[335583736] 'compare' (duration: 94.893067ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:02:51.244885Z","caller":"traceutil/trace.go:171","msg":"trace[931257571] linearizableReadLoop","detail":"{readStateIndex:134; appliedIndex:133; }","duration":"139.436512ms","start":"2026-06-09T17:02:51.102695Z","end":"2026-06-09T17:02:51.242131Z","steps":["trace[931257571] 'read index received' (duration: 44.227826ms)","trace[931257571] 'applied index is now lower than readState.Index' (duration: 95.206766ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:02:51.245838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.953084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:02:51.24613Z","caller":"traceutil/trace.go:171","msg":"trace[2133472261] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:130; }","duration":"143.394754ms","start":"2026-06-09T17:02:51.102632Z","end":"2026-06-09T17:02:51.246027Z","steps":["trace[2133472261] 'agreement among raft nodes before linearized reading' (duration: 139.512707ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:02:51.710144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.209772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:node-proxier\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:02:51.710236Z","caller":"traceutil/trace.go:171","msg":"trace[951646811] range","detail":"{range_begin:/registry/clusterrolebindings/system:node-proxier; range_end:; response_count:0; response_revision:167; }","duration":"145.347702ms","start":"2026-06-09T17:02:51.564867Z","end":"2026-06-09T17:02:51.710215Z","steps":["trace[951646811] 'range keys from in-memory index tree' (duration: 145.058071ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:02:51.710768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.320936ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-09T17:02:51.711002Z","caller":"traceutil/trace.go:171","msg":"trace[64811921] transaction","detail":"{read_only:false; response_revision:168; number_of_response:1; }","duration":"145.941751ms","start":"2026-06-09T17:02:51.564914Z","end":"2026-06-09T17:02:51.710855Z","steps":["trace[64811921] 'process raft request' (duration: 12.143048ms)","trace[64811921] 'compare' (duration: 133.192755ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:02:51.934442Z","caller":"traceutil/trace.go:171","msg":"trace[1006272565] linearizableReadLoop","detail":"{readStateIndex:175; appliedIndex:174; }","duration":"206.380852ms","start":"2026-06-09T17:02:51.728035Z","end":"2026-06-09T17:02:51.934415Z","steps":["trace[1006272565] 'read index received' (duration: 140.756453ms)","trace[1006272565] 'applied index is now lower than readState.Index' (duration: 65.623239ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:02:51.934625Z","caller":"traceutil/trace.go:171","msg":"trace[226066664] transaction","detail":"{read_only:false; response_revision:170; number_of_response:1; }","duration":"207.232973ms","start":"2026-06-09T17:02:51.727356Z","end":"2026-06-09T17:02:51.934589Z","steps":["trace[226066664] 'process raft request' (duration: 141.493382ms)","trace[226066664] 'compare' (duration: 65.378886ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:02:51.934901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.806893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:02:51.935001Z","caller":"traceutil/trace.go:171","msg":"trace[834675868] range","detail":"{range_begin:/registry/clusterrolebindings/system:kube-controller-manager; range_end:; response_count:0; response_revision:170; }","duration":"206.944543ms","start":"2026-06-09T17:02:51.728016Z","end":"2026-06-09T17:02:51.93496Z","steps":["trace[834675868] 'agreement among raft nodes before linearized reading' (duration: 206.500113ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:02:52.195642Z","caller":"traceutil/trace.go:171","msg":"trace[1893937365] transaction","detail":"{read_only:false; response_revision:183; number_of_response:1; }","duration":"161.51108ms","start":"2026-06-09T17:02:52.034096Z","end":"2026-06-09T17:02:52.195607Z","steps":["trace[1893937365] 'process raft request' (duration: 78.765635ms)","trace[1893937365] 'compare' (duration: 82.501941ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:02:52.195892Z","caller":"traceutil/trace.go:171","msg":"trace[1821560116] transaction","detail":"{read_only:false; response_revision:184; number_of_response:1; }","duration":"160.697394ms","start":"2026-06-09T17:02:52.035181Z","end":"2026-06-09T17:02:52.195879Z","steps":["trace[1821560116] 'process raft request' (duration: 160.335747ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:02:52.782514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.549559ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-09T17:02:52.782624Z","caller":"traceutil/trace.go:171","msg":"trace[14065145] transaction","detail":"{read_only:false; response_revision:209; number_of_response:1; }","duration":"134.17217ms","start":"2026-06-09T17:02:52.648435Z","end":"2026-06-09T17:02:52.782608Z","steps":["trace[14065145] 'compare' (duration: 124.409847ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:02:53.099582Z","caller":"traceutil/trace.go:171","msg":"trace[796362300] transaction","detail":"{read_only:false; response_revision:228; number_of_response:1; }","duration":"107.514372ms","start":"2026-06-09T17:02:52.992044Z","end":"2026-06-09T17:02:53.099558Z","steps":["trace[796362300] 'process raft request' (duration: 67.388959ms)","trace[796362300] 'compare' (duration: 39.972783ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:02:53.316753Z","caller":"traceutil/trace.go:171","msg":"trace[641498303] linearizableReadLoop","detail":"{readStateIndex:237; appliedIndex:236; }","duration":"114.202168ms","start":"2026-06-09T17:02:53.202529Z","end":"2026-06-09T17:02:53.316731Z","steps":["trace[641498303] 'read index received' (duration: 28.236324ms)","trace[641498303] 'applied index is now lower than readState.Index' (duration: 85.964994ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:02:53.316904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.351469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:02:53.316933Z","caller":"traceutil/trace.go:171","msg":"trace[662980770] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:232; }","duration":"114.423295ms","start":"2026-06-09T17:02:53.202501Z","end":"2026-06-09T17:02:53.316924Z","steps":["trace[662980770] 'agreement among raft nodes before linearized reading' (duration: 114.330821ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:02:53.316967Z","caller":"traceutil/trace.go:171","msg":"trace[643078495] transaction","detail":"{read_only:false; response_revision:232; number_of_response:1; }","duration":"173.486471ms","start":"2026-06-09T17:02:53.143453Z","end":"2026-06-09T17:02:53.31694Z","steps":["trace[643078495] 'process raft request' (duration: 87.369366ms)","trace[643078495] 'compare' (duration: 85.791716ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:02:53.541319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.901908ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-09T17:02:53.541492Z","caller":"traceutil/trace.go:171","msg":"trace[72448061] transaction","detail":"{read_only:false; response_revision:238; number_of_response:1; }","duration":"128.634907ms","start":"2026-06-09T17:02:53.412825Z","end":"2026-06-09T17:02:53.54146Z","steps":["trace[72448061] 'process raft request' (duration: 11.523631ms)","trace[72448061] 'compare' (duration: 116.71574ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:02:53.875129Z","caller":"traceutil/trace.go:171","msg":"trace[24252431] transaction","detail":"{read_only:false; response_revision:241; number_of_response:1; }","duration":"145.702277ms","start":"2026-06-09T17:02:53.729398Z","end":"2026-06-09T17:02:53.8751Z","steps":["trace[24252431] 'process raft request' (duration: 116.53989ms)","trace[24252431] 'compare' (duration: 29.021546ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:02:54.382686Z","caller":"traceutil/trace.go:171","msg":"trace[1653551164] transaction","detail":"{read_only:false; response_revision:255; number_of_response:1; }","duration":"125.657888ms","start":"2026-06-09T17:02:54.257003Z","end":"2026-06-09T17:02:54.38266Z","steps":["trace[1653551164] 'process raft request' (duration: 65.636382ms)","trace[1653551164] 'compare' (duration: 59.891863ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:02:54.391349Z","caller":"traceutil/trace.go:171","msg":"trace[2064194170] transaction","detail":"{read_only:false; response_revision:256; number_of_response:1; }","duration":"132.897267ms","start":"2026-06-09T17:02:54.258432Z","end":"2026-06-09T17:02:54.391329Z","steps":["trace[2064194170] 'process raft request' (duration: 132.728547ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:02:54.391576Z","caller":"traceutil/trace.go:171","msg":"trace[413237403] linearizableReadLoop","detail":"{readStateIndex:262; appliedIndex:260; }","duration":"128.902069ms","start":"2026-06-09T17:02:54.262656Z","end":"2026-06-09T17:02:54.391558Z","steps":["trace[413237403] 'read index received' (duration: 59.999596ms)","trace[413237403] 'applied index is now lower than readState.Index' (duration: 68.901244ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:02:54.39177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.092658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslicemirroring-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:02:54.391853Z","caller":"traceutil/trace.go:171","msg":"trace[1527932864] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslicemirroring-controller; range_end:; response_count:0; response_revision:257; }","duration":"129.211311ms","start":"2026-06-09T17:02:54.262621Z","end":"2026-06-09T17:02:54.391832Z","steps":["trace[1527932864] 'agreement among raft nodes before linearized reading' (duration: 129.092638ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:02:54.392061Z","caller":"traceutil/trace.go:171","msg":"trace[1053931623] transaction","detail":"{read_only:false; response_revision:257; number_of_response:1; }","duration":"121.417537ms","start":"2026-06-09T17:02:54.270618Z","end":"2026-06-09T17:02:54.392036Z","steps":["trace[1053931623] 'process raft request' (duration: 120.649794ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:02:54.628147Z","caller":"traceutil/trace.go:171","msg":"trace[1665386479] transaction","detail":"{read_only:false; response_revision:259; number_of_response:1; }","duration":"230.875624ms","start":"2026-06-09T17:02:54.397245Z","end":"2026-06-09T17:02:54.62812Z","steps":["trace[1665386479] 'process raft request' (duration: 201.025647ms)","trace[1665386479] 'compare' (duration: 29.615182ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:02:54.628276Z","caller":"traceutil/trace.go:171","msg":"trace[96502647] transaction","detail":"{read_only:false; response_revision:260; number_of_response:1; }","duration":"210.201457ms","start":"2026-06-09T17:02:54.418012Z","end":"2026-06-09T17:02:54.628214Z","steps":["trace[96502647] 'process raft request' (duration: 210.000469ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:02:54.628569Z","caller":"traceutil/trace.go:171","msg":"trace[160124841] transaction","detail":"{read_only:false; response_revision:261; number_of_response:1; }","duration":"207.651542ms","start":"2026-06-09T17:02:54.420805Z","end":"2026-06-09T17:02:54.628457Z","steps":["trace[160124841] 'process raft request' (duration: 207.259816ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:02:54.89248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.976483ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-09T17:02:54.89267Z","caller":"traceutil/trace.go:171","msg":"trace[880879291] linearizableReadLoop","detail":"{readStateIndex:270; appliedIndex:269; }","duration":"177.398539ms","start":"2026-06-09T17:02:54.715224Z","end":"2026-06-09T17:02:54.892623Z","steps":["trace[880879291] 'read index received' (duration: 36.121985ms)","trace[880879291] 'applied index is now lower than readState.Index' (duration: 141.275234ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:02:54.89276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.53782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:02:54.892983Z","caller":"traceutil/trace.go:171","msg":"trace[1346651749] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:0; response_revision:264; }","duration":"177.792865ms","start":"2026-06-09T17:02:54.715172Z","end":"2026-06-09T17:02:54.892965Z","steps":["trace[1346651749] 'agreement among raft nodes before linearized reading' (duration: 177.54656ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:02:54.892839Z","caller":"traceutil/trace.go:171","msg":"trace[461359898] transaction","detail":"{read_only:false; response_revision:264; number_of_response:1; }","duration":"193.134458ms","start":"2026-06-09T17:02:54.699648Z","end":"2026-06-09T17:02:54.892782Z","steps":["trace[461359898] 'process raft request' (duration: 51.751011ms)","trace[461359898] 'compare' (duration: 140.820881ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:02:55.220076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.52977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/bootstrap-token-jy2sma\" ","response":"range_response_count:1 size:540"} {"level":"warn","ts":"2026-06-09T17:02:55.220126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.141764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-06-09T17:02:55.220151Z","caller":"traceutil/trace.go:171","msg":"trace[1237796063] range","detail":"{range_begin:/registry/secrets/kube-system/bootstrap-token-jy2sma; range_end:; response_count:1; response_revision:266; }","duration":"225.637024ms","start":"2026-06-09T17:02:54.994498Z","end":"2026-06-09T17:02:55.220135Z","steps":["trace[1237796063] 'range keys from in-memory index tree' (duration: 225.480323ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:02:55.22018Z","caller":"traceutil/trace.go:171","msg":"trace[1413176778] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:266; }","duration":"226.215639ms","start":"2026-06-09T17:02:54.993948Z","end":"2026-06-09T17:02:55.220164Z","steps":["trace[1413176778] 'range keys from in-memory index tree' (duration: 226.058939ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:02:55.430247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.614713ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-09T17:02:55.430432Z","caller":"traceutil/trace.go:171","msg":"trace[1830298155] transaction","detail":"{read_only:false; response_revision:271; number_of_response:1; }","duration":"108.612561ms","start":"2026-06-09T17:02:55.321791Z","end":"2026-06-09T17:02:55.430403Z","steps":["trace[1830298155] 'process raft request' (duration: 108.556914ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:02:55.430477Z","caller":"traceutil/trace.go:171","msg":"trace[2005004830] transaction","detail":"{read_only:false; response_revision:270; number_of_response:1; }","duration":"135.694476ms","start":"2026-06-09T17:02:55.294749Z","end":"2026-06-09T17:02:55.430443Z","steps":["trace[2005004830] 'process raft request' (duration: 34.79958ms)","trace[2005004830] 'compare' (duration: 100.436983ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:02:55.64207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.87025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-06-09T17:02:55.642184Z","caller":"traceutil/trace.go:171","msg":"trace[630917713] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:274; }","duration":"112.046439ms","start":"2026-06-09T17:02:55.53011Z","end":"2026-06-09T17:02:55.642156Z","steps":["trace[630917713] 'range keys from in-memory index tree' (duration: 111.729818ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:03:00.782151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.719375ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-09T17:03:00.782463Z","caller":"traceutil/trace.go:171","msg":"trace[2099336186] linearizableReadLoop","detail":"{readStateIndex:414; appliedIndex:410; }","duration":"129.824799ms","start":"2026-06-09T17:03:00.652626Z","end":"2026-06-09T17:03:00.782451Z","steps":["trace[2099336186] 'read index received' (duration: 118.759618ms)","trace[2099336186] 'applied index is now lower than readState.Index' (duration: 11.064201ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:03:00.782541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.902365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-09T17:03:00.782568Z","caller":"traceutil/trace.go:171","msg":"trace[685091276] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:402; }","duration":"129.99229ms","start":"2026-06-09T17:03:00.652568Z","end":"2026-06-09T17:03:00.782561Z","steps":["trace[685091276] 'agreement among raft nodes before linearized reading' (duration: 129.925384ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:03:00.782609Z","caller":"traceutil/trace.go:171","msg":"trace[1930169504] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"245.275886ms","start":"2026-06-09T17:03:00.537267Z","end":"2026-06-09T17:03:00.782543Z","steps":["trace[1930169504] 'process raft request' (duration: 106.091203ms)","trace[1930169504] 'compare' (duration: 138.610281ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:03:00.782772Z","caller":"traceutil/trace.go:171","msg":"trace[473895467] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"243.327581ms","start":"2026-06-09T17:03:00.539408Z","end":"2026-06-09T17:03:00.782735Z","steps":["trace[473895467] 'process raft request' (duration: 242.886753ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:03:00.782852Z","caller":"traceutil/trace.go:171","msg":"trace[209378496] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"243.487074ms","start":"2026-06-09T17:03:00.539352Z","end":"2026-06-09T17:03:00.782839Z","steps":["trace[209378496] 'process raft request' (duration: 242.898013ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:03:04.054863Z","caller":"traceutil/trace.go:171","msg":"trace[301412359] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"119.762519ms","start":"2026-06-09T17:03:03.935072Z","end":"2026-06-09T17:03:04.054834Z","steps":["trace[301412359] 'process raft request' (duration: 105.832221ms)","trace[301412359] 'compare' (duration: 13.782984ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:03:09.084188Z","caller":"traceutil/trace.go:171","msg":"trace[633658645] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"215.356888ms","start":"2026-06-09T17:03:08.868789Z","end":"2026-06-09T17:03:09.084146Z","steps":["trace[633658645] 'process raft request' (duration: 177.301594ms)","trace[633658645] 'compare' (duration: 37.891069ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:03:09.088624Z","caller":"traceutil/trace.go:171","msg":"trace[381132129] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"218.725461ms","start":"2026-06-09T17:03:08.869871Z","end":"2026-06-09T17:03:09.088597Z","steps":["trace[381132129] 'process raft request' (duration: 218.570026ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:03:12.791583Z","caller":"traceutil/trace.go:171","msg":"trace[1099935171] transaction","detail":"{read_only:false; response_revision:434; number_of_response:1; }","duration":"107.081625ms","start":"2026-06-09T17:03:12.684483Z","end":"2026-06-09T17:03:12.791564Z","steps":["trace[1099935171] 'process raft request' (duration: 106.988707ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:03:13.213919Z","caller":"traceutil/trace.go:171","msg":"trace[1684995471] transaction","detail":"{read_only:false; response_revision:437; number_of_response:1; }","duration":"109.421298ms","start":"2026-06-09T17:03:13.104476Z","end":"2026-06-09T17:03:13.213897Z","steps":["trace[1684995471] 'process raft request' (duration: 109.34639ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:03:15.328671Z","caller":"traceutil/trace.go:171","msg":"trace[184573327] linearizableReadLoop","detail":"{readStateIndex:456; appliedIndex:455; }","duration":"111.044265ms","start":"2026-06-09T17:03:15.217612Z","end":"2026-06-09T17:03:15.328656Z","steps":["trace[184573327] 'read index received' (duration: 110.928248ms)","trace[184573327] 'applied index is now lower than readState.Index' (duration: 115.587µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:03:15.328735Z","caller":"traceutil/trace.go:171","msg":"trace[36184323] transaction","detail":"{read_only:false; response_revision:441; number_of_response:1; }","duration":"132.085364ms","start":"2026-06-09T17:03:15.196639Z","end":"2026-06-09T17:03:15.328724Z","steps":["trace[36184323] 'process raft request' (duration: 131.922627ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:03:15.328769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.146873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-06-09T17:03:15.328789Z","caller":"traceutil/trace.go:171","msg":"trace[381248980] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:441; }","duration":"111.216851ms","start":"2026-06-09T17:03:15.217565Z","end":"2026-06-09T17:03:15.328782Z","steps":["trace[381248980] 'agreement among raft nodes before linearized reading' (duration: 111.149433ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:03:16.11726Z","caller":"traceutil/trace.go:171","msg":"trace[616101038] transaction","detail":"{read_only:false; response_revision:443; number_of_response:1; }","duration":"129.589192ms","start":"2026-06-09T17:03:15.987645Z","end":"2026-06-09T17:03:16.117235Z","steps":["trace[616101038] 'process raft request' (duration: 129.427676ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:03:16.94793Z","caller":"traceutil/trace.go:171","msg":"trace[1645909953] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"110.89204ms","start":"2026-06-09T17:03:16.836986Z","end":"2026-06-09T17:03:16.947878Z","steps":["trace[1645909953] 'process raft request' (duration: 110.680244ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:03:17.449796Z","caller":"traceutil/trace.go:171","msg":"trace[1475924061] transaction","detail":"{read_only:false; response_revision:447; number_of_response:1; }","duration":"113.464908ms","start":"2026-06-09T17:03:17.336301Z","end":"2026-06-09T17:03:17.449766Z","steps":["trace[1475924061] 'process raft request' (duration: 113.251732ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:03:17.751704Z","caller":"traceutil/trace.go:171","msg":"trace[806917992] transaction","detail":"{read_only:false; response_revision:450; number_of_response:1; }","duration":"102.436292ms","start":"2026-06-09T17:03:17.649238Z","end":"2026-06-09T17:03:17.751675Z","steps":["trace[806917992] 'process raft request' (duration: 87.743718ms)","trace[806917992] 'compare' (duration: 13.890819ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:03:24.020167Z","caller":"traceutil/trace.go:171","msg":"trace[265787584] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"101.338795ms","start":"2026-06-09T17:03:23.9188Z","end":"2026-06-09T17:03:24.020139Z","steps":["trace[265787584] 'process raft request' (duration: 64.451374ms)","trace[265787584] 'compare' (duration: 36.757593ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:03:25.293981Z","caller":"traceutil/trace.go:171","msg":"trace[1900169334] linearizableReadLoop","detail":"{readStateIndex:510; appliedIndex:509; }","duration":"296.616925ms","start":"2026-06-09T17:03:24.997327Z","end":"2026-06-09T17:03:25.293944Z","steps":["trace[1900169334] 'read index received' (duration: 296.521256ms)","trace[1900169334] 'applied index is now lower than readState.Index' (duration: 94.789µs)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:03:25.294179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.828183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-09T17:03:25.294184Z","caller":"traceutil/trace.go:171","msg":"trace[1192198850] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"305.012985ms","start":"2026-06-09T17:03:24.98911Z","end":"2026-06-09T17:03:25.294123Z","steps":["trace[1192198850] 'process raft request' (duration: 304.602139ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:03:25.29424Z","caller":"traceutil/trace.go:171","msg":"trace[881166472] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:492; }","duration":"296.926291ms","start":"2026-06-09T17:03:24.997302Z","end":"2026-06-09T17:03:25.294228Z","steps":["trace[881166472] 'agreement among raft nodes before linearized reading' (duration: 296.765003ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:03:25.294424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:03:24.989086Z","time spent":"305.209154ms","remote":"127.0.0.1:47460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:03:29.620734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.299572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:03:29.620849Z","caller":"traceutil/trace.go:171","msg":"trace[1883095145] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:0; response_revision:503; }","duration":"194.455721ms","start":"2026-06-09T17:03:29.42637Z","end":"2026-06-09T17:03:29.620826Z","steps":["trace[1883095145] 'range keys from in-memory index tree' (duration: 194.236932ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:03:29.620906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.501923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-09T17:03:29.62099Z","caller":"traceutil/trace.go:171","msg":"trace[239363625] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:503; }","duration":"106.620104ms","start":"2026-06-09T17:03:29.514353Z","end":"2026-06-09T17:03:29.620973Z","steps":["trace[239363625] 'range keys from in-memory index tree' (duration: 106.384804ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:03:29.75645Z","caller":"traceutil/trace.go:171","msg":"trace[570439598] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"131.541079ms","start":"2026-06-09T17:03:29.624884Z","end":"2026-06-09T17:03:29.756425Z","steps":["trace[570439598] 'process raft request' (duration: 131.32778ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:03:29.765828Z","caller":"traceutil/trace.go:171","msg":"trace[1161843371] linearizableReadLoop","detail":"{readStateIndex:524; appliedIndex:522; }","duration":"139.750614ms","start":"2026-06-09T17:03:29.626054Z","end":"2026-06-09T17:03:29.765805Z","steps":["trace[1161843371] 'read index received' (duration: 130.136763ms)","trace[1161843371] 'applied index is now lower than readState.Index' (duration: 9.612611ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:03:29.765943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.867544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/cilium-config\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:03:29.765976Z","caller":"traceutil/trace.go:171","msg":"trace[2086192874] range","detail":"{range_begin:/registry/configmaps/kube-system/cilium-config; range_end:; response_count:0; response_revision:505; }","duration":"139.928384ms","start":"2026-06-09T17:03:29.626037Z","end":"2026-06-09T17:03:29.765965Z","steps":["trace[2086192874] 'agreement among raft nodes before linearized reading' (duration: 139.857064ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:03:29.766218Z","caller":"traceutil/trace.go:171","msg":"trace[1363068706] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"141.129941ms","start":"2026-06-09T17:03:29.625068Z","end":"2026-06-09T17:03:29.766197Z","steps":["trace[1363068706] 'process raft request' (duration: 140.645903ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:03:30.189278Z","caller":"traceutil/trace.go:171","msg":"trace[1100207612] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"220.416841ms","start":"2026-06-09T17:03:29.968832Z","end":"2026-06-09T17:03:30.189248Z","steps":["trace[1100207612] 'process raft request' (duration: 196.689752ms)","trace[1100207612] 'compare' (duration: 23.568559ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:03:30.629472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.123813ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-09T17:03:30.629734Z","caller":"traceutil/trace.go:171","msg":"trace[691109780] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"434.933443ms","start":"2026-06-09T17:03:30.194726Z","end":"2026-06-09T17:03:30.62966Z","steps":["trace[691109780] 'process raft request' (duration: 143.477091ms)","trace[691109780] 'compare' (duration: 290.498333ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:03:30.62997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:03:30.194706Z","time spent":"435.169263ms","remote":"127.0.0.1:47544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1639,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-09T17:03:30.630399Z","caller":"traceutil/trace.go:171","msg":"trace[480663077] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"435.125373ms","start":"2026-06-09T17:03:30.195172Z","end":"2026-06-09T17:03:30.630297Z","steps":["trace[480663077] 'process raft request' (duration: 434.478944ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:03:30.63062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:03:30.195159Z","time spent":"435.328263ms","remote":"127.0.0.1:47544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2364,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-09T17:03:30.832916Z","caller":"traceutil/trace.go:171","msg":"trace[581812694] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"197.838216ms","start":"2026-06-09T17:03:30.635056Z","end":"2026-06-09T17:03:30.832894Z","steps":["trace[581812694] 'process raft request' (duration: 166.93222ms)","trace[581812694] 'compare' (duration: 30.790946ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:03:30.854201Z","caller":"traceutil/trace.go:171","msg":"trace[1471030102] linearizableReadLoop","detail":"{readStateIndex:532; appliedIndex:530; }","duration":"115.21667ms","start":"2026-06-09T17:03:30.738967Z","end":"2026-06-09T17:03:30.854183Z","steps":["trace[1471030102] 'read index received' (duration: 63.03333ms)","trace[1471030102] 'applied index is now lower than readState.Index' (duration: 52.18257ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:03:30.854464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.47295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:03:30.854472Z","caller":"traceutil/trace.go:171","msg":"trace[1570721857] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"219.10971ms","start":"2026-06-09T17:03:30.635314Z","end":"2026-06-09T17:03:30.854424Z","steps":["trace[1570721857] 'process raft request' (duration: 218.79226ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:03:30.854532Z","caller":"traceutil/trace.go:171","msg":"trace[156777603] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:513; }","duration":"115.59368ms","start":"2026-06-09T17:03:30.738923Z","end":"2026-06-09T17:03:30.854517Z","steps":["trace[156777603] 'agreement among raft nodes before linearized reading' (duration: 115.341299ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:03:31.070863Z","caller":"traceutil/trace.go:171","msg":"trace[12138984] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"137.931069ms","start":"2026-06-09T17:03:30.932899Z","end":"2026-06-09T17:03:31.07083Z","steps":["trace[12138984] 'process raft request' (duration: 118.5137ms)","trace[12138984] 'compare' (duration: 19.05956ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:03:31.83267Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313096440795897222,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-09T17:03:32.631137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"727.847856ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-09T17:03:32.631242Z","caller":"traceutil/trace.go:171","msg":"trace[2016441847] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"1.553280964s","start":"2026-06-09T17:03:31.07794Z","end":"2026-06-09T17:03:32.631221Z","steps":["trace[2016441847] 'process raft request' (duration: 825.267467ms)","trace[2016441847] 'compare' (duration: 727.341276ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:03:32.631307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:03:31.0779Z","time spent":"1.553385514s","remote":"127.0.0.1:47646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3827,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-09T17:03:32.956254Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.052968354s","expected-duration":"1s"} {"level":"info","ts":"2026-06-09T17:03:32.956907Z","caller":"traceutil/trace.go:171","msg":"trace[2143418020] linearizableReadLoop","detail":"{readStateIndex:537; appliedIndex:535; }","duration":"1.624725174s","start":"2026-06-09T17:03:31.33213Z","end":"2026-06-09T17:03:32.956856Z","steps":["trace[2143418020] 'read index received' (duration: 571.13156ms)","trace[2143418020] 'applied index is now lower than readState.Index' (duration: 1.053592214s)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:03:32.957004Z","caller":"traceutil/trace.go:171","msg":"trace[2072652750] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"1.878315991s","start":"2026-06-09T17:03:31.078624Z","end":"2026-06-09T17:03:32.95694Z","steps":["trace[2072652750] 'process raft request' (duration: 1.878033581s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:03:32.957203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:03:31.078597Z","time spent":"1.878511491s","remote":"127.0.0.1:47672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-09T17:03:32.957271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.625072434s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-09T17:03:32.957459Z","caller":"traceutil/trace.go:171","msg":"trace[379307012] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:518; }","duration":"1.625273935s","start":"2026-06-09T17:03:31.332086Z","end":"2026-06-09T17:03:32.95736Z","steps":["trace[379307012] 'agreement among raft nodes before linearized reading' (duration: 1.624944734s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:03:32.957535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:03:31.332065Z","time spent":"1.625461365s","remote":"127.0.0.1:47460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-09T17:03:33.021887Z","caller":"traceutil/trace.go:171","msg":"trace[30425578] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"379.272284ms","start":"2026-06-09T17:03:32.642577Z","end":"2026-06-09T17:03:33.021849Z","steps":["trace[30425578] 'process raft request' (duration: 378.965673ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:03:33.02215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:03:32.642536Z","time spent":"379.533114ms","remote":"127.0.0.1:47662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4177,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-09T17:03:33.02222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.237699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-09T17:03:33.022215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.607241882s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-09T17:03:33.022328Z","caller":"traceutil/trace.go:171","msg":"trace[683909715] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:520; }","duration":"1.607381813s","start":"2026-06-09T17:03:31.414926Z","end":"2026-06-09T17:03:33.022308Z","steps":["trace[683909715] 'agreement among raft nodes before linearized reading' (duration: 1.607189252s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:03:33.022403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:03:31.414864Z","time spent":"1.607497563s","remote":"127.0.0.1:47460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-09T17:03:33.022463Z","caller":"traceutil/trace.go:171","msg":"trace[877792590] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"307.876077ms","start":"2026-06-09T17:03:32.714555Z","end":"2026-06-09T17:03:33.022431Z","steps":["trace[877792590] 'process raft request' (duration: 307.245786ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:03:33.022282Z","caller":"traceutil/trace.go:171","msg":"trace[1574180447] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:520; }","duration":"283.314149ms","start":"2026-06-09T17:03:32.738953Z","end":"2026-06-09T17:03:33.022267Z","steps":["trace[1574180447] 'agreement among raft nodes before linearized reading' (duration: 283.169479ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:03:33.022694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:03:32.714005Z","time spent":"308.567247ms","remote":"127.0.0.1:47286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":317275,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:03:33.022877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.261878475s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-09T17:03:33.022956Z","caller":"traceutil/trace.go:171","msg":"trace[1983693247] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:520; }","duration":"1.262000635s","start":"2026-06-09T17:03:31.760943Z","end":"2026-06-09T17:03:33.022944Z","steps":["trace[1983693247] 'agreement among raft nodes before linearized reading' (duration: 1.261756655s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:03:33.02305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:03:31.760924Z","time spent":"1.262115385s","remote":"127.0.0.1:47460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-09T17:03:33.023129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.253439969s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-09T17:03:33.023181Z","caller":"traceutil/trace.go:171","msg":"trace[1928827125] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:520; }","duration":"1.25351759s","start":"2026-06-09T17:03:31.76965Z","end":"2026-06-09T17:03:33.023167Z","steps":["trace[1928827125] 'agreement among raft nodes before linearized reading' (duration: 1.253230819s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:03:33.02322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:03:31.769638Z","time spent":"1.25357498s","remote":"127.0.0.1:47460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-09T17:03:33.166815Z","caller":"traceutil/trace.go:171","msg":"trace[298047037] linearizableReadLoop","detail":"{readStateIndex:541; appliedIndex:539; }","duration":"142.815084ms","start":"2026-06-09T17:03:33.023973Z","end":"2026-06-09T17:03:33.166788Z","steps":["trace[298047037] 'read index received' (duration: 69.275969ms)","trace[298047037] 'applied index is now lower than readState.Index' (duration: 73.534555ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:03:33.166869Z","caller":"traceutil/trace.go:171","msg":"trace[938044572] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"198.838993ms","start":"2026-06-09T17:03:32.967993Z","end":"2026-06-09T17:03:33.166832Z","steps":["trace[938044572] 'process raft request' (duration: 198.696323ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:03:33.16691Z","caller":"traceutil/trace.go:171","msg":"trace[959375443] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"204.958727ms","start":"2026-06-09T17:03:32.961921Z","end":"2026-06-09T17:03:33.16688Z","steps":["trace[959375443] 'process raft request' (duration: 131.247182ms)","trace[959375443] 'compare' (duration: 73.369985ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:03:33.166962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.966344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:03:33.166994Z","caller":"traceutil/trace.go:171","msg":"trace[1280622318] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:522; }","duration":"143.053445ms","start":"2026-06-09T17:03:33.023931Z","end":"2026-06-09T17:03:33.166985Z","steps":["trace[1280622318] 'agreement among raft nodes before linearized reading' (duration: 142.977474ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:03:33.184701Z","caller":"traceutil/trace.go:171","msg":"trace[1532924330] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"155.637018ms","start":"2026-06-09T17:03:33.029045Z","end":"2026-06-09T17:03:33.184682Z","steps":["trace[1532924330] 'process raft request' (duration: 155.528648ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:03:33.18473Z","caller":"traceutil/trace.go:171","msg":"trace[1302475310] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"155.857018ms","start":"2026-06-09T17:03:33.028843Z","end":"2026-06-09T17:03:33.1847Z","steps":["trace[1302475310] 'process raft request' (duration: 155.656738ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:03:33.184827Z","caller":"traceutil/trace.go:171","msg":"trace[248185176] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"155.704788ms","start":"2026-06-09T17:03:33.029115Z","end":"2026-06-09T17:03:33.18482Z","steps":["trace[248185176] 'process raft request' (duration: 155.511708ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:03:33.18483Z","caller":"traceutil/trace.go:171","msg":"trace[1375430039] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"155.687178ms","start":"2026-06-09T17:03:33.0291Z","end":"2026-06-09T17:03:33.184787Z","steps":["trace[1375430039] 'process raft request' (duration: 155.506768ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:03:33.185075Z","caller":"traceutil/trace.go:171","msg":"trace[1153145262] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"152.842432ms","start":"2026-06-09T17:03:33.032221Z","end":"2026-06-09T17:03:33.185063Z","steps":["trace[1153145262] 'process raft request' (duration: 152.718672ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:03:33.185104Z","caller":"traceutil/trace.go:171","msg":"trace[1980019335] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"152.999622ms","start":"2026-06-09T17:03:33.032093Z","end":"2026-06-09T17:03:33.185092Z","steps":["trace[1980019335] 'process raft request' (duration: 152.555662ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:03:34.69757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.252071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:03:34.697673Z","caller":"traceutil/trace.go:171","msg":"trace[1466920861] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:544; }","duration":"366.410161ms","start":"2026-06-09T17:03:34.331245Z","end":"2026-06-09T17:03:34.697655Z","steps":["trace[1466920861] 'range keys from in-memory index tree' (duration: 366.190781ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:03:34.69772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:03:34.331229Z","time spent":"366.480622ms","remote":"127.0.0.1:47286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"info","ts":"2026-06-09T17:03:34.885639Z","caller":"traceutil/trace.go:171","msg":"trace[2126315722] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"144.414894ms","start":"2026-06-09T17:03:34.741186Z","end":"2026-06-09T17:03:34.885601Z","steps":["trace[2126315722] 'process raft request' (duration: 144.234684ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:03:35.210301Z","caller":"traceutil/trace.go:171","msg":"trace[295453537] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"130.34361ms","start":"2026-06-09T17:03:35.07993Z","end":"2026-06-09T17:03:35.210274Z","steps":["trace[295453537] 'process raft request' (duration: 130.220929ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:03:35.21031Z","caller":"traceutil/trace.go:171","msg":"trace[49370991] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"136.729698ms","start":"2026-06-09T17:03:35.073539Z","end":"2026-06-09T17:03:35.210269Z","steps":["trace[49370991] 'process raft request' (duration: 130.019ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:03:35.210461Z","caller":"traceutil/trace.go:171","msg":"trace[461163066] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"128.959274ms","start":"2026-06-09T17:03:35.081491Z","end":"2026-06-09T17:03:35.21045Z","steps":["trace[461163066] 'process raft request' (duration: 128.714844ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:03:35.210795Z","caller":"traceutil/trace.go:171","msg":"trace[1222622214] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"126.000273ms","start":"2026-06-09T17:03:35.08478Z","end":"2026-06-09T17:03:35.210781Z","steps":["trace[1222622214] 'process raft request' (duration: 125.475831ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:03:35.210896Z","caller":"traceutil/trace.go:171","msg":"trace[318779568] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"124.004745ms","start":"2026-06-09T17:03:35.086875Z","end":"2026-06-09T17:03:35.21088Z","steps":["trace[318779568] 'process raft request' (duration: 123.424613ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:03:35.211032Z","caller":"traceutil/trace.go:171","msg":"trace[1033664171] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"123.863823ms","start":"2026-06-09T17:03:35.087144Z","end":"2026-06-09T17:03:35.211007Z","steps":["trace[1033664171] 'process raft request' (duration: 123.282741ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:03:35.211146Z","caller":"traceutil/trace.go:171","msg":"trace[1522738062] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"120.6427ms","start":"2026-06-09T17:03:35.090481Z","end":"2026-06-09T17:03:35.211124Z","steps":["trace[1522738062] 'process raft request' (duration: 119.993287ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:03:35.211251Z","caller":"traceutil/trace.go:171","msg":"trace[1921341217] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"105.260546ms","start":"2026-06-09T17:03:35.105963Z","end":"2026-06-09T17:03:35.211224Z","steps":["trace[1921341217] 'process raft request' (duration: 105.187026ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:03:35.224431Z","caller":"traceutil/trace.go:171","msg":"trace[471125960] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"114.738957ms","start":"2026-06-09T17:03:35.109671Z","end":"2026-06-09T17:03:35.22441Z","steps":["trace[471125960] 'process raft request' (duration: 113.977953ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:03:35.224813Z","caller":"traceutil/trace.go:171","msg":"trace[1125171370] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"107.282406ms","start":"2026-06-09T17:03:35.117497Z","end":"2026-06-09T17:03:35.22478Z","steps":["trace[1125171370] 'process raft request' (duration: 106.343532ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:03:35.22497Z","caller":"traceutil/trace.go:171","msg":"trace[598410240] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"106.421251ms","start":"2026-06-09T17:03:35.118517Z","end":"2026-06-09T17:03:35.224938Z","steps":["trace[598410240] 'process raft request' (duration: 105.389127ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:03:35.225158Z","caller":"traceutil/trace.go:171","msg":"trace[487480087] transaction","detail":"{read_only:false; number_of_response:1; response_revision:557; }","duration":"106.516662ms","start":"2026-06-09T17:03:35.11863Z","end":"2026-06-09T17:03:35.225147Z","steps":["trace[487480087] 'process raft request' (duration: 105.366027ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:03:36.11765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"614.760036ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-09T17:03:36.117853Z","caller":"traceutil/trace.go:171","msg":"trace[1005425485] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"861.53839ms","start":"2026-06-09T17:03:35.256282Z","end":"2026-06-09T17:03:36.11782Z","steps":["trace[1005425485] 'process raft request' (duration: 246.519973ms)","trace[1005425485] 'compare' (duration: 614.500804ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:03:36.117954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:03:35.256255Z","time spent":"861.654871ms","remote":"127.0.0.1:47234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-09T17:03:36.118168Z","caller":"traceutil/trace.go:171","msg":"trace[92116541] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"861.391589ms","start":"2026-06-09T17:03:35.256764Z","end":"2026-06-09T17:03:36.118156Z","steps":["trace[92116541] 'process raft request' (duration: 860.992807ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:03:36.118253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:03:35.256749Z","time spent":"861.45484ms","remote":"127.0.0.1:47460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:03:36.118723Z","caller":"traceutil/trace.go:171","msg":"trace[551254424] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"861.801112ms","start":"2026-06-09T17:03:35.256906Z","end":"2026-06-09T17:03:36.118707Z","steps":["trace[551254424] 'process raft request' (duration: 860.961688ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:03:36.118824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:03:35.2569Z","time spent":"861.883782ms","remote":"127.0.0.1:47460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:03:36.119107Z","caller":"traceutil/trace.go:171","msg":"trace[1606363404] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"861.938643ms","start":"2026-06-09T17:03:35.257153Z","end":"2026-06-09T17:03:36.119092Z","steps":["trace[1606363404] 'process raft request' (duration: 861.34111ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:03:36.119196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:03:35.257147Z","time spent":"862.008354ms","remote":"127.0.0.1:47460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:03:36.120274Z","caller":"traceutil/trace.go:171","msg":"trace[1761816738] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"845.679806ms","start":"2026-06-09T17:03:35.273255Z","end":"2026-06-09T17:03:36.118935Z","steps":["trace[1761816738] 'process raft request' (duration: 845.546925ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:03:36.120353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:03:35.273225Z","time spent":"847.089033ms","remote":"127.0.0.1:47234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58844,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:03:36.196611Z","caller":"traceutil/trace.go:171","msg":"trace[1263399311] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"870.868167ms","start":"2026-06-09T17:03:35.32572Z","end":"2026-06-09T17:03:36.196588Z","steps":["trace[1263399311] 'process raft request' (duration: 870.725476ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:03:36.196746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:03:35.325704Z","time spent":"870.975348ms","remote":"127.0.0.1:47234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10846,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:03:36.196964Z","caller":"traceutil/trace.go:171","msg":"trace[85212557] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"871.184408ms","start":"2026-06-09T17:03:35.325767Z","end":"2026-06-09T17:03:36.196952Z","steps":["trace[85212557] 'process raft request' (duration: 870.756166ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:03:36.197025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:03:35.325763Z","time spent":"871.231788ms","remote":"127.0.0.1:47234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:03:36.197056Z","caller":"traceutil/trace.go:171","msg":"trace[768614461] linearizableReadLoop","detail":"{readStateIndex:600; appliedIndex:596; }","duration":"458.982969ms","start":"2026-06-09T17:03:35.73805Z","end":"2026-06-09T17:03:36.197033Z","steps":["trace[768614461] 'read index received' (duration: 458.194345ms)","trace[768614461] 'applied index is now lower than readState.Index' (duration: 787.364µs)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:03:36.197193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.113199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:03:36.197193Z","caller":"traceutil/trace.go:171","msg":"trace[2104936410] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"861.257918ms","start":"2026-06-09T17:03:35.335874Z","end":"2026-06-09T17:03:36.197132Z","steps":["trace[2104936410] 'process raft request' (duration: 860.934117ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:03:36.197426Z","caller":"traceutil/trace.go:171","msg":"trace[969622804] transaction","detail":"{read_only:false; number_of_response:1; response_revision:575; }","duration":"876.953924ms","start":"2026-06-09T17:03:35.320456Z","end":"2026-06-09T17:03:36.19741Z","steps":["trace[969622804] 'process raft request' (duration: 875.852878ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:03:36.197245Z","caller":"traceutil/trace.go:171","msg":"trace[1968813522] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:578; }","duration":"459.22514ms","start":"2026-06-09T17:03:35.738006Z","end":"2026-06-09T17:03:36.197231Z","steps":["trace[1968813522] 'agreement among raft nodes before linearized reading' (duration: 459.124049ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:03:36.197529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:03:35.320433Z","time spent":"877.032855ms","remote":"127.0.0.1:47234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":7376,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:03:36.197481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:03:35.335854Z","time spent":"861.46402ms","remote":"127.0.0.1:47234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79947,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:03:36.19754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:03:35.737986Z","time spent":"459.538482ms","remote":"127.0.0.1:47186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-09T17:03:36.698814Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313096440795897322,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-09T17:03:37.051211Z","caller":"traceutil/trace.go:171","msg":"trace[1767321704] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"873.670805ms","start":"2026-06-09T17:03:36.177513Z","end":"2026-06-09T17:03:37.051184Z","steps":["trace[1767321704] 'process raft request' (duration: 786.150174ms)","trace[1767321704] 'compare' (duration: 87.032589ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:03:37.051508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:03:36.177468Z","time spent":"873.799167ms","remote":"127.0.0.1:47234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-09T17:03:37.051209Z","caller":"traceutil/trace.go:171","msg":"trace[1671601924] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"871.017421ms","start":"2026-06-09T17:03:36.180153Z","end":"2026-06-09T17:03:37.051171Z","steps":["trace[1671601924] 'process raft request' (duration: 870.76279ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:03:37.051733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:03:36.180124Z","time spent":"871.510186ms","remote":"127.0.0.1:47234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:03:37.093998Z","caller":"traceutil/trace.go:171","msg":"trace[1192009532] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"885.570627ms","start":"2026-06-09T17:03:36.2084Z","end":"2026-06-09T17:03:37.09397Z","steps":["trace[1192009532] 'process raft request' (duration: 885.339585ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:03:37.094114Z","caller":"traceutil/trace.go:171","msg":"trace[1887643633] linearizableReadLoop","detail":"{readStateIndex:605; appliedIndex:600; }","duration":"895.537879ms","start":"2026-06-09T17:03:36.198552Z","end":"2026-06-09T17:03:37.09409Z","steps":["trace[1887643633] 'read index received' (duration: 765.251685ms)","trace[1887643633] 'applied index is now lower than readState.Index' (duration: 130.285134ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:03:37.094141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:03:36.208377Z","time spent":"885.684517ms","remote":"127.0.0.1:47234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10855,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:03:37.094127Z","caller":"traceutil/trace.go:171","msg":"trace[403501161] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"885.087193ms","start":"2026-06-09T17:03:36.209016Z","end":"2026-06-09T17:03:37.094104Z","steps":["trace[403501161] 'process raft request' (duration: 884.924143ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:03:37.094241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"895.68763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:03:37.094276Z","caller":"traceutil/trace.go:171","msg":"trace[249572731] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:583; }","duration":"895.74607ms","start":"2026-06-09T17:03:36.198522Z","end":"2026-06-09T17:03:37.094268Z","steps":["trace[249572731] 'agreement among raft nodes before linearized reading' (duration: 895.6503ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:03:37.094302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:03:36.1985Z","time spent":"895.796141ms","remote":"127.0.0.1:47174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-09T17:03:37.094307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:03:36.209006Z","time spent":"885.234434ms","remote":"127.0.0.1:47234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7693,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:03:37.094039Z","caller":"traceutil/trace.go:171","msg":"trace[1110843763] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"885.271404ms","start":"2026-06-09T17:03:36.208745Z","end":"2026-06-09T17:03:37.094016Z","steps":["trace[1110843763] 'process raft request' (duration: 885.123233ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:03:37.094476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:03:36.208735Z","time spent":"885.661436ms","remote":"127.0.0.1:47234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31102,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:03:37.347077Z","caller":"traceutil/trace.go:171","msg":"trace[1908100224] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"203.306547ms","start":"2026-06-09T17:03:37.143746Z","end":"2026-06-09T17:03:37.347052Z","steps":["trace[1908100224] 'process raft request' (duration: 159.179815ms)","trace[1908100224] 'compare' (duration: 43.925741ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:03:37.363432Z","caller":"traceutil/trace.go:171","msg":"trace[1859853197] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"200.401467ms","start":"2026-06-09T17:03:37.163005Z","end":"2026-06-09T17:03:37.363407Z","steps":["trace[1859853197] 'process raft request' (duration: 199.936155ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:03:37.363726Z","caller":"traceutil/trace.go:171","msg":"trace[673066293] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"199.696734ms","start":"2026-06-09T17:03:37.163986Z","end":"2026-06-09T17:03:37.363683Z","steps":["trace[673066293] 'process raft request' (duration: 199.23732ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:03:37.790745Z","caller":"traceutil/trace.go:171","msg":"trace[987519316] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"146.229443ms","start":"2026-06-09T17:03:37.644487Z","end":"2026-06-09T17:03:37.790716Z","steps":["trace[987519316] 'process raft request' (duration: 132.300214ms)","trace[987519316] 'compare' (duration: 13.749927ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:03:38.275354Z","caller":"traceutil/trace.go:171","msg":"trace[2036211745] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"326.741047ms","start":"2026-06-09T17:03:37.948591Z","end":"2026-06-09T17:03:38.275332Z","steps":["trace[2036211745] 'process raft request' (duration: 325.038165ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:03:38.275551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:03:37.948572Z","time spent":"326.869198ms","remote":"127.0.0.1:47234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65412,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:03:38.297426Z","caller":"traceutil/trace.go:171","msg":"trace[230621312] linearizableReadLoop","detail":"{readStateIndex:636; appliedIndex:632; }","duration":"174.04421ms","start":"2026-06-09T17:03:38.123362Z","end":"2026-06-09T17:03:38.297406Z","steps":["trace[230621312] 'read index received' (duration: 150.291663ms)","trace[230621312] 'applied index is now lower than readState.Index' (duration: 23.751687ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:03:38.297481Z","caller":"traceutil/trace.go:171","msg":"trace[954353801] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"319.508726ms","start":"2026-06-09T17:03:37.977941Z","end":"2026-06-09T17:03:38.29745Z","steps":["trace[954353801] 'process raft request' (duration: 319.149852ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:03:38.297623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:03:37.977924Z","time spent":"319.632806ms","remote":"127.0.0.1:47234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-09T17:03:38.297722Z","caller":"traceutil/trace.go:171","msg":"trace[1360745412] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"331.331021ms","start":"2026-06-09T17:03:37.966381Z","end":"2026-06-09T17:03:38.297712Z","steps":["trace[1360745412] 'process raft request' (duration: 329.992251ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:03:38.297818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:03:37.966363Z","time spent":"331.390842ms","remote":"127.0.0.1:47234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":98698,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:03:38.297842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.455473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-06-09T17:03:38.297938Z","caller":"traceutil/trace.go:171","msg":"trace[554214593] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:614; }","duration":"174.598124ms","start":"2026-06-09T17:03:38.123319Z","end":"2026-06-09T17:03:38.297917Z","steps":["trace[554214593] 'agreement among raft nodes before linearized reading' (duration: 174.295592ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:03:38.297941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.402562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-06-09T17:03:38.298049Z","caller":"traceutil/trace.go:171","msg":"trace[1434193358] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:614; }","duration":"174.594044ms","start":"2026-06-09T17:03:38.123434Z","end":"2026-06-09T17:03:38.298028Z","steps":["trace[1434193358] 'agreement among raft nodes before linearized reading' (duration: 174.261091ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:03:38.298165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.668414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-06-09T17:03:38.298205Z","caller":"traceutil/trace.go:171","msg":"trace[494619530] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:614; }","duration":"174.743205ms","start":"2026-06-09T17:03:38.123454Z","end":"2026-06-09T17:03:38.298197Z","steps":["trace[494619530] 'agreement among raft nodes before linearized reading' (duration: 174.621214ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:03:38.298165Z","caller":"traceutil/trace.go:171","msg":"trace[2036831776] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"294.578946ms","start":"2026-06-09T17:03:38.003572Z","end":"2026-06-09T17:03:38.298151Z","steps":["trace[2036831776] 'process raft request' (duration: 293.7717ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:03:39.067412Z","caller":"traceutil/trace.go:171","msg":"trace[1244921092] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"739.713177ms","start":"2026-06-09T17:03:38.327664Z","end":"2026-06-09T17:03:39.067377Z","steps":["trace[1244921092] 'process raft request' (duration: 685.01451ms)","trace[1244921092] 'compare' (duration: 54.171483ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:03:39.067455Z","caller":"traceutil/trace.go:171","msg":"trace[1537670378] transaction","detail":"{read_only:false; number_of_response:1; response_revision:620; }","duration":"681.947817ms","start":"2026-06-09T17:03:38.38548Z","end":"2026-06-09T17:03:39.067428Z","steps":["trace[1537670378] 'process raft request' (duration: 681.753995ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:03:39.067578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.538208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-09T17:03:39.067599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:03:38.385459Z","time spent":"682.077048ms","remote":"127.0.0.1:47234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105,"response count":0,"response size":74797,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:03:39.067632Z","caller":"traceutil/trace.go:171","msg":"trace[1145124985] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:621; }","duration":"329.639988ms","start":"2026-06-09T17:03:38.737983Z","end":"2026-06-09T17:03:39.067623Z","steps":["trace[1145124985] 'agreement among raft nodes before linearized reading' (duration: 329.498387ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:03:39.067598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:03:38.327635Z","time spent":"739.87873ms","remote":"127.0.0.1:47234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:03:39.067617Z","caller":"traceutil/trace.go:171","msg":"trace[1859647867] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"663.232428ms","start":"2026-06-09T17:03:38.404355Z","end":"2026-06-09T17:03:39.067588Z","steps":["trace[1859647867] 'process raft request' (duration: 662.992125ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:03:39.067815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:03:38.40433Z","time spent":"663.450399ms","remote":"127.0.0.1:47234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14661,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-09T17:03:39.067433Z","caller":"traceutil/trace.go:171","msg":"trace[1804070636] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:641; }","duration":"329.371314ms","start":"2026-06-09T17:03:38.738032Z","end":"2026-06-09T17:03:39.067403Z","steps":["trace[1804070636] 'read index received' (duration: 274.656867ms)","trace[1804070636] 'applied index is now lower than readState.Index' (duration: 54.713497ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:03:39.067669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:03:38.737968Z","time spent":"329.692778ms","remote":"127.0.0.1:47186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-09T17:03:39.58485Z","caller":"traceutil/trace.go:171","msg":"trace[1103601278] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"108.051132ms","start":"2026-06-09T17:03:39.476776Z","end":"2026-06-09T17:03:39.584827Z","steps":["trace[1103601278] 'process raft request' (duration: 45.984933ms)","trace[1103601278] 'compare' (duration: 61.350423ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:03:42.08193Z","caller":"traceutil/trace.go:171","msg":"trace[2000616518] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"161.707296ms","start":"2026-06-09T17:03:41.920189Z","end":"2026-06-09T17:03:42.081897Z","steps":["trace[2000616518] 'process raft request' (duration: 161.076949ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:03:45.046801Z","caller":"traceutil/trace.go:171","msg":"trace[1768843274] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"163.654994ms","start":"2026-06-09T17:03:44.883122Z","end":"2026-06-09T17:03:45.046777Z","steps":["trace[1768843274] 'process raft request' (duration: 162.874163ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:03:45.90797Z","caller":"traceutil/trace.go:171","msg":"trace[1422644812] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"180.263296ms","start":"2026-06-09T17:03:45.727682Z","end":"2026-06-09T17:03:45.907945Z","steps":["trace[1422644812] 'process raft request' (duration: 180.111194ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:03:46.038763Z","caller":"traceutil/trace.go:171","msg":"trace[815321712] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"114.007689ms","start":"2026-06-09T17:03:45.924729Z","end":"2026-06-09T17:03:46.038736Z","steps":["trace[815321712] 'process raft request' (duration: 105.999794ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:03:48.38415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.292085561s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-09T17:03:48.384256Z","caller":"traceutil/trace.go:171","msg":"trace[1267572622] linearizableReadLoop","detail":"{readStateIndex:698; appliedIndex:697; }","duration":"1.73675121s","start":"2026-06-09T17:03:46.647489Z","end":"2026-06-09T17:03:48.38424Z","steps":["trace[1267572622] 'read index received' (duration: 444.355164ms)","trace[1267572622] 'applied index is now lower than readState.Index' (duration: 1.292394756s)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:03:48.384455Z","caller":"traceutil/trace.go:171","msg":"trace[1824376841] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"1.935753459s","start":"2026-06-09T17:03:46.44869Z","end":"2026-06-09T17:03:48.384443Z","steps":["trace[1824376841] 'process raft request' (duration: 643.270372ms)","trace[1824376841] 'compare' (duration: 1.291914258s)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:03:48.384514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:03:46.448673Z","time spent":"1.935810341s","remote":"127.0.0.1:47460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:03:48.384749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.737272008s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-t4z28\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-06-09T17:03:48.38478Z","caller":"traceutil/trace.go:171","msg":"trace[800980905] range","detail":"{range_begin:/registry/pods/kube-system/cilium-t4z28; range_end:; response_count:1; response_revision:673; }","duration":"1.737337799s","start":"2026-06-09T17:03:46.64743Z","end":"2026-06-09T17:03:48.384767Z","steps":["trace[800980905] 'agreement among raft nodes before linearized reading' (duration: 1.737223567s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:03:48.384806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:03:46.647407Z","time spent":"1.73739369s","remote":"127.0.0.1:47392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16985,"request content":"key:\"/registry/pods/kube-system/cilium-t4z28\" "} {"level":"info","ts":"2026-06-09T17:03:48.478889Z","caller":"traceutil/trace.go:171","msg":"trace[830303301] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"905.627973ms","start":"2026-06-09T17:03:47.573234Z","end":"2026-06-09T17:03:48.478862Z","steps":["trace[830303301] 'process raft request' (duration: 905.47738ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:03:48.479087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:03:47.573193Z","time spent":"905.789736ms","remote":"127.0.0.1:47460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:03:48.479512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.741770006s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:03:48.479617Z","caller":"traceutil/trace.go:171","msg":"trace[1628489273] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:674; }","duration":"1.741924858s","start":"2026-06-09T17:03:46.737667Z","end":"2026-06-09T17:03:48.479592Z","steps":["trace[1628489273] 'agreement among raft nodes before linearized reading' (duration: 1.741770266s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:03:48.479675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:03:46.737647Z","time spent":"1.742017s","remote":"127.0.0.1:47174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-09T17:03:48.479606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.335832332s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-09T17:03:48.479899Z","caller":"traceutil/trace.go:171","msg":"trace[1267542626] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:674; }","duration":"1.336228748s","start":"2026-06-09T17:03:47.143607Z","end":"2026-06-09T17:03:48.479835Z","steps":["trace[1267542626] 'agreement among raft nodes before linearized reading' (duration: 1.335806451s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:03:48.480026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:03:47.143586Z","time spent":"1.336360291s","remote":"127.0.0.1:47396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":2,"response size":31,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"warn","ts":"2026-06-09T17:03:48.480046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"962.88327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-09T17:03:48.480101Z","caller":"traceutil/trace.go:171","msg":"trace[1678543865] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:674; }","duration":"962.974272ms","start":"2026-06-09T17:03:47.517108Z","end":"2026-06-09T17:03:48.480082Z","steps":["trace[1678543865] 'agreement among raft nodes before linearized reading' (duration: 962.204729ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:03:48.48014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:03:47.517086Z","time spent":"963.046633ms","remote":"127.0.0.1:47460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-09T17:03:48.980282Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313096440795897503,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-09T17:03:49.123495Z","caller":"traceutil/trace.go:171","msg":"trace[701482086] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"725.201984ms","start":"2026-06-09T17:03:48.398271Z","end":"2026-06-09T17:03:49.123473Z","steps":["trace[701482086] 'process raft request' (duration: 724.99971ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:03:49.123967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:03:48.398241Z","time spent":"725.533979ms","remote":"127.0.0.1:47392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:03:49.718299Z","caller":"traceutil/trace.go:171","msg":"trace[1849516590] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"1.234842502s","start":"2026-06-09T17:03:48.483436Z","end":"2026-06-09T17:03:49.718278Z","steps":["trace[1849516590] 'process raft request' (duration: 1.234690028s)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:03:49.718412Z","caller":"traceutil/trace.go:171","msg":"trace[1931224562] linearizableReadLoop","detail":"{readStateIndex:702; appliedIndex:699; }","duration":"1.239123557s","start":"2026-06-09T17:03:48.479266Z","end":"2026-06-09T17:03:49.718389Z","steps":["trace[1931224562] 'read index received' (duration: 643.997316ms)","trace[1931224562] 'applied index is now lower than readState.Index' (duration: 595.125141ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:03:49.718448Z","caller":"traceutil/trace.go:171","msg":"trace[830268345] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"1.164982983s","start":"2026-06-09T17:03:48.553448Z","end":"2026-06-09T17:03:49.718431Z","steps":["trace[830268345] 'process raft request' (duration: 1.164791849s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:03:49.718526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:03:48.553414Z","time spent":"1.165068705s","remote":"127.0.0.1:47460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:03:49.718646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.300236894s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-06-09T17:03:49.718643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.284827815s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-06-09T17:03:49.718428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:03:48.483415Z","time spent":"1.234944584s","remote":"127.0.0.1:47460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:03:49.718702Z","caller":"traceutil/trace.go:171","msg":"trace[1940988646] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:677; }","duration":"1.300322076s","start":"2026-06-09T17:03:48.418364Z","end":"2026-06-09T17:03:49.718686Z","steps":["trace[1940988646] 'agreement among raft nodes before linearized reading' (duration: 1.300115582s)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:03:49.718707Z","caller":"traceutil/trace.go:171","msg":"trace[2143047071] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:677; }","duration":"1.284938147s","start":"2026-06-09T17:03:48.433756Z","end":"2026-06-09T17:03:49.718694Z","steps":["trace[2143047071] 'agreement among raft nodes before linearized reading' (duration: 1.284708633s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:03:49.718744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:03:48.433735Z","time spent":"1.284999099s","remote":"127.0.0.1:47460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-09T17:03:49.718744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:03:48.418353Z","time spent":"1.300379727s","remote":"127.0.0.1:47460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-09T17:03:50.218744Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313096440795897507,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-09T17:03:50.488436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000987103s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-06-09T17:03:50.488564Z","caller":"traceutil/trace.go:171","msg":"trace[1131875417] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.001164536s","start":"2026-06-09T17:03:48.48738Z","end":"2026-06-09T17:03:50.488544Z","steps":["trace[1131875417] 'agreement among raft nodes before linearized reading' (duration: 2.001003663s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:03:50.488619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:03:48.487368Z","time spent":"2.001239478s","remote":"127.0.0.1:47186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/06/09 17:03:50 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-06-09T17:03:50.719359Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313096440795897507,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-09T17:03:51.220167Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313096440795897507,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-09T17:03:51.454904Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.736719752s","expected-duration":"1s"} {"level":"info","ts":"2026-06-09T17:03:51.549264Z","caller":"traceutil/trace.go:171","msg":"trace[2140575194] linearizableReadLoop","detail":"{readStateIndex:705; appliedIndex:702; }","duration":"1.830791097s","start":"2026-06-09T17:03:49.718455Z","end":"2026-06-09T17:03:51.549246Z","steps":["trace[2140575194] 'read index received' (duration: 1.736677791s)","trace[2140575194] 'applied index is now lower than readState.Index' (duration: 94.112416ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:03:51.549352Z","caller":"traceutil/trace.go:171","msg":"trace[1916011719] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"1.826187023s","start":"2026-06-09T17:03:49.723141Z","end":"2026-06-09T17:03:51.549328Z","steps":["trace[1916011719] 'process raft request' (duration: 1.826071851s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:03:51.549446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.729751369s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-09T17:03:51.54948Z","caller":"traceutil/trace.go:171","msg":"trace[1770814220] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:679; }","duration":"2.729874141s","start":"2026-06-09T17:03:48.819598Z","end":"2026-06-09T17:03:51.549472Z","steps":["trace[1770814220] 'agreement among raft nodes before linearized reading' (duration: 2.729748178s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:03:51.549509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:03:48.819565Z","time spent":"2.729934052s","remote":"127.0.0.1:47262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":61,"response size":31,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-06-09T17:03:51.549488Z","caller":"traceutil/trace.go:171","msg":"trace[2009451410] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"1.826411867s","start":"2026-06-09T17:03:49.723042Z","end":"2026-06-09T17:03:51.549454Z","steps":["trace[2009451410] 'process raft request' (duration: 1.826081121s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:03:51.54953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:03:49.723106Z","time spent":"1.826323406s","remote":"127.0.0.1:47460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:03:51.549586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.014754396s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-09T17:03:51.54959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.958536105s","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-06-09T17:03:51.549631Z","caller":"traceutil/trace.go:171","msg":"trace[1261491013] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:679; }","duration":"1.958651787s","start":"2026-06-09T17:03:49.59097Z","end":"2026-06-09T17:03:51.549622Z","steps":["trace[1261491013] 'agreement among raft nodes before linearized reading' (duration: 1.958547704s)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:03:51.549631Z","caller":"traceutil/trace.go:171","msg":"trace[103212901] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:679; }","duration":"2.014861651s","start":"2026-06-09T17:03:49.534758Z","end":"2026-06-09T17:03:51.549619Z","steps":["trace[103212901] 'agreement among raft nodes before linearized reading' (duration: 2.014584794s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:03:51.549652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:03:49.590924Z","time spent":"1.958721218s","remote":"127.0.0.1:47544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":67,"response size":31,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-06-09T17:03:51.549657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:03:49.534734Z","time spent":"2.014917411s","remote":"127.0.0.1:47408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-06-09T17:03:51.549644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:03:49.723018Z","time spent":"1.82655166s","remote":"127.0.0.1:47460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:03:51.549777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.060953625s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:03:51.549796Z","caller":"traceutil/trace.go:171","msg":"trace[1283300120] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:679; }","duration":"1.061011486s","start":"2026-06-09T17:03:50.488778Z","end":"2026-06-09T17:03:51.54979Z","steps":["trace[1283300120] 'agreement among raft nodes before linearized reading' (duration: 1.060976295s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:03:51.549813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:03:50.488757Z","time spent":"1.061050856s","remote":"127.0.0.1:47174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-09T17:03:51.54991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.160009444s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-09T17:03:51.550109Z","caller":"traceutil/trace.go:171","msg":"trace[1421484292] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:679; }","duration":"1.160258398s","start":"2026-06-09T17:03:50.38981Z","end":"2026-06-09T17:03:51.550068Z","steps":["trace[1421484292] 'agreement among raft nodes before linearized reading' (duration: 1.15985283s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:03:51.550169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:03:50.389789Z","time spent":"1.160365271s","remote":"127.0.0.1:47460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-09T17:03:52.820512Z","caller":"traceutil/trace.go:171","msg":"trace[4790590] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"150.208538ms","start":"2026-06-09T17:03:52.67028Z","end":"2026-06-09T17:03:52.820489Z","steps":["trace[4790590] 'process raft request' (duration: 150.047315ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:03:52.859228Z","caller":"traceutil/trace.go:171","msg":"trace[910340665] linearizableReadLoop","detail":"{readStateIndex:715; appliedIndex:714; }","duration":"120.662077ms","start":"2026-06-09T17:03:52.738533Z","end":"2026-06-09T17:03:52.859196Z","steps":["trace[910340665] 'read index received' (duration: 82.124366ms)","trace[910340665] 'applied index is now lower than readState.Index' (duration: 38.535881ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:03:52.859468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.911021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:03:52.859569Z","caller":"traceutil/trace.go:171","msg":"trace[1276346424] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"177.541363ms","start":"2026-06-09T17:03:52.682001Z","end":"2026-06-09T17:03:52.859542Z","steps":["trace[1276346424] 'process raft request' (duration: 176.830348ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:03:52.859637Z","caller":"traceutil/trace.go:171","msg":"trace[1551727488] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:688; }","duration":"121.051794ms","start":"2026-06-09T17:03:52.738471Z","end":"2026-06-09T17:03:52.859523Z","steps":["trace[1551727488] 'agreement among raft nodes before linearized reading' (duration: 120.85709ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:03:53.601961Z","caller":"traceutil/trace.go:171","msg":"trace[1523993148] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"352.409252ms","start":"2026-06-09T17:03:53.249516Z","end":"2026-06-09T17:03:53.601925Z","steps":["trace[1523993148] 'process raft request' (duration: 352.151176ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:03:53.60214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:03:53.249494Z","time spent":"352.562975ms","remote":"127.0.0.1:47262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":722,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-09T17:03:53.773142Z","caller":"traceutil/trace.go:171","msg":"trace[65204718] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"167.783432ms","start":"2026-06-09T17:03:53.605336Z","end":"2026-06-09T17:03:53.77312Z","steps":["trace[65204718] 'process raft request' (duration: 94.785464ms)","trace[65204718] 'compare' (duration: 72.831244ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:03:53.77356Z","caller":"traceutil/trace.go:171","msg":"trace[2098092023] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"167.470806ms","start":"2026-06-09T17:03:53.606066Z","end":"2026-06-09T17:03:53.773537Z","steps":["trace[2098092023] 'process raft request' (duration: 167.017976ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:03:53.773613Z","caller":"traceutil/trace.go:171","msg":"trace[548014713] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"167.22317ms","start":"2026-06-09T17:03:53.606369Z","end":"2026-06-09T17:03:53.773592Z","steps":["trace[548014713] 'process raft request' (duration: 167.071007ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:03:53.903278Z","caller":"traceutil/trace.go:171","msg":"trace[2130911966] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"115.677252ms","start":"2026-06-09T17:03:53.787574Z","end":"2026-06-09T17:03:53.903251Z","steps":["trace[2130911966] 'process raft request' (duration: 111.267495ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:03:55.272788Z","caller":"traceutil/trace.go:171","msg":"trace[373004354] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"213.440941ms","start":"2026-06-09T17:03:55.059323Z","end":"2026-06-09T17:03:55.272764Z","steps":["trace[373004354] 'process raft request' (duration: 213.304458ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:03:55.990576Z","caller":"traceutil/trace.go:171","msg":"trace[402740650] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"131.381026ms","start":"2026-06-09T17:03:55.859164Z","end":"2026-06-09T17:03:55.990545Z","steps":["trace[402740650] 'process raft request' (duration: 105.777063ms)","trace[402740650] 'compare' (duration: 25.47047ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:03:56.047333Z","caller":"traceutil/trace.go:171","msg":"trace[1455013213] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"112.142506ms","start":"2026-06-09T17:03:55.93517Z","end":"2026-06-09T17:03:56.047313Z","steps":["trace[1455013213] 'process raft request' (duration: 112.037334ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:03:56.161454Z","caller":"traceutil/trace.go:171","msg":"trace[1950692701] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"165.16545ms","start":"2026-06-09T17:03:55.996255Z","end":"2026-06-09T17:03:56.161421Z","steps":["trace[1950692701] 'process raft request' (duration: 102.465382ms)","trace[1950692701] 'compare' (duration: 62.539034ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:03:56.222907Z","caller":"traceutil/trace.go:171","msg":"trace[1277228170] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"160.862763ms","start":"2026-06-09T17:03:56.062021Z","end":"2026-06-09T17:03:56.222884Z","steps":["trace[1277228170] 'process raft request' (duration: 160.805271ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:03:56.222935Z","caller":"traceutil/trace.go:171","msg":"trace[270007353] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"171.892271ms","start":"2026-06-09T17:03:56.051001Z","end":"2026-06-09T17:03:56.222893Z","steps":["trace[270007353] 'process raft request' (duration: 171.650745ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:03:56.492091Z","caller":"traceutil/trace.go:171","msg":"trace[2104039895] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"324.688367ms","start":"2026-06-09T17:03:56.167378Z","end":"2026-06-09T17:03:56.492067Z","steps":["trace[2104039895] 'process raft request' (duration: 324.548313ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:03:56.492251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:03:56.167359Z","time spent":"324.802789ms","remote":"127.0.0.1:47234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4165,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:03:56.697358Z","caller":"traceutil/trace.go:171","msg":"trace[1325852758] linearizableReadLoop","detail":"{readStateIndex:768; appliedIndex:765; }","duration":"202.20604ms","start":"2026-06-09T17:03:56.49513Z","end":"2026-06-09T17:03:56.697336Z","steps":["trace[1325852758] 'read index received' (duration: 183.76729ms)","trace[1325852758] 'applied index is now lower than readState.Index' (duration: 18.43792ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:03:56.697363Z","caller":"traceutil/trace.go:171","msg":"trace[683906607] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"459.942824ms","start":"2026-06-09T17:03:56.237395Z","end":"2026-06-09T17:03:56.697338Z","steps":["trace[683906607] 'process raft request' (duration: 441.533225ms)","trace[683906607] 'compare' (duration: 18.196314ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:03:56.697539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.397345ms","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-06-09T17:03:56.69759Z","caller":"traceutil/trace.go:171","msg":"trace[1942425147] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:740; }","duration":"202.527427ms","start":"2026-06-09T17:03:56.495055Z","end":"2026-06-09T17:03:56.697583Z","steps":["trace[1942425147] 'agreement among raft nodes before linearized reading' (duration: 202.368034ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:03:56.697604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:03:56.237351Z","time spent":"460.15823ms","remote":"127.0.0.1:47234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":24220,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:03:56.697439Z","caller":"traceutil/trace.go:171","msg":"trace[1989101703] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"235.970573ms","start":"2026-06-09T17:03:56.461437Z","end":"2026-06-09T17:03:56.697407Z","steps":["trace[1989101703] 'process raft request' (duration: 235.83718ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:03:56.69882Z","caller":"traceutil/trace.go:171","msg":"trace[109445934] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"439.164759ms","start":"2026-06-09T17:03:56.25818Z","end":"2026-06-09T17:03:56.697345Z","steps":["trace[109445934] 'process raft request' (duration: 439.047976ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:03:56.698947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:03:56.258138Z","time spent":"440.746547ms","remote":"127.0.0.1:47234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3104,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-09T17:03:57.077337Z","caller":"traceutil/trace.go:171","msg":"trace[738072747] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"170.439943ms","start":"2026-06-09T17:03:56.906874Z","end":"2026-06-09T17:03:57.077314Z","steps":["trace[738072747] 'process raft request' (duration: 170.200877ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:03:57.091227Z","caller":"traceutil/trace.go:171","msg":"trace[896642177] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"150.773074ms","start":"2026-06-09T17:03:56.940435Z","end":"2026-06-09T17:03:57.091208Z","steps":["trace[896642177] 'process raft request' (duration: 150.019856ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:03:57.372047Z","caller":"traceutil/trace.go:171","msg":"trace[2038910835] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"105.793465ms","start":"2026-06-09T17:03:57.266226Z","end":"2026-06-09T17:03:57.37202Z","steps":["trace[2038910835] 'process raft request' (duration: 103.700072ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:03:57.968868Z","caller":"traceutil/trace.go:171","msg":"trace[458035557] linearizableReadLoop","detail":"{readStateIndex:789; appliedIndex:787; }","duration":"123.822109ms","start":"2026-06-09T17:03:57.844927Z","end":"2026-06-09T17:03:57.968749Z","steps":["trace[458035557] 'read index received' (duration: 94.130891ms)","trace[458035557] 'applied index is now lower than readState.Index' (duration: 29.689688ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:03:57.968852Z","caller":"traceutil/trace.go:171","msg":"trace[1663020749] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"142.775627ms","start":"2026-06-09T17:03:57.826006Z","end":"2026-06-09T17:03:57.968782Z","steps":["trace[1663020749] 'process raft request' (duration: 113.038348ms)","trace[1663020749] 'compare' (duration: 29.445292ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:03:57.968934Z","caller":"traceutil/trace.go:171","msg":"trace[855530178] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"142.812518ms","start":"2026-06-09T17:03:57.826091Z","end":"2026-06-09T17:03:57.968903Z","steps":["trace[855530178] 'process raft request' (duration: 142.587172ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:03:57.969177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.227699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-09T17:03:57.969231Z","caller":"traceutil/trace.go:171","msg":"trace[1098097549] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:761; }","duration":"124.341842ms","start":"2026-06-09T17:03:57.844872Z","end":"2026-06-09T17:03:57.969214Z","steps":["trace[1098097549] 'agreement among raft nodes before linearized reading' (duration: 124.129296ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:03:57.974623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.119905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-06-09T17:03:57.974686Z","caller":"traceutil/trace.go:171","msg":"trace[1602962460] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:762; }","duration":"118.232588ms","start":"2026-06-09T17:03:57.856438Z","end":"2026-06-09T17:03:57.97467Z","steps":["trace[1602962460] 'agreement among raft nodes before linearized reading' (duration: 117.341366ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:01.962974Z","caller":"traceutil/trace.go:171","msg":"trace[1585641337] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"113.80948ms","start":"2026-06-09T17:04:01.849137Z","end":"2026-06-09T17:04:01.962947Z","steps":["trace[1585641337] 'process raft request' (duration: 113.600754ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:02.259769Z","caller":"traceutil/trace.go:171","msg":"trace[1094607807] linearizableReadLoop","detail":"{readStateIndex:824; appliedIndex:822; }","duration":"212.275295ms","start":"2026-06-09T17:04:02.047472Z","end":"2026-06-09T17:04:02.259748Z","steps":["trace[1094607807] 'read index received' (duration: 207.108074ms)","trace[1094607807] 'applied index is now lower than readState.Index' (duration: 5.166381ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:04:02.259837Z","caller":"traceutil/trace.go:171","msg":"trace[47849669] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"244.388517ms","start":"2026-06-09T17:04:02.015421Z","end":"2026-06-09T17:04:02.25981Z","steps":["trace[47849669] 'process raft request' (duration: 244.247973ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:02.259974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.018118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-09T17:04:02.260015Z","caller":"traceutil/trace.go:171","msg":"trace[557078057] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:794; }","duration":"276.110321ms","start":"2026-06-09T17:04:01.983894Z","end":"2026-06-09T17:04:02.260004Z","steps":["trace[557078057] 'agreement among raft nodes before linearized reading' (duration: 275.943136ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:02.260048Z","caller":"traceutil/trace.go:171","msg":"trace[1706242355] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"266.812329ms","start":"2026-06-09T17:04:01.993224Z","end":"2026-06-09T17:04:02.260036Z","steps":["trace[1706242355] 'process raft request' (duration: 261.189916ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:02.260425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.44662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-09T17:04:02.260475Z","caller":"traceutil/trace.go:171","msg":"trace[1799037028] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:794; }","duration":"276.511273ms","start":"2026-06-09T17:04:01.983952Z","end":"2026-06-09T17:04:02.260464Z","steps":["trace[1799037028] 'agreement among raft nodes before linearized reading' (duration: 276.3981ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:02.260772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.922005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-09T17:04:02.26081Z","caller":"traceutil/trace.go:171","msg":"trace[1082925359] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:794; }","duration":"241.974307ms","start":"2026-06-09T17:04:02.018828Z","end":"2026-06-09T17:04:02.260802Z","steps":["trace[1082925359] 'agreement among raft nodes before linearized reading' (duration: 241.405659ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:02.419241Z","caller":"traceutil/trace.go:171","msg":"trace[168161282] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"360.947481ms","start":"2026-06-09T17:04:02.058261Z","end":"2026-06-09T17:04:02.419208Z","steps":["trace[168161282] 'process raft request' (duration: 340.990011ms)","trace[168161282] 'compare' (duration: 19.784125ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:04:02.419436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:02.058246Z","time spent":"361.108726ms","remote":"127.0.0.1:47392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18506,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:04:02.489861Z","caller":"traceutil/trace.go:171","msg":"trace[1907057458] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"222.298575ms","start":"2026-06-09T17:04:02.267536Z","end":"2026-06-09T17:04:02.489834Z","steps":["trace[1907057458] 'process raft request' (duration: 222.249284ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:02.490527Z","caller":"traceutil/trace.go:171","msg":"trace[1085686990] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"224.860119ms","start":"2026-06-09T17:04:02.265638Z","end":"2026-06-09T17:04:02.490498Z","steps":["trace[1085686990] 'process raft request' (duration: 224.083316ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:02.490719Z","caller":"traceutil/trace.go:171","msg":"trace[1912143298] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"225.489537ms","start":"2026-06-09T17:04:02.265213Z","end":"2026-06-09T17:04:02.490702Z","steps":["trace[1912143298] 'process raft request' (duration: 224.372324ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:02.490581Z","caller":"traceutil/trace.go:171","msg":"trace[811074541] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"224.810428ms","start":"2026-06-09T17:04:02.265754Z","end":"2026-06-09T17:04:02.490565Z","steps":["trace[811074541] 'process raft request' (duration: 224.001474ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:02.583182Z","caller":"traceutil/trace.go:171","msg":"trace[1885324648] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"147.372779ms","start":"2026-06-09T17:04:02.43579Z","end":"2026-06-09T17:04:02.583162Z","steps":["trace[1885324648] 'process raft request' (duration: 147.305747ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:02.583356Z","caller":"traceutil/trace.go:171","msg":"trace[1001345946] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"154.090125ms","start":"2026-06-09T17:04:02.429145Z","end":"2026-06-09T17:04:02.583235Z","steps":["trace[1001345946] 'process raft request' (duration: 115.655228ms)","trace[1001345946] 'compare' (duration: 38.158739ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:04:05.932874Z","caller":"traceutil/trace.go:171","msg":"trace[1000062505] transaction","detail":"{read_only:false; response_revision:819; number_of_response:1; }","duration":"130.701618ms","start":"2026-06-09T17:04:05.802122Z","end":"2026-06-09T17:04:05.932823Z","steps":["trace[1000062505] 'process raft request' (duration: 37.147838ms)","trace[1000062505] 'compare' (duration: 93.334603ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:04:05.933161Z","caller":"traceutil/trace.go:171","msg":"trace[2003539450] transaction","detail":"{read_only:false; response_revision:820; number_of_response:1; }","duration":"130.636236ms","start":"2026-06-09T17:04:05.802491Z","end":"2026-06-09T17:04:05.933127Z","steps":["trace[2003539450] 'process raft request' (duration: 130.246014ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:05.933707Z","caller":"traceutil/trace.go:171","msg":"trace[1208011242] transaction","detail":"{read_only:false; response_revision:821; number_of_response:1; }","duration":"128.272833ms","start":"2026-06-09T17:04:05.805407Z","end":"2026-06-09T17:04:05.93368Z","steps":["trace[1208011242] 'process raft request' (duration: 127.55581ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:06.15927Z","caller":"traceutil/trace.go:171","msg":"trace[1184332686] transaction","detail":"{read_only:false; response_revision:822; number_of_response:1; }","duration":"218.144165ms","start":"2026-06-09T17:04:05.941073Z","end":"2026-06-09T17:04:06.159218Z","steps":["trace[1184332686] 'process raft request' (duration: 217.928329ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:06.701836Z","caller":"traceutil/trace.go:171","msg":"trace[129694949] transaction","detail":"{read_only:false; response_revision:826; number_of_response:1; }","duration":"131.732354ms","start":"2026-06-09T17:04:06.570082Z","end":"2026-06-09T17:04:06.701815Z","steps":["trace[129694949] 'process raft request' (duration: 131.690372ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:06.701887Z","caller":"traceutil/trace.go:171","msg":"trace[1815241327] transaction","detail":"{read_only:false; response_revision:825; number_of_response:1; }","duration":"131.881168ms","start":"2026-06-09T17:04:06.569971Z","end":"2026-06-09T17:04:06.701852Z","steps":["trace[1815241327] 'process raft request' (duration: 73.797145ms)","trace[1815241327] 'compare' (duration: 57.887177ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:04:09.09743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.130615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:04:09.097485Z","caller":"traceutil/trace.go:171","msg":"trace[744465458] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:829; }","duration":"358.225049ms","start":"2026-06-09T17:04:08.739247Z","end":"2026-06-09T17:04:09.097472Z","steps":["trace[744465458] 'range keys from in-memory index tree' (duration: 358.032922ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:09.097509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:08.739227Z","time spent":"358.275501ms","remote":"127.0.0.1:47186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-09T17:04:09.09771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.949322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-09T17:04:09.097812Z","caller":"traceutil/trace.go:171","msg":"trace[919610153] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:829; }","duration":"392.099417ms","start":"2026-06-09T17:04:08.705689Z","end":"2026-06-09T17:04:09.097788Z","steps":["trace[919610153] 'range keys from in-memory index tree' (duration: 391.776555ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:09.097868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:08.705666Z","time spent":"392.18808ms","remote":"127.0.0.1:47460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-09T17:04:09.098058Z","caller":"traceutil/trace.go:171","msg":"trace[527111116] linearizableReadLoop","detail":"{readStateIndex:861; appliedIndex:860; }","duration":"158.65404ms","start":"2026-06-09T17:04:08.939396Z","end":"2026-06-09T17:04:09.09805Z","steps":["trace[527111116] 'read index received' (duration: 148.254876ms)","trace[527111116] 'applied index is now lower than readState.Index' (duration: 10.398434ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:04:09.098067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.90268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-09T17:04:09.098172Z","caller":"traceutil/trace.go:171","msg":"trace[492735323] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:829; }","duration":"392.077737ms","start":"2026-06-09T17:04:08.706069Z","end":"2026-06-09T17:04:09.098147Z","steps":["trace[492735323] 'range keys from in-memory index tree' (duration: 391.619071ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:09.098187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.799615ms","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-06-09T17:04:09.098209Z","caller":"traceutil/trace.go:171","msg":"trace[235029922] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:830; }","duration":"158.863968ms","start":"2026-06-09T17:04:08.939339Z","end":"2026-06-09T17:04:09.098203Z","steps":["trace[235029922] 'agreement among raft nodes before linearized reading' (duration: 158.740734ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:09.098234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:08.706049Z","time spent":"392.167479ms","remote":"127.0.0.1:47460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-09T17:04:09.098259Z","caller":"traceutil/trace.go:171","msg":"trace[1737271671] transaction","detail":"{read_only:false; response_revision:830; number_of_response:1; }","duration":"308.31182ms","start":"2026-06-09T17:04:08.789941Z","end":"2026-06-09T17:04:09.098253Z","steps":["trace[1737271671] 'process raft request' (duration: 297.763711ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:09.09834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:08.789912Z","time spent":"308.378672ms","remote":"127.0.0.1:47460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:04:10.909623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.95524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:04:10.909709Z","caller":"traceutil/trace.go:171","msg":"trace[1635269729] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:836; }","duration":"170.089095ms","start":"2026-06-09T17:04:10.739599Z","end":"2026-06-09T17:04:10.909688Z","steps":["trace[1635269729] 'range keys from in-memory index tree' (duration: 169.863888ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:14.189517Z","caller":"traceutil/trace.go:171","msg":"trace[277342686] transaction","detail":"{read_only:false; response_revision:845; number_of_response:1; }","duration":"186.210942ms","start":"2026-06-09T17:04:14.003285Z","end":"2026-06-09T17:04:14.189496Z","steps":["trace[277342686] 'process raft request' (duration: 186.025915ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:16.840364Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313096440795897964,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-09T17:04:16.896425Z","caller":"traceutil/trace.go:171","msg":"trace[60320186] linearizableReadLoop","detail":"{readStateIndex:892; appliedIndex:891; }","duration":"556.448078ms","start":"2026-06-09T17:04:16.339952Z","end":"2026-06-09T17:04:16.8964Z","steps":["trace[60320186] 'read index received' (duration: 556.168558ms)","trace[60320186] 'applied index is now lower than readState.Index' (duration: 278.78µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:04:16.896541Z","caller":"traceutil/trace.go:171","msg":"trace[1590212179] transaction","detail":"{read_only:false; response_revision:859; number_of_response:1; }","duration":"862.903057ms","start":"2026-06-09T17:04:16.033619Z","end":"2026-06-09T17:04:16.896522Z","steps":["trace[1590212179] 'process raft request' (duration: 862.551443ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:16.896676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:16.033594Z","time spent":"862.999461ms","remote":"127.0.0.1:47392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4021,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:04:16.896748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.04285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:04:16.896821Z","caller":"traceutil/trace.go:171","msg":"trace[1926755000] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:859; }","duration":"157.208947ms","start":"2026-06-09T17:04:16.739596Z","end":"2026-06-09T17:04:16.896805Z","steps":["trace[1926755000] 'agreement among raft nodes before linearized reading' (duration: 157.053721ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:16.897171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.672795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-06-09T17:04:16.897264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.311042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-09T17:04:16.897527Z","caller":"traceutil/trace.go:171","msg":"trace[1672178922] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:859; }","duration":"557.612423ms","start":"2026-06-09T17:04:16.339899Z","end":"2026-06-09T17:04:16.897512Z","steps":["trace[1672178922] 'agreement among raft nodes before linearized reading' (duration: 556.669548ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:16.89764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:16.339879Z","time spent":"557.748468ms","remote":"127.0.0.1:47460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-09T17:04:16.898064Z","caller":"traceutil/trace.go:171","msg":"trace[1572207213] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:859; }","duration":"228.957216ms","start":"2026-06-09T17:04:16.66836Z","end":"2026-06-09T17:04:16.897317Z","steps":["trace[1572207213] 'agreement among raft nodes before linearized reading' (duration: 228.308621ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:20.604204Z","caller":"traceutil/trace.go:171","msg":"trace[285783359] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"187.249298ms","start":"2026-06-09T17:04:20.41693Z","end":"2026-06-09T17:04:20.604179Z","steps":["trace[285783359] 'process raft request' (duration: 187.131722ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:20.604229Z","caller":"traceutil/trace.go:171","msg":"trace[999725442] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"241.926ms","start":"2026-06-09T17:04:20.362257Z","end":"2026-06-09T17:04:20.604183Z","steps":["trace[999725442] 'process raft request' (duration: 211.65397ms)","trace[999725442] 'compare' (duration: 29.820891ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:04:20.759659Z","caller":"traceutil/trace.go:171","msg":"trace[1950264534] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"114.280044ms","start":"2026-06-09T17:04:20.645343Z","end":"2026-06-09T17:04:20.759623Z","steps":["trace[1950264534] 'process raft request' (duration: 105.60346ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:21.184857Z","caller":"traceutil/trace.go:171","msg":"trace[575210000] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"191.73358ms","start":"2026-06-09T17:04:20.993095Z","end":"2026-06-09T17:04:21.184829Z","steps":["trace[575210000] 'process raft request' (duration: 148.695938ms)","trace[575210000] 'compare' (duration: 42.188588ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:04:21.185274Z","caller":"traceutil/trace.go:171","msg":"trace[44012014] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"192.069644ms","start":"2026-06-09T17:04:20.993174Z","end":"2026-06-09T17:04:21.185243Z","steps":["trace[44012014] 'process raft request' (duration: 191.047741ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:21.185299Z","caller":"traceutil/trace.go:171","msg":"trace[1078051242] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"192.170128ms","start":"2026-06-09T17:04:20.993101Z","end":"2026-06-09T17:04:21.185271Z","steps":["trace[1078051242] 'process raft request' (duration: 191.00253ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:21.185639Z","caller":"traceutil/trace.go:171","msg":"trace[1516257448] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"192.4626ms","start":"2026-06-09T17:04:20.993162Z","end":"2026-06-09T17:04:21.185624Z","steps":["trace[1516257448] 'process raft request' (duration: 191.00419ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:21.185863Z","caller":"traceutil/trace.go:171","msg":"trace[1335304068] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"192.678119ms","start":"2026-06-09T17:04:20.993152Z","end":"2026-06-09T17:04:21.18583Z","steps":["trace[1335304068] 'process raft request' (duration: 191.144365ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:22.143389Z","caller":"traceutil/trace.go:171","msg":"trace[443637412] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"113.485565ms","start":"2026-06-09T17:04:22.029866Z","end":"2026-06-09T17:04:22.143352Z","steps":["trace[443637412] 'process raft request' (duration: 112.189881ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:28.958479Z","caller":"traceutil/trace.go:171","msg":"trace[1997778559] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"448.782183ms","start":"2026-06-09T17:04:28.509669Z","end":"2026-06-09T17:04:28.958451Z","steps":["trace[1997778559] 'process raft request' (duration: 448.326923ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:28.958701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:28.509631Z","time spent":"448.988483ms","remote":"127.0.0.1:47384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5316,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:04:28.981663Z","caller":"traceutil/trace.go:171","msg":"trace[883487865] linearizableReadLoop","detail":"{readStateIndex:1021; appliedIndex:1019; }","duration":"241.83997ms","start":"2026-06-09T17:04:28.739796Z","end":"2026-06-09T17:04:28.981636Z","steps":["trace[883487865] 'read index received' (duration: 218.1905ms)","trace[883487865] 'applied index is now lower than readState.Index' (duration: 23.64818ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:04:28.981908Z","caller":"traceutil/trace.go:171","msg":"trace[986502799] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"289.827849ms","start":"2026-06-09T17:04:28.692062Z","end":"2026-06-09T17:04:28.98189Z","steps":["trace[986502799] 'process raft request' (duration: 289.408041ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:28.981964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.951143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:04:28.982034Z","caller":"traceutil/trace.go:171","msg":"trace[1896832599] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:0; response_revision:986; }","duration":"109.066599ms","start":"2026-06-09T17:04:28.872948Z","end":"2026-06-09T17:04:28.982015Z","steps":["trace[1896832599] 'agreement among raft nodes before linearized reading' (duration: 108.949503ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:28.98216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.340292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:04:28.982249Z","caller":"traceutil/trace.go:171","msg":"trace[1132709239] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:986; }","duration":"242.476988ms","start":"2026-06-09T17:04:28.739753Z","end":"2026-06-09T17:04:28.98223Z","steps":["trace[1132709239] 'agreement among raft nodes before linearized reading' (duration: 242.321211ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:29.191954Z","caller":"traceutil/trace.go:171","msg":"trace[746263588] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"127.237945ms","start":"2026-06-09T17:04:29.064694Z","end":"2026-06-09T17:04:29.191932Z","steps":["trace[746263588] 'process raft request' (duration: 127.165902ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:29.191954Z","caller":"traceutil/trace.go:171","msg":"trace[12919724] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"130.386367ms","start":"2026-06-09T17:04:29.061542Z","end":"2026-06-09T17:04:29.191928Z","steps":["trace[12919724] 'process raft request' (duration: 129.678174ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:29.455801Z","caller":"traceutil/trace.go:171","msg":"trace[1795848603] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"216.345741ms","start":"2026-06-09T17:04:29.239416Z","end":"2026-06-09T17:04:29.455762Z","steps":["trace[1795848603] 'process raft request' (duration: 158.773203ms)","trace[1795848603] 'compare' (duration: 57.361049ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:04:29.557225Z","caller":"traceutil/trace.go:171","msg":"trace[310673496] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"267.455297ms","start":"2026-06-09T17:04:29.289731Z","end":"2026-06-09T17:04:29.557187Z","steps":["trace[310673496] 'process raft request' (duration: 267.119172ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:29.557371Z","caller":"traceutil/trace.go:171","msg":"trace[288739621] linearizableReadLoop","detail":"{readStateIndex:1029; appliedIndex:1027; }","duration":"293.435614ms","start":"2026-06-09T17:04:29.263909Z","end":"2026-06-09T17:04:29.557345Z","steps":["trace[288739621] 'read index received' (duration: 134.308044ms)","trace[288739621] 'applied index is now lower than readState.Index' (duration: 159.12603ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:04:29.557762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.665497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-06-09T17:04:29.557766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.003412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-09T17:04:29.557805Z","caller":"traceutil/trace.go:171","msg":"trace[1314312447] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:995; }","duration":"286.774142ms","start":"2026-06-09T17:04:29.271017Z","end":"2026-06-09T17:04:29.557791Z","steps":["trace[1314312447] 'agreement among raft nodes before linearized reading' (duration: 286.596474ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:29.55781Z","caller":"traceutil/trace.go:171","msg":"trace[964529215] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:995; }","duration":"134.101025ms","start":"2026-06-09T17:04:29.423697Z","end":"2026-06-09T17:04:29.557798Z","steps":["trace[964529215] 'agreement among raft nodes before linearized reading' (duration: 133.931348ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:29.558084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.170247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-06-09T17:04:29.558078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.95252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-09T17:04:29.558122Z","caller":"traceutil/trace.go:171","msg":"trace[1712494092] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:995; }","duration":"294.272951ms","start":"2026-06-09T17:04:29.26384Z","end":"2026-06-09T17:04:29.558113Z","steps":["trace[1712494092] 'agreement among raft nodes before linearized reading' (duration: 293.700786ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:29.558163Z","caller":"traceutil/trace.go:171","msg":"trace[1249526126] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:995; }","duration":"287.091117ms","start":"2026-06-09T17:04:29.27105Z","end":"2026-06-09T17:04:29.558141Z","steps":["trace[1249526126] 'agreement among raft nodes before linearized reading' (duration: 286.796413ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:29.559161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.826201ms","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-06-09T17:04:29.559281Z","caller":"traceutil/trace.go:171","msg":"trace[1038423047] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:995; }","duration":"126.644727ms","start":"2026-06-09T17:04:29.432606Z","end":"2026-06-09T17:04:29.559251Z","steps":["trace[1038423047] 'agreement among raft nodes before linearized reading' (duration: 125.242523ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:29.826076Z","caller":"traceutil/trace.go:171","msg":"trace[1605296825] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"165.719699ms","start":"2026-06-09T17:04:29.660327Z","end":"2026-06-09T17:04:29.826047Z","steps":["trace[1605296825] 'process raft request' (duration: 165.412365ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:30.098354Z","caller":"traceutil/trace.go:171","msg":"trace[599171671] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"213.435805ms","start":"2026-06-09T17:04:29.884887Z","end":"2026-06-09T17:04:30.098323Z","steps":["trace[599171671] 'process raft request' (duration: 160.406087ms)","trace[599171671] 'compare' (duration: 52.885062ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:04:30.513873Z","caller":"traceutil/trace.go:171","msg":"trace[1368541086] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"626.122663ms","start":"2026-06-09T17:04:29.887721Z","end":"2026-06-09T17:04:30.513843Z","steps":["trace[1368541086] 'process raft request' (duration: 541.972859ms)","trace[1368541086] 'compare' (duration: 83.973386ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:04:30.514044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:29.887695Z","time spent":"626.261169ms","remote":"127.0.0.1:47286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12740,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:04:30.692866Z","caller":"traceutil/trace.go:171","msg":"trace[1671224398] linearizableReadLoop","detail":"{readStateIndex:1042; appliedIndex:1040; }","duration":"588.904864ms","start":"2026-06-09T17:04:30.103937Z","end":"2026-06-09T17:04:30.692842Z","steps":["trace[1671224398] 'read index received' (duration: 325.753845ms)","trace[1671224398] 'applied index is now lower than readState.Index' (duration: 263.149959ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:04:30.693005Z","caller":"traceutil/trace.go:171","msg":"trace[556129867] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"588.548447ms","start":"2026-06-09T17:04:30.104428Z","end":"2026-06-09T17:04:30.692977Z","steps":["trace[556129867] 'process raft request' (duration: 588.340948ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:30.693025Z","caller":"traceutil/trace.go:171","msg":"trace[1638456667] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"589.475759ms","start":"2026-06-09T17:04:30.103523Z","end":"2026-06-09T17:04:30.692999Z","steps":["trace[1638456667] 'process raft request' (duration: 582.082101ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:30.693304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:30.103492Z","time spent":"589.787424ms","remote":"127.0.0.1:47262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":761,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-09T17:04:30.693066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.115653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/local-path-storage/\" range_end:\"/registry/limitranges/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:04:30.693553Z","caller":"traceutil/trace.go:171","msg":"trace[799704858] range","detail":"{range_begin:/registry/limitranges/local-path-storage/; range_end:/registry/limitranges/local-path-storage0; response_count:0; response_revision:1007; }","duration":"589.649327ms","start":"2026-06-09T17:04:30.103889Z","end":"2026-06-09T17:04:30.693539Z","steps":["trace[799704858] 'agreement among raft nodes before linearized reading' (duration: 589.121544ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:30.693591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:30.103873Z","time spent":"589.70845ms","remote":"127.0.0.1:47336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/limitranges/local-path-storage/\" range_end:\"/registry/limitranges/local-path-storage0\" "} {"level":"warn","ts":"2026-06-09T17:04:30.693773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:30.104405Z","time spent":"588.807669ms","remote":"127.0.0.1:47646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3371,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:04:30.742261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.265635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:1 size:12755"} {"level":"info","ts":"2026-06-09T17:04:30.742578Z","caller":"traceutil/trace.go:171","msg":"trace[534172295] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:1008; }","duration":"170.58928ms","start":"2026-06-09T17:04:30.571972Z","end":"2026-06-09T17:04:30.742561Z","steps":["trace[534172295] 'agreement among raft nodes before linearized reading' (duration: 170.14768ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:30.742349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.530314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-09T17:04:30.742865Z","caller":"traceutil/trace.go:171","msg":"trace[1805138734] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1008; }","duration":"433.047748ms","start":"2026-06-09T17:04:30.309786Z","end":"2026-06-09T17:04:30.742833Z","steps":["trace[1805138734] 'agreement among raft nodes before linearized reading' (duration: 432.484672ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:30.742953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:30.309765Z","time spent":"433.153803ms","remote":"127.0.0.1:47578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":31,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"info","ts":"2026-06-09T17:04:30.903736Z","caller":"traceutil/trace.go:171","msg":"trace[1631327991] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"142.444611ms","start":"2026-06-09T17:04:30.761252Z","end":"2026-06-09T17:04:30.903696Z","steps":["trace[1631327991] 'process raft request' (duration: 142.224321ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:30.903946Z","caller":"traceutil/trace.go:171","msg":"trace[570727663] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"141.873986ms","start":"2026-06-09T17:04:30.762062Z","end":"2026-06-09T17:04:30.903936Z","steps":["trace[570727663] 'process raft request' (duration: 141.54264ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:30.904273Z","caller":"traceutil/trace.go:171","msg":"trace[1503601457] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"141.526179ms","start":"2026-06-09T17:04:30.762723Z","end":"2026-06-09T17:04:30.90425Z","steps":["trace[1503601457] 'process raft request' (duration: 140.943152ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:31.152627Z","caller":"traceutil/trace.go:171","msg":"trace[1872368870] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"239.119499ms","start":"2026-06-09T17:04:30.913484Z","end":"2026-06-09T17:04:31.152603Z","steps":["trace[1872368870] 'process raft request' (duration: 238.982853ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:31.152641Z","caller":"traceutil/trace.go:171","msg":"trace[905512811] linearizableReadLoop","detail":"{readStateIndex:1051; appliedIndex:1050; }","duration":"216.791597ms","start":"2026-06-09T17:04:30.935812Z","end":"2026-06-09T17:04:31.152604Z","steps":["trace[905512811] 'read index received' (duration: 216.770086ms)","trace[905512811] 'applied index is now lower than readState.Index' (duration: 20.141µs)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:04:31.152873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.1056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:3696"} {"level":"info","ts":"2026-06-09T17:04:31.152923Z","caller":"traceutil/trace.go:171","msg":"trace[18117372] range","detail":"{range_begin:/registry/deployments/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:1015; }","duration":"241.229246ms","start":"2026-06-09T17:04:30.911681Z","end":"2026-06-09T17:04:31.15291Z","steps":["trace[18117372] 'agreement among raft nodes before linearized reading' (duration: 240.995976ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:31.540011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.146987ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-09T17:04:31.540138Z","caller":"traceutil/trace.go:171","msg":"trace[2057850401] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"598.14544ms","start":"2026-06-09T17:04:30.941979Z","end":"2026-06-09T17:04:31.540124Z","steps":["trace[2057850401] 'process raft request' (duration: 402.825056ms)","trace[2057850401] 'compare' (duration: 194.987119ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:04:31.540172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:30.941958Z","time spent":"598.197973ms","remote":"127.0.0.1:47392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3595,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:04:31.540469Z","caller":"traceutil/trace.go:171","msg":"trace[998766866] linearizableReadLoop","detail":"{readStateIndex:1053; appliedIndex:1051; }","duration":"387.588899ms","start":"2026-06-09T17:04:31.152855Z","end":"2026-06-09T17:04:31.540444Z","steps":["trace[998766866] 'read index received' (duration: 192.139748ms)","trace[998766866] 'applied index is now lower than readState.Index' (duration: 195.448071ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:04:31.540746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.113105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:897"} {"level":"info","ts":"2026-06-09T17:04:31.540792Z","caller":"traceutil/trace.go:171","msg":"trace[1561309173] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:1017; }","duration":"497.22916ms","start":"2026-06-09T17:04:31.043548Z","end":"2026-06-09T17:04:31.540777Z","steps":["trace[1561309173] 'agreement among raft nodes before linearized reading' (duration: 496.982779ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:31.540828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:31.043526Z","time spent":"497.293334ms","remote":"127.0.0.1:47400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":921,"request content":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" "} {"level":"info","ts":"2026-06-09T17:04:31.540894Z","caller":"traceutil/trace.go:171","msg":"trace[860674537] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"553.462316ms","start":"2026-06-09T17:04:30.987402Z","end":"2026-06-09T17:04:31.540864Z","steps":["trace[860674537] 'process raft request' (duration: 552.674369ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:31.541031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:30.987367Z","time spent":"553.590602ms","remote":"127.0.0.1:47460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:04:31.600841Z","caller":"traceutil/trace.go:171","msg":"trace[1608886616] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"442.197204ms","start":"2026-06-09T17:04:31.158611Z","end":"2026-06-09T17:04:31.600809Z","steps":["trace[1608886616] 'process raft request' (duration: 442.004455ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:31.601006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:31.158591Z","time spent":"442.32854ms","remote":"127.0.0.1:47646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3720,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:04:31.811748Z","caller":"traceutil/trace.go:171","msg":"trace[1596198113] linearizableReadLoop","detail":"{readStateIndex:1055; appliedIndex:1054; }","duration":"149.796199ms","start":"2026-06-09T17:04:31.661927Z","end":"2026-06-09T17:04:31.811723Z","steps":["trace[1596198113] 'read index received' (duration: 149.635311ms)","trace[1596198113] 'applied index is now lower than readState.Index' (duration: 159.357µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:04:31.811793Z","caller":"traceutil/trace.go:171","msg":"trace[2114310585] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"206.198858ms","start":"2026-06-09T17:04:31.605569Z","end":"2026-06-09T17:04:31.811767Z","steps":["trace[2114310585] 'process raft request' (duration: 205.978658ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:31.811984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.04156ms","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-06-09T17:04:31.812018Z","caller":"traceutil/trace.go:171","msg":"trace[96604512] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1019; }","duration":"150.154856ms","start":"2026-06-09T17:04:31.661854Z","end":"2026-06-09T17:04:31.812009Z","steps":["trace[96604512] 'agreement among raft nodes before linearized reading' (duration: 150.007029ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:31.812148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.783022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-06-09T17:04:31.812179Z","caller":"traceutil/trace.go:171","msg":"trace[997935716] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1019; }","duration":"148.854105ms","start":"2026-06-09T17:04:31.663317Z","end":"2026-06-09T17:04:31.812171Z","steps":["trace[997935716] 'agreement among raft nodes before linearized reading' (duration: 148.774691ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:31.812277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.474869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-09T17:04:31.812361Z","caller":"traceutil/trace.go:171","msg":"trace[1724228747] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1019; }","duration":"145.593315ms","start":"2026-06-09T17:04:31.666749Z","end":"2026-06-09T17:04:31.812342Z","steps":["trace[1724228747] 'agreement among raft nodes before linearized reading' (duration: 145.329693ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:31.81258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.427238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-09T17:04:31.812661Z","caller":"traceutil/trace.go:171","msg":"trace[606907770] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1019; }","duration":"150.542884ms","start":"2026-06-09T17:04:31.662105Z","end":"2026-06-09T17:04:31.812648Z","steps":["trace[606907770] 'agreement among raft nodes before linearized reading' (duration: 149.81763ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:31.926357Z","caller":"traceutil/trace.go:171","msg":"trace[2102465454] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"109.422572ms","start":"2026-06-09T17:04:31.816895Z","end":"2026-06-09T17:04:31.926317Z","steps":["trace[2102465454] 'process raft request' (duration: 62.49416ms)","trace[2102465454] 'compare' (duration: 46.768424ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:04:31.953094Z","caller":"traceutil/trace.go:171","msg":"trace[276150171] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"135.595542ms","start":"2026-06-09T17:04:31.817471Z","end":"2026-06-09T17:04:31.953067Z","steps":["trace[276150171] 'process raft request' (duration: 135.418534ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:31.953431Z","caller":"traceutil/trace.go:171","msg":"trace[1970059921] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"134.648418ms","start":"2026-06-09T17:04:31.818765Z","end":"2026-06-09T17:04:31.953413Z","steps":["trace[1970059921] 'process raft request' (duration: 134.24899ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:31.953678Z","caller":"traceutil/trace.go:171","msg":"trace[1774791937] transaction","detail":"{read_only:false; response_revision:1023; number_of_response:1; }","duration":"134.733942ms","start":"2026-06-09T17:04:31.818926Z","end":"2026-06-09T17:04:31.95366Z","steps":["trace[1774791937] 'process raft request' (duration: 134.391597ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:31.9538Z","caller":"traceutil/trace.go:171","msg":"trace[1215926545] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"133.260244ms","start":"2026-06-09T17:04:31.820521Z","end":"2026-06-09T17:04:31.953781Z","steps":["trace[1215926545] 'process raft request' (duration: 133.077295ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:32.085092Z","caller":"traceutil/trace.go:171","msg":"trace[856830786] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"121.509339ms","start":"2026-06-09T17:04:31.963553Z","end":"2026-06-09T17:04:32.085062Z","steps":["trace[856830786] 'process raft request' (duration: 121.398964ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:32.085155Z","caller":"traceutil/trace.go:171","msg":"trace[548065708] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"122.485074ms","start":"2026-06-09T17:04:31.962642Z","end":"2026-06-09T17:04:32.085127Z","steps":["trace[548065708] 'process raft request' (duration: 122.158969ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:32.56839Z","caller":"traceutil/trace.go:171","msg":"trace[189714877] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"475.706866ms","start":"2026-06-09T17:04:32.092653Z","end":"2026-06-09T17:04:32.568359Z","steps":["trace[189714877] 'process raft request' (duration: 415.183558ms)","trace[189714877] 'compare' (duration: 60.242065ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:04:32.568542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:32.092642Z","time spent":"475.81895ms","remote":"127.0.0.1:47392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5056,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:04:33.075007Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313096440795898262,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-09T17:04:33.108721Z","caller":"traceutil/trace.go:171","msg":"trace[1014802851] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"947.680788ms","start":"2026-06-09T17:04:32.161014Z","end":"2026-06-09T17:04:33.108694Z","steps":["trace[1014802851] 'process raft request' (duration: 947.618465ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:33.108854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:32.160987Z","time spent":"947.803113ms","remote":"127.0.0.1:58344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1669,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-09T17:04:33.109347Z","caller":"traceutil/trace.go:171","msg":"trace[869112586] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"1.012930246s","start":"2026-06-09T17:04:32.096401Z","end":"2026-06-09T17:04:33.109331Z","steps":["trace[869112586] 'process raft request' (duration: 1.012079556s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:33.110013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:32.096379Z","time spent":"1.01301405s","remote":"127.0.0.1:47646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5626,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:04:33.11785Z","caller":"traceutil/trace.go:171","msg":"trace[35591914] linearizableReadLoop","detail":"{readStateIndex:1068; appliedIndex:1067; }","duration":"543.851183ms","start":"2026-06-09T17:04:32.57397Z","end":"2026-06-09T17:04:33.117821Z","steps":["trace[35591914] 'read index received' (duration: 535.995123ms)","trace[35591914] 'applied index is now lower than readState.Index' (duration: 7.8553ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:04:33.118235Z","caller":"traceutil/trace.go:171","msg":"trace[1720030120] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"544.117006ms","start":"2026-06-09T17:04:32.574108Z","end":"2026-06-09T17:04:33.118225Z","steps":["trace[1720030120] 'process raft request' (duration: 543.562571ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:33.118308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:32.574097Z","time spent":"544.166208ms","remote":"127.0.0.1:47472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1448,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:04:33.118548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.575318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-6hmnc\" ","response":"range_response_count:1 size:5071"} {"level":"info","ts":"2026-06-09T17:04:33.118586Z","caller":"traceutil/trace.go:171","msg":"trace[1327169592] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-6hmnc; range_end:; response_count:1; response_revision:1032; }","duration":"544.643911ms","start":"2026-06-09T17:04:32.573932Z","end":"2026-06-09T17:04:33.118576Z","steps":["trace[1327169592] 'agreement among raft nodes before linearized reading' (duration: 544.532495ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:33.118613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:32.573917Z","time spent":"544.686993ms","remote":"127.0.0.1:47392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":5095,"request content":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-6hmnc\" "} {"level":"warn","ts":"2026-06-09T17:04:33.118752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.900543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:04:33.118781Z","caller":"traceutil/trace.go:171","msg":"trace[1502012407] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1032; }","duration":"379.953236ms","start":"2026-06-09T17:04:32.738821Z","end":"2026-06-09T17:04:33.118774Z","steps":["trace[1502012407] 'agreement among raft nodes before linearized reading' (duration: 379.910714ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:33.118802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:32.738808Z","time spent":"379.988848ms","remote":"127.0.0.1:47186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-09T17:04:33.314972Z","caller":"traceutil/trace.go:171","msg":"trace[1422793926] linearizableReadLoop","detail":"{readStateIndex:1069; appliedIndex:1068; }","duration":"192.456001ms","start":"2026-06-09T17:04:33.122487Z","end":"2026-06-09T17:04:33.314943Z","steps":["trace[1422793926] 'read index received' (duration: 172.358562ms)","trace[1422793926] 'applied index is now lower than readState.Index' (duration: 20.096119ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:04:33.315323Z","caller":"traceutil/trace.go:171","msg":"trace[1085747641] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"196.046879ms","start":"2026-06-09T17:04:33.119224Z","end":"2026-06-09T17:04:33.315271Z","steps":["trace[1085747641] 'process raft request' (duration: 175.73149ms)","trace[1085747641] 'compare' (duration: 19.820915ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:04:33.315443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.86177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:04:33.315576Z","caller":"traceutil/trace.go:171","msg":"trace[1027627509] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1033; }","duration":"193.046047ms","start":"2026-06-09T17:04:33.122469Z","end":"2026-06-09T17:04:33.315515Z","steps":["trace[1027627509] 'agreement among raft nodes before linearized reading' (duration: 192.707132ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:33.31614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.579448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-6hmnc\" ","response":"range_response_count:1 size:5071"} {"level":"info","ts":"2026-06-09T17:04:33.316205Z","caller":"traceutil/trace.go:171","msg":"trace[160783777] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-6hmnc; range_end:; response_count:1; response_revision:1033; }","duration":"191.671632ms","start":"2026-06-09T17:04:33.124519Z","end":"2026-06-09T17:04:33.31619Z","steps":["trace[160783777] 'agreement among raft nodes before linearized reading' (duration: 190.741499ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:33.60873Z","caller":"traceutil/trace.go:171","msg":"trace[1234958625] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"290.090686ms","start":"2026-06-09T17:04:33.318622Z","end":"2026-06-09T17:04:33.608713Z","steps":["trace[1234958625] 'process raft request' (duration: 284.823578ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:34.219559Z","caller":"traceutil/trace.go:171","msg":"trace[1774879901] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"161.081732ms","start":"2026-06-09T17:04:34.058444Z","end":"2026-06-09T17:04:34.219526Z","steps":["trace[1774879901] 'process raft request' (duration: 125.118219ms)","trace[1774879901] 'compare' (duration: 35.795935ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:04:36.451616Z","caller":"traceutil/trace.go:171","msg":"trace[1560951485] transaction","detail":"{read_only:false; response_revision:1046; number_of_response:1; }","duration":"418.797385ms","start":"2026-06-09T17:04:36.032788Z","end":"2026-06-09T17:04:36.451585Z","steps":["trace[1560951485] 'process raft request' (duration: 418.597266ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:36.451804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:36.032755Z","time spent":"418.952392ms","remote":"127.0.0.1:47460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:04:36.584717Z","caller":"traceutil/trace.go:171","msg":"trace[1188278711] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"551.608359ms","start":"2026-06-09T17:04:36.033083Z","end":"2026-06-09T17:04:36.584691Z","steps":["trace[1188278711] 'process raft request' (duration: 551.348276ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:36.584872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:36.03304Z","time spent":"551.750475ms","remote":"127.0.0.1:47460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:04:36.585246Z","caller":"traceutil/trace.go:171","msg":"trace[1542432421] transaction","detail":"{read_only:false; response_revision:1049; number_of_response:1; }","duration":"551.129015ms","start":"2026-06-09T17:04:36.034085Z","end":"2026-06-09T17:04:36.585214Z","steps":["trace[1542432421] 'process raft request' (duration: 550.941026ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:36.585282Z","caller":"traceutil/trace.go:171","msg":"trace[788735044] transaction","detail":"{read_only:false; response_revision:1048; number_of_response:1; }","duration":"552.044549ms","start":"2026-06-09T17:04:36.033185Z","end":"2026-06-09T17:04:36.585229Z","steps":["trace[788735044] 'process raft request' (duration: 551.461301ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:36.585473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:36.03405Z","time spent":"551.276962ms","remote":"127.0.0.1:47460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:04:36.585481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:36.033174Z","time spent":"552.185315ms","remote":"127.0.0.1:47460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:04:37.814743Z","caller":"traceutil/trace.go:171","msg":"trace[1488410611] transaction","detail":"{read_only:false; response_revision:1050; number_of_response:1; }","duration":"113.840191ms","start":"2026-06-09T17:04:37.700871Z","end":"2026-06-09T17:04:37.814711Z","steps":["trace[1488410611] 'process raft request' (duration: 113.667312ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:46.718684Z","caller":"traceutil/trace.go:171","msg":"trace[1058245371] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"108.418643ms","start":"2026-06-09T17:04:46.610233Z","end":"2026-06-09T17:04:46.718652Z","steps":["trace[1058245371] 'process raft request' (duration: 108.207532ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:46.836017Z","caller":"traceutil/trace.go:171","msg":"trace[1156347965] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"113.835088ms","start":"2026-06-09T17:04:46.722133Z","end":"2026-06-09T17:04:46.835968Z","steps":["trace[1156347965] 'process raft request' (duration: 82.896904ms)","trace[1156347965] 'compare' (duration: 30.770806ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:04:46.836232Z","caller":"traceutil/trace.go:171","msg":"trace[1662529612] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"113.742393ms","start":"2026-06-09T17:04:46.722464Z","end":"2026-06-09T17:04:46.836207Z","steps":["trace[1662529612] 'process raft request' (duration: 113.477109ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:46.836307Z","caller":"traceutil/trace.go:171","msg":"trace[2078000602] transaction","detail":"{read_only:false; response_revision:1088; number_of_response:1; }","duration":"113.68384ms","start":"2026-06-09T17:04:46.722608Z","end":"2026-06-09T17:04:46.836292Z","steps":["trace[2078000602] 'process raft request' (duration: 113.455238ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:53.011764Z","caller":"traceutil/trace.go:171","msg":"trace[1748599792] transaction","detail":"{read_only:false; response_revision:1111; number_of_response:1; }","duration":"145.733945ms","start":"2026-06-09T17:04:52.866001Z","end":"2026-06-09T17:04:53.011735Z","steps":["trace[1748599792] 'process raft request' (duration: 144.499167ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:53.011785Z","caller":"traceutil/trace.go:171","msg":"trace[1048751972] transaction","detail":"{read_only:false; response_revision:1112; number_of_response:1; }","duration":"143.022787ms","start":"2026-06-09T17:04:52.868729Z","end":"2026-06-09T17:04:53.011752Z","steps":["trace[1048751972] 'process raft request' (duration: 142.910081ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:54.030826Z","caller":"traceutil/trace.go:171","msg":"trace[1668231132] linearizableReadLoop","detail":"{readStateIndex:1153; appliedIndex:1152; }","duration":"122.726636ms","start":"2026-06-09T17:04:53.908076Z","end":"2026-06-09T17:04:54.030802Z","steps":["trace[1668231132] 'read index received' (duration: 122.531976ms)","trace[1668231132] 'applied index is now lower than readState.Index' (duration: 194.01µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:04:54.030977Z","caller":"traceutil/trace.go:171","msg":"trace[1883742732] transaction","detail":"{read_only:false; response_revision:1113; number_of_response:1; }","duration":"130.704764ms","start":"2026-06-09T17:04:53.900255Z","end":"2026-06-09T17:04:54.03096Z","steps":["trace[1883742732] 'process raft request' (duration: 130.421508ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:54.030997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.890915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.89\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-06-09T17:04:54.031024Z","caller":"traceutil/trace.go:171","msg":"trace[1771466577] range","detail":"{range_begin:/registry/masterleases/199.204.45.89; range_end:; response_count:1; response_revision:1113; }","duration":"122.944298ms","start":"2026-06-09T17:04:53.90807Z","end":"2026-06-09T17:04:54.031015Z","steps":["trace[1771466577] 'agreement among raft nodes before linearized reading' (duration: 122.814711ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:55.31228Z","caller":"traceutil/trace.go:171","msg":"trace[954628239] transaction","detail":"{read_only:false; response_revision:1118; number_of_response:1; }","duration":"263.02787ms","start":"2026-06-09T17:04:55.049216Z","end":"2026-06-09T17:04:55.312244Z","steps":["trace[954628239] 'process raft request' (duration: 262.85564ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:55.453237Z","caller":"traceutil/trace.go:171","msg":"trace[1867922753] linearizableReadLoop","detail":"{readStateIndex:1160; appliedIndex:1158; }","duration":"235.786605ms","start":"2026-06-09T17:04:55.21741Z","end":"2026-06-09T17:04:55.453196Z","steps":["trace[1867922753] 'read index received' (duration: 94.605082ms)","trace[1867922753] 'applied index is now lower than readState.Index' (duration: 141.178163ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:04:55.453357Z","caller":"traceutil/trace.go:171","msg":"trace[1927817298] transaction","detail":"{read_only:false; response_revision:1119; number_of_response:1; }","duration":"404.066684ms","start":"2026-06-09T17:04:55.049261Z","end":"2026-06-09T17:04:55.453328Z","steps":["trace[1927817298] 'process raft request' (duration: 313.37163ms)","trace[1927817298] 'compare' (duration: 90.457781ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:04:55.45344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.000926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-09T17:04:55.453493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:55.049233Z","time spent":"404.195462ms","remote":"127.0.0.1:47460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:04:55.453504Z","caller":"traceutil/trace.go:171","msg":"trace[358149557] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1119; }","duration":"236.161006ms","start":"2026-06-09T17:04:55.217325Z","end":"2026-06-09T17:04:55.453486Z","steps":["trace[358149557] 'agreement among raft nodes before linearized reading' (duration: 235.999207ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:59.085267Z","caller":"traceutil/trace.go:171","msg":"trace[1351185762] transaction","detail":"{read_only:false; response_revision:1129; number_of_response:1; }","duration":"125.101331ms","start":"2026-06-09T17:04:58.960129Z","end":"2026-06-09T17:04:59.085231Z","steps":["trace[1351185762] 'process raft request' (duration: 118.603451ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:05:01.142331Z","caller":"traceutil/trace.go:171","msg":"trace[702520279] transaction","detail":"{read_only:false; response_revision:1136; number_of_response:1; }","duration":"241.099742ms","start":"2026-06-09T17:05:00.901204Z","end":"2026-06-09T17:05:01.142304Z","steps":["trace[702520279] 'process raft request' (duration: 240.54448ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:05:01.551478Z","caller":"traceutil/trace.go:171","msg":"trace[323065303] linearizableReadLoop","detail":"{readStateIndex:1180; appliedIndex:1179; }","duration":"153.692712ms","start":"2026-06-09T17:05:01.397754Z","end":"2026-06-09T17:05:01.551447Z","steps":["trace[323065303] 'read index received' (duration: 130.857219ms)","trace[323065303] 'applied index is now lower than readState.Index' (duration: 22.833872ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:05:01.551501Z","caller":"traceutil/trace.go:171","msg":"trace[1584331397] transaction","detail":"{read_only:false; response_revision:1138; number_of_response:1; }","duration":"304.703279ms","start":"2026-06-09T17:05:01.246762Z","end":"2026-06-09T17:05:01.551465Z","steps":["trace[1584331397] 'process raft request' (duration: 281.921499ms)","trace[1584331397] 'compare' (duration: 22.450921ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:05:01.551666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:05:01.246731Z","time spent":"304.844907ms","remote":"127.0.0.1:47460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:05:01.551766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.987788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-09T17:05:01.551802Z","caller":"traceutil/trace.go:171","msg":"trace[984441789] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1138; }","duration":"154.076333ms","start":"2026-06-09T17:05:01.397715Z","end":"2026-06-09T17:05:01.551791Z","steps":["trace[984441789] 'agreement among raft nodes before linearized reading' (duration: 153.879682ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:05:01.673849Z","caller":"traceutil/trace.go:171","msg":"trace[150996240] transaction","detail":"{read_only:false; response_revision:1140; number_of_response:1; }","duration":"116.596619ms","start":"2026-06-09T17:05:01.557221Z","end":"2026-06-09T17:05:01.673817Z","steps":["trace[150996240] 'process raft request' (duration: 116.540516ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:05:01.674Z","caller":"traceutil/trace.go:171","msg":"trace[809827498] transaction","detail":"{read_only:false; response_revision:1139; number_of_response:1; }","duration":"118.887501ms","start":"2026-06-09T17:05:01.554984Z","end":"2026-06-09T17:05:01.673871Z","steps":["trace[809827498] 'process raft request' (duration: 117.398196ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:05:10.982027Z","caller":"traceutil/trace.go:171","msg":"trace[1355214496] linearizableReadLoop","detail":"{readStateIndex:1212; appliedIndex:1211; }","duration":"243.950624ms","start":"2026-06-09T17:05:10.738053Z","end":"2026-06-09T17:05:10.982004Z","steps":["trace[1355214496] 'read index received' (duration: 243.790825ms)","trace[1355214496] 'applied index is now lower than readState.Index' (duration: 158.789µs)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:05:10.982144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.079473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:05:10.982165Z","caller":"traceutil/trace.go:171","msg":"trace[1026593521] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1168; }","duration":"244.168867ms","start":"2026-06-09T17:05:10.737989Z","end":"2026-06-09T17:05:10.982158Z","steps":["trace[1026593521] 'agreement among raft nodes before linearized reading' (duration: 244.098683ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:05:10.982141Z","caller":"traceutil/trace.go:171","msg":"trace[1827902914] transaction","detail":"{read_only:false; response_revision:1168; number_of_response:1; }","duration":"390.670005ms","start":"2026-06-09T17:05:10.591427Z","end":"2026-06-09T17:05:10.982097Z","steps":["trace[1827902914] 'process raft request' (duration: 390.442961ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:05:10.982345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:05:10.591396Z","time spent":"390.839995ms","remote":"127.0.0.1:47460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:05:14.446575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.059932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-09T17:05:14.446684Z","caller":"traceutil/trace.go:171","msg":"trace[575827013] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1181; }","duration":"111.230143ms","start":"2026-06-09T17:05:14.335432Z","end":"2026-06-09T17:05:14.446662Z","steps":["trace[575827013] 'range keys from in-memory index tree' (duration: 110.854999ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:05:17.481795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.070607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-09T17:05:17.481857Z","caller":"traceutil/trace.go:171","msg":"trace[1552610196] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1189; }","duration":"140.168583ms","start":"2026-06-09T17:05:17.341674Z","end":"2026-06-09T17:05:17.481842Z","steps":["trace[1552610196] 'range keys from in-memory index tree' (duration: 139.920888ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:05:19.473903Z","caller":"traceutil/trace.go:171","msg":"trace[225884011] transaction","detail":"{read_only:false; response_revision:1195; number_of_response:1; }","duration":"188.553068ms","start":"2026-06-09T17:05:19.285316Z","end":"2026-06-09T17:05:19.473869Z","steps":["trace[225884011] 'process raft request' (duration: 188.408199ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:05:22.692603Z","caller":"traceutil/trace.go:171","msg":"trace[916864283] transaction","detail":"{read_only:false; response_revision:1208; number_of_response:1; }","duration":"145.569715ms","start":"2026-06-09T17:05:22.54701Z","end":"2026-06-09T17:05:22.692579Z","steps":["trace[916864283] 'process raft request' (duration: 145.426486ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:05:24.859164Z","caller":"traceutil/trace.go:171","msg":"trace[86370967] linearizableReadLoop","detail":"{readStateIndex:1262; appliedIndex:1261; }","duration":"119.379242ms","start":"2026-06-09T17:05:24.739761Z","end":"2026-06-09T17:05:24.85914Z","steps":["trace[86370967] 'read index received' (duration: 119.205321ms)","trace[86370967] 'applied index is now lower than readState.Index' (duration: 173.161µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:05:24.859231Z","caller":"traceutil/trace.go:171","msg":"trace[1851860186] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"159.001412ms","start":"2026-06-09T17:05:24.700209Z","end":"2026-06-09T17:05:24.859211Z","steps":["trace[1851860186] 'process raft request' (duration: 158.786539ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:05:24.859423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.632807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:05:24.859535Z","caller":"traceutil/trace.go:171","msg":"trace[998716551] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1215; }","duration":"119.789907ms","start":"2026-06-09T17:05:24.739723Z","end":"2026-06-09T17:05:24.859513Z","steps":["trace[998716551] 'agreement among raft nodes before linearized reading' (duration: 119.612006ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:05:29.518467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.552703ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-06-09T17:05:35.137233Z","caller":"traceutil/trace.go:171","msg":"trace[202030103] linearizableReadLoop","detail":"{readStateIndex:1297; appliedIndex:1296; }","duration":"110.148735ms","start":"2026-06-09T17:05:35.027066Z","end":"2026-06-09T17:05:35.137215Z","steps":["trace[202030103] 'read index received' (duration: 109.986344ms)","trace[202030103] 'applied index is now lower than readState.Index' (duration: 161.791µs)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:05:35.137341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.266693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-09T17:05:35.137336Z","caller":"traceutil/trace.go:171","msg":"trace[83900638] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"140.126009ms","start":"2026-06-09T17:05:34.997191Z","end":"2026-06-09T17:05:35.137317Z","steps":["trace[83900638] 'process raft request' (duration: 139.923294ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:05:35.137366Z","caller":"traceutil/trace.go:171","msg":"trace[902522573] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1248; }","duration":"110.348809ms","start":"2026-06-09T17:05:35.02701Z","end":"2026-06-09T17:05:35.137359Z","steps":["trace[902522573] 'agreement among raft nodes before linearized reading' (duration: 110.273623ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:05:43.462372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.1652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-09T17:05:43.462477Z","caller":"traceutil/trace.go:171","msg":"trace[1361264427] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1274; }","duration":"101.323001ms","start":"2026-06-09T17:05:43.361128Z","end":"2026-06-09T17:05:43.462451Z","steps":["trace[1361264427] 'count revisions from in-memory index tree' (duration: 101.048213ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:05:44.411736Z","caller":"traceutil/trace.go:171","msg":"trace[1177452088] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"154.034146ms","start":"2026-06-09T17:05:44.257666Z","end":"2026-06-09T17:05:44.4117Z","steps":["trace[1177452088] 'process raft request' (duration: 118.671491ms)","trace[1177452088] 'compare' (duration: 35.116939ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:05:44.411718Z","caller":"traceutil/trace.go:171","msg":"trace[1707766494] linearizableReadLoop","detail":"{readStateIndex:1329; appliedIndex:1328; }","duration":"115.93481ms","start":"2026-06-09T17:05:44.295732Z","end":"2026-06-09T17:05:44.411666Z","steps":["trace[1707766494] 'read index received' (duration: 80.614148ms)","trace[1707766494] 'applied index is now lower than readState.Index' (duration: 35.318672ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:05:44.412035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.287083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-06-09T17:05:44.412158Z","caller":"traceutil/trace.go:171","msg":"trace[1522056903] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1278; }","duration":"116.472164ms","start":"2026-06-09T17:05:44.295663Z","end":"2026-06-09T17:05:44.412135Z","steps":["trace[1522056903] 'agreement among raft nodes before linearized reading' (duration: 116.184716ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:05:49.669189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.811641ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-06-09T17:05:49.669837Z","caller":"traceutil/trace.go:171","msg":"trace[1272018562] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"317.498055ms","start":"2026-06-09T17:05:49.352318Z","end":"2026-06-09T17:05:49.669816Z","steps":["trace[1272018562] 'process raft request' (duration: 316.998492ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:05:49.670025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:05:49.352289Z","time spent":"317.593762ms","remote":"127.0.0.1:47460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:05:58.50018Z","caller":"traceutil/trace.go:171","msg":"trace[1587837989] linearizableReadLoop","detail":"{readStateIndex:1382; appliedIndex:1381; }","duration":"122.678376ms","start":"2026-06-09T17:05:58.377477Z","end":"2026-06-09T17:05:58.500155Z","steps":["trace[1587837989] 'read index received' (duration: 122.00602ms)","trace[1587837989] 'applied index is now lower than readState.Index' (duration: 671.436µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:05:58.500192Z","caller":"traceutil/trace.go:171","msg":"trace[1064727861] transaction","detail":"{read_only:false; response_revision:1328; number_of_response:1; }","duration":"140.563651ms","start":"2026-06-09T17:05:58.359604Z","end":"2026-06-09T17:05:58.500168Z","steps":["trace[1064727861] 'process raft request' (duration: 139.870783ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:05:58.500549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.894831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/local-path-storage/\" range_end:\"/registry/resourcequotas/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:05:58.500633Z","caller":"traceutil/trace.go:171","msg":"trace[633121308] range","detail":"{range_begin:/registry/resourcequotas/local-path-storage/; range_end:/registry/resourcequotas/local-path-storage0; response_count:0; response_revision:1328; }","duration":"123.148159ms","start":"2026-06-09T17:05:58.377461Z","end":"2026-06-09T17:05:58.500609Z","steps":["trace[633121308] 'agreement among raft nodes before linearized reading' (duration: 122.813186ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:06:03.75399Z","caller":"traceutil/trace.go:171","msg":"trace[1453944055] linearizableReadLoop","detail":"{readStateIndex:1413; appliedIndex:1412; }","duration":"171.56806ms","start":"2026-06-09T17:06:03.582395Z","end":"2026-06-09T17:06:03.753963Z","steps":["trace[1453944055] 'read index received' (duration: 171.280531ms)","trace[1453944055] 'applied index is now lower than readState.Index' (duration: 286.559µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:06:03.754122Z","caller":"traceutil/trace.go:171","msg":"trace[1112948064] transaction","detail":"{read_only:false; response_revision:1358; number_of_response:1; }","duration":"355.626585ms","start":"2026-06-09T17:06:03.398462Z","end":"2026-06-09T17:06:03.754088Z","steps":["trace[1112948064] 'process raft request' (duration: 355.338875ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:06:03.754178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.767374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-63d116de-c73d-4b38-b31e-04d3f6056e47\" ","response":"range_response_count:1 size:3883"} {"level":"info","ts":"2026-06-09T17:06:03.754217Z","caller":"traceutil/trace.go:171","msg":"trace[1680636040] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-63d116de-c73d-4b38-b31e-04d3f6056e47; range_end:; response_count:1; response_revision:1358; }","duration":"171.860792ms","start":"2026-06-09T17:06:03.582344Z","end":"2026-06-09T17:06:03.754204Z","steps":["trace[1680636040] 'agreement among raft nodes before linearized reading' (duration: 171.771465ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:06:03.754309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:06:03.398435Z","time spent":"355.761484ms","remote":"127.0.0.1:47392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3868,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:06:06.949934Z","caller":"traceutil/trace.go:171","msg":"trace[961954702] linearizableReadLoop","detail":"{readStateIndex:1440; appliedIndex:1439; }","duration":"210.192405ms","start":"2026-06-09T17:06:06.739715Z","end":"2026-06-09T17:06:06.949908Z","steps":["trace[961954702] 'read index received' (duration: 181.376897ms)","trace[961954702] 'applied index is now lower than readState.Index' (duration: 28.814598ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:06:06.950051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.309983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:06:06.950041Z","caller":"traceutil/trace.go:171","msg":"trace[1484616874] transaction","detail":"{read_only:false; response_revision:1383; number_of_response:1; }","duration":"211.384996ms","start":"2026-06-09T17:06:06.738631Z","end":"2026-06-09T17:06:06.950016Z","steps":["trace[1484616874] 'process raft request' (duration: 182.515774ms)","trace[1484616874] 'compare' (duration: 28.41008ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:06:06.950087Z","caller":"traceutil/trace.go:171","msg":"trace[31005050] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1383; }","duration":"210.375798ms","start":"2026-06-09T17:06:06.739702Z","end":"2026-06-09T17:06:06.950077Z","steps":["trace[31005050] 'agreement among raft nodes before linearized reading' (duration: 210.299952ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:06:08.762182Z","caller":"traceutil/trace.go:171","msg":"trace[758871579] transaction","detail":"{read_only:false; response_revision:1387; number_of_response:1; }","duration":"151.344255ms","start":"2026-06-09T17:06:08.610708Z","end":"2026-06-09T17:06:08.762052Z","steps":["trace[758871579] 'process raft request' (duration: 151.016783ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:06:13.142396Z","caller":"traceutil/trace.go:171","msg":"trace[1159671695] transaction","detail":"{read_only:false; response_revision:1407; number_of_response:1; }","duration":"111.50101ms","start":"2026-06-09T17:06:13.030861Z","end":"2026-06-09T17:06:13.142362Z","steps":["trace[1159671695] 'process raft request' (duration: 66.872894ms)","trace[1159671695] 'compare' (duration: 44.506787ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:06:15.041751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.015886ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-09T17:06:15.042052Z","caller":"traceutil/trace.go:171","msg":"trace[1472044384] transaction","detail":"{read_only:false; response_revision:1415; number_of_response:1; }","duration":"211.893434ms","start":"2026-06-09T17:06:14.830146Z","end":"2026-06-09T17:06:15.042039Z","steps":["trace[1472044384] 'process raft request' (duration: 44.518772ms)","trace[1472044384] 'compare' (duration: 166.9338ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:06:15.433547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.139259ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-09T17:06:15.433631Z","caller":"traceutil/trace.go:171","msg":"trace[417310125] transaction","detail":"{read_only:false; response_revision:1416; number_of_response:1; }","duration":"387.862737ms","start":"2026-06-09T17:06:15.045754Z","end":"2026-06-09T17:06:15.433617Z","steps":["trace[417310125] 'process raft request' (duration: 119.573117ms)","trace[417310125] 'compare' (duration: 268.027381ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:06:15.433673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:06:15.045729Z","time spent":"387.92544ms","remote":"127.0.0.1:47460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:06:15.456964Z","caller":"traceutil/trace.go:171","msg":"trace[287281853] linearizableReadLoop","detail":"{readStateIndex:1476; appliedIndex:1475; }","duration":"129.01351ms","start":"2026-06-09T17:06:15.327923Z","end":"2026-06-09T17:06:15.456937Z","steps":["trace[287281853] 'read index received' (duration: 128.706968ms)","trace[287281853] 'applied index is now lower than readState.Index' (duration: 305.412µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:06:15.457075Z","caller":"traceutil/trace.go:171","msg":"trace[306503002] transaction","detail":"{read_only:false; response_revision:1417; number_of_response:1; }","duration":"308.59651ms","start":"2026-06-09T17:06:15.148443Z","end":"2026-06-09T17:06:15.45704Z","steps":["trace[306503002] 'process raft request' (duration: 308.294279ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:06:15.457269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:06:15.148416Z","time spent":"308.751091ms","remote":"127.0.0.1:47460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:06:15.457291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.326941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-09T17:06:15.457437Z","caller":"traceutil/trace.go:171","msg":"trace[432888341] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1417; }","duration":"129.539006ms","start":"2026-06-09T17:06:15.327869Z","end":"2026-06-09T17:06:15.457408Z","steps":["trace[432888341] 'agreement among raft nodes before linearized reading' (duration: 129.230415ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:06:16.509989Z","caller":"traceutil/trace.go:171","msg":"trace[838519933] transaction","detail":"{read_only:false; response_revision:1419; number_of_response:1; }","duration":"232.371529ms","start":"2026-06-09T17:06:16.277591Z","end":"2026-06-09T17:06:16.509963Z","steps":["trace[838519933] 'process raft request' (duration: 145.011087ms)","trace[838519933] 'compare' (duration: 87.187299ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:06:17.147925Z","caller":"traceutil/trace.go:171","msg":"trace[1262833598] linearizableReadLoop","detail":"{readStateIndex:1481; appliedIndex:1480; }","duration":"101.892604ms","start":"2026-06-09T17:06:17.046003Z","end":"2026-06-09T17:06:17.147895Z","steps":["trace[1262833598] 'read index received' (duration: 99.794466ms)","trace[1262833598] 'applied index is now lower than readState.Index' (duration: 2.096588ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:06:17.148227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.195165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-09T17:06:17.148286Z","caller":"traceutil/trace.go:171","msg":"trace[1900100513] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1422; }","duration":"102.303752ms","start":"2026-06-09T17:06:17.045969Z","end":"2026-06-09T17:06:17.148272Z","steps":["trace[1900100513] 'agreement among raft nodes before linearized reading' (duration: 102.060125ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:06:17.148427Z","caller":"traceutil/trace.go:171","msg":"trace[1808955065] transaction","detail":"{read_only:false; response_revision:1422; number_of_response:1; }","duration":"128.531949ms","start":"2026-06-09T17:06:17.019864Z","end":"2026-06-09T17:06:17.148396Z","steps":["trace[1808955065] 'process raft request' (duration: 125.951337ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:06:19.005633Z","caller":"traceutil/trace.go:171","msg":"trace[862648515] transaction","detail":"{read_only:false; response_revision:1430; number_of_response:1; }","duration":"157.019068ms","start":"2026-06-09T17:06:18.848585Z","end":"2026-06-09T17:06:19.005604Z","steps":["trace[862648515] 'process raft request' (duration: 156.858887ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:06:19.497563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.104829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" limit:500 ","response":"range_response_count:6 size:2185"} {"level":"info","ts":"2026-06-09T17:06:19.497653Z","caller":"traceutil/trace.go:171","msg":"trace[461832132] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:6; response_revision:1431; }","duration":"141.261889ms","start":"2026-06-09T17:06:19.356366Z","end":"2026-06-09T17:06:19.497628Z","steps":["trace[461832132] 'range keys from in-memory index tree' (duration: 140.853131ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:06:19.560359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.433525ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-06-09T17:06:19.560571Z","caller":"traceutil/trace.go:171","msg":"trace[1922281013] linearizableReadLoop","detail":"{readStateIndex:1491; appliedIndex:1490; }","duration":"102.791275ms","start":"2026-06-09T17:06:19.457757Z","end":"2026-06-09T17:06:19.560548Z","steps":["trace[1922281013] 'read index received' (duration: 42.993µs)","trace[1922281013] 'applied index is now lower than readState.Index' (duration: 102.745672ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:06:19.560826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.056994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-09T17:06:19.560856Z","caller":"traceutil/trace.go:171","msg":"trace[1675104557] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1432; }","duration":"103.14051ms","start":"2026-06-09T17:06:19.457708Z","end":"2026-06-09T17:06:19.560848Z","steps":["trace[1675104557] 'agreement among raft nodes before linearized reading' (duration: 102.962998ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:06:22.491682Z","caller":"traceutil/trace.go:171","msg":"trace[38287260] transaction","detail":"{read_only:false; response_revision:1441; number_of_response:1; }","duration":"232.353567ms","start":"2026-06-09T17:06:22.259311Z","end":"2026-06-09T17:06:22.491664Z","steps":["trace[38287260] 'process raft request' (duration: 232.227598ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:06:34.115392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.589475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-node-lease/\" range_end:\"/registry/resourcequotas/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:06:34.115498Z","caller":"traceutil/trace.go:171","msg":"trace[914190255] range","detail":"{range_begin:/registry/resourcequotas/kube-node-lease/; range_end:/registry/resourcequotas/kube-node-lease0; response_count:0; response_revision:1481; }","duration":"107.769579ms","start":"2026-06-09T17:06:34.007706Z","end":"2026-06-09T17:06:34.115476Z","steps":["trace[914190255] 'range keys from in-memory index tree' (duration: 107.50463ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:06:39.9031Z","caller":"traceutil/trace.go:171","msg":"trace[248659638] linearizableReadLoop","detail":"{readStateIndex:1563; appliedIndex:1562; }","duration":"100.059237ms","start":"2026-06-09T17:06:39.80302Z","end":"2026-06-09T17:06:39.903079Z","steps":["trace[248659638] 'read index received' (duration: 99.875644ms)","trace[248659638] 'applied index is now lower than readState.Index' (duration: 183.033µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:06:39.903162Z","caller":"traceutil/trace.go:171","msg":"trace[295501505] transaction","detail":"{read_only:false; response_revision:1499; number_of_response:1; }","duration":"122.002819ms","start":"2026-06-09T17:06:39.781138Z","end":"2026-06-09T17:06:39.903141Z","steps":["trace[295501505] 'process raft request' (duration: 121.802295ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:06:39.903228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.189967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kube-proxy\" ","response":"range_response_count:1 size:1948"} {"level":"info","ts":"2026-06-09T17:06:39.903252Z","caller":"traceutil/trace.go:171","msg":"trace[1069731838] range","detail":"{range_begin:/registry/configmaps/kube-system/kube-proxy; range_end:; response_count:1; response_revision:1499; }","duration":"100.23086ms","start":"2026-06-09T17:06:39.803014Z","end":"2026-06-09T17:06:39.903245Z","steps":["trace[1069731838] 'agreement among raft nodes before linearized reading' (duration: 100.163315ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:06:40.028802Z","caller":"traceutil/trace.go:171","msg":"trace[185097119] linearizableReadLoop","detail":"{readStateIndex:1564; appliedIndex:1563; }","duration":"120.726488ms","start":"2026-06-09T17:06:39.90806Z","end":"2026-06-09T17:06:40.028786Z","steps":["trace[185097119] 'read index received' (duration: 69.531718ms)","trace[185097119] 'applied index is now lower than readState.Index' (duration: 51.19404ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:06:40.028879Z","caller":"traceutil/trace.go:171","msg":"trace[1777164829] transaction","detail":"{read_only:false; response_revision:1500; number_of_response:1; }","duration":"121.207144ms","start":"2026-06-09T17:06:39.907642Z","end":"2026-06-09T17:06:40.02885Z","steps":["trace[1777164829] 'process raft request' (duration: 69.887495ms)","trace[1777164829] 'compare' (duration: 51.172428ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:06:40.028917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.846847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kube-apiserver-legacy-service-account-token-tracking\" ","response":"range_response_count:1 size:370"} {"level":"info","ts":"2026-06-09T17:06:40.028939Z","caller":"traceutil/trace.go:171","msg":"trace[1166130935] range","detail":"{range_begin:/registry/configmaps/kube-system/kube-apiserver-legacy-service-account-token-tracking; range_end:; response_count:1; response_revision:1500; }","duration":"120.900591ms","start":"2026-06-09T17:06:39.908031Z","end":"2026-06-09T17:06:40.028932Z","steps":["trace[1166130935] 'agreement among raft nodes before linearized reading' (duration: 120.842747ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:06:43.023007Z","caller":"traceutil/trace.go:171","msg":"trace[681360441] transaction","detail":"{read_only:false; response_revision:1508; number_of_response:1; }","duration":"178.926244ms","start":"2026-06-09T17:06:42.844054Z","end":"2026-06-09T17:06:43.02298Z","steps":["trace[681360441] 'process raft request' (duration: 100.778959ms)","trace[681360441] 'compare' (duration: 78.046048ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:06:43.032108Z","caller":"traceutil/trace.go:171","msg":"trace[264593125] linearizableReadLoop","detail":"{readStateIndex:1573; appliedIndex:1571; }","duration":"179.360096ms","start":"2026-06-09T17:06:42.852729Z","end":"2026-06-09T17:06:43.032089Z","steps":["trace[264593125] 'read index received' (duration: 92.08217ms)","trace[264593125] 'applied index is now lower than readState.Index' (duration: 87.276726ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:06:43.032234Z","caller":"traceutil/trace.go:171","msg":"trace[784209640] transaction","detail":"{read_only:false; response_revision:1509; number_of_response:1; }","duration":"184.861333ms","start":"2026-06-09T17:06:42.847327Z","end":"2026-06-09T17:06:43.032188Z","steps":["trace[784209640] 'process raft request' (duration: 184.662309ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:06:43.03235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.608044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-system/cilium-config-agent\" ","response":"range_response_count:1 size:695"} {"level":"info","ts":"2026-06-09T17:06:43.032388Z","caller":"traceutil/trace.go:171","msg":"trace[107112522] range","detail":"{range_begin:/registry/roles/kube-system/cilium-config-agent; range_end:; response_count:1; response_revision:1509; }","duration":"179.68826ms","start":"2026-06-09T17:06:42.852688Z","end":"2026-06-09T17:06:43.032376Z","steps":["trace[107112522] 'agreement among raft nodes before linearized reading' (duration: 179.522458ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:06:43.032425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.424564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-system/extension-apiserver-authentication-reader\" ","response":"range_response_count:1 size:671"} {"level":"info","ts":"2026-06-09T17:06:43.032509Z","caller":"traceutil/trace.go:171","msg":"trace[1298368603] range","detail":"{range_begin:/registry/roles/kube-system/extension-apiserver-authentication-reader; range_end:; response_count:1; response_revision:1509; }","duration":"122.554064ms","start":"2026-06-09T17:06:42.909936Z","end":"2026-06-09T17:06:43.03249Z","steps":["trace[1298368603] 'agreement among raft nodes before linearized reading' (duration: 122.374301ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:06:45.498797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.856854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ephemeral-volume-controller\" ","response":"range_response_count:1 size:220"} {"level":"info","ts":"2026-06-09T17:06:45.498904Z","caller":"traceutil/trace.go:171","msg":"trace[1517737795] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ephemeral-volume-controller; range_end:; response_count:1; response_revision:1518; }","duration":"101.973282ms","start":"2026-06-09T17:06:45.396908Z","end":"2026-06-09T17:06:45.498881Z","steps":["trace[1517737795] 'range keys from in-memory index tree' (duration: 101.770617ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:06:53.30946Z","caller":"traceutil/trace.go:171","msg":"trace[2065656768] transaction","detail":"{read_only:false; response_revision:1541; number_of_response:1; }","duration":"204.081353ms","start":"2026-06-09T17:06:53.105355Z","end":"2026-06-09T17:06:53.309436Z","steps":["trace[2065656768] 'process raft request' (duration: 203.857746ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:06:53.406575Z","caller":"traceutil/trace.go:171","msg":"trace[752438451] linearizableReadLoop","detail":"{readStateIndex:1608; appliedIndex:1606; }","duration":"240.270398ms","start":"2026-06-09T17:06:53.166278Z","end":"2026-06-09T17:06:53.406549Z","steps":["trace[752438451] 'read index received' (duration: 142.978412ms)","trace[752438451] 'applied index is now lower than readState.Index' (duration: 97.290976ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:06:53.406732Z","caller":"traceutil/trace.go:171","msg":"trace[1629012506] transaction","detail":"{read_only:false; response_revision:1542; number_of_response:1; }","duration":"244.97246ms","start":"2026-06-09T17:06:53.161727Z","end":"2026-06-09T17:06:53.4067Z","steps":["trace[1629012506] 'process raft request' (duration: 202.288212ms)","trace[1629012506] 'compare' (duration: 42.235346ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:06:53.406821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.522576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-67659f764b-v5jbq\" ","response":"range_response_count:1 size:4907"} {"level":"info","ts":"2026-06-09T17:06:53.407492Z","caller":"traceutil/trace.go:171","msg":"trace[1460215993] range","detail":"{range_begin:/registry/pods/kube-system/coredns-67659f764b-v5jbq; range_end:; response_count:1; response_revision:1542; }","duration":"241.172504ms","start":"2026-06-09T17:06:53.166252Z","end":"2026-06-09T17:06:53.407424Z","steps":["trace[1460215993] 'agreement among raft nodes before linearized reading' (duration: 240.448161ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:06:53.513613Z","caller":"traceutil/trace.go:171","msg":"trace[1161242678] transaction","detail":"{read_only:false; response_revision:1543; number_of_response:1; }","duration":"101.35173ms","start":"2026-06-09T17:06:53.412228Z","end":"2026-06-09T17:06:53.51358Z","steps":["trace[1161242678] 'process raft request' (duration: 99.264569ms)"],"step_count":1}