{"level":"warn","ts":"2026-07-08T23:27:47.704387Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-08T23:27:47.704507Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.3: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.3:2380","--initial-cluster=instance=https://199.204.45.3:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.3:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.3:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-08T23:27:47.704632Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-08T23:27:47.704644Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.3:2380"]} {"level":"info","ts":"2026-07-08T23:27:47.704705Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-08T23:27:47.70546Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"]} {"level":"info","ts":"2026-07-08T23:27:47.70553Z","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.3:2380"],"listen-peer-urls":["https://199.204.45.3:2380"],"advertise-client-urls":["https://199.204.45.3:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.3:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-08T23:27:47.719159Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"13.430427ms"} {"level":"info","ts":"2026-07-08T23:27:47.733936Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"597401b06ce1cf3d","cluster-id":"dfc54657ad8a2432"} {"level":"info","ts":"2026-07-08T23:27:47.734083Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=()"} {"level":"info","ts":"2026-07-08T23:27:47.734134Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became follower at term 0"} {"level":"info","ts":"2026-07-08T23:27:47.734162Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 597401b06ce1cf3d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-08T23:27:47.734186Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became follower at term 1"} {"level":"info","ts":"2026-07-08T23:27:47.734265Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=(6445778823926632253)"} {"level":"warn","ts":"2026-07-08T23:27:47.740009Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-08T23:27:47.741346Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-08T23:27:47.742331Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-08T23:27:47.744128Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"597401b06ce1cf3d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-08T23:27:47.745225Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"597401b06ce1cf3d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-08T23:27:47.745488Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-08T23:27:47.745561Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-08T23:27:47.745588Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-08T23:27:47.750065Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=(6445778823926632253)"} {"level":"info","ts":"2026-07-08T23:27:47.750315Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"dfc54657ad8a2432","local-member-id":"597401b06ce1cf3d","added-peer-id":"597401b06ce1cf3d","added-peer-peer-urls":["https://199.204.45.3:2380"]} {"level":"info","ts":"2026-07-08T23:27:47.751152Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-08T23:27:47.751372Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"597401b06ce1cf3d","initial-advertise-peer-urls":["https://199.204.45.3:2380"],"listen-peer-urls":["https://199.204.45.3:2380"],"advertise-client-urls":["https://199.204.45.3:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-08T23:27:47.751416Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-08T23:27:47.75188Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.3:2380"} {"level":"info","ts":"2026-07-08T23:27:47.751911Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.3:2380"} {"level":"info","ts":"2026-07-08T23:27:48.634691Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d is starting a new election at term 1"} {"level":"info","ts":"2026-07-08T23:27:48.634795Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became pre-candidate at term 1"} {"level":"info","ts":"2026-07-08T23:27:48.634831Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d received MsgPreVoteResp from 597401b06ce1cf3d at term 1"} {"level":"info","ts":"2026-07-08T23:27:48.634859Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became candidate at term 2"} {"level":"info","ts":"2026-07-08T23:27:48.634872Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d received MsgVoteResp from 597401b06ce1cf3d at term 2"} {"level":"info","ts":"2026-07-08T23:27:48.63489Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became leader at term 2"} {"level":"info","ts":"2026-07-08T23:27:48.634906Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 597401b06ce1cf3d elected leader 597401b06ce1cf3d at term 2"} {"level":"info","ts":"2026-07-08T23:27:48.680896Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T23:27:48.683642Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-08T23:27:48.68366Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"597401b06ce1cf3d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.3:2379]}","request-path":"/0/members/597401b06ce1cf3d/attributes","cluster-id":"dfc54657ad8a2432","publish-timeout":"7s"} {"level":"info","ts":"2026-07-08T23:27:48.683897Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-08T23:27:48.684029Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-08T23:27:48.684143Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-08T23:27:48.687057Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.3:2379"} {"level":"info","ts":"2026-07-08T23:27:48.687058Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"dfc54657ad8a2432","local-member-id":"597401b06ce1cf3d","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T23:27:48.687203Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T23:27:48.687261Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T23:27:48.687536Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-07-08T23:28:12.479117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.235889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.3\" ","response":"range_response_count:1 size:131"} {"level":"info","ts":"2026-07-08T23:28:12.479255Z","caller":"traceutil/trace.go:171","msg":"trace[492232702] range","detail":"{range_begin:/registry/masterleases/199.204.45.3; range_end:; response_count:1; response_revision:388; }","duration":"537.470167ms","start":"2026-07-08T23:28:11.941758Z","end":"2026-07-08T23:28:12.479228Z","steps":["trace[492232702] 'range keys from in-memory index tree' (duration: 537.066021ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:28:12.479322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:28:11.941709Z","time spent":"537.601992ms","remote":"127.0.0.1:53610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":154,"request content":"key:\"/registry/masterleases/199.204.45.3\" "} {"level":"info","ts":"2026-07-08T23:28:13.018569Z","caller":"traceutil/trace.go:171","msg":"trace[1502497421] linearizableReadLoop","detail":"{readStateIndex:400; appliedIndex:399; }","duration":"373.159323ms","start":"2026-07-08T23:28:12.645334Z","end":"2026-07-08T23:28:13.018493Z","steps":["trace[1502497421] 'read index received' (duration: 372.863122ms)","trace[1502497421] 'applied index is now lower than readState.Index' (duration: 294.511µs)"],"step_count":2} {"level":"warn","ts":"2026-07-08T23:28:13.018595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:28:12.481Z","time spent":"537.58313ms","remote":"127.0.0.1:53610","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-08T23:28:13.018823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.394512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-08T23:28:13.018906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.152586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-08T23:28:13.018989Z","caller":"traceutil/trace.go:171","msg":"trace[245417557] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:388; }","duration":"359.26587ms","start":"2026-07-08T23:28:12.659711Z","end":"2026-07-08T23:28:13.018977Z","steps":["trace[245417557] 'agreement among raft nodes before linearized reading' (duration: 359.103324ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:28:13.019042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:28:12.65963Z","time spent":"359.403915ms","remote":"127.0.0.1:53830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-08T23:28:13.019293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.941914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-08T23:28:13.019369Z","caller":"traceutil/trace.go:171","msg":"trace[628491928] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:388; }","duration":"199.073739ms","start":"2026-07-08T23:28:12.820265Z","end":"2026-07-08T23:28:13.019339Z","steps":["trace[628491928] 'agreement among raft nodes before linearized reading' (duration: 198.770688ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:28:13.019671Z","caller":"traceutil/trace.go:171","msg":"trace[2005341704] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:388; }","duration":"373.63637ms","start":"2026-07-08T23:28:12.645315Z","end":"2026-07-08T23:28:13.018951Z","steps":["trace[2005341704] 'agreement among raft nodes before linearized reading' (duration: 373.323679ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:28:13.019738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:28:12.645234Z","time spent":"374.495481ms","remote":"127.0.0.1:53568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-08T23:28:14.343829Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.322623763s","expected-duration":"1s"} {"level":"info","ts":"2026-07-08T23:28:14.3443Z","caller":"traceutil/trace.go:171","msg":"trace[881705925] linearizableReadLoop","detail":"{readStateIndex:401; appliedIndex:400; }","duration":"1.323163122s","start":"2026-07-08T23:28:13.021103Z","end":"2026-07-08T23:28:14.344266Z","steps":["trace[881705925] 'read index received' (duration: 60.682µs)","trace[881705925] 'applied index is now lower than readState.Index' (duration: 1.32310111s)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:28:14.344402Z","caller":"traceutil/trace.go:171","msg":"trace[1483500123] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"1.323245076s","start":"2026-07-08T23:28:13.02108Z","end":"2026-07-08T23:28:14.344325Z","steps":["trace[1483500123] 'process raft request' (duration: 1.323003987s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:28:14.344529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.323400021s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T23:28:14.344579Z","caller":"traceutil/trace.go:171","msg":"trace[1779062411] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:389; }","duration":"1.323505834s","start":"2026-07-08T23:28:13.02106Z","end":"2026-07-08T23:28:14.344566Z","steps":["trace[1779062411] 'agreement among raft nodes before linearized reading' (duration: 1.32338582s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:28:14.344622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:28:13.021042Z","time spent":"1.323570897s","remote":"127.0.0.1:53558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-08T23:28:14.345261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:28:13.021064Z","time spent":"1.323475483s","remote":"127.0.0.1:53610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":116,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T23:28:14.726331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.650373ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T23:28:14.726695Z","caller":"traceutil/trace.go:171","msg":"trace[1215129274] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"1.70150008s","start":"2026-07-08T23:28:13.025133Z","end":"2026-07-08T23:28:14.726633Z","steps":["trace[1215129274] 'process raft request' (duration: 1.458169062s)","trace[1215129274] 'compare' (duration: 242.204336ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:28:14.726738Z","caller":"traceutil/trace.go:171","msg":"trace[1189352415] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"1.701455049s","start":"2026-07-08T23:28:13.025236Z","end":"2026-07-08T23:28:14.726691Z","steps":["trace[1189352415] 'process raft request' (duration: 1.701251511s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:28:14.726912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:28:13.025113Z","time spent":"1.701677826s","remote":"127.0.0.1:53830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T23:28:14.726988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:28:13.025216Z","time spent":"1.701625334s","remote":"127.0.0.1:53830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T23:28:14.727264Z","caller":"traceutil/trace.go:171","msg":"trace[1144815017] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"1.124979257s","start":"2026-07-08T23:28:13.602247Z","end":"2026-07-08T23:28:14.727226Z","steps":["trace[1144815017] 'process raft request' (duration: 1.124345434s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:28:14.727334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.199448863s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-08T23:28:14.727364Z","caller":"traceutil/trace.go:171","msg":"trace[1443699499] linearizableReadLoop","detail":"{readStateIndex:404; appliedIndex:401; }","duration":"382.925354ms","start":"2026-07-08T23:28:14.344414Z","end":"2026-07-08T23:28:14.72734Z","steps":["trace[1443699499] 'read index received' (duration: 138.87883ms)","trace[1443699499] 'applied index is now lower than readState.Index' (duration: 243.3619ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:28:14.727396Z","caller":"traceutil/trace.go:171","msg":"trace[1103512099] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:392; }","duration":"1.199557986s","start":"2026-07-08T23:28:13.527821Z","end":"2026-07-08T23:28:14.727379Z","steps":["trace[1103512099] 'agreement among raft nodes before linearized reading' (duration: 1.199429772s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:28:14.727475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:28:13.527794Z","time spent":"1.19966837s","remote":"127.0.0.1:53830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-08T23:28:14.727469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:28:13.602219Z","time spent":"1.125133142s","remote":"127.0.0.1:53830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T23:28:14.727469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.458305417s","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-07-08T23:28:14.727547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.541542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:420"} {"level":"info","ts":"2026-07-08T23:28:14.727561Z","caller":"traceutil/trace.go:171","msg":"trace[427772675] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:392; }","duration":"1.458440812s","start":"2026-07-08T23:28:13.269099Z","end":"2026-07-08T23:28:14.72754Z","steps":["trace[427772675] 'agreement among raft nodes before linearized reading' (duration: 1.458289427s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:28:14.727619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.942142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-08T23:28:14.727624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:28:13.269078Z","time spent":"1.458536466s","remote":"127.0.0.1:53830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-08T23:28:14.727645Z","caller":"traceutil/trace.go:171","msg":"trace[4441975] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:392; }","duration":"375.980543ms","start":"2026-07-08T23:28:14.351658Z","end":"2026-07-08T23:28:14.727638Z","steps":["trace[4441975] 'agreement among raft nodes before linearized reading' (duration: 375.943411ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:28:14.727686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:28:14.351651Z","time spent":"376.027004ms","remote":"127.0.0.1:53568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-08T23:28:14.727582Z","caller":"traceutil/trace.go:171","msg":"trace[2055720155] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:392; }","duration":"379.638905ms","start":"2026-07-08T23:28:14.347932Z","end":"2026-07-08T23:28:14.727571Z","steps":["trace[2055720155] 'agreement among raft nodes before linearized reading' (duration: 379.557502ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:28:14.727838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:28:14.347903Z","time spent":"379.917575ms","remote":"127.0.0.1:53734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":443,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-07-08T23:28:23.131951Z","caller":"traceutil/trace.go:171","msg":"trace[771174797] transaction","detail":"{read_only:false; response_revision:441; number_of_response:1; }","duration":"312.068203ms","start":"2026-07-08T23:28:22.819841Z","end":"2026-07-08T23:28:23.131909Z","steps":["trace[771174797] 'process raft request' (duration: 311.912368ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:28:23.132121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:28:22.819834Z","time spent":"312.199967ms","remote":"127.0.0.1:53830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T23:28:23.132843Z","caller":"traceutil/trace.go:171","msg":"trace[1673443756] linearizableReadLoop","detail":"{readStateIndex:457; appliedIndex:455; }","duration":"253.57891ms","start":"2026-07-08T23:28:22.879235Z","end":"2026-07-08T23:28:23.132814Z","steps":["trace[1673443756] 'read index received' (duration: 252.63486ms)","trace[1673443756] 'applied index is now lower than readState.Index' (duration: 942.35µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:28:23.132977Z","caller":"traceutil/trace.go:171","msg":"trace[384068660] transaction","detail":"{read_only:false; response_revision:442; number_of_response:1; }","duration":"310.859363ms","start":"2026-07-08T23:28:22.822088Z","end":"2026-07-08T23:28:23.132947Z","steps":["trace[384068660] 'process raft request' (duration: 310.559633ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:28:23.133067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.775417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T23:28:23.133115Z","caller":"traceutil/trace.go:171","msg":"trace[169526973] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:442; }","duration":"253.888801ms","start":"2026-07-08T23:28:22.879216Z","end":"2026-07-08T23:28:23.133105Z","steps":["trace[169526973] 'agreement among raft nodes before linearized reading' (duration: 253.748636ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:28:23.133117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:28:22.822055Z","time spent":"310.988947ms","remote":"127.0.0.1:53830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T23:28:32.571926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.086074279s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T23:28:32.572096Z","caller":"traceutil/trace.go:171","msg":"trace[2116144649] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:492; }","duration":"1.086264335s","start":"2026-07-08T23:28:31.48581Z","end":"2026-07-08T23:28:32.572074Z","steps":["trace[2116144649] 'range keys from in-memory index tree' (duration: 1.086054179s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:28:32.571972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.275842419s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T23:28:32.572211Z","caller":"traceutil/trace.go:171","msg":"trace[7794476] linearizableReadLoop","detail":"{readStateIndex:509; appliedIndex:508; }","duration":"1.315869121s","start":"2026-07-08T23:28:31.256317Z","end":"2026-07-08T23:28:32.572186Z","steps":["trace[7794476] 'read index received' (duration: 39.656791ms)","trace[7794476] 'applied index is now lower than readState.Index' (duration: 1.27621131s)"],"step_count":2} {"level":"warn","ts":"2026-07-08T23:28:32.572275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.315956494s","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-07-08T23:28:32.572288Z","caller":"traceutil/trace.go:171","msg":"trace[228415460] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:493; }","duration":"1.316015806s","start":"2026-07-08T23:28:31.256267Z","end":"2026-07-08T23:28:32.572283Z","steps":["trace[228415460] 'agreement among raft nodes before linearized reading' (duration: 1.315979815s)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:28:32.572272Z","caller":"traceutil/trace.go:171","msg":"trace[845417009] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"1.341407248s","start":"2026-07-08T23:28:31.230844Z","end":"2026-07-08T23:28:32.572251Z","steps":["trace[845417009] 'process raft request' (duration: 65.209248ms)","trace[845417009] 'compare' (duration: 1.275007214s)"],"step_count":2} {"level":"warn","ts":"2026-07-08T23:28:32.572309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:28:31.256243Z","time spent":"1.316060857s","remote":"127.0.0.1:53830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-08T23:28:32.572424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:28:31.230831Z","time spent":"1.341484541s","remote":"127.0.0.1:53830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T23:28:32.590346Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.206511999s","expected-duration":"1s"} {"level":"info","ts":"2026-07-08T23:28:32.590913Z","caller":"traceutil/trace.go:171","msg":"trace[1966112273] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"1.2071717s","start":"2026-07-08T23:28:31.383715Z","end":"2026-07-08T23:28:32.590887Z","steps":["trace[1966112273] 'process raft request' (duration: 1.206993484s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:28:32.591089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:28:31.383686Z","time spent":"1.207317044s","remote":"127.0.0.1:53618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-08T23:28:32.914556Z","caller":"traceutil/trace.go:171","msg":"trace[1815521051] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"1.525722529s","start":"2026-07-08T23:28:31.388745Z","end":"2026-07-08T23:28:32.914467Z","steps":["trace[1815521051] 'process raft request' (duration: 1.525458622s)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:28:32.914665Z","caller":"traceutil/trace.go:171","msg":"trace[70501641] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"1.525656618s","start":"2026-07-08T23:28:31.388929Z","end":"2026-07-08T23:28:32.914585Z","steps":["trace[70501641] 'process raft request' (duration: 1.525411651s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:28:32.914888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:28:31.388923Z","time spent":"1.525858753s","remote":"127.0.0.1:53618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-08T23:28:32.914969Z","caller":"traceutil/trace.go:171","msg":"trace[2064270208] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"1.515879173s","start":"2026-07-08T23:28:31.399072Z","end":"2026-07-08T23:28:32.914951Z","steps":["trace[2064270208] 'process raft request' (duration: 1.515443921s)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:28:32.914966Z","caller":"traceutil/trace.go:171","msg":"trace[68064863] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"1.516018738s","start":"2026-07-08T23:28:31.398916Z","end":"2026-07-08T23:28:32.914934Z","steps":["trace[68064863] 'process raft request' (duration: 1.515472792s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:28:32.91506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:28:31.399066Z","time spent":"1.515938776s","remote":"127.0.0.1:53618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-08T23:28:32.915126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:28:31.398907Z","time spent":"1.516134731s","remote":"127.0.0.1:53618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-08T23:28:32.915258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:28:31.388715Z","time spent":"1.525951416s","remote":"127.0.0.1:53618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-08T23:28:32.91546Z","caller":"traceutil/trace.go:171","msg":"trace[1897744023] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"1.515957716s","start":"2026-07-08T23:28:31.399488Z","end":"2026-07-08T23:28:32.915446Z","steps":["trace[1897744023] 'process raft request' (duration: 1.515164473s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:28:32.915532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:28:31.399448Z","time spent":"1.516052299s","remote":"127.0.0.1:53618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-08T23:28:32.915795Z","caller":"traceutil/trace.go:171","msg":"trace[513266121] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"1.516173262s","start":"2026-07-08T23:28:31.399608Z","end":"2026-07-08T23:28:32.915781Z","steps":["trace[513266121] 'process raft request' (duration: 1.516016307s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:28:32.915862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:28:31.399602Z","time spent":"1.516228904s","remote":"127.0.0.1:53618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-08T23:28:32.915916Z","caller":"traceutil/trace.go:171","msg":"trace[272482007] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"1.514648466s","start":"2026-07-08T23:28:31.401243Z","end":"2026-07-08T23:28:32.915891Z","steps":["trace[272482007] 'process raft request' (duration: 1.5144422s)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:28:32.916043Z","caller":"traceutil/trace.go:171","msg":"trace[479307046] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"1.495328586s","start":"2026-07-08T23:28:31.420702Z","end":"2026-07-08T23:28:32.91603Z","steps":["trace[479307046] 'process raft request' (duration: 1.495113249s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:28:32.916059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:28:31.401235Z","time spent":"1.514754369s","remote":"127.0.0.1:53618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-08T23:28:32.916111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:28:31.420687Z","time spent":"1.495399289s","remote":"127.0.0.1:53618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-08T23:28:32.916547Z","caller":"traceutil/trace.go:171","msg":"trace[1556259006] linearizableReadLoop","detail":"{readStateIndex:521; appliedIndex:509; }","duration":"344.300387ms","start":"2026-07-08T23:28:32.572227Z","end":"2026-07-08T23:28:32.916528Z","steps":["trace[1556259006] 'read index received' (duration: 18.469829ms)","trace[1556259006] 'applied index is now lower than readState.Index' (duration: 325.829588ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:28:32.916626Z","caller":"traceutil/trace.go:171","msg":"trace[474292757] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"1.483912223s","start":"2026-07-08T23:28:31.432704Z","end":"2026-07-08T23:28:32.916617Z","steps":["trace[474292757] 'process raft request' (duration: 1.483501941s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:28:32.916683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:28:31.432666Z","time spent":"1.483985255s","remote":"127.0.0.1:53618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-08T23:28:32.916835Z","caller":"traceutil/trace.go:171","msg":"trace[1728684875] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"1.454939373s","start":"2026-07-08T23:28:31.461885Z","end":"2026-07-08T23:28:32.916825Z","steps":["trace[1728684875] 'process raft request' (duration: 1.454385187s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:28:32.916887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:28:31.461858Z","time spent":"1.455007556s","remote":"127.0.0.1:53618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-08T23:28:32.917005Z","caller":"traceutil/trace.go:171","msg":"trace[2085513981] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"1.342481984s","start":"2026-07-08T23:28:31.574514Z","end":"2026-07-08T23:28:32.916996Z","steps":["trace[2085513981] 'process raft request' (duration: 1.341896117s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:28:32.917055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:28:31.574474Z","time spent":"1.342559687s","remote":"127.0.0.1:53618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-08T23:28:32.917262Z","caller":"traceutil/trace.go:171","msg":"trace[1283989218] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"339.755171ms","start":"2026-07-08T23:28:32.577497Z","end":"2026-07-08T23:28:32.917252Z","steps":["trace[1283989218] 'process raft request' (duration: 338.973068ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:28:32.917331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:28:32.577473Z","time spent":"339.820392ms","remote":"127.0.0.1:53830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T23:28:32.917471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"974.084659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.3\" ","response":"range_response_count:1 size:131"} {"level":"info","ts":"2026-07-08T23:28:32.917513Z","caller":"traceutil/trace.go:171","msg":"trace[1952048745] range","detail":"{range_begin:/registry/masterleases/199.204.45.3; range_end:; response_count:1; response_revision:506; }","duration":"974.173722ms","start":"2026-07-08T23:28:31.943326Z","end":"2026-07-08T23:28:32.917499Z","steps":["trace[1952048745] 'agreement among raft nodes before linearized reading' (duration: 974.10772ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:28:32.917542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:28:31.943299Z","time spent":"974.235513ms","remote":"127.0.0.1:53610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":154,"request content":"key:\"/registry/masterleases/199.204.45.3\" "} {"level":"warn","ts":"2026-07-08T23:28:33.417098Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933267054090263147,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-08T23:28:33.918307Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933267054090263147,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-08T23:28:34.418774Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933267054090263147,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-08T23:28:34.521824Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.606786597s","expected-duration":"1s"} {"level":"info","ts":"2026-07-08T23:28:34.567115Z","caller":"traceutil/trace.go:171","msg":"trace[308481566] transaction","detail":"{read_only:false; number_of_response:1; response_revision:508; }","duration":"1.969750631s","start":"2026-07-08T23:28:32.597312Z","end":"2026-07-08T23:28:34.567062Z","steps":["trace[308481566] 'process raft request' (duration: 1.969708869s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:28:34.567294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:28:32.597292Z","time spent":"1.969913605s","remote":"127.0.0.1:53618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":6937,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T23:28:34.567351Z","caller":"traceutil/trace.go:171","msg":"trace[759694489] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"1.972274046s","start":"2026-07-08T23:28:32.595032Z","end":"2026-07-08T23:28:34.567306Z","steps":["trace[759694489] 'process raft request' (duration: 1.927030268s)","trace[759694489] 'compare' (duration: 44.672491ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:28:34.567496Z","caller":"traceutil/trace.go:171","msg":"trace[1408720511] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"1.970191253s","start":"2026-07-08T23:28:32.597268Z","end":"2026-07-08T23:28:34.567459Z","steps":["trace[1408720511] 'process raft request' (duration: 1.969643637s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:28:34.567524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:28:32.595003Z","time spent":"1.972439369s","remote":"127.0.0.1:54136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-08T23:28:34.567694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:28:32.597254Z","time spent":"1.970319406s","remote":"127.0.0.1:53618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6888,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T23:28:34.579775Z","caller":"traceutil/trace.go:171","msg":"trace[809847471] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"1.643818512s","start":"2026-07-08T23:28:32.935939Z","end":"2026-07-08T23:28:34.579757Z","steps":["trace[809847471] 'process raft request' (duration: 1.64374629s)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:28:34.579801Z","caller":"traceutil/trace.go:171","msg":"trace[876960311] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"1.650373737s","start":"2026-07-08T23:28:32.929362Z","end":"2026-07-08T23:28:34.579736Z","steps":["trace[876960311] 'process raft request' (duration: 1.650217653s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:28:34.58005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:28:32.935933Z","time spent":"1.643965846s","remote":"127.0.0.1:54136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-08T23:28:34.580153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:28:32.929354Z","time spent":"1.650714879s","remote":"127.0.0.1:54136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-08T23:28:34.580758Z","caller":"traceutil/trace.go:171","msg":"trace[2075137240] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"1.652553692s","start":"2026-07-08T23:28:32.928169Z","end":"2026-07-08T23:28:34.580722Z","steps":["trace[2075137240] 'process raft request' (duration: 1.651390237s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:28:34.58087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:28:32.928161Z","time spent":"1.652668586s","remote":"127.0.0.1:54136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-08T23:28:34.580239Z","caller":"traceutil/trace.go:171","msg":"trace[939873000] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"1.644569004s","start":"2026-07-08T23:28:32.935658Z","end":"2026-07-08T23:28:34.580227Z","steps":["trace[939873000] 'process raft request' (duration: 1.643943785s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:28:34.581101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:28:32.935649Z","time spent":"1.645377928s","remote":"127.0.0.1:53618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T23:28:34.58132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:28:32.922971Z","time spent":"1.658346764s","remote":"127.0.0.1:53610","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-08T23:28:34.581784Z","caller":"traceutil/trace.go:171","msg":"trace[624435761] linearizableReadLoop","detail":"{readStateIndex:535; appliedIndex:522; }","duration":"1.665170836s","start":"2026-07-08T23:28:32.916587Z","end":"2026-07-08T23:28:34.581758Z","steps":["trace[624435761] 'read index received' (duration: 1.605573331s)","trace[624435761] 'applied index is now lower than readState.Index' (duration: 59.596635ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:28:34.581903Z","caller":"traceutil/trace.go:171","msg":"trace[931152054] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"1.613050517s","start":"2026-07-08T23:28:32.968842Z","end":"2026-07-08T23:28:34.581892Z","steps":["trace[931152054] 'process raft request' (duration: 1.612875822s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:28:34.581983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:28:32.968789Z","time spent":"1.61315543s","remote":"127.0.0.1:53618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-08T23:28:34.582078Z","caller":"traceutil/trace.go:171","msg":"trace[214118970] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"1.6427898s","start":"2026-07-08T23:28:32.939279Z","end":"2026-07-08T23:28:34.582069Z","steps":["trace[214118970] 'process raft request' (duration: 1.640444092s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:28:34.582159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:28:32.939272Z","time spent":"1.642844252s","remote":"127.0.0.1:53618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T23:28:34.582259Z","caller":"traceutil/trace.go:171","msg":"trace[1954046179] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"1.640827872s","start":"2026-07-08T23:28:32.94142Z","end":"2026-07-08T23:28:34.582248Z","steps":["trace[1954046179] 'process raft request' (duration: 1.640108591s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:28:34.582312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:28:32.94141Z","time spent":"1.640876924s","remote":"127.0.0.1:53618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T23:28:34.582324Z","caller":"traceutil/trace.go:171","msg":"trace[1175445972] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"1.637870504s","start":"2026-07-08T23:28:32.944445Z","end":"2026-07-08T23:28:34.582316Z","steps":["trace[1175445972] 'process raft request' (duration: 1.637203144s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:28:34.582399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:28:32.944438Z","time spent":"1.637936325s","remote":"127.0.0.1:53618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T23:28:34.582522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.941831891s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T23:28:34.582555Z","caller":"traceutil/trace.go:171","msg":"trace[1328955625] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:517; }","duration":"1.941908593s","start":"2026-07-08T23:28:32.640638Z","end":"2026-07-08T23:28:34.582547Z","steps":["trace[1328955625] 'agreement among raft nodes before linearized reading' (duration: 1.941846451s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:28:34.582578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:28:32.64062Z","time spent":"1.941953215s","remote":"127.0.0.1:53568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-08T23:28:34.582656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.66902816s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-08T23:28:34.582695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.678509722s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-08T23:28:34.58272Z","caller":"traceutil/trace.go:171","msg":"trace[1926284582] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:517; }","duration":"1.678585814s","start":"2026-07-08T23:28:32.904127Z","end":"2026-07-08T23:28:34.582713Z","steps":["trace[1926284582] 'agreement among raft nodes before linearized reading' (duration: 1.678538223s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:28:34.582737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:28:32.904097Z","time spent":"1.678635796s","remote":"127.0.0.1:53830","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-07-08T23:28:34.582697Z","caller":"traceutil/trace.go:171","msg":"trace[2135844866] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:517; }","duration":"1.669320338s","start":"2026-07-08T23:28:32.913367Z","end":"2026-07-08T23:28:34.582687Z","steps":["trace[2135844866] 'agreement among raft nodes before linearized reading' (duration: 1.668990019s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:28:34.58286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:28:32.913343Z","time spent":"1.669509685s","remote":"127.0.0.1:53830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-08T23:28:34.584461Z","caller":"traceutil/trace.go:171","msg":"trace[1536767112] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"1.657284041s","start":"2026-07-08T23:28:32.927124Z","end":"2026-07-08T23:28:34.584408Z","steps":["trace[1536767112] 'process raft request' (duration: 1.652359997s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:28:34.584666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:28:32.927111Z","time spent":"1.657459247s","remote":"127.0.0.1:54136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-08T23:28:34.749047Z","caller":"traceutil/trace.go:171","msg":"trace[396053577] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"113.762904ms","start":"2026-07-08T23:28:34.63526Z","end":"2026-07-08T23:28:34.749023Z","steps":["trace[396053577] 'process raft request' (duration: 113.680812ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:28:34.749089Z","caller":"traceutil/trace.go:171","msg":"trace[816444516] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"114.837985ms","start":"2026-07-08T23:28:34.634185Z","end":"2026-07-08T23:28:34.749023Z","steps":["trace[816444516] 'process raft request' (duration: 75.203111ms)","trace[816444516] 'compare' (duration: 39.374847ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:28:34.749223Z","caller":"traceutil/trace.go:171","msg":"trace[1893725034] linearizableReadLoop","detail":"{readStateIndex:550; appliedIndex:547; }","duration":"110.329634ms","start":"2026-07-08T23:28:34.63887Z","end":"2026-07-08T23:28:34.749199Z","steps":["trace[1893725034] 'read index received' (duration: 70.565146ms)","trace[1893725034] 'applied index is now lower than readState.Index' (duration: 39.763598ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T23:28:34.749365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.154951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1alpha2.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:1013"} {"level":"info","ts":"2026-07-08T23:28:34.749397Z","caller":"traceutil/trace.go:171","msg":"trace[55123499] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1alpha2.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:528; }","duration":"119.207343ms","start":"2026-07-08T23:28:34.63018Z","end":"2026-07-08T23:28:34.749388Z","steps":["trace[55123499] 'agreement among raft nodes before linearized reading' (duration: 119.10849ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:28:34.74961Z","caller":"traceutil/trace.go:171","msg":"trace[720856068] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"112.703203ms","start":"2026-07-08T23:28:34.636858Z","end":"2026-07-08T23:28:34.749561Z","steps":["trace[720856068] 'process raft request' (duration: 112.127006ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:28:34.749925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.538084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:131"} {"level":"info","ts":"2026-07-08T23:28:34.74996Z","caller":"traceutil/trace.go:171","msg":"trace[1899082607] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:530; }","duration":"117.589486ms","start":"2026-07-08T23:28:34.632363Z","end":"2026-07-08T23:28:34.749952Z","steps":["trace[1899082607] 'agreement among raft nodes before linearized reading' (duration: 117.447462ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:28:35.260477Z","caller":"traceutil/trace.go:171","msg":"trace[111203766] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"144.868187ms","start":"2026-07-08T23:28:35.11558Z","end":"2026-07-08T23:28:35.260448Z","steps":["trace[111203766] 'process raft request' (duration: 102.078263ms)","trace[111203766] 'compare' (duration: 41.992121ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:28:35.260516Z","caller":"traceutil/trace.go:171","msg":"trace[499377592] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"143.290752ms","start":"2026-07-08T23:28:35.117199Z","end":"2026-07-08T23:28:35.26049Z","steps":["trace[499377592] 'process raft request' (duration: 143.21447ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:28:40.242665Z","caller":"traceutil/trace.go:171","msg":"trace[48148941] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"285.744674ms","start":"2026-07-08T23:28:39.956882Z","end":"2026-07-08T23:28:40.242626Z","steps":["trace[48148941] 'process raft request' (duration: 285.092706ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:28:41.107022Z","caller":"traceutil/trace.go:171","msg":"trace[101554335] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"376.624836ms","start":"2026-07-08T23:28:40.730383Z","end":"2026-07-08T23:28:41.107007Z","steps":["trace[101554335] 'process raft request' (duration: 375.025803ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:28:41.107156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:28:40.730351Z","time spent":"376.732799ms","remote":"127.0.0.1:53654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036109,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T23:28:41.106971Z","caller":"traceutil/trace.go:171","msg":"trace[572307110] linearizableReadLoop","detail":"{readStateIndex:617; appliedIndex:616; }","duration":"138.05152ms","start":"2026-07-08T23:28:40.968896Z","end":"2026-07-08T23:28:41.106947Z","steps":["trace[572307110] 'read index received' (duration: 136.270182ms)","trace[572307110] 'applied index is now lower than readState.Index' (duration: 1.780408ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T23:28:41.107462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.543034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-08T23:28:41.107651Z","caller":"traceutil/trace.go:171","msg":"trace[1033261632] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:594; }","duration":"138.78195ms","start":"2026-07-08T23:28:40.968846Z","end":"2026-07-08T23:28:41.107627Z","steps":["trace[1033261632] 'agreement among raft nodes before linearized reading' (duration: 138.361678ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:28:42.068575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.908172ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T23:28:42.068709Z","caller":"traceutil/trace.go:171","msg":"trace[1315679809] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"901.251561ms","start":"2026-07-08T23:28:41.167409Z","end":"2026-07-08T23:28:42.06866Z","steps":["trace[1315679809] 'process raft request' (duration: 896.280917ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:28:42.069168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:28:41.167388Z","time spent":"901.725684ms","remote":"127.0.0.1:53762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":818,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-08T23:28:42.069062Z","caller":"traceutil/trace.go:171","msg":"trace[406421393] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:595; }","duration":"583.406715ms","start":"2026-07-08T23:28:41.48563Z","end":"2026-07-08T23:28:42.069037Z","steps":["trace[406421393] 'range keys from in-memory index tree' (duration: 582.885132ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:28:42.089681Z","caller":"traceutil/trace.go:171","msg":"trace[623885558] linearizableReadLoop","detail":"{readStateIndex:620; appliedIndex:618; }","duration":"493.217849ms","start":"2026-07-08T23:28:41.596441Z","end":"2026-07-08T23:28:42.089659Z","steps":["trace[623885558] 'read index received' (duration: 467.258711ms)","trace[623885558] 'applied index is now lower than readState.Index' (duration: 25.958248ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:28:42.089778Z","caller":"traceutil/trace.go:171","msg":"trace[191397236] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"767.333127ms","start":"2026-07-08T23:28:41.322417Z","end":"2026-07-08T23:28:42.08975Z","steps":["trace[191397236] 'process raft request' (duration: 767.119642ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:28:42.089937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:28:41.322389Z","time spent":"767.451581ms","remote":"127.0.0.1:53830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T23:28:42.090103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.661931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-r64jm\" ","response":"range_response_count:1 size:16957"} {"level":"info","ts":"2026-07-08T23:28:42.090168Z","caller":"traceutil/trace.go:171","msg":"trace[577281929] range","detail":"{range_begin:/registry/pods/kube-system/cilium-r64jm; range_end:; response_count:1; response_revision:597; }","duration":"493.826455ms","start":"2026-07-08T23:28:41.596325Z","end":"2026-07-08T23:28:42.090152Z","steps":["trace[577281929] 'agreement among raft nodes before linearized reading' (duration: 493.63441ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:28:42.09016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.703909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T23:28:42.090276Z","caller":"traceutil/trace.go:171","msg":"trace[1012502614] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:597; }","duration":"448.904365ms","start":"2026-07-08T23:28:41.641343Z","end":"2026-07-08T23:28:42.090247Z","steps":["trace[1012502614] 'agreement among raft nodes before linearized reading' (duration: 448.681239ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:28:42.090328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:28:41.641321Z","time spent":"448.998208ms","remote":"127.0.0.1:53558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-08T23:28:42.090203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:28:41.596301Z","time spent":"493.894557ms","remote":"127.0.0.1:53752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16980,"request content":"key:\"/registry/pods/kube-system/cilium-r64jm\" "} {"level":"warn","ts":"2026-07-08T23:28:42.090698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.092931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.3\" ","response":"range_response_count:1 size:131"} {"level":"info","ts":"2026-07-08T23:28:42.090809Z","caller":"traceutil/trace.go:171","msg":"trace[478644824] range","detail":"{range_begin:/registry/masterleases/199.204.45.3; range_end:; response_count:1; response_revision:597; }","duration":"148.197934ms","start":"2026-07-08T23:28:41.942551Z","end":"2026-07-08T23:28:42.090749Z","steps":["trace[478644824] 'agreement among raft nodes before linearized reading' (duration: 147.542306ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:28:43.148316Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933267054090263327,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-08T23:28:43.648982Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933267054090263327,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-08T23:28:44.150064Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933267054090263327,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-08T23:28:44.31368Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.189477209s","expected-duration":"1s"} {"level":"info","ts":"2026-07-08T23:28:44.336852Z","caller":"traceutil/trace.go:171","msg":"trace[1092934800] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"2.230896652s","start":"2026-07-08T23:28:42.105925Z","end":"2026-07-08T23:28:44.336821Z","steps":["trace[1092934800] 'process raft request' (duration: 2.207964997s)","trace[1092934800] 'compare' (duration: 22.254557ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T23:28:44.337006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:28:42.105907Z","time spent":"2.231016106s","remote":"127.0.0.1:53752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17126,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T23:28:44.362616Z","caller":"traceutil/trace.go:171","msg":"trace[1627104404] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"2.237390974s","start":"2026-07-08T23:28:42.125191Z","end":"2026-07-08T23:28:44.362582Z","steps":["trace[1627104404] 'process raft request' (duration: 2.237155227s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:28:44.362795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:28:42.125172Z","time spent":"2.237530718s","remote":"127.0.0.1:53610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":116,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T23:28:44.363368Z","caller":"traceutil/trace.go:171","msg":"trace[1116213739] linearizableReadLoop","detail":"{readStateIndex:624; appliedIndex:621; }","duration":"1.715909012s","start":"2026-07-08T23:28:42.647429Z","end":"2026-07-08T23:28:44.363338Z","steps":["trace[1116213739] 'read index received' (duration: 1.666532338s)","trace[1116213739] 'applied index is now lower than readState.Index' (duration: 49.375454ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:28:44.363537Z","caller":"traceutil/trace.go:171","msg":"trace[11402038] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"1.864212562s","start":"2026-07-08T23:28:42.499309Z","end":"2026-07-08T23:28:44.363522Z","steps":["trace[11402038] 'process raft request' (duration: 1.863407431s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:28:44.363793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:28:42.499283Z","time spent":"1.864304875s","remote":"127.0.0.1:53654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036161,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T23:28:44.363837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.709222211s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-08T23:28:44.3639Z","caller":"traceutil/trace.go:171","msg":"trace[20522901] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:600; }","duration":"1.709322444s","start":"2026-07-08T23:28:42.654559Z","end":"2026-07-08T23:28:44.363882Z","steps":["trace[20522901] 'agreement among raft nodes before linearized reading' (duration: 1.709198241s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:28:44.363957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:28:42.654545Z","time spent":"1.709398715s","remote":"127.0.0.1:53830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-08T23:28:44.364027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.220541237s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-08T23:28:44.364102Z","caller":"traceutil/trace.go:171","msg":"trace[121022098] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:600; }","duration":"1.220693551s","start":"2026-07-08T23:28:43.143393Z","end":"2026-07-08T23:28:44.364086Z","steps":["trace[121022098] 'agreement among raft nodes before linearized reading' (duration: 1.220401823s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:28:44.36417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:28:43.143371Z","time spent":"1.220787024s","remote":"127.0.0.1:53830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-08T23:28:44.364293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.705890481s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-08T23:28:44.364341Z","caller":"traceutil/trace.go:171","msg":"trace[360918998] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:600; }","duration":"1.705977733s","start":"2026-07-08T23:28:42.658351Z","end":"2026-07-08T23:28:44.364329Z","steps":["trace[360918998] 'agreement among raft nodes before linearized reading' (duration: 1.70583747s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:28:44.364415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:28:42.658332Z","time spent":"1.706076617s","remote":"127.0.0.1:53830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-08T23:28:44.364593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.717163614s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-08T23:28:44.364759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.707424731s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-08T23:28:44.364813Z","caller":"traceutil/trace.go:171","msg":"trace[1167886584] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:600; }","duration":"1.707499854s","start":"2026-07-08T23:28:42.657302Z","end":"2026-07-08T23:28:44.364802Z","steps":["trace[1167886584] 'agreement among raft nodes before linearized reading' (duration: 1.70737021s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:28:44.364844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:28:42.657253Z","time spent":"1.707582586s","remote":"127.0.0.1:53830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-08T23:28:44.364727Z","caller":"traceutil/trace.go:171","msg":"trace[659441110] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:600; }","duration":"1.717271647s","start":"2026-07-08T23:28:42.647369Z","end":"2026-07-08T23:28:44.364641Z","steps":["trace[659441110] 'agreement among raft nodes before linearized reading' (duration: 1.717127393s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:28:44.364938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:28:42.647341Z","time spent":"1.717590535s","remote":"127.0.0.1:53568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-08T23:28:46.320091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.872186ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-08T23:28:46.320404Z","caller":"traceutil/trace.go:171","msg":"trace[1946212229] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"504.462531ms","start":"2026-07-08T23:28:45.815916Z","end":"2026-07-08T23:28:46.320379Z","steps":["trace[1946212229] 'process raft request' (duration: 83.179955ms)","trace[1946212229] 'compare' (duration: 420.708832ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T23:28:46.320624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:28:45.815896Z","time spent":"504.659977ms","remote":"127.0.0.1:53636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":763,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-08T23:28:46.326312Z","caller":"traceutil/trace.go:171","msg":"trace[772603080] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"412.929727ms","start":"2026-07-08T23:28:45.913358Z","end":"2026-07-08T23:28:46.326287Z","steps":["trace[772603080] 'process raft request' (duration: 410.761271ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:28:46.326622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:28:45.913329Z","time spent":"413.051021ms","remote":"127.0.0.1:53654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036261,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T23:28:56.681437Z","caller":"traceutil/trace.go:171","msg":"trace[315021297] linearizableReadLoop","detail":"{readStateIndex:782; appliedIndex:781; }","duration":"230.541779ms","start":"2026-07-08T23:28:56.450836Z","end":"2026-07-08T23:28:56.681378Z","steps":["trace[315021297] 'read index received' (duration: 230.489167ms)","trace[315021297] 'applied index is now lower than readState.Index' (duration: 51.622µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:28:56.681526Z","caller":"traceutil/trace.go:171","msg":"trace[1035978327] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"233.54445ms","start":"2026-07-08T23:28:56.447931Z","end":"2026-07-08T23:28:56.681476Z","steps":["trace[1035978327] 'process raft request' (duration: 233.272253ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:28:56.681736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.876557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-08T23:28:56.681825Z","caller":"traceutil/trace.go:171","msg":"trace[984450374] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:754; }","duration":"230.99775ms","start":"2026-07-08T23:28:56.450809Z","end":"2026-07-08T23:28:56.681807Z","steps":["trace[984450374] 'agreement among raft nodes before linearized reading' (duration: 230.712963ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:28:56.682159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.876619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-08T23:28:56.682179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.563521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-08T23:28:56.682256Z","caller":"traceutil/trace.go:171","msg":"trace[1284828126] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:754; }","duration":"225.677214ms","start":"2026-07-08T23:28:56.456563Z","end":"2026-07-08T23:28:56.68224Z","steps":["trace[1284828126] 'agreement among raft nodes before linearized reading' (duration: 225.445768ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:28:56.682254Z","caller":"traceutil/trace.go:171","msg":"trace[970110461] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:754; }","duration":"228.982781ms","start":"2026-07-08T23:28:56.453248Z","end":"2026-07-08T23:28:56.682231Z","steps":["trace[970110461] 'agreement among raft nodes before linearized reading' (duration: 228.802027ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:28:56.682555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.666748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-08T23:28:56.682597Z","caller":"traceutil/trace.go:171","msg":"trace[859005430] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:754; }","duration":"229.766631ms","start":"2026-07-08T23:28:56.452821Z","end":"2026-07-08T23:28:56.682588Z","steps":["trace[859005430] 'agreement among raft nodes before linearized reading' (duration: 228.960062ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:29:00.30521Z","caller":"traceutil/trace.go:171","msg":"trace[1024109394] linearizableReadLoop","detail":"{readStateIndex:802; appliedIndex:801; }","duration":"259.398141ms","start":"2026-07-08T23:29:00.045787Z","end":"2026-07-08T23:29:00.305185Z","steps":["trace[1024109394] 'read index received' (duration: 259.191556ms)","trace[1024109394] 'applied index is now lower than readState.Index' (duration: 205.795µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:29:00.305261Z","caller":"traceutil/trace.go:171","msg":"trace[1530038231] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"588.063641ms","start":"2026-07-08T23:28:59.717176Z","end":"2026-07-08T23:29:00.305239Z","steps":["trace[1530038231] 'process raft request' (duration: 587.850545ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:29:00.305391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:28:59.717146Z","time spent":"588.168982ms","remote":"127.0.0.1:53814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T23:29:00.30537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.571436ms","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-07-08T23:29:00.305561Z","caller":"traceutil/trace.go:171","msg":"trace[1181878196] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:773; }","duration":"259.81303ms","start":"2026-07-08T23:29:00.045731Z","end":"2026-07-08T23:29:00.305544Z","steps":["trace[1181878196] 'agreement among raft nodes before linearized reading' (duration: 259.563195ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:29:06.957859Z","caller":"traceutil/trace.go:171","msg":"trace[17058994] linearizableReadLoop","detail":"{readStateIndex:870; appliedIndex:869; }","duration":"143.064126ms","start":"2026-07-08T23:29:06.814773Z","end":"2026-07-08T23:29:06.957837Z","steps":["trace[17058994] 'read index received' (duration: 46.813607ms)","trace[17058994] 'applied index is now lower than readState.Index' (duration: 96.249429ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:29:06.957908Z","caller":"traceutil/trace.go:171","msg":"trace[223470463] transaction","detail":"{read_only:false; response_revision:840; number_of_response:1; }","duration":"179.894615ms","start":"2026-07-08T23:29:06.777991Z","end":"2026-07-08T23:29:06.957886Z","steps":["trace[223470463] 'process raft request' (duration: 83.577984ms)","trace[223470463] 'compare' (duration: 96.175508ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T23:29:06.95812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.326271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-08T23:29:06.958179Z","caller":"traceutil/trace.go:171","msg":"trace[380019575] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:840; }","duration":"143.430193ms","start":"2026-07-08T23:29:06.814732Z","end":"2026-07-08T23:29:06.958162Z","steps":["trace[380019575] 'agreement among raft nodes before linearized reading' (duration: 143.227288ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:29:06.963742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.581802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-08T23:29:06.96372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.869471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-08T23:29:06.963797Z","caller":"traceutil/trace.go:171","msg":"trace[1233976966] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:841; }","duration":"141.672964ms","start":"2026-07-08T23:29:06.822109Z","end":"2026-07-08T23:29:06.963782Z","steps":["trace[1233976966] 'agreement among raft nodes before linearized reading' (duration: 141.551402ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:29:06.96381Z","caller":"traceutil/trace.go:171","msg":"trace[1582856080] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:841; }","duration":"149.013654ms","start":"2026-07-08T23:29:06.814779Z","end":"2026-07-08T23:29:06.963793Z","steps":["trace[1582856080] 'agreement among raft nodes before linearized reading' (duration: 148.780949ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:29:14.015017Z","caller":"traceutil/trace.go:171","msg":"trace[1515525561] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"216.244185ms","start":"2026-07-08T23:29:13.798749Z","end":"2026-07-08T23:29:14.014993Z","steps":["trace[1515525561] 'process raft request' (duration: 216.114262ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:29:14.731253Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933267054090264026,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-08T23:29:14.928316Z","caller":"traceutil/trace.go:171","msg":"trace[600845369] linearizableReadLoop","detail":"{readStateIndex:973; appliedIndex:972; }","duration":"697.479082ms","start":"2026-07-08T23:29:14.230814Z","end":"2026-07-08T23:29:14.928293Z","steps":["trace[600845369] 'read index received' (duration: 697.243717ms)","trace[600845369] 'applied index is now lower than readState.Index' (duration: 234.325µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:29:14.928687Z","caller":"traceutil/trace.go:171","msg":"trace[234636043] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"906.50661ms","start":"2026-07-08T23:29:14.02196Z","end":"2026-07-08T23:29:14.928467Z","steps":["trace[234636043] 'process raft request' (duration: 906.154962ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:29:14.92876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.669738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T23:29:14.928847Z","caller":"traceutil/trace.go:171","msg":"trace[468200153] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:941; }","duration":"287.807491ms","start":"2026-07-08T23:29:14.64102Z","end":"2026-07-08T23:29:14.928828Z","steps":["trace[468200153] 'agreement among raft nodes before linearized reading' (duration: 287.652598ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:29:14.928978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.273898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-08T23:29:14.929048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:29:14.02194Z","time spent":"906.948028ms","remote":"127.0.0.1:54048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3745,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T23:29:14.929096Z","caller":"traceutil/trace.go:171","msg":"trace[505622548] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:941; }","duration":"146.463683ms","start":"2026-07-08T23:29:14.782607Z","end":"2026-07-08T23:29:14.92907Z","steps":["trace[505622548] 'agreement among raft nodes before linearized reading' (duration: 146.104456ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:29:14.929214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"698.36121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-6rp6q\" ","response":"range_response_count:1 size:5669"} {"level":"info","ts":"2026-07-08T23:29:14.929298Z","caller":"traceutil/trace.go:171","msg":"trace[1411413308] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-6rp6q; range_end:; response_count:1; response_revision:941; }","duration":"698.515563ms","start":"2026-07-08T23:29:14.230764Z","end":"2026-07-08T23:29:14.92928Z","steps":["trace[1411413308] 'agreement among raft nodes before linearized reading' (duration: 697.932412ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:29:14.929386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:29:14.230744Z","time spent":"698.625556ms","remote":"127.0.0.1:53752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":1,"response size":5692,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-6rp6q\" "} {"level":"info","ts":"2026-07-08T23:29:15.211217Z","caller":"traceutil/trace.go:171","msg":"trace[1947794047] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"196.04321ms","start":"2026-07-08T23:29:15.015131Z","end":"2026-07-08T23:29:15.211174Z","steps":["trace[1947794047] 'process raft request' (duration: 195.829836ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:29:15.211332Z","caller":"traceutil/trace.go:171","msg":"trace[1079141551] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"193.44483ms","start":"2026-07-08T23:29:15.017852Z","end":"2026-07-08T23:29:15.211297Z","steps":["trace[1079141551] 'process raft request' (duration: 193.261155ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:29:15.211475Z","caller":"traceutil/trace.go:171","msg":"trace[293387003] linearizableReadLoop","detail":"{readStateIndex:977; appliedIndex:975; }","duration":"195.721415ms","start":"2026-07-08T23:29:15.015722Z","end":"2026-07-08T23:29:15.211443Z","steps":["trace[293387003] 'read index received' (duration: 23.033425ms)","trace[293387003] 'applied index is now lower than readState.Index' (duration: 172.68587ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:29:15.211495Z","caller":"traceutil/trace.go:171","msg":"trace[683037201] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"190.778275ms","start":"2026-07-08T23:29:15.020694Z","end":"2026-07-08T23:29:15.211472Z","steps":["trace[683037201] 'process raft request' (duration: 190.469628ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:29:15.211702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.954159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-08T23:29:15.211727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.98562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-08T23:29:15.211756Z","caller":"traceutil/trace.go:171","msg":"trace[1398870026] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:947; }","duration":"196.055722ms","start":"2026-07-08T23:29:15.015685Z","end":"2026-07-08T23:29:15.211741Z","steps":["trace[1398870026] 'agreement among raft nodes before linearized reading' (duration: 195.876778ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:29:15.211761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.64963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-08T23:29:15.211779Z","caller":"traceutil/trace.go:171","msg":"trace[1282941662] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:947; }","duration":"191.077242ms","start":"2026-07-08T23:29:15.020687Z","end":"2026-07-08T23:29:15.211764Z","steps":["trace[1282941662] 'agreement among raft nodes before linearized reading' (duration: 190.913909ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:29:15.211799Z","caller":"traceutil/trace.go:171","msg":"trace[159907070] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:947; }","duration":"131.741012ms","start":"2026-07-08T23:29:15.080047Z","end":"2026-07-08T23:29:15.211788Z","steps":["trace[159907070] 'agreement among raft nodes before linearized reading' (duration: 131.558088ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:29:15.211826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.081198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-08T23:29:15.211877Z","caller":"traceutil/trace.go:171","msg":"trace[1698066881] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:947; }","duration":"176.16616ms","start":"2026-07-08T23:29:15.035698Z","end":"2026-07-08T23:29:15.211864Z","steps":["trace[1698066881] 'agreement among raft nodes before linearized reading' (duration: 175.987687ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:29:15.358987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.224329ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T23:29:15.359237Z","caller":"traceutil/trace.go:171","msg":"trace[160757213] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"140.398047ms","start":"2026-07-08T23:29:15.218821Z","end":"2026-07-08T23:29:15.359219Z","steps":["trace[160757213] 'process raft request' (duration: 140.329466ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:29:15.359272Z","caller":"traceutil/trace.go:171","msg":"trace[1584570525] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"140.986598ms","start":"2026-07-08T23:29:15.218256Z","end":"2026-07-08T23:29:15.359243Z","steps":["trace[1584570525] 'process raft request' (duration: 140.833295ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:29:15.3593Z","caller":"traceutil/trace.go:171","msg":"trace[1311792190] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"142.103961ms","start":"2026-07-08T23:29:15.217169Z","end":"2026-07-08T23:29:15.359273Z","steps":["trace[1311792190] 'process raft request' (duration: 24.506455ms)","trace[1311792190] 'compare' (duration: 117.098936ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:29:15.359556Z","caller":"traceutil/trace.go:171","msg":"trace[1965375269] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"139.427946ms","start":"2026-07-08T23:29:15.220115Z","end":"2026-07-08T23:29:15.359543Z","steps":["trace[1965375269] 'process raft request' (duration: 139.072419ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:29:15.359582Z","caller":"traceutil/trace.go:171","msg":"trace[2037016255] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"137.59835ms","start":"2026-07-08T23:29:15.221975Z","end":"2026-07-08T23:29:15.359573Z","steps":["trace[2037016255] 'process raft request' (duration: 137.497748ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:29:15.886802Z","caller":"traceutil/trace.go:171","msg":"trace[1494508786] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"110.159637ms","start":"2026-07-08T23:29:15.77662Z","end":"2026-07-08T23:29:15.886779Z","steps":["trace[1494508786] 'process raft request' (duration: 110.019764ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:30:03.813928Z","caller":"traceutil/trace.go:171","msg":"trace[280459112] transaction","detail":"{read_only:false; response_revision:1117; number_of_response:1; }","duration":"119.44999ms","start":"2026-07-08T23:30:03.694454Z","end":"2026-07-08T23:30:03.813904Z","steps":["trace[280459112] 'process raft request' (duration: 119.204227ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:30:03.95507Z","caller":"traceutil/trace.go:171","msg":"trace[1520810020] transaction","detail":"{read_only:false; response_revision:1119; number_of_response:1; }","duration":"137.358259ms","start":"2026-07-08T23:30:03.817692Z","end":"2026-07-08T23:30:03.95505Z","steps":["trace[1520810020] 'process raft request' (duration: 76.72691ms)","trace[1520810020] 'compare' (duration: 60.414725ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:30:09.700486Z","caller":"traceutil/trace.go:171","msg":"trace[539834043] linearizableReadLoop","detail":"{readStateIndex:1178; appliedIndex:1177; }","duration":"160.693662ms","start":"2026-07-08T23:30:09.539769Z","end":"2026-07-08T23:30:09.700463Z","steps":["trace[539834043] 'read index received' (duration: 160.422487ms)","trace[539834043] 'applied index is now lower than readState.Index' (duration: 270.255µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:30:09.700611Z","caller":"traceutil/trace.go:171","msg":"trace[421972118] transaction","detail":"{read_only:false; response_revision:1135; number_of_response:1; }","duration":"216.82875ms","start":"2026-07-08T23:30:09.483746Z","end":"2026-07-08T23:30:09.700575Z","steps":["trace[421972118] 'process raft request' (duration: 216.510455ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:30:09.700754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.953986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-08T23:30:09.700802Z","caller":"traceutil/trace.go:171","msg":"trace[362305260] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1135; }","duration":"161.084427ms","start":"2026-07-08T23:30:09.539704Z","end":"2026-07-08T23:30:09.700788Z","steps":["trace[362305260] 'agreement among raft nodes before linearized reading' (duration: 160.896815ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:30:10.151735Z","caller":"traceutil/trace.go:171","msg":"trace[1957913688] linearizableReadLoop","detail":"{readStateIndex:1181; appliedIndex:1180; }","duration":"115.064469ms","start":"2026-07-08T23:30:10.036649Z","end":"2026-07-08T23:30:10.151713Z","steps":["trace[1957913688] 'read index received' (duration: 35.803409ms)","trace[1957913688] 'applied index is now lower than readState.Index' (duration: 79.26022ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:30:10.151812Z","caller":"traceutil/trace.go:171","msg":"trace[1488500753] transaction","detail":"{read_only:false; response_revision:1138; number_of_response:1; }","duration":"117.91158ms","start":"2026-07-08T23:30:10.03387Z","end":"2026-07-08T23:30:10.151781Z","steps":["trace[1488500753] 'process raft request' (duration: 38.577459ms)","trace[1488500753] 'compare' (duration: 79.166099ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T23:30:10.152023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.64503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-08T23:30:10.152067Z","caller":"traceutil/trace.go:171","msg":"trace[1816068998] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1138; }","duration":"109.753092ms","start":"2026-07-08T23:30:10.042304Z","end":"2026-07-08T23:30:10.152057Z","steps":["trace[1816068998] 'agreement among raft nodes before linearized reading' (duration: 109.58334ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:30:10.152551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.900511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-08T23:30:10.152579Z","caller":"traceutil/trace.go:171","msg":"trace[876210180] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1138; }","duration":"115.940132ms","start":"2026-07-08T23:30:10.036632Z","end":"2026-07-08T23:30:10.152572Z","steps":["trace[876210180] 'agreement among raft nodes before linearized reading' (duration: 115.223041ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:30:16.214164Z","caller":"traceutil/trace.go:171","msg":"trace[972176681] transaction","detail":"{read_only:false; response_revision:1157; number_of_response:1; }","duration":"141.979398ms","start":"2026-07-08T23:30:16.072171Z","end":"2026-07-08T23:30:16.214151Z","steps":["trace[972176681] 'process raft request' (duration: 141.623033ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:30:16.385515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.205584ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T23:30:16.385605Z","caller":"traceutil/trace.go:171","msg":"trace[1198293449] linearizableReadLoop","detail":"{readStateIndex:1202; appliedIndex:1201; }","duration":"160.383597ms","start":"2026-07-08T23:30:16.225206Z","end":"2026-07-08T23:30:16.38559Z","steps":["trace[1198293449] 'read index received' (duration: 45.925749ms)","trace[1198293449] 'applied index is now lower than readState.Index' (duration: 114.456718ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T23:30:16.385926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.714421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-08T23:30:16.385958Z","caller":"traceutil/trace.go:171","msg":"trace[1559579304] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1158; }","duration":"160.755962ms","start":"2026-07-08T23:30:16.225192Z","end":"2026-07-08T23:30:16.385948Z","steps":["trace[1559579304] 'agreement among raft nodes before linearized reading' (duration: 160.6432ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:30:16.385985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.119503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-08T23:30:16.386055Z","caller":"traceutil/trace.go:171","msg":"trace[1128004937] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1158; }","duration":"102.248255ms","start":"2026-07-08T23:30:16.283789Z","end":"2026-07-08T23:30:16.386038Z","steps":["trace[1128004937] 'agreement among raft nodes before linearized reading' (duration: 102.042952ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:30:16.386056Z","caller":"traceutil/trace.go:171","msg":"trace[1352574710] transaction","detail":"{read_only:false; response_revision:1158; number_of_response:1; }","duration":"168.268399ms","start":"2026-07-08T23:30:16.217747Z","end":"2026-07-08T23:30:16.386015Z","steps":["trace[1352574710] 'process raft request' (duration: 53.472016ms)","trace[1352574710] 'compare' (duration: 113.90428ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:30:20.065085Z","caller":"traceutil/trace.go:171","msg":"trace[1657073386] transaction","detail":"{read_only:false; response_revision:1169; number_of_response:1; }","duration":"108.654793ms","start":"2026-07-08T23:30:19.956406Z","end":"2026-07-08T23:30:20.06506Z","steps":["trace[1657073386] 'process raft request' (duration: 108.501921ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:30:20.579817Z","caller":"traceutil/trace.go:171","msg":"trace[829906951] transaction","detail":"{read_only:false; response_revision:1173; number_of_response:1; }","duration":"119.006895ms","start":"2026-07-08T23:30:20.460778Z","end":"2026-07-08T23:30:20.579785Z","steps":["trace[829906951] 'process raft request' (duration: 67.839983ms)","trace[829906951] 'compare' (duration: 51.05211ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:30:21.93066Z","caller":"traceutil/trace.go:171","msg":"trace[2099702627] transaction","detail":"{read_only:false; response_revision:1174; number_of_response:1; }","duration":"114.030798ms","start":"2026-07-08T23:30:21.816606Z","end":"2026-07-08T23:30:21.930637Z","steps":["trace[2099702627] 'process raft request' (duration: 113.864716ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:30:22.402428Z","caller":"traceutil/trace.go:171","msg":"trace[1834293639] linearizableReadLoop","detail":"{readStateIndex:1222; appliedIndex:1221; }","duration":"293.194674ms","start":"2026-07-08T23:30:22.10921Z","end":"2026-07-08T23:30:22.402404Z","steps":["trace[1834293639] 'read index received' (duration: 216.828081ms)","trace[1834293639] 'applied index is now lower than readState.Index' (duration: 76.365403ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:30:22.402492Z","caller":"traceutil/trace.go:171","msg":"trace[1341763081] transaction","detail":"{read_only:false; response_revision:1176; number_of_response:1; }","duration":"293.802103ms","start":"2026-07-08T23:30:22.108644Z","end":"2026-07-08T23:30:22.402446Z","steps":["trace[1341763081] 'process raft request' (duration: 217.345758ms)","trace[1341763081] 'compare' (duration: 76.310493ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T23:30:22.402694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.462718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-08T23:30:22.402707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.242156ms","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-07-08T23:30:22.402762Z","caller":"traceutil/trace.go:171","msg":"trace[700278043] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1176; }","duration":"293.60673ms","start":"2026-07-08T23:30:22.109137Z","end":"2026-07-08T23:30:22.402744Z","steps":["trace[700278043] 'agreement among raft nodes before linearized reading' (duration: 293.391657ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:30:22.402765Z","caller":"traceutil/trace.go:171","msg":"trace[1758041321] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1176; }","duration":"114.302837ms","start":"2026-07-08T23:30:22.288448Z","end":"2026-07-08T23:30:22.402751Z","steps":["trace[1758041321] 'agreement among raft nodes before linearized reading' (duration: 114.114355ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:30:22.843368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.296454ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T23:30:22.843444Z","caller":"traceutil/trace.go:171","msg":"trace[944181726] linearizableReadLoop","detail":"{readStateIndex:1223; appliedIndex:1222; }","duration":"383.868256ms","start":"2026-07-08T23:30:22.459562Z","end":"2026-07-08T23:30:22.843431Z","steps":["trace[944181726] 'read index received' (duration: 125.41813ms)","trace[944181726] 'applied index is now lower than readState.Index' (duration: 258.449226ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:30:22.843503Z","caller":"traceutil/trace.go:171","msg":"trace[1523336167] transaction","detail":"{read_only:false; response_revision:1177; number_of_response:1; }","duration":"437.173952ms","start":"2026-07-08T23:30:22.406319Z","end":"2026-07-08T23:30:22.843493Z","steps":["trace[1523336167] 'process raft request' (duration: 178.679755ms)","trace[1523336167] 'compare' (duration: 257.93636ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T23:30:22.843539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:30:22.406302Z","time spent":"437.217722ms","remote":"127.0.0.1:53830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T23:30:22.843681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.589995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-08T23:30:22.843749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.530035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T23:30:22.843749Z","caller":"traceutil/trace.go:171","msg":"trace[1016136497] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1177; }","duration":"376.681657ms","start":"2026-07-08T23:30:22.46705Z","end":"2026-07-08T23:30:22.843732Z","steps":["trace[1016136497] 'agreement among raft nodes before linearized reading' (duration: 376.498675ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:30:22.843771Z","caller":"traceutil/trace.go:171","msg":"trace[1033502788] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1177; }","duration":"202.574855ms","start":"2026-07-08T23:30:22.64119Z","end":"2026-07-08T23:30:22.843764Z","steps":["trace[1033502788] 'agreement among raft nodes before linearized reading' (duration: 202.538144ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:30:22.843789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:30:22.467043Z","time spent":"376.737238ms","remote":"127.0.0.1:53830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-08T23:30:22.84392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.358744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-08T23:30:22.843941Z","caller":"traceutil/trace.go:171","msg":"trace[896021378] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1177; }","duration":"384.379464ms","start":"2026-07-08T23:30:22.459556Z","end":"2026-07-08T23:30:22.843936Z","steps":["trace[896021378] 'agreement among raft nodes before linearized reading' (duration: 384.292583ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:30:22.843957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:30:22.459518Z","time spent":"384.434804ms","remote":"127.0.0.1:53830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-08T23:30:22.844019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.474974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-08T23:30:22.844059Z","caller":"traceutil/trace.go:171","msg":"trace[740763101] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1177; }","duration":"260.554296ms","start":"2026-07-08T23:30:22.583495Z","end":"2026-07-08T23:30:22.844049Z","steps":["trace[740763101] 'agreement among raft nodes before linearized reading' (duration: 260.459144ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:30:22.978996Z","caller":"traceutil/trace.go:171","msg":"trace[1809558454] transaction","detail":"{read_only:false; response_revision:1179; number_of_response:1; }","duration":"131.512264ms","start":"2026-07-08T23:30:22.847448Z","end":"2026-07-08T23:30:22.97896Z","steps":["trace[1809558454] 'process raft request' (duration: 118.16919ms)","trace[1809558454] 'compare' (duration: 13.15253ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:30:22.979089Z","caller":"traceutil/trace.go:171","msg":"trace[1628201844] transaction","detail":"{read_only:false; response_revision:1180; number_of_response:1; }","duration":"131.300781ms","start":"2026-07-08T23:30:22.847759Z","end":"2026-07-08T23:30:22.979059Z","steps":["trace[1628201844] 'process raft request' (duration: 131.133789ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:30:27.611258Z","caller":"traceutil/trace.go:171","msg":"trace[1843517635] transaction","detail":"{read_only:false; response_revision:1194; number_of_response:1; }","duration":"161.87155ms","start":"2026-07-08T23:30:27.449343Z","end":"2026-07-08T23:30:27.611215Z","steps":["trace[1843517635] 'process raft request' (duration: 161.638427ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:30:45.707909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.743549ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T23:30:45.708202Z","caller":"traceutil/trace.go:171","msg":"trace[391600355] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"361.657303ms","start":"2026-07-08T23:30:45.34653Z","end":"2026-07-08T23:30:45.708188Z","steps":["trace[391600355] 'process raft request' (duration: 361.480861ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:30:45.708191Z","caller":"traceutil/trace.go:171","msg":"trace[1400343935] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"362.268151ms","start":"2026-07-08T23:30:45.345887Z","end":"2026-07-08T23:30:45.708155Z","steps":["trace[1400343935] 'process raft request' (duration: 180.194108ms)","trace[1400343935] 'compare' (duration: 181.595757ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T23:30:45.708278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:30:45.346502Z","time spent":"361.741984ms","remote":"127.0.0.1:53830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T23:30:45.708299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:30:45.345861Z","time spent":"362.391492ms","remote":"127.0.0.1:53830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T23:30:53.3792Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933267054090264999,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-08T23:30:53.71845Z","caller":"traceutil/trace.go:171","msg":"trace[2007560318] linearizableReadLoop","detail":"{readStateIndex:1367; appliedIndex:1366; }","duration":"840.182903ms","start":"2026-07-08T23:30:52.878223Z","end":"2026-07-08T23:30:53.718406Z","steps":["trace[2007560318] 'read index received' (duration: 839.94676ms)","trace[2007560318] 'applied index is now lower than readState.Index' (duration: 235.203µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:30:53.718497Z","caller":"traceutil/trace.go:171","msg":"trace[812912432] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"1.087971043s","start":"2026-07-08T23:30:52.6305Z","end":"2026-07-08T23:30:53.718471Z","steps":["trace[812912432] 'process raft request' (duration: 1.08773987s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:30:53.718641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"840.393836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T23:30:53.718728Z","caller":"traceutil/trace.go:171","msg":"trace[592772648] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1313; }","duration":"840.549317ms","start":"2026-07-08T23:30:52.87816Z","end":"2026-07-08T23:30:53.71871Z","steps":["trace[592772648] 'agreement among raft nodes before linearized reading' (duration: 840.371345ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:30:53.718732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"630.086258ms","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-07-08T23:30:53.718757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.295358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-08T23:30:53.718776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:30:52.878142Z","time spent":"840.625858ms","remote":"127.0.0.1:53568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-08T23:30:53.718815Z","caller":"traceutil/trace.go:171","msg":"trace[1273783965] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1313; }","duration":"630.258731ms","start":"2026-07-08T23:30:53.08854Z","end":"2026-07-08T23:30:53.718799Z","steps":["trace[1273783965] 'agreement among raft nodes before linearized reading' (duration: 630.058508ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:30:53.718824Z","caller":"traceutil/trace.go:171","msg":"trace[1268595458] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1313; }","duration":"309.3682ms","start":"2026-07-08T23:30:53.409438Z","end":"2026-07-08T23:30:53.718806Z","steps":["trace[1268595458] 'agreement among raft nodes before linearized reading' (duration: 309.185607ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:30:53.718863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:30:53.08852Z","time spent":"630.333561ms","remote":"127.0.0.1:53830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-08T23:30:53.718866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:30:53.409389Z","time spent":"309.46924ms","remote":"127.0.0.1:53830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-08T23:30:53.719077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:30:52.630483Z","time spent":"1.088477569s","remote":"127.0.0.1:53830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T23:30:54.207582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.659619ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T23:30:54.207697Z","caller":"traceutil/trace.go:171","msg":"trace[1513399544] transaction","detail":"{read_only:false; response_revision:1314; number_of_response:1; }","duration":"483.21423ms","start":"2026-07-08T23:30:53.724464Z","end":"2026-07-08T23:30:54.207679Z","steps":["trace[1513399544] 'process raft request' (duration: 123.231317ms)","trace[1513399544] 'compare' (duration: 359.439267ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T23:30:54.207796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:30:53.724442Z","time spent":"483.324361ms","remote":"127.0.0.1:53830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T23:30:54.573837Z","caller":"traceutil/trace.go:171","msg":"trace[1014920091] linearizableReadLoop","detail":"{readStateIndex:1369; appliedIndex:1367; }","duration":"836.966791ms","start":"2026-07-08T23:30:53.736848Z","end":"2026-07-08T23:30:54.573815Z","steps":["trace[1014920091] 'read index received' (duration: 110.859434ms)","trace[1014920091] 'applied index is now lower than readState.Index' (duration: 726.106237ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:30:54.573939Z","caller":"traceutil/trace.go:171","msg":"trace[1488006128] transaction","detail":"{read_only:false; response_revision:1315; number_of_response:1; }","duration":"848.87537ms","start":"2026-07-08T23:30:53.725032Z","end":"2026-07-08T23:30:54.573908Z","steps":["trace[1488006128] 'process raft request' (duration: 834.691353ms)","trace[1488006128] 'compare' (duration: 13.65625ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T23:30:54.57404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"837.181894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-08T23:30:54.574082Z","caller":"traceutil/trace.go:171","msg":"trace[439322087] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1315; }","duration":"837.264405ms","start":"2026-07-08T23:30:53.736805Z","end":"2026-07-08T23:30:54.574069Z","steps":["trace[439322087] 'agreement among raft nodes before linearized reading' (duration: 837.114073ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:30:54.574118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:30:53.736779Z","time spent":"837.331526ms","remote":"127.0.0.1:53830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-08T23:30:54.574264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:30:53.725018Z","time spent":"849.050001ms","remote":"127.0.0.1:53830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T23:30:54.618882Z","caller":"traceutil/trace.go:171","msg":"trace[2087144434] transaction","detail":"{read_only:false; response_revision:1316; number_of_response:1; }","duration":"395.907225ms","start":"2026-07-08T23:30:54.222953Z","end":"2026-07-08T23:30:54.618861Z","steps":["trace[2087144434] 'process raft request' (duration: 395.795963ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:30:54.61903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:30:54.222931Z","time spent":"396.021696ms","remote":"127.0.0.1:53830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T23:30:54.619106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.372006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T23:30:54.619166Z","caller":"traceutil/trace.go:171","msg":"trace[678661214] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1316; }","duration":"550.477298ms","start":"2026-07-08T23:30:54.068675Z","end":"2026-07-08T23:30:54.619153Z","steps":["trace[678661214] 'agreement among raft nodes before linearized reading' (duration: 550.339297ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:30:54.619211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:30:54.068644Z","time spent":"550.559239ms","remote":"127.0.0.1:53718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"warn","ts":"2026-07-08T23:30:54.619216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"878.452077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-08T23:30:54.619293Z","caller":"traceutil/trace.go:171","msg":"trace[1789111945] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1316; }","duration":"878.545378ms","start":"2026-07-08T23:30:53.740729Z","end":"2026-07-08T23:30:54.619275Z","steps":["trace[1789111945] 'agreement among raft nodes before linearized reading' (duration: 878.275274ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:30:54.619338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:30:53.740704Z","time spent":"878.626418ms","remote":"127.0.0.1:53830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-08T23:30:54.787062Z","caller":"traceutil/trace.go:171","msg":"trace[370206276] linearizableReadLoop","detail":"{readStateIndex:1372; appliedIndex:1371; }","duration":"138.957458ms","start":"2026-07-08T23:30:54.648082Z","end":"2026-07-08T23:30:54.78704Z","steps":["trace[370206276] 'read index received' (duration: 40.922898ms)","trace[370206276] 'applied index is now lower than readState.Index' (duration: 98.03365ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:30:54.787127Z","caller":"traceutil/trace.go:171","msg":"trace[81111011] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"163.828497ms","start":"2026-07-08T23:30:54.623272Z","end":"2026-07-08T23:30:54.787101Z","steps":["trace[81111011] 'process raft request' (duration: 65.710137ms)","trace[81111011] 'compare' (duration: 97.947648ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T23:30:54.787177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.75721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T23:30:54.787361Z","caller":"traceutil/trace.go:171","msg":"trace[1956163745] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1318; }","duration":"148.946552ms","start":"2026-07-08T23:30:54.638381Z","end":"2026-07-08T23:30:54.787327Z","steps":["trace[1956163745] 'agreement among raft nodes before linearized reading' (duration: 148.74821ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:30:55.723172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"812.623946ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T23:30:55.723327Z","caller":"traceutil/trace.go:171","msg":"trace[2077855022] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"839.43762ms","start":"2026-07-08T23:30:54.883871Z","end":"2026-07-08T23:30:55.723309Z","steps":["trace[2077855022] 'process raft request' (duration: 26.610291ms)","trace[2077855022] 'compare' (duration: 812.189841ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T23:30:55.723433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:30:54.883836Z","time spent":"839.545652ms","remote":"127.0.0.1:53830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T23:30:55.852336Z","caller":"traceutil/trace.go:171","msg":"trace[2057836179] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"124.310702ms","start":"2026-07-08T23:30:55.728003Z","end":"2026-07-08T23:30:55.852314Z","steps":["trace[2057836179] 'process raft request' (duration: 110.293008ms)","trace[2057836179] 'compare' (duration: 13.921633ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T23:31:17.05895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"926.686826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-08T23:31:17.059113Z","caller":"traceutil/trace.go:171","msg":"trace[2020393691] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1390; }","duration":"926.867658ms","start":"2026-07-08T23:31:16.132226Z","end":"2026-07-08T23:31:17.059093Z","steps":["trace[2020393691] 'count revisions from in-memory index tree' (duration: 926.628576ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:31:17.059188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:31:16.132214Z","time spent":"926.95865ms","remote":"127.0.0.1:53956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":30,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-07-08T23:31:17.059259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"837.028395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-08T23:31:17.059295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"897.36151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/envoy-rate-limit\" ","response":"range_response_count:1 size:4456"} {"level":"info","ts":"2026-07-08T23:31:17.059348Z","caller":"traceutil/trace.go:171","msg":"trace[2080216429] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:1390; }","duration":"837.150447ms","start":"2026-07-08T23:31:16.222175Z","end":"2026-07-08T23:31:17.059326Z","steps":["trace[2080216429] 'count revisions from in-memory index tree' (duration: 836.964814ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:31:17.059373Z","caller":"traceutil/trace.go:171","msg":"trace[1612631664] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/envoy-rate-limit; range_end:; response_count:1; response_revision:1390; }","duration":"897.471381ms","start":"2026-07-08T23:31:16.161882Z","end":"2026-07-08T23:31:17.059353Z","steps":["trace[1612631664] 'range keys from in-memory index tree' (duration: 897.254558ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:31:17.059381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"763.85323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/sh.helm.release.v1.envoy-gateway.v1\" ","response":"range_response_count:1 size:1036313"} {"level":"warn","ts":"2026-07-08T23:31:17.0594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:31:16.222165Z","time spent":"837.223617ms","remote":"127.0.0.1:38658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"warn","ts":"2026-07-08T23:31:17.0594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"851.607177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-public/\" range_end:\"/registry/rolebindings/kube-public0\" limit:500 ","response":"range_response_count:2 size:1230"} {"level":"info","ts":"2026-07-08T23:31:17.05944Z","caller":"traceutil/trace.go:171","msg":"trace[1655148335] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/sh.helm.release.v1.envoy-gateway.v1; range_end:; response_count:1; response_revision:1390; }","duration":"763.944501ms","start":"2026-07-08T23:31:16.295479Z","end":"2026-07-08T23:31:17.059424Z","steps":["trace[1655148335] 'range keys from in-memory index tree' (duration: 763.443025ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:31:17.059458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"778.858167ms","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-07-08T23:31:17.059458Z","caller":"traceutil/trace.go:171","msg":"trace[1003440624] range","detail":"{range_begin:/registry/rolebindings/kube-public/; range_end:/registry/rolebindings/kube-public0; response_count:2; response_revision:1390; }","duration":"851.691749ms","start":"2026-07-08T23:31:16.207748Z","end":"2026-07-08T23:31:17.05944Z","steps":["trace[1003440624] 'range keys from in-memory index tree' (duration: 851.482297ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:31:17.059482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:31:16.295463Z","time spent":"764.011351ms","remote":"127.0.0.1:53654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":1036336,"request content":"key:\"/registry/secrets/envoy-gateway-system/sh.helm.release.v1.envoy-gateway.v1\" "} {"level":"info","ts":"2026-07-08T23:31:17.059486Z","caller":"traceutil/trace.go:171","msg":"trace[1939481673] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1390; }","duration":"778.920807ms","start":"2026-07-08T23:31:16.280556Z","end":"2026-07-08T23:31:17.059477Z","steps":["trace[1939481673] 'count revisions from in-memory index tree' (duration: 778.751466ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:31:17.059498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:31:16.207734Z","time spent":"851.754679ms","remote":"127.0.0.1:53920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":77,"response count":2,"response size":1253,"request content":"key:\"/registry/rolebindings/kube-public/\" range_end:\"/registry/rolebindings/kube-public0\" limit:500 "} {"level":"warn","ts":"2026-07-08T23:31:17.059525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:31:16.280538Z","time spent":"778.976398ms","remote":"127.0.0.1:53970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":30,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-07-08T23:31:17.059415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:31:16.161869Z","time spent":"897.536631ms","remote":"127.0.0.1:53654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":4479,"request content":"key:\"/registry/secrets/envoy-gateway-system/envoy-rate-limit\" "} {"level":"warn","ts":"2026-07-08T23:31:17.06065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.644663ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T23:31:17.061078Z","caller":"traceutil/trace.go:171","msg":"trace[2039029908] transaction","detail":"{read_only:false; response_revision:1392; number_of_response:1; }","duration":"253.852962ms","start":"2026-07-08T23:31:16.80721Z","end":"2026-07-08T23:31:17.061063Z","steps":["trace[2039029908] 'process raft request' (duration: 253.704431ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:31:17.061108Z","caller":"traceutil/trace.go:171","msg":"trace[127736725] linearizableReadLoop","detail":"{readStateIndex:1450; appliedIndex:1449; }","duration":"747.645008ms","start":"2026-07-08T23:31:16.313434Z","end":"2026-07-08T23:31:17.06108Z","steps":["trace[127736725] 'read index received' (duration: 241.466718ms)","trace[127736725] 'applied index is now lower than readState.Index' (duration: 506.17511ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:31:17.061251Z","caller":"traceutil/trace.go:171","msg":"trace[1205020206] transaction","detail":"{read_only:false; response_revision:1391; number_of_response:1; }","duration":"752.512064ms","start":"2026-07-08T23:31:16.308709Z","end":"2026-07-08T23:31:17.061221Z","steps":["trace[1205020206] 'process raft request' (duration: 246.236384ms)","trace[1205020206] 'compare' (duration: 505.536352ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T23:31:17.061393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:31:16.30869Z","time spent":"752.629975ms","remote":"127.0.0.1:53830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T23:31:17.061428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"747.945091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T23:31:17.061514Z","caller":"traceutil/trace.go:171","msg":"trace[1611700411] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1392; }","duration":"748.085453ms","start":"2026-07-08T23:31:16.313379Z","end":"2026-07-08T23:31:17.061464Z","steps":["trace[1611700411] 'agreement among raft nodes before linearized reading' (duration: 747.920311ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:31:17.061606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:31:16.313359Z","time spent":"748.231685ms","remote":"127.0.0.1:53864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":28,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-07-08T23:31:17.137201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.343066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T23:31:17.137291Z","caller":"traceutil/trace.go:171","msg":"trace[944973097] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1392; }","duration":"488.47163ms","start":"2026-07-08T23:31:16.648809Z","end":"2026-07-08T23:31:17.137281Z","steps":["trace[944973097] 'agreement among raft nodes before linearized reading' (duration: 488.346197ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:31:17.137283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"744.811852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-08T23:31:17.137316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:31:16.648792Z","time spent":"488.51874ms","remote":"127.0.0.1:53558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-08T23:31:17.137346Z","caller":"traceutil/trace.go:171","msg":"trace[441460512] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1392; }","duration":"744.922673ms","start":"2026-07-08T23:31:16.392405Z","end":"2026-07-08T23:31:17.137328Z","steps":["trace[441460512] 'agreement among raft nodes before linearized reading' (duration: 744.810382ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:31:17.137329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"799.272197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-08T23:31:17.137381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:31:16.392388Z","time spent":"744.987305ms","remote":"127.0.0.1:53568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-08T23:31:17.137392Z","caller":"traceutil/trace.go:171","msg":"trace[871025647] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1392; }","duration":"799.372829ms","start":"2026-07-08T23:31:16.338007Z","end":"2026-07-08T23:31:17.13738Z","steps":["trace[871025647] 'agreement among raft nodes before linearized reading' (duration: 799.182206ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:31:17.137421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:31:16.337992Z","time spent":"799.423429ms","remote":"127.0.0.1:53830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-08T23:31:17.137462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.313254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/kube-node-lease/\" range_end:\"/registry/persistentvolumeclaims/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T23:31:17.137486Z","caller":"traceutil/trace.go:171","msg":"trace[455903320] range","detail":"{range_begin:/registry/persistentvolumeclaims/kube-node-lease/; range_end:/registry/persistentvolumeclaims/kube-node-lease0; response_count:0; response_revision:1392; }","duration":"241.359085ms","start":"2026-07-08T23:31:16.896119Z","end":"2026-07-08T23:31:17.137479Z","steps":["trace[455903320] 'agreement among raft nodes before linearized reading' (duration: 241.323364ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:31:17.137621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.239976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-08T23:31:17.137641Z","caller":"traceutil/trace.go:171","msg":"trace[1348024895] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1392; }","duration":"314.286618ms","start":"2026-07-08T23:31:16.823349Z","end":"2026-07-08T23:31:17.137636Z","steps":["trace[1348024895] 'agreement among raft nodes before linearized reading' (duration: 314.211807ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:31:17.137654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:31:16.823334Z","time spent":"314.317358ms","remote":"127.0.0.1:53830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-08T23:31:17.137751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.614081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-08T23:31:17.137768Z","caller":"traceutil/trace.go:171","msg":"trace[731634258] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1392; }","duration":"363.669211ms","start":"2026-07-08T23:31:16.774095Z","end":"2026-07-08T23:31:17.137764Z","steps":["trace[731634258] 'agreement among raft nodes before linearized reading' (duration: 363.625891ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:31:17.137782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:31:16.774077Z","time spent":"363.700551ms","remote":"127.0.0.1:53830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-08T23:31:17.137995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.710014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-08T23:31:17.138018Z","caller":"traceutil/trace.go:171","msg":"trace[677154353] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1392; }","duration":"206.762564ms","start":"2026-07-08T23:31:16.931251Z","end":"2026-07-08T23:31:17.138013Z","steps":["trace[677154353] 'agreement among raft nodes before linearized reading' (duration: 205.883423ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:31:19.585296Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933267054090265899,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-08T23:31:19.645294Z","caller":"traceutil/trace.go:171","msg":"trace[745488988] linearizableReadLoop","detail":"{readStateIndex:1459; appliedIndex:1458; }","duration":"560.285643ms","start":"2026-07-08T23:31:19.084987Z","end":"2026-07-08T23:31:19.645272Z","steps":["trace[745488988] 'read index received' (duration: 560.072561ms)","trace[745488988] 'applied index is now lower than readState.Index' (duration: 211.692µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:31:19.645358Z","caller":"traceutil/trace.go:171","msg":"trace[1099778066] transaction","detail":"{read_only:false; response_revision:1399; number_of_response:1; }","duration":"579.747862ms","start":"2026-07-08T23:31:19.065584Z","end":"2026-07-08T23:31:19.645332Z","steps":["trace[1099778066] 'process raft request' (duration: 579.522129ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:31:19.645486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.472965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/local-path-storage/\" range_end:\"/registry/persistentvolumeclaims/local-path-storage0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-08T23:31:19.645489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:31:19.065562Z","time spent":"579.856123ms","remote":"127.0.0.1:53830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T23:31:19.645527Z","caller":"traceutil/trace.go:171","msg":"trace[1111202743] range","detail":"{range_begin:/registry/persistentvolumeclaims/local-path-storage/; range_end:/registry/persistentvolumeclaims/local-path-storage0; response_count:0; response_revision:1399; }","duration":"560.558386ms","start":"2026-07-08T23:31:19.084958Z","end":"2026-07-08T23:31:19.645516Z","steps":["trace[1111202743] 'agreement among raft nodes before linearized reading' (duration: 560.475055ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:31:19.645563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:31:19.084944Z","time spent":"560.608756ms","remote":"127.0.0.1:53724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumeclaims/local-path-storage/\" range_end:\"/registry/persistentvolumeclaims/local-path-storage0\" limit:500 "} {"level":"warn","ts":"2026-07-08T23:31:19.645622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.327268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-08T23:31:19.645684Z","caller":"traceutil/trace.go:171","msg":"trace[837529982] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1399; }","duration":"455.41979ms","start":"2026-07-08T23:31:19.190248Z","end":"2026-07-08T23:31:19.645668Z","steps":["trace[837529982] 'agreement among raft nodes before linearized reading' (duration: 455.250498ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:31:19.645724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:31:19.190236Z","time spent":"455.47824ms","remote":"127.0.0.1:53830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-08T23:31:19.645716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.624015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/local-path-storage/\" range_end:\"/registry/rolebindings/local-path-storage0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-08T23:31:19.645757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.666253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/cilium\" ","response":"range_response_count:1 size:14480"} {"level":"info","ts":"2026-07-08T23:31:19.645776Z","caller":"traceutil/trace.go:171","msg":"trace[995934404] range","detail":"{range_begin:/registry/rolebindings/local-path-storage/; range_end:/registry/rolebindings/local-path-storage0; response_count:0; response_revision:1399; }","duration":"491.716867ms","start":"2026-07-08T23:31:19.154044Z","end":"2026-07-08T23:31:19.645761Z","steps":["trace[995934404] 'agreement among raft nodes before linearized reading' (duration: 491.621565ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:31:19.645811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.406807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/kube-proxy\" ","response":"range_response_count:1 size:2918"} {"level":"info","ts":"2026-07-08T23:31:19.645816Z","caller":"traceutil/trace.go:171","msg":"trace[535663489] range","detail":"{range_begin:/registry/daemonsets/kube-system/cilium; range_end:; response_count:1; response_revision:1399; }","duration":"558.727874ms","start":"2026-07-08T23:31:19.087072Z","end":"2026-07-08T23:31:19.6458Z","steps":["trace[535663489] 'agreement among raft nodes before linearized reading' (duration: 558.535062ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:31:19.64584Z","caller":"traceutil/trace.go:171","msg":"trace[1211901642] range","detail":"{range_begin:/registry/daemonsets/kube-system/kube-proxy; range_end:; response_count:1; response_revision:1399; }","duration":"539.459177ms","start":"2026-07-08T23:31:19.106373Z","end":"2026-07-08T23:31:19.645832Z","steps":["trace[1211901642] 'agreement among raft nodes before linearized reading' (duration: 539.407747ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:31:19.645757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.020542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-08T23:31:19.645861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:31:19.106362Z","time spent":"539.494728ms","remote":"127.0.0.1:54068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":2941,"request content":"key:\"/registry/daemonsets/kube-system/kube-proxy\" "} {"level":"info","ts":"2026-07-08T23:31:19.645869Z","caller":"traceutil/trace.go:171","msg":"trace[997260649] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1399; }","duration":"454.182154ms","start":"2026-07-08T23:31:19.191678Z","end":"2026-07-08T23:31:19.64586Z","steps":["trace[997260649] 'agreement among raft nodes before linearized reading' (duration: 453.933091ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:31:19.645867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:31:19.087068Z","time spent":"558.783715ms","remote":"127.0.0.1:54068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":14503,"request content":"key:\"/registry/daemonsets/kube-system/cilium\" "} {"level":"warn","ts":"2026-07-08T23:31:19.645895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:31:19.191662Z","time spent":"454.226845ms","remote":"127.0.0.1:53830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-08T23:31:19.645906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.663942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-08T23:31:19.645835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:31:19.15403Z","time spent":"491.794897ms","remote":"127.0.0.1:53920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":91,"response count":0,"response size":28,"request content":"key:\"/registry/rolebindings/local-path-storage/\" range_end:\"/registry/rolebindings/local-path-storage0\" limit:500 "} {"level":"info","ts":"2026-07-08T23:31:19.645945Z","caller":"traceutil/trace.go:171","msg":"trace[29640343] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1399; }","duration":"455.748253ms","start":"2026-07-08T23:31:19.190186Z","end":"2026-07-08T23:31:19.645934Z","steps":["trace[29640343] 'agreement among raft nodes before linearized reading' (duration: 455.604681ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:31:19.645977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:31:19.190165Z","time spent":"455.805263ms","remote":"127.0.0.1:53830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-08T23:31:19.646216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.467218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-08T23:31:19.646257Z","caller":"traceutil/trace.go:171","msg":"trace[404902062] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1399; }","duration":"454.529438ms","start":"2026-07-08T23:31:19.191716Z","end":"2026-07-08T23:31:19.646246Z","steps":["trace[404902062] 'agreement among raft nodes before linearized reading' (duration: 454.382276ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:31:19.646398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:31:19.191706Z","time spent":"454.658699ms","remote":"127.0.0.1:53830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "}