{"level":"warn","ts":"2026-04-15T05:02:33.58897Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-15T05:02:33.589065Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.222: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.222:2380","--initial-cluster=instance=https://199.204.45.222:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.222:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.222: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-04-15T05:02:33.589161Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-15T05:02:33.589174Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.222:2380"]} {"level":"info","ts":"2026-04-15T05:02:33.589223Z","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-04-15T05:02:33.58987Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.222:2379"]} {"level":"info","ts":"2026-04-15T05:02:33.589971Z","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.222:2380"],"listen-peer-urls":["https://199.204.45.222:2380"],"advertise-client-urls":["https://199.204.45.222:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.222:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.222: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-04-15T05:02:34.989032Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"1.398788617s"} {"level":"info","ts":"2026-04-15T05:02:35.089433Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"380f6760faea5f9e","cluster-id":"b87e8aca83a17eab"} {"level":"info","ts":"2026-04-15T05:02:35.089623Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e switched to configuration voters=()"} {"level":"info","ts":"2026-04-15T05:02:35.089717Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became follower at term 0"} {"level":"info","ts":"2026-04-15T05:02:35.089739Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 380f6760faea5f9e [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-15T05:02:35.089766Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became follower at term 1"} {"level":"info","ts":"2026-04-15T05:02:35.089845Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e switched to configuration voters=(4039561056998809502)"} {"level":"warn","ts":"2026-04-15T05:02:35.103717Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-15T05:02:35.106948Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-15T05:02:35.10794Z","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-04-15T05:02:35.110173Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"380f6760faea5f9e","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-15T05:02:35.110313Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"380f6760faea5f9e","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-15T05:02:35.110504Z","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-04-15T05:02:35.110566Z","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-04-15T05:02:35.110586Z","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-04-15T05:02:35.112778Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e switched to configuration voters=(4039561056998809502)"} {"level":"info","ts":"2026-04-15T05:02:35.113138Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b87e8aca83a17eab","local-member-id":"380f6760faea5f9e","added-peer-id":"380f6760faea5f9e","added-peer-peer-urls":["https://199.204.45.222:2380"]} {"level":"info","ts":"2026-04-15T05:02:35.114887Z","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-04-15T05:02:35.115207Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"380f6760faea5f9e","initial-advertise-peer-urls":["https://199.204.45.222:2380"],"listen-peer-urls":["https://199.204.45.222:2380"],"advertise-client-urls":["https://199.204.45.222:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.222:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-15T05:02:35.115257Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-15T05:02:35.115032Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.222:2380"} {"level":"info","ts":"2026-04-15T05:02:35.115661Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.222:2380"} {"level":"info","ts":"2026-04-15T05:02:35.790713Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e is starting a new election at term 1"} {"level":"info","ts":"2026-04-15T05:02:35.79079Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became pre-candidate at term 1"} {"level":"info","ts":"2026-04-15T05:02:35.790846Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e received MsgPreVoteResp from 380f6760faea5f9e at term 1"} {"level":"info","ts":"2026-04-15T05:02:35.790865Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became candidate at term 2"} {"level":"info","ts":"2026-04-15T05:02:35.790874Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e received MsgVoteResp from 380f6760faea5f9e at term 2"} {"level":"info","ts":"2026-04-15T05:02:35.790887Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became leader at term 2"} {"level":"info","ts":"2026-04-15T05:02:35.790916Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 380f6760faea5f9e elected leader 380f6760faea5f9e at term 2"} {"level":"info","ts":"2026-04-15T05:02:35.792112Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"380f6760faea5f9e","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.222:2379]}","request-path":"/0/members/380f6760faea5f9e/attributes","cluster-id":"b87e8aca83a17eab","publish-timeout":"7s"} {"level":"info","ts":"2026-04-15T05:02:35.792079Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-15T05:02:35.792115Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-15T05:02:35.792145Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-15T05:02:35.792421Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-15T05:02:35.79248Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-15T05:02:35.793024Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b87e8aca83a17eab","local-member-id":"380f6760faea5f9e","cluster-version":"3.5"} {"level":"info","ts":"2026-04-15T05:02:35.793152Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-15T05:02:35.793189Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-15T05:02:35.793979Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.222:2379"} {"level":"info","ts":"2026-04-15T05:02:35.797221Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-15T05:02:40.891744Z","caller":"traceutil/trace.go:171","msg":"trace[1548772062] transaction","detail":"{read_only:false; response_revision:294; number_of_response:1; }","duration":"102.947982ms","start":"2026-04-15T05:02:40.788749Z","end":"2026-04-15T05:02:40.891697Z","steps":["trace[1548772062] 'process raft request' (duration: 91.398535ms)","trace[1548772062] 'compare' (duration: 11.128985ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T05:02:40.899514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.9149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T05:02:40.899682Z","caller":"traceutil/trace.go:171","msg":"trace[1893350635] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:295; }","duration":"103.117337ms","start":"2026-04-15T05:02:40.796535Z","end":"2026-04-15T05:02:40.899652Z","steps":["trace[1893350635] 'agreement among raft nodes before linearized reading' (duration: 102.887981ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:03:01.568924Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6890117719661630970,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-15T05:03:02.069237Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6890117719661630970,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-15T05:03:02.377404Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.485839075s","expected-duration":"1s"} {"level":"info","ts":"2026-04-15T05:03:02.377768Z","caller":"traceutil/trace.go:171","msg":"trace[615196512] linearizableReadLoop","detail":"{readStateIndex:484; appliedIndex:483; }","duration":"1.309290806s","start":"2026-04-15T05:03:01.068449Z","end":"2026-04-15T05:03:02.37774Z","steps":["trace[615196512] 'read index received' (duration: 1.309098021s)","trace[615196512] 'applied index is now lower than readState.Index' (duration: 191.605µs)"],"step_count":2} {"level":"info","ts":"2026-04-15T05:03:02.377855Z","caller":"traceutil/trace.go:171","msg":"trace[1572415261] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"1.486398241s","start":"2026-04-15T05:03:00.891431Z","end":"2026-04-15T05:03:02.37783Z","steps":["trace[1572415261] 'process raft request' (duration: 1.486162565s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:03:02.377998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.309508811s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-15T05:03:02.378005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.240177547s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-15T05:03:02.378048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.576174ms","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-04-15T05:03:02.378086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"915.319704ms","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-04-15T05:03:02.378095Z","caller":"traceutil/trace.go:171","msg":"trace[1706248194] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:470; }","duration":"1.240360923s","start":"2026-04-15T05:03:01.137717Z","end":"2026-04-15T05:03:02.378078Z","steps":["trace[1706248194] 'agreement among raft nodes before linearized reading' (duration: 1.240177008s)"],"step_count":1} {"level":"info","ts":"2026-04-15T05:03:02.378088Z","caller":"traceutil/trace.go:171","msg":"trace[926375784] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:470; }","duration":"1.309650856s","start":"2026-04-15T05:03:01.06841Z","end":"2026-04-15T05:03:02.378061Z","steps":["trace[926375784] 'agreement among raft nodes before linearized reading' (duration: 1.30945004s)"],"step_count":1} {"level":"info","ts":"2026-04-15T05:03:02.378124Z","caller":"traceutil/trace.go:171","msg":"trace[618737843] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:470; }","duration":"915.399866ms","start":"2026-04-15T05:03:01.462715Z","end":"2026-04-15T05:03:02.378115Z","steps":["trace[618737843] 'agreement among raft nodes before linearized reading' (duration: 915.283433ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T05:03:02.378113Z","caller":"traceutil/trace.go:171","msg":"trace[1580858810] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:470; }","duration":"534.682598ms","start":"2026-04-15T05:03:01.843413Z","end":"2026-04-15T05:03:02.378096Z","steps":["trace[1580858810] 'agreement among raft nodes before linearized reading' (duration: 534.543244ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:03:02.37815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:01.462695Z","time spent":"915.449097ms","remote":"127.0.0.1:56042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-15T05:03:02.378144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.018475187s","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-04-15T05:03:02.378167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:01.843393Z","time spent":"534.756379ms","remote":"127.0.0.1:56042","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-04-15T05:03:02.378158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:01.068392Z","time spent":"1.309756068s","remote":"127.0.0.1:56042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-15T05:03:02.378151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:01.137701Z","time spent":"1.240436505s","remote":"127.0.0.1:55764","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-04-15T05:03:02.37823Z","caller":"traceutil/trace.go:171","msg":"trace[1105765453] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:470; }","duration":"1.018589141s","start":"2026-04-15T05:03:01.359618Z","end":"2026-04-15T05:03:02.378208Z","steps":["trace[1105765453] 'agreement among raft nodes before linearized reading' (duration: 1.018416696s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:03:02.378275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:01.3596Z","time spent":"1.018663152s","remote":"127.0.0.1:56042","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-04-15T05:03:02.378695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:00.891407Z","time spent":"1.486505414s","remote":"127.0.0.1:56042","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-04-15T05:03:07.660785Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6890117719661631059,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-15T05:03:07.891572Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.21369444s","expected-duration":"1s"} {"level":"info","ts":"2026-04-15T05:03:07.891894Z","caller":"traceutil/trace.go:171","msg":"trace[600604539] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"1.21408532s","start":"2026-04-15T05:03:06.677781Z","end":"2026-04-15T05:03:07.891867Z","steps":["trace[600604539] 'process raft request' (duration: 1.213968616s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:03:07.892035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:06.677766Z","time spent":"1.214206514s","remote":"127.0.0.1:55820","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-04-15T05:03:07.902918Z","caller":"traceutil/trace.go:171","msg":"trace[361348230] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"1.219466288s","start":"2026-04-15T05:03:06.683431Z","end":"2026-04-15T05:03:07.902898Z","steps":["trace[361348230] 'process raft request' (duration: 1.219248532s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:03:07.903001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:06.683413Z","time spent":"1.21954815s","remote":"127.0.0.1:55820","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-04-15T05:03:07.903057Z","caller":"traceutil/trace.go:171","msg":"trace[2080664962] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"1.219517089s","start":"2026-04-15T05:03:06.683489Z","end":"2026-04-15T05:03:07.903007Z","steps":["trace[2080664962] 'process raft request' (duration: 1.219304823s)"],"step_count":1} {"level":"info","ts":"2026-04-15T05:03:07.903175Z","caller":"traceutil/trace.go:171","msg":"trace[385970009] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"1.209167816s","start":"2026-04-15T05:03:06.693991Z","end":"2026-04-15T05:03:07.903159Z","steps":["trace[385970009] 'process raft request' (duration: 1.20893877s)"],"step_count":1} {"level":"info","ts":"2026-04-15T05:03:07.903151Z","caller":"traceutil/trace.go:171","msg":"trace[1958335386] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"1.209153465s","start":"2026-04-15T05:03:06.693942Z","end":"2026-04-15T05:03:07.903095Z","steps":["trace[1958335386] 'process raft request' (duration: 1.208929689s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:03:07.903221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:06.693988Z","time spent":"1.209215407s","remote":"127.0.0.1:55820","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-04-15T05:03:07.903329Z","caller":"traceutil/trace.go:171","msg":"trace[863007539] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"1.203582253s","start":"2026-04-15T05:03:06.699718Z","end":"2026-04-15T05:03:07.903301Z","steps":["trace[863007539] 'process raft request' (duration: 1.203458549s)"],"step_count":1} {"level":"info","ts":"2026-04-15T05:03:07.903407Z","caller":"traceutil/trace.go:171","msg":"trace[325061888] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"1.199687415s","start":"2026-04-15T05:03:06.703703Z","end":"2026-04-15T05:03:07.903391Z","steps":["trace[325061888] 'process raft request' (duration: 1.199543732s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:03:07.903345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:06.693935Z","time spent":"1.20931371s","remote":"127.0.0.1:55820","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-04-15T05:03:07.903453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:06.703697Z","time spent":"1.199737256s","remote":"127.0.0.1:55820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-15T05:03:07.903571Z","caller":"traceutil/trace.go:171","msg":"trace[582921422] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"1.183684616s","start":"2026-04-15T05:03:06.719846Z","end":"2026-04-15T05:03:07.90353Z","steps":["trace[582921422] 'process raft request' (duration: 1.183544662s)"],"step_count":1} {"level":"info","ts":"2026-04-15T05:03:07.903316Z","caller":"traceutil/trace.go:171","msg":"trace[1611110288] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"1.209165765s","start":"2026-04-15T05:03:06.694126Z","end":"2026-04-15T05:03:07.903292Z","steps":["trace[1611110288] 'process raft request' (duration: 1.20897597s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:03:07.903661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:06.719839Z","time spent":"1.183798169s","remote":"127.0.0.1:55820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-15T05:03:07.903698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:06.694121Z","time spent":"1.209535215s","remote":"127.0.0.1:55820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-15T05:03:07.903518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:06.699712Z","time spent":"1.203710436s","remote":"127.0.0.1:55820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-15T05:03:07.903678Z","caller":"traceutil/trace.go:171","msg":"trace[1906166930] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"1.190552805s","start":"2026-04-15T05:03:06.712964Z","end":"2026-04-15T05:03:07.903517Z","steps":["trace[1906166930] 'process raft request' (duration: 1.190345429s)"],"step_count":1} {"level":"info","ts":"2026-04-15T05:03:07.903834Z","caller":"traceutil/trace.go:171","msg":"trace[114105717] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"1.171094399s","start":"2026-04-15T05:03:06.732726Z","end":"2026-04-15T05:03:07.90382Z","steps":["trace[114105717] 'process raft request' (duration: 1.17073651s)"],"step_count":1} {"level":"info","ts":"2026-04-15T05:03:07.903853Z","caller":"traceutil/trace.go:171","msg":"trace[1091838781] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"1.073241611s","start":"2026-04-15T05:03:06.830603Z","end":"2026-04-15T05:03:07.903844Z","steps":["trace[1091838781] 'process raft request' (duration: 1.073036076s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:03:07.903861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"744.000347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-15T05:03:07.903891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:06.830573Z","time spent":"1.073300693s","remote":"127.0.0.1:55820","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-04-15T05:03:07.903749Z","caller":"traceutil/trace.go:171","msg":"trace[769189601] linearizableReadLoop","detail":"{readStateIndex:543; appliedIndex:531; }","duration":"743.875224ms","start":"2026-04-15T05:03:07.159851Z","end":"2026-04-15T05:03:07.903727Z","steps":["trace[769189601] 'read index received' (duration: 731.967387ms)","trace[769189601] 'applied index is now lower than readState.Index' (duration: 11.906837ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T05:03:07.90393Z","caller":"traceutil/trace.go:171","msg":"trace[1667979636] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:528; }","duration":"744.118031ms","start":"2026-04-15T05:03:07.159797Z","end":"2026-04-15T05:03:07.903915Z","steps":["trace[1667979636] 'agreement among raft nodes before linearized reading' (duration: 743.990787ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:03:07.903267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:06.683486Z","time spent":"1.219677154s","remote":"127.0.0.1:55820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-15T05:03:07.903983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:07.159771Z","time spent":"744.199813ms","remote":"127.0.0.1:55762","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-04-15T05:03:07.903952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:06.712957Z","time spent":"1.190884883s","remote":"127.0.0.1:55820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-15T05:03:07.904073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:06.732714Z","time spent":"1.171158141s","remote":"127.0.0.1:55820","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-04-15T05:03:08.509769Z","caller":"traceutil/trace.go:171","msg":"trace[782603812] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"433.768645ms","start":"2026-04-15T05:03:08.075983Z","end":"2026-04-15T05:03:08.509752Z","steps":["trace[782603812] 'process raft request' (duration: 426.650589ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:03:08.50987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:08.075962Z","time spent":"433.859208ms","remote":"127.0.0.1:55820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31121,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T05:03:08.510626Z","caller":"traceutil/trace.go:171","msg":"trace[1368693520] linearizableReadLoop","detail":"{readStateIndex:573; appliedIndex:570; }","duration":"372.323768ms","start":"2026-04-15T05:03:08.13809Z","end":"2026-04-15T05:03:08.510413Z","steps":["trace[1368693520] 'read index received' (duration: 364.623127ms)","trace[1368693520] 'applied index is now lower than readState.Index' (duration: 7.699931ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T05:03:08.510655Z","caller":"traceutil/trace.go:171","msg":"trace[252501082] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"429.816466ms","start":"2026-04-15T05:03:08.080807Z","end":"2026-04-15T05:03:08.510624Z","steps":["trace[252501082] 'process raft request' (duration: 429.415506ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T05:03:08.510728Z","caller":"traceutil/trace.go:171","msg":"trace[1615206674] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"427.40601ms","start":"2026-04-15T05:03:08.08331Z","end":"2026-04-15T05:03:08.510716Z","steps":["trace[1615206674] 'process raft request' (duration: 427.053231ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:03:08.510811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:08.080792Z","time spent":"429.94109ms","remote":"127.0.0.1:55820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":97665,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-15T05:03:08.510889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.807791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T05:03:08.510919Z","caller":"traceutil/trace.go:171","msg":"trace[1243455565] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:555; }","duration":"372.872273ms","start":"2026-04-15T05:03:08.138039Z","end":"2026-04-15T05:03:08.510911Z","steps":["trace[1243455565] 'agreement among raft nodes before linearized reading' (duration: 372.8031ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:03:08.510941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:08.13802Z","time spent":"372.915983ms","remote":"127.0.0.1:55764","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-04-15T05:03:08.510821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:08.0833Z","time spent":"427.452593ms","remote":"127.0.0.1:55820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23066,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T05:03:09.638898Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6890117719661631130,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-15T05:03:10.139938Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6890117719661631130,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-15T05:03:10.640453Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6890117719661631130,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-15T05:03:11.138131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000121613s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-15T05:03:11.13823Z","caller":"traceutil/trace.go:171","msg":"trace[1676223172] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000264387s","start":"2026-04-15T05:03:09.137939Z","end":"2026-04-15T05:03:11.138203Z","steps":["trace[1676223172] 'agreement among raft nodes before linearized reading' (duration: 2.000144774s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:03:11.138309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:09.137924Z","time spent":"2.000368301s","remote":"127.0.0.1:55762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/15 05:03:11 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-15T05:03:11.14064Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6890117719661631130,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-15T05:03:11.202476Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.091829043s","expected-duration":"1s"} {"level":"info","ts":"2026-04-15T05:03:11.220347Z","caller":"traceutil/trace.go:171","msg":"trace[713472907] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"2.109862687s","start":"2026-04-15T05:03:09.110456Z","end":"2026-04-15T05:03:11.220318Z","steps":["trace[713472907] 'process raft request' (duration: 2.092226593s)","trace[713472907] 'compare' (duration: 17.489721ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T05:03:11.220521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:09.110428Z","time spent":"2.109997861s","remote":"127.0.0.1:55820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":74754,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T05:03:11.222757Z","caller":"traceutil/trace.go:171","msg":"trace[480345800] linearizableReadLoop","detail":"{readStateIndex:610; appliedIndex:607; }","duration":"2.084759738s","start":"2026-04-15T05:03:09.13797Z","end":"2026-04-15T05:03:11.22273Z","steps":["trace[480345800] 'read index received' (duration: 2.064850341s)","trace[480345800] 'applied index is now lower than readState.Index' (duration: 19.908267ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T05:03:11.222845Z","caller":"traceutil/trace.go:171","msg":"trace[1598519380] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"2.019878815s","start":"2026-04-15T05:03:09.202932Z","end":"2026-04-15T05:03:11.222811Z","steps":["trace[1598519380] 'process raft request' (duration: 2.018316492s)"],"step_count":1} {"level":"info","ts":"2026-04-15T05:03:11.222896Z","caller":"traceutil/trace.go:171","msg":"trace[908852918] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"1.951514288s","start":"2026-04-15T05:03:09.271368Z","end":"2026-04-15T05:03:11.222882Z","steps":["trace[908852918] 'process raft request' (duration: 1.951090177s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:03:11.222996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:09.271348Z","time spent":"1.951581009s","remote":"127.0.0.1:55820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":207462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-15T05:03:11.223002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:09.202908Z","time spent":"2.020002269s","remote":"127.0.0.1:55820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570081,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T05:03:11.22412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"705.137459ms","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-04-15T05:03:11.224158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"682.261291ms","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-04-15T05:03:11.224171Z","caller":"traceutil/trace.go:171","msg":"trace[818564385] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:591; }","duration":"705.222342ms","start":"2026-04-15T05:03:10.518935Z","end":"2026-04-15T05:03:11.224158Z","steps":["trace[818564385] 'agreement among raft nodes before linearized reading' (duration: 704.207344ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T05:03:11.224201Z","caller":"traceutil/trace.go:171","msg":"trace[1081346807] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:591; }","duration":"682.324064ms","start":"2026-04-15T05:03:10.541862Z","end":"2026-04-15T05:03:11.224186Z","steps":["trace[1081346807] 'agreement among raft nodes before linearized reading' (duration: 682.200801ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:03:11.224216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:10.51892Z","time spent":"705.288814ms","remote":"127.0.0.1:56042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-15T05:03:11.224233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:10.541849Z","time spent":"682.377896ms","remote":"127.0.0.1:56042","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-04-15T05:03:11.226807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"676.632056ms","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-04-15T05:03:11.226865Z","caller":"traceutil/trace.go:171","msg":"trace[250608857] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:591; }","duration":"676.72053ms","start":"2026-04-15T05:03:10.550129Z","end":"2026-04-15T05:03:11.22685Z","steps":["trace[250608857] 'agreement among raft nodes before linearized reading' (duration: 673.307016ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:03:11.226905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:10.550113Z","time spent":"676.783591ms","remote":"127.0.0.1:56042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-15T05:03:11.22763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"677.488311ms","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-04-15T05:03:11.227668Z","caller":"traceutil/trace.go:171","msg":"trace[1410780623] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:591; }","duration":"677.563172ms","start":"2026-04-15T05:03:10.550097Z","end":"2026-04-15T05:03:11.22766Z","steps":["trace[1410780623] 'agreement among raft nodes before linearized reading' (duration: 673.81661ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:03:11.22769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:10.550079Z","time spent":"677.605473ms","remote":"127.0.0.1:56042","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-04-15T05:03:15.551675Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6890117719661631264,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-15T05:03:16.052059Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6890117719661631264,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-15T05:03:16.102885Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.063629148s","expected-duration":"1s"} {"level":"info","ts":"2026-04-15T05:03:16.103898Z","caller":"traceutil/trace.go:171","msg":"trace[1113294994] linearizableReadLoop","detail":"{readStateIndex:642; appliedIndex:641; }","duration":"1.052547143s","start":"2026-04-15T05:03:15.051322Z","end":"2026-04-15T05:03:16.103869Z","steps":["trace[1113294994] 'read index received' (duration: 1.051709781s)","trace[1113294994] 'applied index is now lower than readState.Index' (duration: 836.262µs)"],"step_count":2} {"level":"info","ts":"2026-04-15T05:03:16.103957Z","caller":"traceutil/trace.go:171","msg":"trace[1099285792] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"1.066325012s","start":"2026-04-15T05:03:15.0376Z","end":"2026-04-15T05:03:16.103925Z","steps":["trace[1099285792] 'process raft request' (duration: 1.065718565s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:03:16.104031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.052670777s","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-04-15T05:03:16.104083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.051923306s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T05:03:16.104097Z","caller":"traceutil/trace.go:171","msg":"trace[1891227863] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:622; }","duration":"1.05278828s","start":"2026-04-15T05:03:15.051293Z","end":"2026-04-15T05:03:16.104082Z","steps":["trace[1891227863] 'agreement among raft nodes before linearized reading' (duration: 1.052659407s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:03:16.104106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.052756019s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-15T05:03:16.104148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:15.051278Z","time spent":"1.052857342s","remote":"127.0.0.1:52806","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-04-15T05:03:16.104126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:15.037553Z","time spent":"1.066480826s","remote":"127.0.0.1:55876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036225,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T05:03:16.104172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"979.350473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-15T05:03:16.104194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"979.430855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T05:03:16.104214Z","caller":"traceutil/trace.go:171","msg":"trace[1343002414] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:622; }","duration":"1.052871082s","start":"2026-04-15T05:03:15.051311Z","end":"2026-04-15T05:03:16.104182Z","steps":["trace[1343002414] 'agreement among raft nodes before linearized reading' (duration: 1.052696938s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:03:16.10427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"905.898495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T05:03:16.104285Z","caller":"traceutil/trace.go:171","msg":"trace[1255800043] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:622; }","duration":"905.934976ms","start":"2026-04-15T05:03:15.198346Z","end":"2026-04-15T05:03:16.104281Z","steps":["trace[1255800043] 'agreement among raft nodes before linearized reading' (duration: 905.910385ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:03:16.104311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:15.198329Z","time spent":"905.977038ms","remote":"127.0.0.1:52852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-04-15T05:03:16.104312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"849.516546ms","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-04-15T05:03:16.104314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"979.533517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-15T05:03:16.104387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:15.051272Z","time spent":"1.053006665s","remote":"127.0.0.1:52806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 "} {"level":"info","ts":"2026-04-15T05:03:16.104402Z","caller":"traceutil/trace.go:171","msg":"trace[570734200] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:622; }","duration":"979.680743ms","start":"2026-04-15T05:03:15.124711Z","end":"2026-04-15T05:03:16.104392Z","steps":["trace[570734200] 'agreement among raft nodes before linearized reading' (duration: 979.548409ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:03:16.104448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"851.264174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-15T05:03:16.104452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:15.124694Z","time spent":"979.743884ms","remote":"127.0.0.1:52824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:500 "} {"level":"info","ts":"2026-04-15T05:03:16.104137Z","caller":"traceutil/trace.go:171","msg":"trace[1940629009] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:622; }","duration":"1.05201952s","start":"2026-04-15T05:03:15.052104Z","end":"2026-04-15T05:03:16.104123Z","steps":["trace[1940629009] 'agreement among raft nodes before linearized reading' (duration: 1.051920917s)"],"step_count":1} {"level":"info","ts":"2026-04-15T05:03:16.104481Z","caller":"traceutil/trace.go:171","msg":"trace[1264688888] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:622; }","duration":"851.320306ms","start":"2026-04-15T05:03:15.253151Z","end":"2026-04-15T05:03:16.104472Z","steps":["trace[1264688888] 'agreement among raft nodes before linearized reading' (duration: 851.270275ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:03:16.104506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:15.05209Z","time spent":"1.052403789s","remote":"127.0.0.1:52806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 "} {"level":"warn","ts":"2026-04-15T05:03:16.104543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"963.419495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-15T05:03:16.104549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"905.802412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T05:03:16.104562Z","caller":"traceutil/trace.go:171","msg":"trace[1031746500] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:622; }","duration":"963.446416ms","start":"2026-04-15T05:03:15.141111Z","end":"2026-04-15T05:03:16.104557Z","steps":["trace[1031746500] 'agreement among raft nodes before linearized reading' (duration: 963.422025ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:03:16.104176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"905.569556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-15T05:03:16.104576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:15.141106Z","time spent":"963.466267ms","remote":"127.0.0.1:52838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"info","ts":"2026-04-15T05:03:16.104573Z","caller":"traceutil/trace.go:171","msg":"trace[472645563] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:622; }","duration":"905.838063ms","start":"2026-04-15T05:03:15.198728Z","end":"2026-04-15T05:03:16.104566Z","steps":["trace[472645563] 'agreement among raft nodes before linearized reading' (duration: 905.801122ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T05:03:16.104595Z","caller":"traceutil/trace.go:171","msg":"trace[143846966] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:622; }","duration":"906.034009ms","start":"2026-04-15T05:03:15.198546Z","end":"2026-04-15T05:03:16.10458Z","steps":["trace[143846966] 'agreement among raft nodes before linearized reading' (duration: 905.544886ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:03:16.104599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:15.198724Z","time spent":"905.868644ms","remote":"127.0.0.1:52852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:500 "} {"level":"warn","ts":"2026-04-15T05:03:16.10461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.024925205s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-15T05:03:16.104635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:15.198531Z","time spent":"906.09303ms","remote":"127.0.0.1:52852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-15T05:03:16.104695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.004958386s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T05:03:16.104734Z","caller":"traceutil/trace.go:171","msg":"trace[1889765899] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:622; }","duration":"1.005007588s","start":"2026-04-15T05:03:15.099717Z","end":"2026-04-15T05:03:16.104725Z","steps":["trace[1889765899] 'agreement among raft nodes before linearized reading' (duration: 1.004956137s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:03:16.104749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"788.552173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-15T05:03:16.104758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"765.303184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-15T05:03:16.104789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"765.377455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-15T05:03:16.104782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.025826339s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-15T05:03:16.104821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"787.958625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T05:03:16.104834Z","caller":"traceutil/trace.go:171","msg":"trace[7679009] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:622; }","duration":"787.985285ms","start":"2026-04-15T05:03:15.316845Z","end":"2026-04-15T05:03:16.10483Z","steps":["trace[7679009] 'agreement among raft nodes before linearized reading' (duration: 787.967825ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:03:16.104832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.006091437s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-15T05:03:16.104852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:15.316838Z","time spent":"788.010618ms","remote":"127.0.0.1:52878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 "} {"level":"info","ts":"2026-04-15T05:03:16.104843Z","caller":"traceutil/trace.go:171","msg":"trace[2138590864] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:622; }","duration":"1.025903571s","start":"2026-04-15T05:03:15.078923Z","end":"2026-04-15T05:03:16.104827Z","steps":["trace[2138590864] 'agreement among raft nodes before linearized reading' (duration: 1.02583044s)"],"step_count":1} {"level":"info","ts":"2026-04-15T05:03:16.104869Z","caller":"traceutil/trace.go:171","msg":"trace[1107882186] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:622; }","duration":"1.006128659s","start":"2026-04-15T05:03:15.098724Z","end":"2026-04-15T05:03:16.104852Z","steps":["trace[1107882186] 'agreement among raft nodes before linearized reading' (duration: 1.006088957s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:03:16.104886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.879912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-15T05:03:16.104891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:15.07891Z","time spent":"1.025966593s","remote":"127.0.0.1:52808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"info","ts":"2026-04-15T05:03:16.104912Z","caller":"traceutil/trace.go:171","msg":"trace[1892329013] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:622; }","duration":"732.937474ms","start":"2026-04-15T05:03:15.371966Z","end":"2026-04-15T05:03:16.104904Z","steps":["trace[1892329013] 'agreement among raft nodes before linearized reading' (duration: 732.902193ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:03:16.104928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"788.699125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T05:03:16.104943Z","caller":"traceutil/trace.go:171","msg":"trace[2075243751] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:622; }","duration":"788.732895ms","start":"2026-04-15T05:03:15.316206Z","end":"2026-04-15T05:03:16.104939Z","steps":["trace[2075243751] 'agreement among raft nodes before linearized reading' (duration: 788.708605ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:03:16.104944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:15.371942Z","time spent":"732.995085ms","remote":"127.0.0.1:52900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 "} {"level":"warn","ts":"2026-04-15T05:03:16.104961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:15.316194Z","time spent":"788.762979ms","remote":"127.0.0.1:52878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 "} {"level":"warn","ts":"2026-04-15T05:03:16.104982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"751.742121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T05:03:16.105014Z","caller":"traceutil/trace.go:171","msg":"trace[431653653] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:622; }","duration":"751.803993ms","start":"2026-04-15T05:03:15.3532Z","end":"2026-04-15T05:03:16.105004Z","steps":["trace[431653653] 'agreement among raft nodes before linearized reading' (duration: 751.763562ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:03:16.105022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"751.825703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-15T05:03:16.105051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:15.353184Z","time spent":"751.860243ms","remote":"127.0.0.1:52886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 "} {"level":"info","ts":"2026-04-15T05:03:16.105058Z","caller":"traceutil/trace.go:171","msg":"trace[1378868823] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:622; }","duration":"751.876643ms","start":"2026-04-15T05:03:15.353171Z","end":"2026-04-15T05:03:16.105048Z","steps":["trace[1378868823] 'agreement among raft nodes before linearized reading' (duration: 751.819352ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T05:03:16.104233Z","caller":"traceutil/trace.go:171","msg":"trace[1131846970] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:622; }","duration":"979.445955ms","start":"2026-04-15T05:03:15.124772Z","end":"2026-04-15T05:03:16.104218Z","steps":["trace[1131846970] 'agreement among raft nodes before linearized reading' (duration: 979.301311ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:03:16.105094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:15.353164Z","time spent":"751.918875ms","remote":"127.0.0.1:52894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-15T05:03:16.105107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"802.955377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T05:03:16.105121Z","caller":"traceutil/trace.go:171","msg":"trace[1285806418] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:622; }","duration":"802.986678ms","start":"2026-04-15T05:03:15.30213Z","end":"2026-04-15T05:03:16.105117Z","steps":["trace[1285806418] 'agreement among raft nodes before linearized reading' (duration: 802.965617ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:03:16.105139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:15.302122Z","time spent":"803.013348ms","remote":"127.0.0.1:52866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 "} {"level":"warn","ts":"2026-04-15T05:03:16.105126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:15.124763Z","time spent":"980.34423ms","remote":"127.0.0.1:52824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:10000 "} {"level":"warn","ts":"2026-04-15T05:03:16.104846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"731.910876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T05:03:16.105203Z","caller":"traceutil/trace.go:171","msg":"trace[2099629970] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:622; }","duration":"732.303607ms","start":"2026-04-15T05:03:15.372889Z","end":"2026-04-15T05:03:16.105193Z","steps":["trace[2099629970] 'agreement among raft nodes before linearized reading' (duration: 731.937756ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:03:16.105249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:15.372874Z","time spent":"732.362748ms","remote":"127.0.0.1:52900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 "} {"level":"warn","ts":"2026-04-15T05:03:16.105247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"752.022258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-15T05:03:16.104906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:15.098719Z","time spent":"1.00617962s","remote":"127.0.0.1:52812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":131,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 "} {"level":"info","ts":"2026-04-15T05:03:16.105286Z","caller":"traceutil/trace.go:171","msg":"trace[1692027255] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:622; }","duration":"752.072811ms","start":"2026-04-15T05:03:15.353205Z","end":"2026-04-15T05:03:16.105277Z","steps":["trace[1692027255] 'agreement among raft nodes before linearized reading' (duration: 752.016939ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T05:03:16.104768Z","caller":"traceutil/trace.go:171","msg":"trace[24571350] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:622; }","duration":"788.583803ms","start":"2026-04-15T05:03:15.31618Z","end":"2026-04-15T05:03:16.104763Z","steps":["trace[24571350] 'agreement among raft nodes before linearized reading' (duration: 788.555342ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:03:16.105315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:15.3532Z","time spent":"752.107911ms","remote":"127.0.0.1:52894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 "} {"level":"warn","ts":"2026-04-15T05:03:16.104944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.006233241s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-15T05:03:16.105344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:15.316173Z","time spent":"789.159239ms","remote":"127.0.0.1:52878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"info","ts":"2026-04-15T05:03:16.105372Z","caller":"traceutil/trace.go:171","msg":"trace[2065889983] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:622; }","duration":"1.006673643s","start":"2026-04-15T05:03:15.098687Z","end":"2026-04-15T05:03:16.105361Z","steps":["trace[2065889983] 'agreement among raft nodes before linearized reading' (duration: 1.006238131s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:03:16.105381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"803.82723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T05:03:16.105407Z","caller":"traceutil/trace.go:171","msg":"trace[1399025349] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:622; }","duration":"803.869333ms","start":"2026-04-15T05:03:15.301532Z","end":"2026-04-15T05:03:16.105401Z","steps":["trace[1399025349] 'agreement among raft nodes before linearized reading' (duration: 803.835401ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:03:16.105411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:15.098676Z","time spent":"1.006724225s","remote":"127.0.0.1:52812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-15T05:03:16.105425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:15.30152Z","time spent":"803.900773ms","remote":"127.0.0.1:52866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 "} {"level":"warn","ts":"2026-04-15T05:03:16.10466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"963.743204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-15T05:03:16.104722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.025694816s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-15T05:03:16.104789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:15.099711Z","time spent":"1.005052779s","remote":"127.0.0.1:52812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":131,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 "} {"level":"warn","ts":"2026-04-15T05:03:16.105209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"733.305264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T05:03:16.105503Z","caller":"traceutil/trace.go:171","msg":"trace[112382795] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:622; }","duration":"1.026508758s","start":"2026-04-15T05:03:15.078982Z","end":"2026-04-15T05:03:16.105491Z","steps":["trace[112382795] 'agreement among raft nodes before linearized reading' (duration: 1.025693876s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:03:16.105537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"846.467322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-15T05:03:16.105533Z","caller":"traceutil/trace.go:171","msg":"trace[1662053678] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:622; }","duration":"733.648543ms","start":"2026-04-15T05:03:15.371876Z","end":"2026-04-15T05:03:16.105525Z","steps":["trace[1662053678] 'agreement among raft nodes before linearized reading' (duration: 733.309464ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T05:03:16.105554Z","caller":"traceutil/trace.go:171","msg":"trace[178069698] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:622; }","duration":"846.506624ms","start":"2026-04-15T05:03:15.259043Z","end":"2026-04-15T05:03:16.10555Z","steps":["trace[178069698] 'agreement among raft nodes before linearized reading' (duration: 846.460463ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:03:16.105549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:15.078963Z","time spent":"1.02657164s","remote":"127.0.0.1:52808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:10000 "} {"level":"warn","ts":"2026-04-15T05:03:16.105566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:15.259037Z","time spent":"846.526724ms","remote":"127.0.0.1:56042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-15T05:03:16.105565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:15.371866Z","time spent":"733.689265ms","remote":"127.0.0.1:52900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"info","ts":"2026-04-15T05:03:16.104651Z","caller":"traceutil/trace.go:171","msg":"trace[1916555016] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:622; }","duration":"1.024978777s","start":"2026-04-15T05:03:15.07966Z","end":"2026-04-15T05:03:16.104639Z","steps":["trace[1916555016] 'agreement among raft nodes before linearized reading' (duration: 1.024914125s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:03:16.105607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:15.079653Z","time spent":"1.025942372s","remote":"127.0.0.1:52808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:500 "} {"level":"warn","ts":"2026-04-15T05:03:16.104522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"962.900871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-15T05:03:16.104512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:15.253137Z","time spent":"851.368827ms","remote":"127.0.0.1:56042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-15T05:03:16.104401Z","caller":"traceutil/trace.go:171","msg":"trace[828346549] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:622; }","duration":"849.607958ms","start":"2026-04-15T05:03:15.254778Z","end":"2026-04-15T05:03:16.104386Z","steps":["trace[828346549] 'agreement among raft nodes before linearized reading' (duration: 849.438344ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T05:03:16.105673Z","caller":"traceutil/trace.go:171","msg":"trace[1022998748] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:622; }","duration":"964.079444ms","start":"2026-04-15T05:03:15.141585Z","end":"2026-04-15T05:03:16.105665Z","steps":["trace[1022998748] 'agreement among raft nodes before linearized reading' (duration: 962.922762ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:03:16.105689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:15.254772Z","time spent":"850.912875ms","remote":"127.0.0.1:56042","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-04-15T05:03:16.105707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:15.141565Z","time spent":"964.131754ms","remote":"127.0.0.1:52838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:500 "} {"level":"info","ts":"2026-04-15T05:03:16.105488Z","caller":"traceutil/trace.go:171","msg":"trace[53105686] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:622; }","duration":"964.582056ms","start":"2026-04-15T05:03:15.140893Z","end":"2026-04-15T05:03:16.105475Z","steps":["trace[53105686] 'agreement among raft nodes before linearized reading' (duration: 963.753124ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T05:03:16.104802Z","caller":"traceutil/trace.go:171","msg":"trace[382483726] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:622; }","duration":"765.402135ms","start":"2026-04-15T05:03:15.339397Z","end":"2026-04-15T05:03:16.104799Z","steps":["trace[382483726] 'agreement among raft nodes before linearized reading' (duration: 765.381325ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T05:03:16.104795Z","caller":"traceutil/trace.go:171","msg":"trace[365885927] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:622; }","duration":"765.347445ms","start":"2026-04-15T05:03:15.339438Z","end":"2026-04-15T05:03:16.104786Z","steps":["trace[365885927] 'agreement among raft nodes before linearized reading' (duration: 765.286213ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:03:16.105833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:15.339433Z","time spent":"766.378982ms","remote":"127.0.0.1:52886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 "} {"level":"warn","ts":"2026-04-15T05:03:16.105773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:15.339389Z","time spent":"766.373982ms","remote":"127.0.0.1:52886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-15T05:03:16.105747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:15.14088Z","time spent":"964.862865ms","remote":"127.0.0.1:52838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-04-15T05:03:16.105085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"803.156403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T05:03:16.106005Z","caller":"traceutil/trace.go:171","msg":"trace[652632235] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:622; }","duration":"804.101409ms","start":"2026-04-15T05:03:15.301893Z","end":"2026-04-15T05:03:16.105994Z","steps":["trace[652632235] 'agreement among raft nodes before linearized reading' (duration: 803.179504ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:03:16.10605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:15.30187Z","time spent":"804.167061ms","remote":"127.0.0.1:52866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"info","ts":"2026-04-15T05:03:16.104233Z","caller":"traceutil/trace.go:171","msg":"trace[535697710] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:622; }","duration":"979.613239ms","start":"2026-04-15T05:03:15.124609Z","end":"2026-04-15T05:03:16.104223Z","steps":["trace[535697710] 'agreement among raft nodes before linearized reading' (duration: 979.554667ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:03:16.106179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:15.124586Z","time spent":"981.582865ms","remote":"127.0.0.1:52824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"warn","ts":"2026-04-15T05:03:16.108119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"849.406644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-15T05:03:16.108541Z","caller":"traceutil/trace.go:171","msg":"trace[361161] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:622; }","duration":"853.235358ms","start":"2026-04-15T05:03:15.255028Z","end":"2026-04-15T05:03:16.108263Z","steps":["trace[361161] 'agreement among raft nodes before linearized reading' (duration: 849.033113ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:03:16.108655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:15.255023Z","time spent":"853.61199ms","remote":"127.0.0.1:56042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-15T05:03:16.307458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.232808ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-15T05:03:16.307612Z","caller":"traceutil/trace.go:171","msg":"trace[1431017353] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"195.368606ms","start":"2026-04-15T05:03:16.112219Z","end":"2026-04-15T05:03:16.307588Z","steps":["trace[1431017353] 'process raft request' (duration: 45.749027ms)","trace[1431017353] 'compare' (duration: 149.080404ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T05:03:16.318417Z","caller":"traceutil/trace.go:171","msg":"trace[34227532] linearizableReadLoop","detail":"{readStateIndex:646; appliedIndex:642; }","duration":"180.579321ms","start":"2026-04-15T05:03:16.137825Z","end":"2026-04-15T05:03:16.318405Z","steps":["trace[34227532] 'read index received' (duration: 20.152294ms)","trace[34227532] 'applied index is now lower than readState.Index' (duration: 160.426317ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T05:03:16.31849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.657252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T05:03:16.31848Z","caller":"traceutil/trace.go:171","msg":"trace[949923533] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"205.399722ms","start":"2026-04-15T05:03:16.113051Z","end":"2026-04-15T05:03:16.318451Z","steps":["trace[949923533] 'process raft request' (duration: 205.230487ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T05:03:16.318584Z","caller":"traceutil/trace.go:171","msg":"trace[1349466428] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:626; }","duration":"180.704744ms","start":"2026-04-15T05:03:16.137797Z","end":"2026-04-15T05:03:16.318502Z","steps":["trace[1349466428] 'agreement among raft nodes before linearized reading' (duration: 180.655283ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T05:03:16.31861Z","caller":"traceutil/trace.go:171","msg":"trace[698226875] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"205.488665ms","start":"2026-04-15T05:03:16.113116Z","end":"2026-04-15T05:03:16.318604Z","steps":["trace[698226875] 'process raft request' (duration: 205.240838ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T05:03:16.318658Z","caller":"traceutil/trace.go:171","msg":"trace[932617405] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"204.670062ms","start":"2026-04-15T05:03:16.113969Z","end":"2026-04-15T05:03:16.318639Z","steps":["trace[932617405] 'process raft request' (duration: 204.412935ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:03:18.321025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999428023s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-15T05:03:18.32115Z","caller":"traceutil/trace.go:171","msg":"trace[235730694] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999584848s","start":"2026-04-15T05:03:16.321537Z","end":"2026-04-15T05:03:18.321122Z","steps":["trace[235730694] 'agreement among raft nodes before linearized reading' (duration: 1.999412393s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:03:18.321218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:16.321485Z","time spent":"1.999720993s","remote":"127.0.0.1:55764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-15T05:03:18.759866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.010388814s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-15T05:03:18.760026Z","caller":"traceutil/trace.go:171","msg":"trace[1425900223] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"589.449082ms","start":"2026-04-15T05:03:18.170564Z","end":"2026-04-15T05:03:18.760013Z","steps":["trace[1425900223] 'process raft request' (duration: 589.3793ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T05:03:18.760005Z","caller":"traceutil/trace.go:171","msg":"trace[1629536445] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"2.598509952s","start":"2026-04-15T05:03:16.161467Z","end":"2026-04-15T05:03:18.759977Z","steps":["trace[1629536445] 'process raft request' (duration: 588.017033ms)","trace[1629536445] 'compare' (duration: 2.009193793s)"],"step_count":2} {"level":"warn","ts":"2026-04-15T05:03:18.760076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:18.170539Z","time spent":"589.509343ms","remote":"127.0.0.1:56042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T05:03:18.760065Z","caller":"traceutil/trace.go:171","msg":"trace[385298245] linearizableReadLoop","detail":"{readStateIndex:647; appliedIndex:646; }","duration":"2.438501745s","start":"2026-04-15T05:03:16.321543Z","end":"2026-04-15T05:03:18.760045Z","steps":["trace[385298245] 'read index received' (duration: 427.764921ms)","trace[385298245] 'applied index is now lower than readState.Index' (duration: 2.010735204s)"],"step_count":2} {"level":"warn","ts":"2026-04-15T05:03:18.760121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:16.161443Z","time spent":"2.598618594s","remote":"127.0.0.1:56104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1092,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-15T05:03:18.760215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.601417821s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T05:03:18.760242Z","caller":"traceutil/trace.go:171","msg":"trace[1310003377] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:628; }","duration":"1.601491713s","start":"2026-04-15T05:03:17.158742Z","end":"2026-04-15T05:03:18.760233Z","steps":["trace[1310003377] 'agreement among raft nodes before linearized reading' (duration: 1.601414131s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:03:18.760263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:17.158713Z","time spent":"1.601545104s","remote":"127.0.0.1:55762","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-04-15T05:03:18.76039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.792711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T05:03:18.760418Z","caller":"traceutil/trace.go:171","msg":"trace[689965096] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:628; }","duration":"431.839263ms","start":"2026-04-15T05:03:18.328568Z","end":"2026-04-15T05:03:18.760407Z","steps":["trace[689965096] 'agreement among raft nodes before linearized reading' (duration: 431.794622ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:03:18.760427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.442989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-15T05:03:18.760456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.682116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-15T05:03:18.760463Z","caller":"traceutil/trace.go:171","msg":"trace[1932972653] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:628; }","duration":"436.525401ms","start":"2026-04-15T05:03:18.323928Z","end":"2026-04-15T05:03:18.760453Z","steps":["trace[1932972653] 'agreement among raft nodes before linearized reading' (duration: 436.390368ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T05:03:18.760476Z","caller":"traceutil/trace.go:171","msg":"trace[1447780767] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:628; }","duration":"436.741467ms","start":"2026-04-15T05:03:18.323729Z","end":"2026-04-15T05:03:18.760471Z","steps":["trace[1447780767] 'agreement among raft nodes before linearized reading' (duration: 436.655065ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:03:18.760495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:18.323704Z","time spent":"436.786978ms","remote":"127.0.0.1:56042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-15T05:03:18.760491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:18.323906Z","time spent":"436.579652ms","remote":"127.0.0.1:56042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-15T05:03:18.760519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.678708ms","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-04-15T05:03:18.760536Z","caller":"traceutil/trace.go:171","msg":"trace[1302031748] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:628; }","duration":"447.727499ms","start":"2026-04-15T05:03:18.312804Z","end":"2026-04-15T05:03:18.760531Z","steps":["trace[1302031748] 'agreement among raft nodes before linearized reading' (duration: 447.652676ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:03:18.760554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:18.312785Z","time spent":"447.766191ms","remote":"127.0.0.1:56042","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-04-15T05:03:18.760437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:18.328556Z","time spent":"431.877534ms","remote":"127.0.0.1:55762","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-04-15T05:03:18.760624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.632267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18a66f386fd23b79\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-04-15T05:03:18.760652Z","caller":"traceutil/trace.go:171","msg":"trace[824705562] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18a66f386fd23b79; range_end:; response_count:1; response_revision:628; }","duration":"435.677589ms","start":"2026-04-15T05:03:18.324965Z","end":"2026-04-15T05:03:18.760642Z","steps":["trace[824705562] 'agreement among raft nodes before linearized reading' (duration: 435.629327ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:03:18.760677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:18.324954Z","time spent":"435.717149ms","remote":"127.0.0.1:55854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":797,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.18a66f386fd23b79\" "} {"level":"warn","ts":"2026-04-15T05:03:18.760695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.988294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-15T05:03:18.760713Z","caller":"traceutil/trace.go:171","msg":"trace[800412690] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:628; }","duration":"437.048247ms","start":"2026-04-15T05:03:18.323659Z","end":"2026-04-15T05:03:18.760707Z","steps":["trace[800412690] 'agreement among raft nodes before linearized reading' (duration: 436.995775ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:03:18.760729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:18.323638Z","time spent":"437.088237ms","remote":"127.0.0.1:56042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-15T05:03:20.348014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.122266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T05:03:20.348152Z","caller":"traceutil/trace.go:171","msg":"trace[1711700033] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:642; }","duration":"208.26394ms","start":"2026-04-15T05:03:20.139848Z","end":"2026-04-15T05:03:20.348111Z","steps":["trace[1711700033] 'range keys from in-memory index tree' (duration: 208.049935ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T05:03:35.959988Z","caller":"traceutil/trace.go:171","msg":"trace[1759463775] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"121.753574ms","start":"2026-04-15T05:03:35.838201Z","end":"2026-04-15T05:03:35.959955Z","steps":["trace[1759463775] 'process raft request' (duration: 121.424635ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:03:36.466807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.121473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T05:03:36.467265Z","caller":"traceutil/trace.go:171","msg":"trace[172562829] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:802; }","duration":"328.640797ms","start":"2026-04-15T05:03:36.138603Z","end":"2026-04-15T05:03:36.467243Z","steps":["trace[172562829] 'range keys from in-memory index tree' (duration: 327.99277ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:03:36.467431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:36.138579Z","time spent":"328.835893ms","remote":"127.0.0.1:55762","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-04-15T05:03:37.281875Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6890117719661631610,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-15T05:03:37.782964Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6890117719661631610,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-15T05:03:38.28349Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6890117719661631610,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-15T05:03:38.784568Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6890117719661631610,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-15T05:03:39.161837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000551375s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-15T05:03:39.161913Z","caller":"traceutil/trace.go:171","msg":"trace[897482180] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000666839s","start":"2026-04-15T05:03:37.161228Z","end":"2026-04-15T05:03:39.161895Z","steps":["trace[897482180] 'agreement among raft nodes before linearized reading' (duration: 2.000575187s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:03:39.161947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:37.161211Z","time spent":"2.00073018s","remote":"127.0.0.1:55762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-15T05:03:39.203411Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.46342652s","expected-duration":"1s"} {"level":"info","ts":"2026-04-15T05:03:39.203753Z","caller":"traceutil/trace.go:171","msg":"trace[740248429] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"2.463857891s","start":"2026-04-15T05:03:36.739878Z","end":"2026-04-15T05:03:39.203736Z","steps":["trace[740248429] 'process raft request' (duration: 2.463706468s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:03:39.203875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:36.739852Z","time spent":"2.463956775s","remote":"127.0.0.1:56042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T05:03:39.966381Z","caller":"traceutil/trace.go:171","msg":"trace[1710921421] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"2.976054402s","start":"2026-04-15T05:03:36.990302Z","end":"2026-04-15T05:03:39.966356Z","steps":["trace[1710921421] 'process raft request' (duration: 2.975825606s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:03:39.966641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:36.990277Z","time spent":"2.976235987s","remote":"127.0.0.1:56026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T05:03:39.966671Z","caller":"traceutil/trace.go:171","msg":"trace[1740068310] linearizableReadLoop","detail":"{readStateIndex:829; appliedIndex:827; }","duration":"3.185592798s","start":"2026-04-15T05:03:36.781036Z","end":"2026-04-15T05:03:39.966629Z","steps":["trace[1740068310] 'read index received' (duration: 2.422526747s)","trace[1740068310] 'applied index is now lower than readState.Index' (duration: 763.063321ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T05:03:39.966789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:39.166023Z","time spent":"800.633164ms","remote":"127.0.0.1:55854","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-15T05:03:39.96673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.185681471s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T05:03:39.966865Z","caller":"traceutil/trace.go:171","msg":"trace[60204981] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:804; }","duration":"3.185868945s","start":"2026-04-15T05:03:36.780983Z","end":"2026-04-15T05:03:39.966852Z","steps":["trace[60204981] 'agreement among raft nodes before linearized reading' (duration: 3.185698621s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:03:39.966965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:36.780964Z","time spent":"3.185944327s","remote":"127.0.0.1:56058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":28,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-15T05:03:40.003199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.865106104s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T05:03:40.003293Z","caller":"traceutil/trace.go:171","msg":"trace[699525684] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:805; }","duration":"1.865249888s","start":"2026-04-15T05:03:38.138017Z","end":"2026-04-15T05:03:40.003267Z","steps":["trace[699525684] 'agreement among raft nodes before linearized reading' (duration: 1.865039273s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:03:40.003343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:38.137998Z","time spent":"1.865330651s","remote":"127.0.0.1:55764","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-04-15T05:03:40.003605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.943460266s","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-04-15T05:03:40.00366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.944035612s","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-04-15T05:03:40.003735Z","caller":"traceutil/trace.go:171","msg":"trace[1613506600] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:805; }","duration":"2.943590591s","start":"2026-04-15T05:03:37.060098Z","end":"2026-04-15T05:03:40.003689Z","steps":["trace[1613506600] 'agreement among raft nodes before linearized reading' (duration: 2.943159119s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:03:40.003816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:37.060079Z","time spent":"2.943723754s","remote":"127.0.0.1:56042","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-04-15T05:03:40.003205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.994119139s","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-04-15T05:03:40.003729Z","caller":"traceutil/trace.go:171","msg":"trace[1990209121] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:805; }","duration":"2.944194356s","start":"2026-04-15T05:03:37.059513Z","end":"2026-04-15T05:03:40.003707Z","steps":["trace[1990209121] 'agreement among raft nodes before linearized reading' (duration: 2.94396425s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:03:40.003987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.969507573s","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-04-15T05:03:40.004102Z","caller":"traceutil/trace.go:171","msg":"trace[1082244711] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:805; }","duration":"2.969685157s","start":"2026-04-15T05:03:37.03439Z","end":"2026-04-15T05:03:40.004075Z","steps":["trace[1082244711] 'agreement among raft nodes before linearized reading' (duration: 2.969326647s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:03:40.004055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:37.059492Z","time spent":"2.944550096s","remote":"127.0.0.1:56042","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-04-15T05:03:40.004183Z","caller":"traceutil/trace.go:171","msg":"trace[1636588215] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"768.640476ms","start":"2026-04-15T05:03:39.235521Z","end":"2026-04-15T05:03:40.004161Z","steps":["trace[1636588215] 'process raft request' (duration: 767.35907ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:03:40.004338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:39.235492Z","time spent":"768.766237ms","remote":"127.0.0.1:56042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T05:03:40.004035Z","caller":"traceutil/trace.go:171","msg":"trace[1880114816] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:805; }","duration":"2.99489121s","start":"2026-04-15T05:03:37.009005Z","end":"2026-04-15T05:03:40.003896Z","steps":["trace[1880114816] 'agreement among raft nodes before linearized reading' (duration: 2.994034356s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:03:40.004461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:37.008963Z","time spent":"2.995489256s","remote":"127.0.0.1:56042","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-04-15T05:03:40.004166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:37.034362Z","time spent":"2.96979164s","remote":"127.0.0.1:56042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-15T05:03:40.003861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.212422835s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.222\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-15T05:03:40.0046Z","caller":"traceutil/trace.go:171","msg":"trace[1283678249] range","detail":"{range_begin:/registry/masterleases/199.204.45.222; range_end:; response_count:1; response_revision:805; }","duration":"1.213219526s","start":"2026-04-15T05:03:38.791369Z","end":"2026-04-15T05:03:40.004588Z","steps":["trace[1283678249] 'agreement among raft nodes before linearized reading' (duration: 1.212418595s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:03:40.004635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:38.791336Z","time spent":"1.213292119s","remote":"127.0.0.1:55800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.222\" "} {"level":"warn","ts":"2026-04-15T05:03:40.328454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:40.005746Z","time spent":"322.696115ms","remote":"127.0.0.1:55800","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-15T05:03:40.333628Z","caller":"traceutil/trace.go:171","msg":"trace[1395652505] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"323.756824ms","start":"2026-04-15T05:03:40.009847Z","end":"2026-04-15T05:03:40.333603Z","steps":["trace[1395652505] 'process raft request' (duration: 323.675582ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:03:40.33389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:40.009815Z","time spent":"323.881277ms","remote":"127.0.0.1:56042","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-04-15T05:03:40.33424Z","caller":"traceutil/trace.go:171","msg":"trace[1614766064] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"324.000469ms","start":"2026-04-15T05:03:40.010212Z","end":"2026-04-15T05:03:40.334213Z","steps":["trace[1614766064] 'process raft request' (duration: 323.350331ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:03:40.334444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:40.010198Z","time spent":"324.135063ms","remote":"127.0.0.1:56042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T05:03:40.334416Z","caller":"traceutil/trace.go:171","msg":"trace[79275053] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"324.536035ms","start":"2026-04-15T05:03:40.009723Z","end":"2026-04-15T05:03:40.334259Z","steps":["trace[79275053] 'process raft request' (duration: 323.587699ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T05:03:40.334633Z","caller":"traceutil/trace.go:171","msg":"trace[1237771844] linearizableReadLoop","detail":"{readStateIndex:837; appliedIndex:832; }","duration":"322.463077ms","start":"2026-04-15T05:03:40.012141Z","end":"2026-04-15T05:03:40.334604Z","steps":["trace[1237771844] 'read index received' (duration: 299.944269ms)","trace[1237771844] 'applied index is now lower than readState.Index' (duration: 22.512018ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T05:03:40.334826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.657073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T05:03:40.334806Z","caller":"traceutil/trace.go:171","msg":"trace[373799774] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"323.433444ms","start":"2026-04-15T05:03:40.011266Z","end":"2026-04-15T05:03:40.334699Z","steps":["trace[373799774] 'process raft request' (duration: 322.777636ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:03:40.335017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:40.009704Z","time spent":"325.024649ms","remote":"127.0.0.1:56042","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-04-15T05:03:40.335137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.532165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T05:03:40.33519Z","caller":"traceutil/trace.go:171","msg":"trace[441320233] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:811; }","duration":"229.637068ms","start":"2026-04-15T05:03:40.105542Z","end":"2026-04-15T05:03:40.33518Z","steps":["trace[441320233] 'agreement among raft nodes before linearized reading' (duration: 229.465874ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:03:40.335182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:40.011259Z","time spent":"323.742974ms","remote":"127.0.0.1:56042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T05:03:40.334916Z","caller":"traceutil/trace.go:171","msg":"trace[1466178498] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:810; }","duration":"322.771488ms","start":"2026-04-15T05:03:40.012124Z","end":"2026-04-15T05:03:40.334896Z","steps":["trace[1466178498] 'agreement among raft nodes before linearized reading' (duration: 322.616553ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:03:40.335393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:03:40.012118Z","time spent":"323.26262ms","remote":"127.0.0.1:55762","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-04-15T05:04:16.93822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"801.410841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T05:04:16.93833Z","caller":"traceutil/trace.go:171","msg":"trace[1525024884] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1023; }","duration":"801.568136ms","start":"2026-04-15T05:04:16.136737Z","end":"2026-04-15T05:04:16.938306Z","steps":["trace[1525024884] 'range keys from in-memory index tree' (duration: 801.312219ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:04:16.938407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:04:16.136714Z","time spent":"801.658709ms","remote":"127.0.0.1:55762","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-04-15T05:04:16.938527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.530113559s","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-04-15T05:04:16.9386Z","caller":"traceutil/trace.go:171","msg":"trace[764841910] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1023; }","duration":"1.530189551s","start":"2026-04-15T05:04:15.408394Z","end":"2026-04-15T05:04:16.938584Z","steps":["trace[764841910] 'range keys from in-memory index tree' (duration: 1.529938924s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:04:16.938647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:04:15.40834Z","time spent":"1.530294253s","remote":"127.0.0.1:56042","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-04-15T05:04:16.939114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.851176ms","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-04-15T05:04:16.939179Z","caller":"traceutil/trace.go:171","msg":"trace[904937093] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1023; }","duration":"430.950967ms","start":"2026-04-15T05:04:16.508213Z","end":"2026-04-15T05:04:16.939164Z","steps":["trace[904937093] 'range keys from in-memory index tree' (duration: 430.720232ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:04:16.939224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:04:16.508192Z","time spent":"431.01969ms","remote":"127.0.0.1:56042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-15T05:04:16.939586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.408808ms","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-04-15T05:04:16.939643Z","caller":"traceutil/trace.go:171","msg":"trace[976659950] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1023; }","duration":"428.4965ms","start":"2026-04-15T05:04:16.511133Z","end":"2026-04-15T05:04:16.93963Z","steps":["trace[976659950] 'range keys from in-memory index tree' (duration: 428.263714ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:04:16.939664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.552352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-15T05:04:16.939681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:04:16.511104Z","time spent":"428.566622ms","remote":"127.0.0.1:56042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-15T05:04:16.939699Z","caller":"traceutil/trace.go:171","msg":"trace[1424019109] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1023; }","duration":"424.623145ms","start":"2026-04-15T05:04:16.515066Z","end":"2026-04-15T05:04:16.939689Z","steps":["trace[1424019109] 'range keys from in-memory index tree' (duration: 424.44269ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:04:16.939722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:04:16.515047Z","time spent":"424.669926ms","remote":"127.0.0.1:56042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-15T05:04:16.939815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.012225ms","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-04-15T05:04:16.939836Z","caller":"traceutil/trace.go:171","msg":"trace[868213915] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1023; }","duration":"417.041116ms","start":"2026-04-15T05:04:16.522788Z","end":"2026-04-15T05:04:16.939829Z","steps":["trace[868213915] 'range keys from in-memory index tree' (duration: 416.963944ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:04:16.939854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:04:16.522783Z","time spent":"417.066597ms","remote":"127.0.0.1:56042","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-04-15T05:04:17.40228Z","caller":"traceutil/trace.go:171","msg":"trace[1634972078] linearizableReadLoop","detail":"{readStateIndex:1059; appliedIndex:1058; }","duration":"457.503237ms","start":"2026-04-15T05:04:16.944749Z","end":"2026-04-15T05:04:17.402253Z","steps":["trace[1634972078] 'read index received' (duration: 457.426406ms)","trace[1634972078] 'applied index is now lower than readState.Index' (duration: 75.651µs)"],"step_count":2} {"level":"info","ts":"2026-04-15T05:04:17.402397Z","caller":"traceutil/trace.go:171","msg":"trace[1731693727] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"457.693133ms","start":"2026-04-15T05:04:16.944693Z","end":"2026-04-15T05:04:17.402386Z","steps":["trace[1731693727] 'process raft request' (duration: 457.215639ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:04:17.402566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.730223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T05:04:17.402699Z","caller":"traceutil/trace.go:171","msg":"trace[748831998] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1024; }","duration":"457.960459ms","start":"2026-04-15T05:04:16.944717Z","end":"2026-04-15T05:04:17.402677Z","steps":["trace[748831998] 'agreement among raft nodes before linearized reading' (duration: 457.685632ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:04:17.402718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:04:16.944665Z","time spent":"457.761374ms","remote":"127.0.0.1:56042","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-04-15T05:04:17.402758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:04:16.944701Z","time spent":"458.045422ms","remote":"127.0.0.1:55764","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-04-15T05:04:17.415265Z","caller":"traceutil/trace.go:171","msg":"trace[1292388570] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"470.02972ms","start":"2026-04-15T05:04:16.945218Z","end":"2026-04-15T05:04:17.415248Z","steps":["trace[1292388570] 'process raft request' (duration: 469.931757ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T05:04:17.415266Z","caller":"traceutil/trace.go:171","msg":"trace[558690769] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"470.121154ms","start":"2026-04-15T05:04:16.945077Z","end":"2026-04-15T05:04:17.415198Z","steps":["trace[558690769] 'process raft request' (duration: 469.874537ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T05:04:17.415303Z","caller":"traceutil/trace.go:171","msg":"trace[56380168] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"470.029421ms","start":"2026-04-15T05:04:16.945244Z","end":"2026-04-15T05:04:17.415273Z","steps":["trace[56380168] 'process raft request' (duration: 469.931048ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:04:17.415349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:04:16.945207Z","time spent":"470.098023ms","remote":"127.0.0.1:56042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T05:04:17.415429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:04:16.945226Z","time spent":"470.130703ms","remote":"127.0.0.1:56042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T05:04:17.415356Z","caller":"traceutil/trace.go:171","msg":"trace[1922149562] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"130.071353ms","start":"2026-04-15T05:04:17.28526Z","end":"2026-04-15T05:04:17.415331Z","steps":["trace[1922149562] 'process raft request' (duration: 129.943189ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T05:04:17.415472Z","caller":"traceutil/trace.go:171","msg":"trace[1411028067] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"470.285068ms","start":"2026-04-15T05:04:16.94518Z","end":"2026-04-15T05:04:17.415465Z","steps":["trace[1411028067] 'process raft request' (duration: 469.928529ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:04:17.415465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:04:16.945058Z","time spent":"470.292348ms","remote":"127.0.0.1:56042","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-04-15T05:04:17.415511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:04:16.945169Z","time spent":"470.320879ms","remote":"127.0.0.1:56042","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-04-15T05:04:17.415697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.417112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T05:04:17.415786Z","caller":"traceutil/trace.go:171","msg":"trace[540323934] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1029; }","duration":"253.567286ms","start":"2026-04-15T05:04:17.162204Z","end":"2026-04-15T05:04:17.415772Z","steps":["trace[540323934] 'agreement among raft nodes before linearized reading' (duration: 253.034241ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:04:41.333113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.395438964s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-15T05:04:41.333232Z","caller":"traceutil/trace.go:171","msg":"trace[714989827] linearizableReadLoop","detail":"{readStateIndex:1138; appliedIndex:1137; }","duration":"1.791620346s","start":"2026-04-15T05:04:39.541584Z","end":"2026-04-15T05:04:41.333205Z","steps":["trace[714989827] 'read index received' (duration: 395.945487ms)","trace[714989827] 'applied index is now lower than readState.Index' (duration: 1.395673559s)"],"step_count":2} {"level":"info","ts":"2026-04-15T05:04:41.333433Z","caller":"traceutil/trace.go:171","msg":"trace[931439032] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"1.813563169s","start":"2026-04-15T05:04:39.519857Z","end":"2026-04-15T05:04:41.333421Z","steps":["trace[931439032] 'process raft request' (duration: 417.738845ms)","trace[931439032] 'compare' (duration: 1.395023143s)"],"step_count":2} {"level":"warn","ts":"2026-04-15T05:04:41.333501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:04:39.519831Z","time spent":"1.813636972s","remote":"127.0.0.1:56042","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-04-15T05:04:41.333752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.792165411s","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-04-15T05:04:41.333792Z","caller":"traceutil/trace.go:171","msg":"trace[1301308759] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1098; }","duration":"1.792230583s","start":"2026-04-15T05:04:39.541543Z","end":"2026-04-15T05:04:41.333773Z","steps":["trace[1301308759] 'agreement among raft nodes before linearized reading' (duration: 1.792111389s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:04:41.333823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:04:39.541525Z","time spent":"1.792290624s","remote":"127.0.0.1:56042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-15T05:04:41.333996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.769334874s","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-04-15T05:04:41.334029Z","caller":"traceutil/trace.go:171","msg":"trace[773541990] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1098; }","duration":"1.769387746s","start":"2026-04-15T05:04:39.564633Z","end":"2026-04-15T05:04:41.334021Z","steps":["trace[773541990] 'agreement among raft nodes before linearized reading' (duration: 1.769307754s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:04:41.334055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:04:39.564622Z","time spent":"1.769427257s","remote":"127.0.0.1:56042","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-04-15T05:04:41.334132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.195389238s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T05:04:41.334159Z","caller":"traceutil/trace.go:171","msg":"trace[582240144] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1098; }","duration":"1.195447188s","start":"2026-04-15T05:04:40.138705Z","end":"2026-04-15T05:04:41.334152Z","steps":["trace[582240144] 'agreement among raft nodes before linearized reading' (duration: 1.195409178s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:04:41.334188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:04:40.138665Z","time spent":"1.195518161s","remote":"127.0.0.1:55762","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-04-15T05:04:41.334249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.157554279s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-15T05:04:41.334323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.343400203s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-15T05:04:41.334306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.995737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-15T05:04:41.33436Z","caller":"traceutil/trace.go:171","msg":"trace[1908449350] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1098; }","duration":"1.343477266s","start":"2026-04-15T05:04:39.990873Z","end":"2026-04-15T05:04:41.33435Z","steps":["trace[1908449350] 'agreement among raft nodes before linearized reading' (duration: 1.343419264s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:04:41.334392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:04:39.990853Z","time spent":"1.343532067s","remote":"127.0.0.1:56170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"info","ts":"2026-04-15T05:04:41.334393Z","caller":"traceutil/trace.go:171","msg":"trace[1338277738] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1098; }","duration":"167.144831ms","start":"2026-04-15T05:04:41.167227Z","end":"2026-04-15T05:04:41.334372Z","steps":["trace[1338277738] 'agreement among raft nodes before linearized reading' (duration: 166.883865ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T05:04:41.334341Z","caller":"traceutil/trace.go:171","msg":"trace[441722492] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1098; }","duration":"1.157793365s","start":"2026-04-15T05:04:40.176531Z","end":"2026-04-15T05:04:41.334324Z","steps":["trace[441722492] 'agreement among raft nodes before linearized reading' (duration: 1.157462125s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:04:41.334515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:04:40.176512Z","time spent":"1.15798832s","remote":"127.0.0.1:56100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":16,"response size":30,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-04-15T05:04:41.33476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"694.213558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-15T05:04:41.334833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.655106187s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-15T05:04:41.334897Z","caller":"traceutil/trace.go:171","msg":"trace[552782146] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1098; }","duration":"1.655163049s","start":"2026-04-15T05:04:39.679715Z","end":"2026-04-15T05:04:41.334878Z","steps":["trace[552782146] 'agreement among raft nodes before linearized reading' (duration: 1.655088726s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:04:41.334949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:04:39.679673Z","time spent":"1.655265102s","remote":"127.0.0.1:55980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":3,"response size":30,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"warn","ts":"2026-04-15T05:04:41.334751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.765783257s","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-04-15T05:04:41.335135Z","caller":"traceutil/trace.go:171","msg":"trace[757954569] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1098; }","duration":"1.766206678s","start":"2026-04-15T05:04:39.568916Z","end":"2026-04-15T05:04:41.335123Z","steps":["trace[757954569] 'agreement among raft nodes before linearized reading' (duration: 1.765646053s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:04:41.335178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:04:39.568906Z","time spent":"1.766256919s","remote":"127.0.0.1:56042","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-04-15T05:04:41.33484Z","caller":"traceutil/trace.go:171","msg":"trace[2017612466] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1098; }","duration":"694.345732ms","start":"2026-04-15T05:04:40.640479Z","end":"2026-04-15T05:04:41.334825Z","steps":["trace[2017612466] 'agreement among raft nodes before linearized reading' (duration: 694.244019ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:04:41.33523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:04:40.640449Z","time spent":"694.767924ms","remote":"127.0.0.1:52752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-04-15T05:04:41.334529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.370824856s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T05:04:41.33541Z","caller":"traceutil/trace.go:171","msg":"trace[322129310] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1098; }","duration":"1.371740823s","start":"2026-04-15T05:04:39.963657Z","end":"2026-04-15T05:04:41.335398Z","steps":["trace[322129310] 'agreement among raft nodes before linearized reading' (duration: 1.370848718s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:04:41.335456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:04:39.963635Z","time spent":"1.371806404s","remote":"127.0.0.1:55860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-04-15T05:04:41.335416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.762698191s","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-04-15T05:04:41.335617Z","caller":"traceutil/trace.go:171","msg":"trace[1124932196] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1098; }","duration":"1.762925837s","start":"2026-04-15T05:04:39.572654Z","end":"2026-04-15T05:04:41.33558Z","steps":["trace[1124932196] 'agreement among raft nodes before linearized reading' (duration: 1.762517336s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:04:41.335691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:04:39.572644Z","time spent":"1.763035841s","remote":"127.0.0.1:56042","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-04-15T05:04:41.613117Z","caller":"traceutil/trace.go:171","msg":"trace[399875073] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"274.597144ms","start":"2026-04-15T05:04:41.338493Z","end":"2026-04-15T05:04:41.61309Z","steps":["trace[399875073] 'process raft request' (duration: 274.423339ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T05:04:41.630016Z","caller":"traceutil/trace.go:171","msg":"trace[279432726] linearizableReadLoop","detail":"{readStateIndex:1142; appliedIndex:1138; }","duration":"287.870649ms","start":"2026-04-15T05:04:41.342127Z","end":"2026-04-15T05:04:41.629997Z","steps":["trace[279432726] 'read index received' (duration: 270.753278ms)","trace[279432726] 'applied index is now lower than readState.Index' (duration: 17.116491ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T05:04:41.630268Z","caller":"traceutil/trace.go:171","msg":"trace[1060409335] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"291.148687ms","start":"2026-04-15T05:04:41.339107Z","end":"2026-04-15T05:04:41.630255Z","steps":["trace[1060409335] 'process raft request' (duration: 290.663045ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T05:04:41.630408Z","caller":"traceutil/trace.go:171","msg":"trace[534332238] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"290.994004ms","start":"2026-04-15T05:04:41.339406Z","end":"2026-04-15T05:04:41.6304Z","steps":["trace[534332238] 'process raft request' (duration: 290.47085ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T05:04:41.630505Z","caller":"traceutil/trace.go:171","msg":"trace[653176670] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"290.848431ms","start":"2026-04-15T05:04:41.339649Z","end":"2026-04-15T05:04:41.630497Z","steps":["trace[653176670] 'process raft request' (duration: 290.280554ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T05:04:41.630603Z","caller":"traceutil/trace.go:171","msg":"trace[1322960799] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"147.611286ms","start":"2026-04-15T05:04:41.482984Z","end":"2026-04-15T05:04:41.630595Z","steps":["trace[1322960799] 'process raft request' (duration: 146.980228ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:04:41.630719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.573719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T05:04:41.630744Z","caller":"traceutil/trace.go:171","msg":"trace[648256544] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1103; }","duration":"288.622529ms","start":"2026-04-15T05:04:41.342114Z","end":"2026-04-15T05:04:41.630736Z","steps":["trace[648256544] 'agreement among raft nodes before linearized reading' (duration: 288.567597ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:04:41.630872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.966918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T05:04:41.630896Z","caller":"traceutil/trace.go:171","msg":"trace[126202549] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1103; }","duration":"131.021559ms","start":"2026-04-15T05:04:41.499867Z","end":"2026-04-15T05:04:41.630888Z","steps":["trace[126202549] 'agreement among raft nodes before linearized reading' (duration: 130.981488ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:04:41.631212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.105535ms","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-04-15T05:04:41.631238Z","caller":"traceutil/trace.go:171","msg":"trace[739728359] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1103; }","duration":"110.159165ms","start":"2026-04-15T05:04:41.521072Z","end":"2026-04-15T05:04:41.631231Z","steps":["trace[739728359] 'agreement among raft nodes before linearized reading' (duration: 110.060673ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T05:04:41.804218Z","caller":"traceutil/trace.go:171","msg":"trace[868489152] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"168.680493ms","start":"2026-04-15T05:04:41.635507Z","end":"2026-04-15T05:04:41.804188Z","steps":["trace[868489152] 'process raft request' (duration: 74.808855ms)","trace[868489152] 'compare' (duration: 93.603041ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T05:04:43.952774Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6890117719661632299,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-15T05:04:44.081038Z","caller":"traceutil/trace.go:171","msg":"trace[806648208] linearizableReadLoop","detail":"{readStateIndex:1145; appliedIndex:1144; }","duration":"628.68918ms","start":"2026-04-15T05:04:43.452325Z","end":"2026-04-15T05:04:44.081014Z","steps":["trace[806648208] 'read index received' (duration: 628.502265ms)","trace[806648208] 'applied index is now lower than readState.Index' (duration: 186.055µs)"],"step_count":2} {"level":"info","ts":"2026-04-15T05:04:44.081126Z","caller":"traceutil/trace.go:171","msg":"trace[1559100185] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"741.841089ms","start":"2026-04-15T05:04:43.339251Z","end":"2026-04-15T05:04:44.081092Z","steps":["trace[1559100185] 'process raft request' (duration: 741.624893ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:04:44.081195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.854145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T05:04:44.081237Z","caller":"traceutil/trace.go:171","msg":"trace[1082765431] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:1105; }","duration":"628.962517ms","start":"2026-04-15T05:04:43.452264Z","end":"2026-04-15T05:04:44.081226Z","steps":["trace[1082765431] 'agreement among raft nodes before linearized reading' (duration: 628.873904ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:04:44.081443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:04:43.45224Z","time spent":"629.187225ms","remote":"127.0.0.1:56232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-04-15T05:04:44.08146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.942045ms","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-04-15T05:04:44.081411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:04:43.339225Z","time spent":"741.974673ms","remote":"127.0.0.1:56042","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-04-15T05:04:44.081537Z","caller":"traceutil/trace.go:171","msg":"trace[1050567017] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1105; }","duration":"464.073829ms","start":"2026-04-15T05:04:43.617445Z","end":"2026-04-15T05:04:44.081519Z","steps":["trace[1050567017] 'agreement among raft nodes before linearized reading' (duration: 463.771801ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:04:44.081582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.113437ms","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-04-15T05:04:44.081658Z","caller":"traceutil/trace.go:171","msg":"trace[2112581566] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1105; }","duration":"445.21884ms","start":"2026-04-15T05:04:43.636419Z","end":"2026-04-15T05:04:44.081638Z","steps":["trace[2112581566] 'agreement among raft nodes before linearized reading' (duration: 444.946253ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:04:44.081661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.216915ms","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-04-15T05:04:44.081785Z","caller":"traceutil/trace.go:171","msg":"trace[1288083083] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1105; }","duration":"273.403111ms","start":"2026-04-15T05:04:43.808356Z","end":"2026-04-15T05:04:44.081759Z","steps":["trace[1288083083] 'agreement among raft nodes before linearized reading' (duration: 272.978899ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:04:44.081597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:04:43.617425Z","time spent":"464.162391ms","remote":"127.0.0.1:56042","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-04-15T05:04:44.081737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.2227ms","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-04-15T05:04:44.081907Z","caller":"traceutil/trace.go:171","msg":"trace[39865741] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1105; }","duration":"445.465757ms","start":"2026-04-15T05:04:43.636419Z","end":"2026-04-15T05:04:44.081885Z","steps":["trace[39865741] 'agreement among raft nodes before linearized reading' (duration: 444.942952ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:04:44.081981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:04:43.6364Z","time spent":"445.56887ms","remote":"127.0.0.1:56042","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-04-15T05:04:44.081712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:04:43.6364Z","time spent":"445.300273ms","remote":"127.0.0.1:56042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-15T05:04:44.082105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.233203ms","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-04-15T05:04:44.082152Z","caller":"traceutil/trace.go:171","msg":"trace[1693088710] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1105; }","duration":"444.310055ms","start":"2026-04-15T05:04:43.637831Z","end":"2026-04-15T05:04:44.082141Z","steps":["trace[1693088710] 'agreement among raft nodes before linearized reading' (duration: 443.423401ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:04:44.08219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:04:43.637813Z","time spent":"444.368296ms","remote":"127.0.0.1:56042","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-04-15T05:04:44.446944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.162926ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-15T05:04:46.219723Z","caller":"traceutil/trace.go:171","msg":"trace[581002181] linearizableReadLoop","detail":"{readStateIndex:1153; appliedIndex:1152; }","duration":"106.527187ms","start":"2026-04-15T05:04:46.113169Z","end":"2026-04-15T05:04:46.219697Z","steps":["trace[581002181] 'read index received' (duration: 106.227758ms)","trace[581002181] 'applied index is now lower than readState.Index' (duration: 298.569µs)"],"step_count":2} {"level":"info","ts":"2026-04-15T05:04:46.219843Z","caller":"traceutil/trace.go:171","msg":"trace[1564258457] transaction","detail":"{read_only:false; response_revision:1112; number_of_response:1; }","duration":"127.968285ms","start":"2026-04-15T05:04:46.091863Z","end":"2026-04-15T05:04:46.219832Z","steps":["trace[1564258457] 'process raft request' (duration: 127.568734ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:04:46.220037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.865617ms","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-04-15T05:04:46.220552Z","caller":"traceutil/trace.go:171","msg":"trace[450451916] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1112; }","duration":"107.439081ms","start":"2026-04-15T05:04:46.113097Z","end":"2026-04-15T05:04:46.220536Z","steps":["trace[450451916] 'agreement among raft nodes before linearized reading' (duration: 106.841105ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T05:04:58.429811Z","caller":"traceutil/trace.go:171","msg":"trace[561176512] linearizableReadLoop","detail":"{readStateIndex:1247; appliedIndex:1246; }","duration":"137.691701ms","start":"2026-04-15T05:04:58.2921Z","end":"2026-04-15T05:04:58.429792Z","steps":["trace[561176512] 'read index received' (duration: 137.551428ms)","trace[561176512] 'applied index is now lower than readState.Index' (duration: 139.363µs)"],"step_count":2} {"level":"warn","ts":"2026-04-15T05:04:58.430044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.718837ms","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-04-15T05:04:58.430039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.929488ms","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-04-15T05:04:58.430075Z","caller":"traceutil/trace.go:171","msg":"trace[1042012603] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1202; }","duration":"116.780797ms","start":"2026-04-15T05:04:58.313286Z","end":"2026-04-15T05:04:58.430067Z","steps":["trace[1042012603] 'agreement among raft nodes before linearized reading' (duration: 116.666554ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T05:04:58.430093Z","caller":"traceutil/trace.go:171","msg":"trace[1447760595] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1202; }","duration":"119.019879ms","start":"2026-04-15T05:04:58.311058Z","end":"2026-04-15T05:04:58.430078Z","steps":["trace[1447760595] 'agreement among raft nodes before linearized reading' (duration: 118.863455ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:04:58.430083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.68286ms","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-04-15T05:04:58.430113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.966937ms","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-04-15T05:04:58.430136Z","caller":"traceutil/trace.go:171","msg":"trace[2062372249] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1202; }","duration":"118.761333ms","start":"2026-04-15T05:04:58.311364Z","end":"2026-04-15T05:04:58.430125Z","steps":["trace[2062372249] 'agreement among raft nodes before linearized reading' (duration: 118.570537ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T05:04:58.430162Z","caller":"traceutil/trace.go:171","msg":"trace[1444260089] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1202; }","duration":"119.055951ms","start":"2026-04-15T05:04:58.311092Z","end":"2026-04-15T05:04:58.430148Z","steps":["trace[1444260089] 'agreement among raft nodes before linearized reading' (duration: 118.926308ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T05:04:58.429879Z","caller":"traceutil/trace.go:171","msg":"trace[606852386] transaction","detail":"{read_only:false; response_revision:1202; number_of_response:1; }","duration":"159.507451ms","start":"2026-04-15T05:04:58.270337Z","end":"2026-04-15T05:04:58.429844Z","steps":["trace[606852386] 'process raft request' (duration: 159.317846ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:04:58.43043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.328469ms","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-04-15T05:04:58.430462Z","caller":"traceutil/trace.go:171","msg":"trace[1254739268] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1202; }","duration":"138.384031ms","start":"2026-04-15T05:04:58.292068Z","end":"2026-04-15T05:04:58.430452Z","steps":["trace[1254739268] 'agreement among raft nodes before linearized reading' (duration: 137.824825ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:04:59.297052Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6890117719661632505,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-15T05:04:59.798235Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6890117719661632505,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-15T05:05:00.299406Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6890117719661632505,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-15T05:05:00.800277Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6890117719661632505,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-15T05:05:01.096978Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.596754103s","expected-duration":"1s"} {"level":"info","ts":"2026-04-15T05:05:01.102248Z","caller":"traceutil/trace.go:171","msg":"trace[784764123] transaction","detail":"{read_only:false; response_revision:1204; number_of_response:1; }","duration":"2.667576316s","start":"2026-04-15T05:04:58.434647Z","end":"2026-04-15T05:05:01.102224Z","steps":["trace[784764123] 'process raft request' (duration: 2.66257748s)"],"step_count":1} {"level":"info","ts":"2026-04-15T05:05:01.102303Z","caller":"traceutil/trace.go:171","msg":"trace[501661006] transaction","detail":"{read_only:false; response_revision:1207; number_of_response:1; }","duration":"2.666982611s","start":"2026-04-15T05:04:58.43529Z","end":"2026-04-15T05:05:01.102272Z","steps":["trace[501661006] 'process raft request' (duration: 2.666955901s)"],"step_count":1} {"level":"info","ts":"2026-04-15T05:05:01.102306Z","caller":"traceutil/trace.go:171","msg":"trace[425140138] transaction","detail":"{read_only:false; response_revision:1205; number_of_response:1; }","duration":"2.667496525s","start":"2026-04-15T05:04:58.434776Z","end":"2026-04-15T05:05:01.102273Z","steps":["trace[425140138] 'process raft request' (duration: 2.667378372s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:05:01.102358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:04:58.434634Z","time spent":"2.667666691s","remote":"127.0.0.1:56042","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-04-15T05:05:01.102408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:04:58.435275Z","time spent":"2.667073314s","remote":"127.0.0.1:56042","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-04-15T05:05:01.102417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:04:58.43476Z","time spent":"2.667600178s","remote":"127.0.0.1:56042","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-04-15T05:05:01.102432Z","caller":"traceutil/trace.go:171","msg":"trace[143115673] transaction","detail":"{read_only:false; response_revision:1206; number_of_response:1; }","duration":"2.667183217s","start":"2026-04-15T05:04:58.43523Z","end":"2026-04-15T05:05:01.102413Z","steps":["trace[143115673] 'process raft request' (duration: 2.666989551s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:05:01.102521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:04:58.435222Z","time spent":"2.667243209s","remote":"127.0.0.1:56042","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-04-15T05:05:01.104723Z","caller":"traceutil/trace.go:171","msg":"trace[404409371] linearizableReadLoop","detail":"{readStateIndex:1253; appliedIndex:1248; }","duration":"2.308401541s","start":"2026-04-15T05:04:58.79631Z","end":"2026-04-15T05:05:01.104712Z","steps":["trace[404409371] 'read index received' (duration: 2.300928716s)","trace[404409371] 'applied index is now lower than readState.Index' (duration: 7.471845ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T05:05:01.104783Z","caller":"traceutil/trace.go:171","msg":"trace[1111726330] transaction","detail":"{read_only:false; response_revision:1208; number_of_response:1; }","duration":"669.058489ms","start":"2026-04-15T05:05:00.435718Z","end":"2026-04-15T05:05:01.104776Z","steps":["trace[1111726330] 'process raft request' (duration: 668.922245ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:05:01.104841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:05:00.43569Z","time spent":"669.11396ms","remote":"127.0.0.1:56042","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-04-15T05:05:01.104884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.966301353s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T05:05:01.104954Z","caller":"traceutil/trace.go:171","msg":"trace[640171540] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1208; }","duration":"1.966423137s","start":"2026-04-15T05:04:59.13851Z","end":"2026-04-15T05:05:01.104933Z","steps":["trace[640171540] 'agreement among raft nodes before linearized reading' (duration: 1.966301283s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:05:01.10497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.308660568s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.222\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-04-15T05:05:01.104982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.316255ms","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-04-15T05:05:01.105006Z","caller":"traceutil/trace.go:171","msg":"trace[1987373377] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1208; }","duration":"602.373937ms","start":"2026-04-15T05:05:00.502625Z","end":"2026-04-15T05:05:01.104999Z","steps":["trace[1987373377] 'agreement among raft nodes before linearized reading' (duration: 602.287154ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T05:05:01.105004Z","caller":"traceutil/trace.go:171","msg":"trace[1091277420] range","detail":"{range_begin:/registry/masterleases/199.204.45.222; range_end:; response_count:1; response_revision:1208; }","duration":"2.308732451s","start":"2026-04-15T05:04:58.796263Z","end":"2026-04-15T05:05:01.104995Z","steps":["trace[1091277420] 'agreement among raft nodes before linearized reading' (duration: 2.308623748s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:05:01.105005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:04:59.138491Z","time spent":"1.966500649s","remote":"127.0.0.1:55762","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-04-15T05:05:01.10503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:04:58.796244Z","time spent":"2.308781701s","remote":"127.0.0.1:55800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.222\" "} {"level":"warn","ts":"2026-04-15T05:05:01.105032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:05:00.502603Z","time spent":"602.425427ms","remote":"127.0.0.1:56042","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-04-15T05:05:22.893832Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6890117719661632729,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-15T05:05:23.035271Z","caller":"traceutil/trace.go:171","msg":"trace[1798949000] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"662.880915ms","start":"2026-04-15T05:05:22.372363Z","end":"2026-04-15T05:05:23.035244Z","steps":["trace[1798949000] 'process raft request' (duration: 662.716031ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:05:23.035481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:05:22.372345Z","time spent":"663.0433ms","remote":"127.0.0.1:56042","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-04-15T05:05:23.035584Z","caller":"traceutil/trace.go:171","msg":"trace[888970750] linearizableReadLoop","detail":"{readStateIndex:1354; appliedIndex:1354; }","duration":"642.329302ms","start":"2026-04-15T05:05:22.393226Z","end":"2026-04-15T05:05:23.035556Z","steps":["trace[888970750] 'read index received' (duration: 642.320642ms)","trace[888970750] 'applied index is now lower than readState.Index' (duration: 7.18µs)"],"step_count":2} {"level":"warn","ts":"2026-04-15T05:05:23.035733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.65586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T05:05:23.035765Z","caller":"traceutil/trace.go:171","msg":"trace[596121041] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1304; }","duration":"584.775924ms","start":"2026-04-15T05:05:22.450979Z","end":"2026-04-15T05:05:23.035755Z","steps":["trace[596121041] 'agreement among raft nodes before linearized reading' (duration: 584.688422ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:05:23.035801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:05:22.450954Z","time spent":"584.840055ms","remote":"127.0.0.1:37350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"warn","ts":"2026-04-15T05:05:23.035833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"642.598599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-15T05:05:23.035906Z","caller":"traceutil/trace.go:171","msg":"trace[1568418446] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1304; }","duration":"642.713053ms","start":"2026-04-15T05:05:22.393175Z","end":"2026-04-15T05:05:23.035888Z","steps":["trace[1568418446] 'agreement among raft nodes before linearized reading' (duration: 642.59375ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:05:23.035961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:05:22.393156Z","time spent":"642.790415ms","remote":"127.0.0.1:53442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-04-15T05:05:23.546478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.675868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T05:05:23.54656Z","caller":"traceutil/trace.go:171","msg":"trace[584202529] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1304; }","duration":"407.811872ms","start":"2026-04-15T05:05:23.13873Z","end":"2026-04-15T05:05:23.546542Z","steps":["trace[584202529] 'range keys from in-memory index tree' (duration: 407.592056ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:05:23.546604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:05:23.138712Z","time spent":"407.883244ms","remote":"127.0.0.1:55762","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-04-15T05:05:23.546772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.231138ms","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-04-15T05:05:23.547021Z","caller":"traceutil/trace.go:171","msg":"trace[1437899848] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1304; }","duration":"232.432674ms","start":"2026-04-15T05:05:23.314501Z","end":"2026-04-15T05:05:23.546934Z","steps":["trace[1437899848] 'range keys from in-memory index tree' (duration: 232.209348ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:05:23.547033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.028727ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-15T05:05:23.547325Z","caller":"traceutil/trace.go:171","msg":"trace[110173638] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"371.012693ms","start":"2026-04-15T05:05:23.176304Z","end":"2026-04-15T05:05:23.547317Z","steps":["trace[110173638] 'process raft request' (duration: 260.644037ms)","trace[110173638] 'compare' (duration: 109.748029ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T05:05:23.547337Z","caller":"traceutil/trace.go:171","msg":"trace[82593979] linearizableReadLoop","detail":"{readStateIndex:1355; appliedIndex:1354; }","duration":"347.635052ms","start":"2026-04-15T05:05:23.199683Z","end":"2026-04-15T05:05:23.547318Z","steps":["trace[82593979] 'read index received' (duration: 237.207664ms)","trace[82593979] 'applied index is now lower than readState.Index' (duration: 110.425778ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T05:05:23.547375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:05:23.176284Z","time spent":"371.066763ms","remote":"127.0.0.1:56042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T05:05:23.547526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.844397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-15T05:05:23.547586Z","caller":"traceutil/trace.go:171","msg":"trace[77675246] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1305; }","duration":"347.941ms","start":"2026-04-15T05:05:23.199634Z","end":"2026-04-15T05:05:23.547575Z","steps":["trace[77675246] 'agreement among raft nodes before linearized reading' (duration: 347.758325ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:05:23.547627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:05:23.199612Z","time spent":"348.001411ms","remote":"127.0.0.1:56042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-15T05:05:23.547657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.347187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-15T05:05:23.54768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.84487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-15T05:05:23.547709Z","caller":"traceutil/trace.go:171","msg":"trace[318124360] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1305; }","duration":"338.892693ms","start":"2026-04-15T05:05:23.208809Z","end":"2026-04-15T05:05:23.547702Z","steps":["trace[318124360] 'agreement among raft nodes before linearized reading' (duration: 338.81522ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T05:05:23.547714Z","caller":"traceutil/trace.go:171","msg":"trace[1675596023] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1305; }","duration":"346.427249ms","start":"2026-04-15T05:05:23.201272Z","end":"2026-04-15T05:05:23.547699Z","steps":["trace[1675596023] 'agreement among raft nodes before linearized reading' (duration: 346.207883ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:05:23.547736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:05:23.208799Z","time spent":"338.931193ms","remote":"127.0.0.1:56042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-15T05:05:23.547743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.308223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-15T05:05:23.547762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:05:23.20126Z","time spent":"346.48994ms","remote":"127.0.0.1:56042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-15T05:05:23.547796Z","caller":"traceutil/trace.go:171","msg":"trace[196580816] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1305; }","duration":"258.394654ms","start":"2026-04-15T05:05:23.289387Z","end":"2026-04-15T05:05:23.547782Z","steps":["trace[196580816] 'agreement among raft nodes before linearized reading' (duration: 258.318153ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:05:23.547861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.836258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-04-15T05:05:23.547658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.277801ms","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-04-15T05:05:23.547887Z","caller":"traceutil/trace.go:171","msg":"trace[1577257949] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1305; }","duration":"343.861338ms","start":"2026-04-15T05:05:23.204018Z","end":"2026-04-15T05:05:23.54788Z","steps":["trace[1577257949] 'agreement among raft nodes before linearized reading' (duration: 343.799487ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:05:23.547905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:05:23.203988Z","time spent":"343.91246ms","remote":"127.0.0.1:56042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-15T05:05:23.547902Z","caller":"traceutil/trace.go:171","msg":"trace[1373001692] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1305; }","duration":"328.566778ms","start":"2026-04-15T05:05:23.21932Z","end":"2026-04-15T05:05:23.547887Z","steps":["trace[1373001692] 'agreement among raft nodes before linearized reading' (duration: 328.173498ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:05:23.547953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:05:23.219314Z","time spent":"328.626021ms","remote":"127.0.0.1:56042","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-04-15T05:05:23.699012Z","caller":"traceutil/trace.go:171","msg":"trace[159063659] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"146.794575ms","start":"2026-04-15T05:05:23.55219Z","end":"2026-04-15T05:05:23.698985Z","steps":["trace[159063659] 'process raft request' (duration: 146.695343ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T05:05:23.699047Z","caller":"traceutil/trace.go:171","msg":"trace[1117077855] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"146.912958ms","start":"2026-04-15T05:05:23.552103Z","end":"2026-04-15T05:05:23.699016Z","steps":["trace[1117077855] 'process raft request' (duration: 125.283305ms)","trace[1117077855] 'compare' (duration: 21.338425ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T05:05:23.699276Z","caller":"traceutil/trace.go:171","msg":"trace[721810202] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"147.046652ms","start":"2026-04-15T05:05:23.552202Z","end":"2026-04-15T05:05:23.699248Z","steps":["trace[721810202] 'process raft request' (duration: 146.738633ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T05:05:23.69978Z","caller":"traceutil/trace.go:171","msg":"trace[869637953] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"146.885207ms","start":"2026-04-15T05:05:23.552885Z","end":"2026-04-15T05:05:23.69977Z","steps":["trace[869637953] 'process raft request' (duration: 146.2956ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:05:24.051294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.785706ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-15T05:05:25.603834Z","caller":"traceutil/trace.go:171","msg":"trace[1644549668] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"163.299308ms","start":"2026-04-15T05:05:25.44051Z","end":"2026-04-15T05:05:25.603809Z","steps":["trace[1644549668] 'process raft request' (duration: 163.083102ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:05:27.034136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.080215ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-15T05:05:27.034257Z","caller":"traceutil/trace.go:171","msg":"trace[1429187153] linearizableReadLoop","detail":"{readStateIndex:1365; appliedIndex:1362; }","duration":"1.435745217s","start":"2026-04-15T05:05:25.5985Z","end":"2026-04-15T05:05:27.034245Z","steps":["trace[1429187153] 'read index received' (duration: 5.163941ms)","trace[1429187153] 'applied index is now lower than readState.Index' (duration: 1.430580666s)"],"step_count":2} {"level":"info","ts":"2026-04-15T05:05:27.034422Z","caller":"traceutil/trace.go:171","msg":"trace[110378619] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"1.583397425s","start":"2026-04-15T05:05:25.451016Z","end":"2026-04-15T05:05:27.034413Z","steps":["trace[110378619] 'process raft request' (duration: 1.12297816s)","trace[110378619] 'compare' (duration: 459.810218ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T05:05:27.034467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:05:25.450987Z","time spent":"1.583459057s","remote":"127.0.0.1:56042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T05:05:27.03458Z","caller":"traceutil/trace.go:171","msg":"trace[348321742] transaction","detail":"{read_only:false; response_revision:1314; number_of_response:1; }","duration":"1.482343044s","start":"2026-04-15T05:05:25.552231Z","end":"2026-04-15T05:05:27.034574Z","steps":["trace[348321742] 'process raft request' (duration: 1.481977154s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:05:27.034617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:05:25.552198Z","time spent":"1.482400206s","remote":"127.0.0.1:56042","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-04-15T05:05:27.034828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.436318842s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-04-15T05:05:27.034899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"895.932686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T05:05:27.034899Z","caller":"traceutil/trace.go:171","msg":"trace[994065858] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1314; }","duration":"1.436438285s","start":"2026-04-15T05:05:25.598442Z","end":"2026-04-15T05:05:27.03488Z","steps":["trace[994065858] 'agreement among raft nodes before linearized reading' (duration: 1.436219009s)"],"step_count":1} {"level":"info","ts":"2026-04-15T05:05:27.034937Z","caller":"traceutil/trace.go:171","msg":"trace[1800121912] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1314; }","duration":"896.133341ms","start":"2026-04-15T05:05:26.13879Z","end":"2026-04-15T05:05:27.034923Z","steps":["trace[1800121912] 'agreement among raft nodes before linearized reading' (duration: 896.08635ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:05:27.03494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:05:25.598413Z","time spent":"1.436518817s","remote":"127.0.0.1:56042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-15T05:05:27.034968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:05:26.13877Z","time spent":"896.191093ms","remote":"127.0.0.1:55762","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-04-15T05:05:27.034844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.330634904s","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-04-15T05:05:27.035056Z","caller":"traceutil/trace.go:171","msg":"trace[209214852] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1314; }","duration":"1.33086548s","start":"2026-04-15T05:05:25.704162Z","end":"2026-04-15T05:05:27.035027Z","steps":["trace[209214852] 'agreement among raft nodes before linearized reading' (duration: 1.330561172s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:05:27.035175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.330966245s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-15T05:05:27.03517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:05:25.704146Z","time spent":"1.330987675s","remote":"127.0.0.1:56042","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-04-15T05:05:27.035162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.33085479s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-15T05:05:27.035205Z","caller":"traceutil/trace.go:171","msg":"trace[1892737652] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1314; }","duration":"1.331021635s","start":"2026-04-15T05:05:25.704174Z","end":"2026-04-15T05:05:27.035196Z","steps":["trace[1892737652] 'agreement among raft nodes before linearized reading' (duration: 1.330932603s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:05:27.035233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:05:25.704159Z","time spent":"1.331068086s","remote":"127.0.0.1:56042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-15T05:05:27.035234Z","caller":"traceutil/trace.go:171","msg":"trace[266819396] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1314; }","duration":"1.330976715s","start":"2026-04-15T05:05:25.704239Z","end":"2026-04-15T05:05:27.035216Z","steps":["trace[266819396] 'agreement among raft nodes before linearized reading' (duration: 1.330760609s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:05:27.035245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.330950273s","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-04-15T05:05:27.035273Z","caller":"traceutil/trace.go:171","msg":"trace[12047495] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1314; }","duration":"1.330991373s","start":"2026-04-15T05:05:25.704274Z","end":"2026-04-15T05:05:27.035266Z","steps":["trace[12047495] 'agreement among raft nodes before linearized reading' (duration: 1.330885942s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:05:27.03528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:05:25.704215Z","time spent":"1.331056057s","remote":"127.0.0.1:56042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-15T05:05:27.035293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:05:25.704268Z","time spent":"1.331020415s","remote":"127.0.0.1:56042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-15T05:05:27.309439Z","caller":"traceutil/trace.go:171","msg":"trace[475532860] transaction","detail":"{read_only:false; response_revision:1316; number_of_response:1; }","duration":"270.013273ms","start":"2026-04-15T05:05:27.039388Z","end":"2026-04-15T05:05:27.309401Z","steps":["trace[475532860] 'process raft request' (duration: 174.484584ms)","trace[475532860] 'compare' (duration: 95.239801ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T05:05:27.30961Z","caller":"traceutil/trace.go:171","msg":"trace[892296231] transaction","detail":"{read_only:false; response_revision:1317; number_of_response:1; }","duration":"270.186879ms","start":"2026-04-15T05:05:27.039399Z","end":"2026-04-15T05:05:27.309586Z","steps":["trace[892296231] 'process raft request' (duration: 269.86237ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T05:05:27.309779Z","caller":"traceutil/trace.go:171","msg":"trace[1757237666] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"269.970072ms","start":"2026-04-15T05:05:27.039793Z","end":"2026-04-15T05:05:27.309763Z","steps":["trace[1757237666] 'process raft request' (duration: 269.700545ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T05:05:27.310006Z","caller":"traceutil/trace.go:171","msg":"trace[1349461351] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"269.450098ms","start":"2026-04-15T05:05:27.04054Z","end":"2026-04-15T05:05:27.30999Z","steps":["trace[1349461351] 'process raft request' (duration: 269.152131ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T05:05:27.310001Z","caller":"traceutil/trace.go:171","msg":"trace[1548695939] linearizableReadLoop","detail":"{readStateIndex:1370; appliedIndex:1366; }","duration":"148.804289ms","start":"2026-04-15T05:05:27.161174Z","end":"2026-04-15T05:05:27.309978Z","steps":["trace[1548695939] 'read index received' (duration: 52.771037ms)","trace[1548695939] 'applied index is now lower than readState.Index' (duration: 96.032122ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T05:05:27.310103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.921254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T05:05:27.310143Z","caller":"traceutil/trace.go:171","msg":"trace[1269842997] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1319; }","duration":"149.014866ms","start":"2026-04-15T05:05:27.161118Z","end":"2026-04-15T05:05:27.310133Z","steps":["trace[1269842997] 'agreement among raft nodes before linearized reading' (duration: 148.938433ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T05:05:27.60141Z","caller":"traceutil/trace.go:171","msg":"trace[940843932] linearizableReadLoop","detail":"{readStateIndex:1371; appliedIndex:1370; }","duration":"123.723112ms","start":"2026-04-15T05:05:27.477668Z","end":"2026-04-15T05:05:27.601391Z","steps":["trace[940843932] 'read index received' (duration: 123.553288ms)","trace[940843932] 'applied index is now lower than readState.Index' (duration: 169.204µs)"],"step_count":2} {"level":"info","ts":"2026-04-15T05:05:27.601454Z","caller":"traceutil/trace.go:171","msg":"trace[1543215821] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"214.145242ms","start":"2026-04-15T05:05:27.387288Z","end":"2026-04-15T05:05:27.601433Z","steps":["trace[1543215821] 'process raft request' (duration: 213.992677ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:05:27.601552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.882108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T05:05:27.60158Z","caller":"traceutil/trace.go:171","msg":"trace[105955206] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1320; }","duration":"123.962589ms","start":"2026-04-15T05:05:27.47761Z","end":"2026-04-15T05:05:27.601572Z","steps":["trace[105955206] 'agreement among raft nodes before linearized reading' (duration: 123.854136ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:05:27.805643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.367403ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-15T05:05:27.805785Z","caller":"traceutil/trace.go:171","msg":"trace[1157568806] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"196.825357ms","start":"2026-04-15T05:05:27.608936Z","end":"2026-04-15T05:05:27.805762Z","steps":["trace[1157568806] 'process raft request' (duration: 77.195317ms)","trace[1157568806] 'compare' (duration: 119.161208ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T05:05:27.981873Z","caller":"traceutil/trace.go:171","msg":"trace[900428785] transaction","detail":"{read_only:false; response_revision:1323; number_of_response:1; }","duration":"168.800378ms","start":"2026-04-15T05:05:27.813044Z","end":"2026-04-15T05:05:27.981844Z","steps":["trace[900428785] 'process raft request' (duration: 123.419475ms)","trace[900428785] 'compare' (duration: 45.201258ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T05:05:28.034086Z","caller":"traceutil/trace.go:171","msg":"trace[1360081907] transaction","detail":"{read_only:false; response_revision:1325; number_of_response:1; }","duration":"183.0738ms","start":"2026-04-15T05:05:27.850992Z","end":"2026-04-15T05:05:28.034066Z","steps":["trace[1360081907] 'process raft request' (duration: 182.995408ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T05:05:28.034109Z","caller":"traceutil/trace.go:171","msg":"trace[1522730049] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"184.55883ms","start":"2026-04-15T05:05:27.849511Z","end":"2026-04-15T05:05:28.03407Z","steps":["trace[1522730049] 'process raft request' (duration: 184.391255ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:05:28.509709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.201709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T05:05:28.509791Z","caller":"traceutil/trace.go:171","msg":"trace[1405482304] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1325; }","duration":"425.304691ms","start":"2026-04-15T05:05:28.084468Z","end":"2026-04-15T05:05:28.509772Z","steps":["trace[1405482304] 'count revisions from in-memory index tree' (duration: 425.082525ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:05:28.509833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:05:28.084446Z","time spent":"425.375782ms","remote":"127.0.0.1:52808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"warn","ts":"2026-04-15T05:05:28.510052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.979617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T05:05:28.510083Z","caller":"traceutil/trace.go:171","msg":"trace[2001827611] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1325; }","duration":"373.048538ms","start":"2026-04-15T05:05:28.137026Z","end":"2026-04-15T05:05:28.510074Z","steps":["trace[2001827611] 'range keys from in-memory index tree' (duration: 372.871383ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:05:28.510109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:05:28.137006Z","time spent":"373.096479ms","remote":"127.0.0.1:55764","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-04-15T05:05:29.166732Z","caller":"traceutil/trace.go:171","msg":"trace[319755646] transaction","detail":"{read_only:false; response_revision:1327; number_of_response:1; }","duration":"126.464798ms","start":"2026-04-15T05:05:29.040236Z","end":"2026-04-15T05:05:29.1667Z","steps":["trace[319755646] 'process raft request' (duration: 104.32453ms)","trace[319755646] 'compare' (duration: 21.952312ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T05:05:29.444614Z","caller":"traceutil/trace.go:171","msg":"trace[261412172] linearizableReadLoop","detail":"{readStateIndex:1380; appliedIndex:1379; }","duration":"130.950021ms","start":"2026-04-15T05:05:29.313634Z","end":"2026-04-15T05:05:29.444584Z","steps":["trace[261412172] 'read index received' (duration: 113.599025ms)","trace[261412172] 'applied index is now lower than readState.Index' (duration: 17.349836ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T05:05:29.44467Z","caller":"traceutil/trace.go:171","msg":"trace[166896894] transaction","detail":"{read_only:false; response_revision:1328; number_of_response:1; }","duration":"273.88954ms","start":"2026-04-15T05:05:29.170758Z","end":"2026-04-15T05:05:29.444647Z","steps":["trace[166896894] 'process raft request' (duration: 256.480982ms)","trace[166896894] 'compare' (duration: 17.196782ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T05:05:29.444859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.699073ms","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-04-15T05:05:29.444859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.204368ms","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-04-15T05:05:29.444919Z","caller":"traceutil/trace.go:171","msg":"trace[364053236] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1328; }","duration":"130.786896ms","start":"2026-04-15T05:05:29.314117Z","end":"2026-04-15T05:05:29.444904Z","steps":["trace[364053236] 'agreement among raft nodes before linearized reading' (duration: 130.631152ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T05:05:29.444929Z","caller":"traceutil/trace.go:171","msg":"trace[40183791] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1328; }","duration":"131.323881ms","start":"2026-04-15T05:05:29.31359Z","end":"2026-04-15T05:05:29.444914Z","steps":["trace[40183791] 'agreement among raft nodes before linearized reading' (duration: 131.105325ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:05:29.444974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.790467ms","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-04-15T05:05:29.444881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.300643ms","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-04-15T05:05:29.445012Z","caller":"traceutil/trace.go:171","msg":"trace[654161150] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1328; }","duration":"130.833667ms","start":"2026-04-15T05:05:29.314168Z","end":"2026-04-15T05:05:29.445002Z","steps":["trace[654161150] 'agreement among raft nodes before linearized reading' (duration: 130.749605ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T05:05:29.445099Z","caller":"traceutil/trace.go:171","msg":"trace[918257805] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1328; }","duration":"130.504238ms","start":"2026-04-15T05:05:29.314555Z","end":"2026-04-15T05:05:29.44506Z","steps":["trace[918257805] 'agreement among raft nodes before linearized reading' (duration: 130.23238ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T05:05:31.155067Z","caller":"traceutil/trace.go:171","msg":"trace[1232226096] transaction","detail":"{read_only:false; response_revision:1333; number_of_response:1; }","duration":"541.686313ms","start":"2026-04-15T05:05:30.613344Z","end":"2026-04-15T05:05:31.15503Z","steps":["trace[1232226096] 'process raft request' (duration: 541.371294ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:05:31.15527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:05:30.613328Z","time spent":"541.835056ms","remote":"127.0.0.1:56042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T05:05:32.454975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.50545ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-15T05:05:32.455081Z","caller":"traceutil/trace.go:171","msg":"trace[187482779] linearizableReadLoop","detail":"{readStateIndex:1386; appliedIndex:1385; }","duration":"1.005780717s","start":"2026-04-15T05:05:31.449283Z","end":"2026-04-15T05:05:32.455064Z","steps":["trace[187482779] 'read index received' (duration: 461.141664ms)","trace[187482779] 'applied index is now lower than readState.Index' (duration: 544.637303ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T05:05:32.455104Z","caller":"traceutil/trace.go:171","msg":"trace[438967765] transaction","detail":"{read_only:false; response_revision:1334; number_of_response:1; }","duration":"1.283776149s","start":"2026-04-15T05:05:31.171304Z","end":"2026-04-15T05:05:32.45508Z","steps":["trace[438967765] 'process raft request' (duration: 739.077524ms)","trace[438967765] 'compare' (duration: 544.14736ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T05:05:32.455224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:05:31.171283Z","time spent":"1.283874294s","remote":"127.0.0.1:56042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T05:05:32.455275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.005995533s","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-04-15T05:05:32.455302Z","caller":"traceutil/trace.go:171","msg":"trace[591362752] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1334; }","duration":"1.006060605s","start":"2026-04-15T05:05:31.449232Z","end":"2026-04-15T05:05:32.455293Z","steps":["trace[591362752] 'agreement among raft nodes before linearized reading' (duration: 1.005915292s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:05:32.455337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:05:31.449213Z","time spent":"1.006113676s","remote":"127.0.0.1:56042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-15T05:05:32.455469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"992.030631ms","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-04-15T05:05:32.455498Z","caller":"traceutil/trace.go:171","msg":"trace[1887182431] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1334; }","duration":"992.100992ms","start":"2026-04-15T05:05:31.46339Z","end":"2026-04-15T05:05:32.455491Z","steps":["trace[1887182431] 'agreement among raft nodes before linearized reading' (duration: 991.979419ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:05:32.455519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:05:31.463357Z","time spent":"992.156723ms","remote":"127.0.0.1:56042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-15T05:05:32.455626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"926.405861ms","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-04-15T05:05:32.455645Z","caller":"traceutil/trace.go:171","msg":"trace[847273698] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1334; }","duration":"926.444731ms","start":"2026-04-15T05:05:31.529195Z","end":"2026-04-15T05:05:32.455639Z","steps":["trace[847273698] 'agreement among raft nodes before linearized reading' (duration: 926.391331ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:05:32.455662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:05:31.529187Z","time spent":"926.469493ms","remote":"127.0.0.1:56042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-15T05:05:32.455679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"926.295839ms","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-04-15T05:05:32.455743Z","caller":"traceutil/trace.go:171","msg":"trace[1814447524] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1334; }","duration":"926.406001ms","start":"2026-04-15T05:05:31.529315Z","end":"2026-04-15T05:05:32.455721Z","steps":["trace[1814447524] 'agreement among raft nodes before linearized reading' (duration: 926.214285ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:05:32.455751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"926.822452ms","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-04-15T05:05:32.455702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.7648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T05:05:32.45577Z","caller":"traceutil/trace.go:171","msg":"trace[27980015] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1334; }","duration":"926.874525ms","start":"2026-04-15T05:05:31.52889Z","end":"2026-04-15T05:05:32.455765Z","steps":["trace[27980015] 'agreement among raft nodes before linearized reading' (duration: 926.814583ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:05:32.455774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:05:31.529286Z","time spent":"926.481243ms","remote":"127.0.0.1:56042","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-04-15T05:05:32.455796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:05:31.528864Z","time spent":"926.927125ms","remote":"127.0.0.1:56042","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-04-15T05:05:32.455809Z","caller":"traceutil/trace.go:171","msg":"trace[1715490797] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1334; }","duration":"318.917805ms","start":"2026-04-15T05:05:32.136865Z","end":"2026-04-15T05:05:32.455783Z","steps":["trace[1715490797] 'agreement among raft nodes before linearized reading' (duration: 318.74089ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:05:32.45603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:05:32.136849Z","time spent":"319.165391ms","remote":"127.0.0.1:55762","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-04-15T05:08:47.668662Z","caller":"traceutil/trace.go:171","msg":"trace[1073256840] transaction","detail":"{read_only:false; response_revision:2282; number_of_response:1; }","duration":"137.509865ms","start":"2026-04-15T05:08:47.531117Z","end":"2026-04-15T05:08:47.668627Z","steps":["trace[1073256840] 'process raft request' (duration: 83.338794ms)","trace[1073256840] 'compare' (duration: 54.021017ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T05:08:48.025071Z","caller":"traceutil/trace.go:171","msg":"trace[427170919] transaction","detail":"{read_only:false; response_revision:2285; number_of_response:1; }","duration":"349.808769ms","start":"2026-04-15T05:08:47.675234Z","end":"2026-04-15T05:08:48.025043Z","steps":["trace[427170919] 'process raft request' (duration: 349.727718ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T05:08:48.02509Z","caller":"traceutil/trace.go:171","msg":"trace[1025587971] transaction","detail":"{read_only:false; response_revision:2284; number_of_response:1; }","duration":"349.898212ms","start":"2026-04-15T05:08:47.675153Z","end":"2026-04-15T05:08:48.025051Z","steps":["trace[1025587971] 'process raft request' (duration: 349.634835ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:08:48.025253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:08:47.675228Z","time spent":"349.929983ms","remote":"127.0.0.1:56042","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-04-15T05:08:48.02526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:08:47.675141Z","time spent":"350.028346ms","remote":"127.0.0.1:56042","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-04-15T05:08:48.025352Z","caller":"traceutil/trace.go:171","msg":"trace[711190582] transaction","detail":"{read_only:false; response_revision:2286; number_of_response:1; }","duration":"349.688986ms","start":"2026-04-15T05:08:47.67562Z","end":"2026-04-15T05:08:48.025309Z","steps":["trace[711190582] 'process raft request' (duration: 349.385728ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:08:48.025518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:08:47.675609Z","time spent":"349.820719ms","remote":"127.0.0.1:56042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T05:08:51.235897Z","caller":"traceutil/trace.go:171","msg":"trace[763840502] transaction","detail":"{read_only:false; response_revision:2300; number_of_response:1; }","duration":"542.710759ms","start":"2026-04-15T05:08:50.693139Z","end":"2026-04-15T05:08:51.23585Z","steps":["trace[763840502] 'process raft request' (duration: 542.332789ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T05:08:51.236121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T05:08:50.693116Z","time spent":"542.915974ms","remote":"127.0.0.1:56042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"}