{"level":"warn","ts":"2026-05-30T09:50:06.202871Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-30T09:50:06.202985Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.210: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.210:2380","--initial-cluster=instance=https://199.204.45.210:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.210:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.210:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-30T09:50:06.203065Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-30T09:50:06.203081Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.210:2380"]} {"level":"info","ts":"2026-05-30T09:50:06.203109Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-30T09:50:06.20395Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"]} {"level":"info","ts":"2026-05-30T09:50:06.204085Z","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.210:2380"],"listen-peer-urls":["https://199.204.45.210:2380"],"advertise-client-urls":["https://199.204.45.210:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.210:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-30T09:50:06.3363Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"131.962982ms"} {"level":"info","ts":"2026-05-30T09:50:08.396172Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1cac722af8b2b8a6","cluster-id":"ba0a336ca61ee41d"} {"level":"info","ts":"2026-05-30T09:50:08.396285Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=()"} {"level":"info","ts":"2026-05-30T09:50:08.396314Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became follower at term 0"} {"level":"info","ts":"2026-05-30T09:50:08.396359Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1cac722af8b2b8a6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-30T09:50:08.396375Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became follower at term 1"} {"level":"info","ts":"2026-05-30T09:50:08.396425Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=(2066151857942870182)"} {"level":"warn","ts":"2026-05-30T09:50:09.487134Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-30T09:50:09.489976Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-30T09:50:09.491695Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-30T09:50:09.49641Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1cac722af8b2b8a6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-30T09:50:09.49737Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-30T09:50:09.497438Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-30T09:50:09.497449Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-30T09:50:09.497103Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1cac722af8b2b8a6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-30T09:50:09.501061Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=(2066151857942870182)"} {"level":"info","ts":"2026-05-30T09:50:09.501187Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ba0a336ca61ee41d","local-member-id":"1cac722af8b2b8a6","added-peer-id":"1cac722af8b2b8a6","added-peer-peer-urls":["https://199.204.45.210:2380"]} {"level":"info","ts":"2026-05-30T09:50:09.501994Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-30T09:50:09.502169Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.210:2380"} {"level":"info","ts":"2026-05-30T09:50:09.502216Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.210:2380"} {"level":"info","ts":"2026-05-30T09:50:09.502369Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1cac722af8b2b8a6","initial-advertise-peer-urls":["https://199.204.45.210:2380"],"listen-peer-urls":["https://199.204.45.210:2380"],"advertise-client-urls":["https://199.204.45.210:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-30T09:50:09.502396Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-30T09:50:10.298113Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 is starting a new election at term 1"} {"level":"info","ts":"2026-05-30T09:50:10.298171Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-30T09:50:10.298213Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 received MsgPreVoteResp from 1cac722af8b2b8a6 at term 1"} {"level":"info","ts":"2026-05-30T09:50:10.298233Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became candidate at term 2"} {"level":"info","ts":"2026-05-30T09:50:10.298251Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 received MsgVoteResp from 1cac722af8b2b8a6 at term 2"} {"level":"info","ts":"2026-05-30T09:50:10.298291Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became leader at term 2"} {"level":"info","ts":"2026-05-30T09:50:10.298305Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1cac722af8b2b8a6 elected leader 1cac722af8b2b8a6 at term 2"} {"level":"info","ts":"2026-05-30T09:50:10.299371Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1cac722af8b2b8a6","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.210:2379]}","request-path":"/0/members/1cac722af8b2b8a6/attributes","cluster-id":"ba0a336ca61ee41d","publish-timeout":"7s"} {"level":"info","ts":"2026-05-30T09:50:10.299371Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-30T09:50:10.299393Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-30T09:50:10.299426Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-30T09:50:10.299714Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-30T09:50:10.299752Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-30T09:50:10.302274Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-30T09:50:10.300668Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ba0a336ca61ee41d","local-member-id":"1cac722af8b2b8a6","cluster-version":"3.5"} {"level":"info","ts":"2026-05-30T09:50:10.302783Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-30T09:50:10.302836Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-30T09:50:10.303078Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.210:2379"} {"level":"info","ts":"2026-05-30T09:50:30.361681Z","caller":"traceutil/trace.go:171","msg":"trace[1297292056] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"177.632514ms","start":"2026-05-30T09:50:30.184024Z","end":"2026-05-30T09:50:30.361656Z","steps":["trace[1297292056] 'process raft request' (duration: 107.169628ms)","trace[1297292056] 'compare' (duration: 70.382614ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:50:33.175046Z","caller":"traceutil/trace.go:171","msg":"trace[1748078233] transaction","detail":"{read_only:false; response_revision:435; number_of_response:1; }","duration":"124.659611ms","start":"2026-05-30T09:50:33.050362Z","end":"2026-05-30T09:50:33.175022Z","steps":["trace[1748078233] 'process raft request' (duration: 124.482076ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:50:38.646883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.944954ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-30T09:50:40.353928Z","caller":"traceutil/trace.go:171","msg":"trace[127751766] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"259.356957ms","start":"2026-05-30T09:50:40.094532Z","end":"2026-05-30T09:50:40.353889Z","steps":["trace[127751766] 'process raft request' (duration: 259.07931ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:50:44.105128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.173324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-30T09:50:44.105225Z","caller":"traceutil/trace.go:171","msg":"trace[1291443593] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:487; }","duration":"248.346518ms","start":"2026-05-30T09:50:43.856855Z","end":"2026-05-30T09:50:44.105201Z","steps":["trace[1291443593] 'range keys from in-memory index tree' (duration: 248.03994ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:50:44.10563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.13051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:50:44.105675Z","caller":"traceutil/trace.go:171","msg":"trace[240601967] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:487; }","duration":"395.224793ms","start":"2026-05-30T09:50:43.710439Z","end":"2026-05-30T09:50:44.105664Z","steps":["trace[240601967] 'range keys from in-memory index tree' (duration: 395.036498ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:50:44.105705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:50:43.710414Z","time spent":"395.281264ms","remote":"127.0.0.1:43562","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-05-30T09:50:48.367878Z","caller":"traceutil/trace.go:171","msg":"trace[1856385688] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"400.714921ms","start":"2026-05-30T09:50:47.967127Z","end":"2026-05-30T09:50:48.367842Z","steps":["trace[1856385688] 'process raft request' (duration: 400.535926ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:50:48.36862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:50:47.967105Z","time spent":"400.894866ms","remote":"127.0.0.1:43628","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-05-30T09:50:48.650402Z","caller":"traceutil/trace.go:171","msg":"trace[26623811] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"681.80667ms","start":"2026-05-30T09:50:47.968537Z","end":"2026-05-30T09:50:48.650344Z","steps":["trace[26623811] 'process raft request' (duration: 681.515442ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:50:48.650714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:50:47.968511Z","time spent":"682.045256ms","remote":"127.0.0.1:43628","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-05-30T09:50:48.650972Z","caller":"traceutil/trace.go:171","msg":"trace[1525298110] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"669.510878ms","start":"2026-05-30T09:50:47.981415Z","end":"2026-05-30T09:50:48.650925Z","steps":["trace[1525298110] 'process raft request' (duration: 668.970904ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:50:48.651087Z","caller":"traceutil/trace.go:171","msg":"trace[1721579562] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"669.296303ms","start":"2026-05-30T09:50:47.981687Z","end":"2026-05-30T09:50:48.650983Z","steps":["trace[1721579562] 'process raft request' (duration: 668.801569ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:50:48.651166Z","caller":"traceutil/trace.go:171","msg":"trace[1648293621] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"643.582071ms","start":"2026-05-30T09:50:48.007551Z","end":"2026-05-30T09:50:48.651133Z","steps":["trace[1648293621] 'process raft request' (duration: 643.282054ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:50:48.651649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:50:48.007532Z","time spent":"644.085564ms","remote":"127.0.0.1:43628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-30T09:50:48.651174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:50:47.981383Z","time spent":"669.695923ms","remote":"127.0.0.1:43628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-30T09:50:48.651794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:50:47.98168Z","time spent":"669.864098ms","remote":"127.0.0.1:43628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-30T09:50:48.651247Z","caller":"traceutil/trace.go:171","msg":"trace[312341450] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"677.126412ms","start":"2026-05-30T09:50:47.973873Z","end":"2026-05-30T09:50:48.650999Z","steps":["trace[312341450] 'process raft request' (duration: 676.395643ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:50:48.651244Z","caller":"traceutil/trace.go:171","msg":"trace[324770563] linearizableReadLoop","detail":"{readStateIndex:561; appliedIndex:550; }","duration":"522.690136ms","start":"2026-05-30T09:50:48.128528Z","end":"2026-05-30T09:50:48.651218Z","steps":["trace[324770563] 'read index received' (duration: 239.192636ms)","trace[324770563] 'applied index is now lower than readState.Index' (duration: 283.49649ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:50:48.651294Z","caller":"traceutil/trace.go:171","msg":"trace[96856802] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"682.329924ms","start":"2026-05-30T09:50:47.968678Z","end":"2026-05-30T09:50:48.651008Z","steps":["trace[96856802] 'process raft request' (duration: 681.505313ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:50:48.652109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:50:47.973864Z","time spent":"678.062175ms","remote":"127.0.0.1:43628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-30T09:50:48.651319Z","caller":"traceutil/trace.go:171","msg":"trace[1739781943] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"676.725921ms","start":"2026-05-30T09:50:47.974284Z","end":"2026-05-30T09:50:48.65101Z","steps":["trace[1739781943] 'process raft request' (duration: 676.031243ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:50:48.652203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:50:47.974261Z","time spent":"677.911821ms","remote":"127.0.0.1:43628","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-05-30T09:50:48.651342Z","caller":"traceutil/trace.go:171","msg":"trace[966533681] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"663.181378ms","start":"2026-05-30T09:50:47.987845Z","end":"2026-05-30T09:50:48.651026Z","steps":["trace[966533681] 'process raft request' (duration: 662.690605ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:50:48.65225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:50:47.968672Z","time spent":"683.428542ms","remote":"127.0.0.1:43628","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-05-30T09:50:48.652306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:50:47.987831Z","time spent":"664.45754ms","remote":"127.0.0.1:43628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-30T09:50:48.651285Z","caller":"traceutil/trace.go:171","msg":"trace[1694575365] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"500.722499ms","start":"2026-05-30T09:50:48.1505Z","end":"2026-05-30T09:50:48.651223Z","steps":["trace[1694575365] 'process raft request' (duration: 500.523284ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:50:48.65239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:50:48.150468Z","time spent":"501.905178ms","remote":"127.0.0.1:43628","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-05-30T09:50:48.651364Z","caller":"traceutil/trace.go:171","msg":"trace[1604518825] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"657.003052ms","start":"2026-05-30T09:50:47.994035Z","end":"2026-05-30T09:50:48.651038Z","steps":["trace[1604518825] 'process raft request' (duration: 656.630033ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:50:48.65252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:50:47.99402Z","time spent":"658.477388ms","remote":"127.0.0.1:43628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-30T09:50:48.651384Z","caller":"traceutil/trace.go:171","msg":"trace[1312223697] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"669.498498ms","start":"2026-05-30T09:50:47.981545Z","end":"2026-05-30T09:50:48.651044Z","steps":["trace[1312223697] 'process raft request' (duration: 668.884762ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:50:48.652585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:50:47.981509Z","time spent":"671.055167ms","remote":"127.0.0.1:43628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-30T09:50:48.651426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.87661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-30T09:50:48.652689Z","caller":"traceutil/trace.go:171","msg":"trace[1456150236] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:543; }","duration":"524.194283ms","start":"2026-05-30T09:50:48.128481Z","end":"2026-05-30T09:50:48.652676Z","steps":["trace[1456150236] 'agreement among raft nodes before linearized reading' (duration: 522.820959ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:50:48.652736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:50:48.128457Z","time spent":"524.262665ms","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-30T09:50:48.663598Z","caller":"traceutil/trace.go:171","msg":"trace[801868447] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"287.397907ms","start":"2026-05-30T09:50:48.376176Z","end":"2026-05-30T09:50:48.663574Z","steps":["trace[801868447] 'process raft request' (duration: 286.811123ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:50:48.66373Z","caller":"traceutil/trace.go:171","msg":"trace[1889554435] transaction","detail":"{read_only:false; number_of_response:1; response_revision:545; }","duration":"286.754441ms","start":"2026-05-30T09:50:48.376892Z","end":"2026-05-30T09:50:48.663646Z","steps":["trace[1889554435] 'process raft request' (duration: 286.187667ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:50:48.664166Z","caller":"traceutil/trace.go:171","msg":"trace[1762800490] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"289.253625ms","start":"2026-05-30T09:50:48.374896Z","end":"2026-05-30T09:50:48.664149Z","steps":["trace[1762800490] 'process raft request' (duration: 287.932612ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:50:48.669254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.785275ms","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-05-30T09:50:48.6693Z","caller":"traceutil/trace.go:171","msg":"trace[705655197] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:545; }","duration":"279.906748ms","start":"2026-05-30T09:50:48.389382Z","end":"2026-05-30T09:50:48.669289Z","steps":["trace[705655197] 'agreement among raft nodes before linearized reading' (duration: 279.783785ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:50:48.669446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.144905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-30T09:50:48.669465Z","caller":"traceutil/trace.go:171","msg":"trace[1619774707] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:545; }","duration":"213.190366ms","start":"2026-05-30T09:50:48.45627Z","end":"2026-05-30T09:50:48.669461Z","steps":["trace[1619774707] 'agreement among raft nodes before linearized reading' (duration: 213.152226ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:50:48.669549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.02003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-30T09:50:48.669567Z","caller":"traceutil/trace.go:171","msg":"trace[1558686577] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:545; }","duration":"278.073981ms","start":"2026-05-30T09:50:48.391488Z","end":"2026-05-30T09:50:48.669563Z","steps":["trace[1558686577] 'agreement among raft nodes before linearized reading' (duration: 278.02914ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:50:55.183658Z","caller":"traceutil/trace.go:171","msg":"trace[835582053] linearizableReadLoop","detail":"{readStateIndex:661; appliedIndex:660; }","duration":"472.7742ms","start":"2026-05-30T09:50:54.710858Z","end":"2026-05-30T09:50:55.183633Z","steps":["trace[835582053] 'read index received' (duration: 471.987319ms)","trace[835582053] 'applied index is now lower than readState.Index' (duration: 785.891µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:50:55.183777Z","caller":"traceutil/trace.go:171","msg":"trace[323027856] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"531.94079ms","start":"2026-05-30T09:50:54.651814Z","end":"2026-05-30T09:50:55.183755Z","steps":["trace[323027856] 'process raft request' (duration: 531.252921ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:50:55.183843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.965014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:50:55.183887Z","caller":"traceutil/trace.go:171","msg":"trace[1423938811] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:638; }","duration":"473.059897ms","start":"2026-05-30T09:50:54.710814Z","end":"2026-05-30T09:50:55.183874Z","steps":["trace[1423938811] 'agreement among raft nodes before linearized reading' (duration: 472.971514ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:50:55.183923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:50:54.710788Z","time spent":"473.127709ms","remote":"127.0.0.1:43562","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-05-30T09:50:55.184112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:50:54.651782Z","time spent":"532.053703ms","remote":"127.0.0.1:43654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:50:55.184126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.588106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-30T09:50:55.18423Z","caller":"traceutil/trace.go:171","msg":"trace[865403766] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:638; }","duration":"463.71253ms","start":"2026-05-30T09:50:54.720488Z","end":"2026-05-30T09:50:55.1842Z","steps":["trace[865403766] 'agreement among raft nodes before linearized reading' (duration: 463.417712ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:50:55.184282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:50:54.720481Z","time spent":"463.791732ms","remote":"127.0.0.1:43846","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-05-30T09:50:55.184245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.376586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-30T09:50:55.184278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.069384ms","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-05-30T09:50:55.184354Z","caller":"traceutil/trace.go:171","msg":"trace[626877711] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:638; }","duration":"423.509819ms","start":"2026-05-30T09:50:54.760828Z","end":"2026-05-30T09:50:55.184337Z","steps":["trace[626877711] 'agreement among raft nodes before linearized reading' (duration: 423.202401ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:50:55.184411Z","caller":"traceutil/trace.go:171","msg":"trace[1577300390] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:638; }","duration":"424.252239ms","start":"2026-05-30T09:50:54.760126Z","end":"2026-05-30T09:50:55.184378Z","steps":["trace[1577300390] 'agreement among raft nodes before linearized reading' (duration: 423.851919ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:50:55.184435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:50:54.760818Z","time spent":"423.605542ms","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-30T09:50:55.18447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:50:54.760114Z","time spent":"424.345661ms","remote":"127.0.0.1:43846","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-05-30T09:50:55.184711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.665675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-30T09:50:55.184838Z","caller":"traceutil/trace.go:171","msg":"trace[1662143721] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:638; }","duration":"425.839599ms","start":"2026-05-30T09:50:54.758979Z","end":"2026-05-30T09:50:55.184819Z","steps":["trace[1662143721] 'agreement among raft nodes before linearized reading' (duration: 425.108961ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:50:55.184932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:50:54.758953Z","time spent":"425.969262ms","remote":"127.0.0.1:43846","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-05-30T09:50:56.293219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.822282ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-30T09:50:56.293799Z","caller":"traceutil/trace.go:171","msg":"trace[1641518739] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"1.104215852s","start":"2026-05-30T09:50:55.189522Z","end":"2026-05-30T09:50:56.293738Z","steps":["trace[1641518739] 'process raft request' (duration: 579.771174ms)","trace[1641518739] 'compare' (duration: 523.477373ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:50:56.293967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.07534968s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-5wc4s\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-05-30T09:50:56.293977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:50:55.189491Z","time spent":"1.104392217s","remote":"127.0.0.1:43846","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-05-30T09:50:56.293946Z","caller":"traceutil/trace.go:171","msg":"trace[1248384164] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"1.104330055s","start":"2026-05-30T09:50:55.189573Z","end":"2026-05-30T09:50:56.293904Z","steps":["trace[1248384164] 'process raft request' (duration: 1.104155031s)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:50:56.294038Z","caller":"traceutil/trace.go:171","msg":"trace[596274202] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"1.103265238s","start":"2026-05-30T09:50:55.190764Z","end":"2026-05-30T09:50:56.294029Z","steps":["trace[596274202] 'process raft request' (duration: 1.103019222s)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:50:56.293838Z","caller":"traceutil/trace.go:171","msg":"trace[1716551702] linearizableReadLoop","detail":"{readStateIndex:665; appliedIndex:661; }","duration":"1.075200737s","start":"2026-05-30T09:50:55.218621Z","end":"2026-05-30T09:50:56.293822Z","steps":["trace[1716551702] 'read index received' (duration: 2.237597ms)","trace[1716551702] 'applied index is now lower than readState.Index' (duration: 1.07296234s)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:50:56.294097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:50:55.190755Z","time spent":"1.10331077s","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:50:56.294182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:50:55.189558Z","time spent":"1.104502119s","remote":"127.0.0.1:43846","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-05-30T09:50:56.294004Z","caller":"traceutil/trace.go:171","msg":"trace[1300572271] range","detail":"{range_begin:/registry/pods/kube-system/cilium-5wc4s; range_end:; response_count:1; response_revision:642; }","duration":"1.075430102s","start":"2026-05-30T09:50:55.218564Z","end":"2026-05-30T09:50:56.293994Z","steps":["trace[1300572271] 'agreement among raft nodes before linearized reading' (duration: 1.075304989s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:50:56.294321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:50:55.218541Z","time spent":"1.0757398s","remote":"127.0.0.1:43754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-5wc4s\" "} {"level":"warn","ts":"2026-05-30T09:50:56.811232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.506666ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-30T09:50:56.811388Z","caller":"traceutil/trace.go:171","msg":"trace[448432658] linearizableReadLoop","detail":"{readStateIndex:666; appliedIndex:665; }","duration":"517.262386ms","start":"2026-05-30T09:50:56.294098Z","end":"2026-05-30T09:50:56.811361Z","steps":["trace[448432658] 'read index received' (duration: 271.591966ms)","trace[448432658] 'applied index is now lower than readState.Index' (duration: 245.66846ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:50:56.811508Z","caller":"traceutil/trace.go:171","msg":"trace[1332638219] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"1.558182054s","start":"2026-05-30T09:50:55.253304Z","end":"2026-05-30T09:50:56.811486Z","steps":["trace[1332638219] 'process raft request' (duration: 1.312327599s)","trace[1332638219] 'compare' (duration: 245.318411ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:50:56.811594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:50:55.253254Z","time spent":"1.558301927s","remote":"127.0.0.1:44096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-30T09:50:56.811659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"958.078484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:50:56.811719Z","caller":"traceutil/trace.go:171","msg":"trace[1708666297] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:643; }","duration":"958.191717ms","start":"2026-05-30T09:50:55.85351Z","end":"2026-05-30T09:50:56.811702Z","steps":["trace[1708666297] 'agreement among raft nodes before linearized reading' (duration: 958.064473ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:50:56.811904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:50:55.853484Z","time spent":"958.407602ms","remote":"127.0.0.1:43562","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-05-30T09:50:57.061229Z","caller":"traceutil/trace.go:171","msg":"trace[1221021579] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"753.522188ms","start":"2026-05-30T09:50:56.307679Z","end":"2026-05-30T09:50:57.061201Z","steps":["trace[1221021579] 'process raft request' (duration: 753.283012ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:50:57.061385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:50:56.307652Z","time spent":"753.650041ms","remote":"127.0.0.1:43754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T09:50:57.061459Z","caller":"traceutil/trace.go:171","msg":"trace[1944313865] linearizableReadLoop","detail":"{readStateIndex:668; appliedIndex:666; }","duration":"249.97889ms","start":"2026-05-30T09:50:56.811442Z","end":"2026-05-30T09:50:57.061421Z","steps":["trace[1944313865] 'read index received' (duration: 249.507438ms)","trace[1944313865] 'applied index is now lower than readState.Index' (duration: 468.492µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:50:57.061527Z","caller":"traceutil/trace.go:171","msg":"trace[1167269210] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"569.237606ms","start":"2026-05-30T09:50:56.492279Z","end":"2026-05-30T09:50:57.061516Z","steps":["trace[1167269210] 'process raft request' (duration: 568.857836ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:50:57.061575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:50:56.492254Z","time spent":"569.295317ms","remote":"127.0.0.1:43846","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-05-30T09:50:57.061694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.619047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-30T09:50:57.061684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.724522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:50:57.061737Z","caller":"traceutil/trace.go:171","msg":"trace[1852047186] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:645; }","duration":"237.694139ms","start":"2026-05-30T09:50:56.82403Z","end":"2026-05-30T09:50:57.061724Z","steps":["trace[1852047186] 'agreement among raft nodes before linearized reading' (duration: 237.631587ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:50:57.061758Z","caller":"traceutil/trace.go:171","msg":"trace[873618054] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:645; }","duration":"351.899436ms","start":"2026-05-30T09:50:56.709841Z","end":"2026-05-30T09:50:57.06174Z","steps":["trace[873618054] 'agreement among raft nodes before linearized reading' (duration: 351.749152ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:50:57.061809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:50:56.709807Z","time spent":"351.988769ms","remote":"127.0.0.1:43572","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-05-30T09:50:58.211645Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305496388595749469,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-30T09:50:58.712249Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305496388595749469,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-30T09:50:58.812353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.586891429s","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-05-30T09:50:58.812434Z","caller":"traceutil/trace.go:171","msg":"trace[2005206694] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:645; }","duration":"1.586984591s","start":"2026-05-30T09:50:57.225428Z","end":"2026-05-30T09:50:58.812413Z","steps":["trace[2005206694] 'range keys from in-memory index tree' (duration: 1.586716375s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:50:58.81248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:50:57.225381Z","time spent":"1.587089734s","remote":"127.0.0.1:43846","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-05-30T09:50:58.812378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.399016ms","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-05-30T09:50:58.812563Z","caller":"traceutil/trace.go:171","msg":"trace[1443184811] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:645; }","duration":"639.625431ms","start":"2026-05-30T09:50:58.172911Z","end":"2026-05-30T09:50:58.812536Z","steps":["trace[1443184811] 'range keys from in-memory index tree' (duration: 639.366485ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:50:58.814355Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.369889016s","expected-duration":"1s"} {"level":"info","ts":"2026-05-30T09:50:58.816287Z","caller":"traceutil/trace.go:171","msg":"trace[2124645827] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"1.373875776s","start":"2026-05-30T09:50:57.442392Z","end":"2026-05-30T09:50:58.816267Z","steps":["trace[2124645827] 'process raft request' (duration: 1.372647995s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:50:58.816465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:50:57.442343Z","time spent":"1.37401898s","remote":"127.0.0.1:43654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T09:50:58.828717Z","caller":"traceutil/trace.go:171","msg":"trace[682669791] linearizableReadLoop","detail":"{readStateIndex:670; appliedIndex:668; }","duration":"1.117586289s","start":"2026-05-30T09:50:57.711104Z","end":"2026-05-30T09:50:58.82869Z","steps":["trace[682669791] 'read index received' (duration: 1.103648556s)","trace[682669791] 'applied index is now lower than readState.Index' (duration: 13.934303ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:50:58.830554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.630868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-30T09:50:58.830624Z","caller":"traceutil/trace.go:171","msg":"trace[1578060889] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:647; }","duration":"532.739111ms","start":"2026-05-30T09:50:58.297854Z","end":"2026-05-30T09:50:58.830594Z","steps":["trace[1578060889] 'agreement among raft nodes before linearized reading' (duration: 530.991687ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:50:58.830662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:50:58.297835Z","time spent":"532.819033ms","remote":"127.0.0.1:43846","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-05-30T09:50:58.831378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.06746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-30T09:50:58.831614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.677885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-30T09:50:58.831696Z","caller":"traceutil/trace.go:171","msg":"trace[1937917967] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:647; }","duration":"533.714206ms","start":"2026-05-30T09:50:58.297927Z","end":"2026-05-30T09:50:58.831641Z","steps":["trace[1937917967] 'agreement among raft nodes before linearized reading' (duration: 530.997907ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:50:58.831653Z","caller":"traceutil/trace.go:171","msg":"trace[843017133] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:647; }","duration":"533.245214ms","start":"2026-05-30T09:50:58.298216Z","end":"2026-05-30T09:50:58.831461Z","steps":["trace[843017133] 'agreement among raft nodes before linearized reading' (duration: 530.68536ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:50:58.831784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:50:58.298209Z","time spent":"533.555272ms","remote":"127.0.0.1:43846","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-05-30T09:50:58.83174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:50:58.29786Z","time spent":"533.86773ms","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-30T09:50:58.83138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.120239907s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:50:58.831893Z","caller":"traceutil/trace.go:171","msg":"trace[1937991076] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:647; }","duration":"1.120842942s","start":"2026-05-30T09:50:57.711032Z","end":"2026-05-30T09:50:58.831875Z","steps":["trace[1937991076] 'agreement among raft nodes before linearized reading' (duration: 1.117758074s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:50:58.831943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:50:57.711002Z","time spent":"1.120918374s","remote":"127.0.0.1:43562","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-05-30T09:51:00.7043Z","caller":"traceutil/trace.go:171","msg":"trace[1129212737] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"245.535335ms","start":"2026-05-30T09:51:00.458736Z","end":"2026-05-30T09:51:00.704271Z","steps":["trace[1129212737] 'process raft request' (duration: 245.388991ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:02.378353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.117994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-5wc4s\" ","response":"range_response_count:1 size:17790"} {"level":"info","ts":"2026-05-30T09:51:02.378453Z","caller":"traceutil/trace.go:171","msg":"trace[1364962470] range","detail":"{range_begin:/registry/pods/kube-system/cilium-5wc4s; range_end:; response_count:1; response_revision:707; }","duration":"118.274139ms","start":"2026-05-30T09:51:02.260154Z","end":"2026-05-30T09:51:02.378428Z","steps":["trace[1364962470] 'range keys from in-memory index tree' (duration: 117.898829ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:02.378398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.011664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-05-30T09:51:02.378613Z","caller":"traceutil/trace.go:171","msg":"trace[518144128] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:707; }","duration":"122.286191ms","start":"2026-05-30T09:51:02.256302Z","end":"2026-05-30T09:51:02.378588Z","steps":["trace[518144128] 'range keys from in-memory index tree' (duration: 121.767648ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:51:02.394886Z","caller":"traceutil/trace.go:171","msg":"trace[1244564538] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"133.616408ms","start":"2026-05-30T09:51:02.261248Z","end":"2026-05-30T09:51:02.394864Z","steps":["trace[1244564538] 'process raft request' (duration: 133.472144ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:51:02.514751Z","caller":"traceutil/trace.go:171","msg":"trace[490900747] transaction","detail":"{read_only:false; response_revision:709; number_of_response:1; }","duration":"120.857835ms","start":"2026-05-30T09:51:02.393863Z","end":"2026-05-30T09:51:02.514721Z","steps":["trace[490900747] 'process raft request' (duration: 102.085759ms)","trace[490900747] 'compare' (duration: 18.514989ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:51:02.692285Z","caller":"traceutil/trace.go:171","msg":"trace[18272376] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"155.086013ms","start":"2026-05-30T09:51:02.537154Z","end":"2026-05-30T09:51:02.69224Z","steps":["trace[18272376] 'process raft request' (duration: 130.634223ms)","trace[18272376] 'compare' (duration: 24.226314ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:51:02.968721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.900955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-30T09:51:02.968824Z","caller":"traceutil/trace.go:171","msg":"trace[1243588128] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:710; }","duration":"104.039858ms","start":"2026-05-30T09:51:02.864755Z","end":"2026-05-30T09:51:02.968795Z","steps":["trace[1243588128] 'range keys from in-memory index tree' (duration: 103.73662ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:02.968721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.707322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-30T09:51:02.968926Z","caller":"traceutil/trace.go:171","msg":"trace[1448712989] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:710; }","duration":"111.9958ms","start":"2026-05-30T09:51:02.856909Z","end":"2026-05-30T09:51:02.968905Z","steps":["trace[1448712989] 'range keys from in-memory index tree' (duration: 111.462926ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:02.968898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.589674ms","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-05-30T09:51:02.968823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.86228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-30T09:51:02.969013Z","caller":"traceutil/trace.go:171","msg":"trace[970966189] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:710; }","duration":"123.756688ms","start":"2026-05-30T09:51:02.845229Z","end":"2026-05-30T09:51:02.968986Z","steps":["trace[970966189] 'range keys from in-memory index tree' (duration: 123.319997ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:51:02.969042Z","caller":"traceutil/trace.go:171","msg":"trace[144993421] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:710; }","duration":"107.113087ms","start":"2026-05-30T09:51:02.861897Z","end":"2026-05-30T09:51:02.96901Z","steps":["trace[144993421] 'range keys from in-memory index tree' (duration: 106.690016ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:02.969028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.457472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-05-30T09:51:02.969138Z","caller":"traceutil/trace.go:171","msg":"trace[69722618] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:710; }","duration":"112.616885ms","start":"2026-05-30T09:51:02.856492Z","end":"2026-05-30T09:51:02.969108Z","steps":["trace[69722618] 'range keys from in-memory index tree' (duration: 112.215716ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:51:03.159557Z","caller":"traceutil/trace.go:171","msg":"trace[1742280695] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"185.956236ms","start":"2026-05-30T09:51:02.973563Z","end":"2026-05-30T09:51:03.159519Z","steps":["trace[1742280695] 'process raft request' (duration: 185.662239ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:51:03.159995Z","caller":"traceutil/trace.go:171","msg":"trace[396520914] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"186.334716ms","start":"2026-05-30T09:51:02.973624Z","end":"2026-05-30T09:51:03.159959Z","steps":["trace[396520914] 'process raft request' (duration: 185.798613ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:51:03.161292Z","caller":"traceutil/trace.go:171","msg":"trace[686685183] linearizableReadLoop","detail":"{readStateIndex:739; appliedIndex:735; }","duration":"104.939431ms","start":"2026-05-30T09:51:03.056325Z","end":"2026-05-30T09:51:03.161265Z","steps":["trace[686685183] 'read index received' (duration: 102.870789ms)","trace[686685183] 'applied index is now lower than readState.Index' (duration: 2.067282ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:51:03.161823Z","caller":"traceutil/trace.go:171","msg":"trace[1247385150] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"188.019779ms","start":"2026-05-30T09:51:02.973787Z","end":"2026-05-30T09:51:03.161806Z","steps":["trace[1247385150] 'process raft request' (duration: 186.058489ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:51:03.161974Z","caller":"traceutil/trace.go:171","msg":"trace[1912527832] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"151.78883ms","start":"2026-05-30T09:51:03.010176Z","end":"2026-05-30T09:51:03.161965Z","steps":["trace[1912527832] 'process raft request' (duration: 151.03299ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:03.162313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.961417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-05-30T09:51:03.162547Z","caller":"traceutil/trace.go:171","msg":"trace[1274178637] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:715; }","duration":"106.249335ms","start":"2026-05-30T09:51:03.056267Z","end":"2026-05-30T09:51:03.162516Z","steps":["trace[1274178637] 'agreement among raft nodes before linearized reading' (duration: 105.880106ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:51:08.284152Z","caller":"traceutil/trace.go:171","msg":"trace[885291364] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"107.345202ms","start":"2026-05-30T09:51:08.17678Z","end":"2026-05-30T09:51:08.284125Z","steps":["trace[885291364] 'process raft request' (duration: 107.166437ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:08.76427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.482095ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-30T09:51:08.764966Z","caller":"traceutil/trace.go:171","msg":"trace[2137324589] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"121.749258ms","start":"2026-05-30T09:51:08.643195Z","end":"2026-05-30T09:51:08.764945Z","steps":["trace[2137324589] 'process raft request' (duration: 121.186833ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:51:09.177568Z","caller":"traceutil/trace.go:171","msg":"trace[383285729] linearizableReadLoop","detail":"{readStateIndex:797; appliedIndex:796; }","duration":"122.021225ms","start":"2026-05-30T09:51:09.055507Z","end":"2026-05-30T09:51:09.177528Z","steps":["trace[383285729] 'read index received' (duration: 35.587593ms)","trace[383285729] 'applied index is now lower than readState.Index' (duration: 86.432212ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:51:09.177656Z","caller":"traceutil/trace.go:171","msg":"trace[33986115] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"170.913375ms","start":"2026-05-30T09:51:09.006716Z","end":"2026-05-30T09:51:09.17763Z","steps":["trace[33986115] 'process raft request' (duration: 170.592187ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:09.17788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.328182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-30T09:51:09.17793Z","caller":"traceutil/trace.go:171","msg":"trace[259752813] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:770; }","duration":"122.474536ms","start":"2026-05-30T09:51:09.055444Z","end":"2026-05-30T09:51:09.177918Z","steps":["trace[259752813] 'agreement among raft nodes before linearized reading' (duration: 122.23179ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:51:09.335429Z","caller":"traceutil/trace.go:171","msg":"trace[2101542757] linearizableReadLoop","detail":"{readStateIndex:798; appliedIndex:797; }","duration":"151.270376ms","start":"2026-05-30T09:51:09.184127Z","end":"2026-05-30T09:51:09.335398Z","steps":["trace[2101542757] 'read index received' (duration: 151.058831ms)","trace[2101542757] 'applied index is now lower than readState.Index' (duration: 210.035µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:51:09.335539Z","caller":"traceutil/trace.go:171","msg":"trace[264005438] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"236.921098ms","start":"2026-05-30T09:51:09.098586Z","end":"2026-05-30T09:51:09.335507Z","steps":["trace[264005438] 'process raft request' (duration: 236.632641ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:09.335718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.562373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-30T09:51:09.33577Z","caller":"traceutil/trace.go:171","msg":"trace[1767964620] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:771; }","duration":"151.658856ms","start":"2026-05-30T09:51:09.184098Z","end":"2026-05-30T09:51:09.335757Z","steps":["trace[1767964620] 'agreement among raft nodes before linearized reading' (duration: 151.459701ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:51:09.404798Z","caller":"traceutil/trace.go:171","msg":"trace[915006366] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"220.451831ms","start":"2026-05-30T09:51:09.184315Z","end":"2026-05-30T09:51:09.404767Z","steps":["trace[915006366] 'process raft request' (duration: 220.222295ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:09.404941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.653196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-30T09:51:09.404962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.789552ms","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-05-30T09:51:09.405259Z","caller":"traceutil/trace.go:171","msg":"trace[132126385] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:772; }","duration":"216.125661ms","start":"2026-05-30T09:51:09.189104Z","end":"2026-05-30T09:51:09.40523Z","steps":["trace[132126385] 'agreement among raft nodes before linearized reading' (duration: 215.68175ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:51:09.405217Z","caller":"traceutil/trace.go:171","msg":"trace[952887802] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:772; }","duration":"220.949944ms","start":"2026-05-30T09:51:09.184219Z","end":"2026-05-30T09:51:09.405168Z","steps":["trace[952887802] 'agreement among raft nodes before linearized reading' (duration: 220.517933ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:51:09.629245Z","caller":"traceutil/trace.go:171","msg":"trace[895545328] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"218.724106ms","start":"2026-05-30T09:51:09.41049Z","end":"2026-05-30T09:51:09.629215Z","steps":["trace[895545328] 'process raft request' (duration: 202.561646ms)","trace[895545328] 'compare' (duration: 15.824352ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:51:09.671323Z","caller":"traceutil/trace.go:171","msg":"trace[609750031] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"256.846584ms","start":"2026-05-30T09:51:09.414453Z","end":"2026-05-30T09:51:09.6713Z","steps":["trace[609750031] 'process raft request' (duration: 256.647339ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:15.933181Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305496388595749792,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-30T09:51:16.343811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.144276357s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:51:16.343932Z","caller":"traceutil/trace.go:171","msg":"trace[675188194] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:809; }","duration":"1.144542744s","start":"2026-05-30T09:51:15.199364Z","end":"2026-05-30T09:51:16.343907Z","steps":["trace[675188194] 'count revisions from in-memory index tree' (duration: 1.144078453s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:16.343987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:15.19932Z","time spent":"1.144652157s","remote":"127.0.0.1:43794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-05-30T09:51:16.434229Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305496388595749792,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-30T09:51:16.696572Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.35062115s","expected-duration":"1s"} {"level":"info","ts":"2026-05-30T09:51:16.697183Z","caller":"traceutil/trace.go:171","msg":"trace[402054534] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"1.35141155s","start":"2026-05-30T09:51:15.345742Z","end":"2026-05-30T09:51:16.697153Z","steps":["trace[402054534] 'process raft request' (duration: 1.351236576s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:16.697369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:15.3457Z","time spent":"1.351568974s","remote":"127.0.0.1:43818","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-05-30T09:51:16.702415Z","caller":"traceutil/trace.go:171","msg":"trace[144491978] linearizableReadLoop","detail":"{readStateIndex:840; appliedIndex:838; }","duration":"1.270081358s","start":"2026-05-30T09:51:15.432298Z","end":"2026-05-30T09:51:16.702379Z","steps":["trace[144491978] 'read index received' (duration: 1.264767183s)","trace[144491978] 'applied index is now lower than readState.Index' (duration: 5.312575ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:51:16.702627Z","caller":"traceutil/trace.go:171","msg":"trace[1402434062] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"612.799072ms","start":"2026-05-30T09:51:16.089813Z","end":"2026-05-30T09:51:16.702612Z","steps":["trace[1402434062] 'process raft request' (duration: 612.234247ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:16.702737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:16.089773Z","time spent":"612.892334ms","remote":"127.0.0.1:43748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5052,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:51:16.70283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.268638802s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-30T09:51:16.70288Z","caller":"traceutil/trace.go:171","msg":"trace[1163050442] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:811; }","duration":"1.268714323s","start":"2026-05-30T09:51:15.434157Z","end":"2026-05-30T09:51:16.702871Z","steps":["trace[1163050442] 'agreement among raft nodes before linearized reading' (duration: 1.268567699s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:16.702911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:15.434139Z","time spent":"1.268764435s","remote":"127.0.0.1:43846","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-05-30T09:51:16.702972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"969.108456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-30T09:51:16.7031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.740643ms","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-05-30T09:51:16.703069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.010207317s","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-05-30T09:51:16.703133Z","caller":"traceutil/trace.go:171","msg":"trace[290162750] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:811; }","duration":"275.813464ms","start":"2026-05-30T09:51:16.42731Z","end":"2026-05-30T09:51:16.703124Z","steps":["trace[290162750] 'agreement among raft nodes before linearized reading' (duration: 275.727122ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:16.703143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"850.312083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:51:16.703134Z","caller":"traceutil/trace.go:171","msg":"trace[621803103] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:811; }","duration":"969.279849ms","start":"2026-05-30T09:51:15.733768Z","end":"2026-05-30T09:51:16.703048Z","steps":["trace[621803103] 'agreement among raft nodes before linearized reading' (duration: 969.095215ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:16.70301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.049994716s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-30T09:51:16.703164Z","caller":"traceutil/trace.go:171","msg":"trace[241497676] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:811; }","duration":"850.379864ms","start":"2026-05-30T09:51:15.852778Z","end":"2026-05-30T09:51:16.703158Z","steps":["trace[241497676] 'agreement among raft nodes before linearized reading' (duration: 850.347513ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:16.703183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:15.85274Z","time spent":"850.438906ms","remote":"127.0.0.1:43562","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-05-30T09:51:16.703159Z","caller":"traceutil/trace.go:171","msg":"trace[404823484] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:811; }","duration":"1.0103353s","start":"2026-05-30T09:51:15.692803Z","end":"2026-05-30T09:51:16.703139Z","steps":["trace[404823484] 'agreement among raft nodes before linearized reading' (duration: 1.010082373s)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:51:16.703202Z","caller":"traceutil/trace.go:171","msg":"trace[215269657] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:811; }","duration":"1.050193632s","start":"2026-05-30T09:51:15.652982Z","end":"2026-05-30T09:51:16.703175Z","steps":["trace[215269657] 'agreement among raft nodes before linearized reading' (duration: 1.049967866s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:16.703235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:15.69279Z","time spent":"1.010431753s","remote":"127.0.0.1:43846","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-05-30T09:51:16.703277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:15.652969Z","time spent":"1.050289614s","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-30T09:51:16.703214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:15.733757Z","time spent":"969.416984ms","remote":"127.0.0.1:44032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":2,"response size":30,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-05-30T09:51:16.703281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.27095549s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-30T09:51:16.703434Z","caller":"traceutil/trace.go:171","msg":"trace[1070230080] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:811; }","duration":"1.271178755s","start":"2026-05-30T09:51:15.432231Z","end":"2026-05-30T09:51:16.70341Z","steps":["trace[1070230080] 'agreement among raft nodes before linearized reading' (duration: 1.270422727s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:16.703518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:15.432206Z","time spent":"1.271295298s","remote":"127.0.0.1:43846","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-05-30T09:51:30.943961Z","caller":"traceutil/trace.go:171","msg":"trace[686320397] linearizableReadLoop","detail":"{readStateIndex:972; appliedIndex:971; }","duration":"114.621812ms","start":"2026-05-30T09:51:30.829313Z","end":"2026-05-30T09:51:30.943935Z","steps":["trace[686320397] 'read index received' (duration: 114.426977ms)","trace[686320397] 'applied index is now lower than readState.Index' (duration: 193.605µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:51:30.944131Z","caller":"traceutil/trace.go:171","msg":"trace[1955958500] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"123.410455ms","start":"2026-05-30T09:51:30.82066Z","end":"2026-05-30T09:51:30.94407Z","steps":["trace[1955958500] 'process raft request' (duration: 123.105268ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:30.944402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.91515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-30T09:51:30.944377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.515159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-30T09:51:30.944457Z","caller":"traceutil/trace.go:171","msg":"trace[301441940] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:940; }","duration":"115.157526ms","start":"2026-05-30T09:51:30.829286Z","end":"2026-05-30T09:51:30.944443Z","steps":["trace[301441940] 'agreement among raft nodes before linearized reading' (duration: 114.801777ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:30.944437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.727859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-30T09:51:30.944496Z","caller":"traceutil/trace.go:171","msg":"trace[1690745593] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:940; }","duration":"112.686053ms","start":"2026-05-30T09:51:30.831786Z","end":"2026-05-30T09:51:30.944472Z","steps":["trace[1690745593] 'agreement among raft nodes before linearized reading' (duration: 112.331645ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:51:30.944528Z","caller":"traceutil/trace.go:171","msg":"trace[1518988845] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:940; }","duration":"111.834571ms","start":"2026-05-30T09:51:30.83267Z","end":"2026-05-30T09:51:30.944504Z","steps":["trace[1518988845] 'agreement among raft nodes before linearized reading' (duration: 111.614406ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:30.944703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.599241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-30T09:51:30.94473Z","caller":"traceutil/trace.go:171","msg":"trace[228716833] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:940; }","duration":"112.666353ms","start":"2026-05-30T09:51:30.832054Z","end":"2026-05-30T09:51:30.944721Z","steps":["trace[228716833] 'agreement among raft nodes before linearized reading' (duration: 112.584891ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:51:31.213102Z","caller":"traceutil/trace.go:171","msg":"trace[645518536] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"263.787002ms","start":"2026-05-30T09:51:30.949288Z","end":"2026-05-30T09:51:31.213075Z","steps":["trace[645518536] 'process raft request' (duration: 263.619098ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:51:31.216958Z","caller":"traceutil/trace.go:171","msg":"trace[1619535681] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"266.929812ms","start":"2026-05-30T09:51:30.949991Z","end":"2026-05-30T09:51:31.216921Z","steps":["trace[1619535681] 'process raft request' (duration: 266.682275ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:51:31.217191Z","caller":"traceutil/trace.go:171","msg":"trace[174494919] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"263.441933ms","start":"2026-05-30T09:51:30.953733Z","end":"2026-05-30T09:51:31.217175Z","steps":["trace[174494919] 'process raft request' (duration: 262.973041ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:51:31.217222Z","caller":"traceutil/trace.go:171","msg":"trace[1676813218] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"267.385033ms","start":"2026-05-30T09:51:30.949826Z","end":"2026-05-30T09:51:31.217211Z","steps":["trace[1676813218] 'process raft request' (duration: 266.46479ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:51:31.217365Z","caller":"traceutil/trace.go:171","msg":"trace[679354251] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"267.418474ms","start":"2026-05-30T09:51:30.949937Z","end":"2026-05-30T09:51:31.217356Z","steps":["trace[679354251] 'process raft request' (duration: 266.684925ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:51:32.534345Z","caller":"traceutil/trace.go:171","msg":"trace[1822013626] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"116.213313ms","start":"2026-05-30T09:51:32.418104Z","end":"2026-05-30T09:51:32.534317Z","steps":["trace[1822013626] 'process raft request' (duration: 116.039018ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:47.418733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.820626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-30T09:51:47.418822Z","caller":"traceutil/trace.go:171","msg":"trace[1409248241] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1008; }","duration":"124.002001ms","start":"2026-05-30T09:51:47.2948Z","end":"2026-05-30T09:51:47.418802Z","steps":["trace[1409248241] 'range keys from in-memory index tree' (duration: 123.658742ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:47.418985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.989245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-30T09:51:47.419081Z","caller":"traceutil/trace.go:171","msg":"trace[563214391] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1008; }","duration":"123.122978ms","start":"2026-05-30T09:51:47.295934Z","end":"2026-05-30T09:51:47.419056Z","steps":["trace[563214391] 'range keys from in-memory index tree' (duration: 122.865212ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:47.419158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.516504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-30T09:51:47.419246Z","caller":"traceutil/trace.go:171","msg":"trace[1261084700] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1008; }","duration":"124.682358ms","start":"2026-05-30T09:51:47.294542Z","end":"2026-05-30T09:51:47.419224Z","steps":["trace[1261084700] 'range keys from in-memory index tree' (duration: 124.232497ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:47.419276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.57404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-30T09:51:47.419332Z","caller":"traceutil/trace.go:171","msg":"trace[1219889062] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1008; }","duration":"123.653482ms","start":"2026-05-30T09:51:47.295664Z","end":"2026-05-30T09:51:47.419318Z","steps":["trace[1219889062] 'range keys from in-memory index tree' (duration: 122.964835ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:51:47.537929Z","caller":"traceutil/trace.go:171","msg":"trace[1202521175] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"114.667963ms","start":"2026-05-30T09:51:47.423239Z","end":"2026-05-30T09:51:47.537907Z","steps":["trace[1202521175] 'process raft request' (duration: 114.5272ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:51:47.684535Z","caller":"traceutil/trace.go:171","msg":"trace[1791706845] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"260.423727ms","start":"2026-05-30T09:51:47.424092Z","end":"2026-05-30T09:51:47.684516Z","steps":["trace[1791706845] 'process raft request' (duration: 257.141783ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:51:47.684848Z","caller":"traceutil/trace.go:171","msg":"trace[1128919937] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"260.714385ms","start":"2026-05-30T09:51:47.424094Z","end":"2026-05-30T09:51:47.684808Z","steps":["trace[1128919937] 'process raft request' (duration: 260.380106ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:51:47.684934Z","caller":"traceutil/trace.go:171","msg":"trace[1921605530] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"259.76584ms","start":"2026-05-30T09:51:47.425155Z","end":"2026-05-30T09:51:47.684921Z","steps":["trace[1921605530] 'process raft request' (duration: 259.613767ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:48.429019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.787279ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-30T09:51:51.396024Z","caller":"traceutil/trace.go:171","msg":"trace[1310951512] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"293.16254ms","start":"2026-05-30T09:51:51.102837Z","end":"2026-05-30T09:51:51.395999Z","steps":["trace[1310951512] 'process raft request' (duration: 293.024945ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:53.941022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.144839979s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-30T09:51:53.941073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.242759427s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-30T09:51:53.941079Z","caller":"traceutil/trace.go:171","msg":"trace[1713038311] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1026; }","duration":"2.144944351s","start":"2026-05-30T09:51:51.796121Z","end":"2026-05-30T09:51:53.941066Z","steps":["trace[1713038311] 'count revisions from in-memory index tree' (duration: 2.144746957s)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:51:53.941103Z","caller":"traceutil/trace.go:171","msg":"trace[1960570145] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1026; }","duration":"2.242823799s","start":"2026-05-30T09:51:51.69827Z","end":"2026-05-30T09:51:53.941094Z","steps":["trace[1960570145] 'range keys from in-memory index tree' (duration: 2.242611003s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:53.941108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:51.796104Z","time spent":"2.144996933s","remote":"127.0.0.1:43886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-30T09:51:53.941126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:51.698253Z","time spent":"2.24286606s","remote":"127.0.0.1:43846","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-05-30T09:51:53.941253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.241959526s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-30T09:51:53.941271Z","caller":"traceutil/trace.go:171","msg":"trace[238206947] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1026; }","duration":"2.242013357s","start":"2026-05-30T09:51:51.699252Z","end":"2026-05-30T09:51:53.941266Z","steps":["trace[238206947] 'range keys from in-memory index tree' (duration: 2.241880174s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:53.941287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:51.699236Z","time spent":"2.242047469s","remote":"127.0.0.1:43846","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-05-30T09:51:53.941379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.241588707s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-30T09:51:53.941394Z","caller":"traceutil/trace.go:171","msg":"trace[1152437019] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1026; }","duration":"2.241618407s","start":"2026-05-30T09:51:51.699771Z","end":"2026-05-30T09:51:53.941389Z","steps":["trace[1152437019] 'range keys from in-memory index tree' (duration: 2.241531685s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:53.941413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:51.699764Z","time spent":"2.241643308s","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-30T09:51:53.941434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.232837405s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:51:53.941505Z","caller":"traceutil/trace.go:171","msg":"trace[208260562] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1026; }","duration":"2.232929227s","start":"2026-05-30T09:51:51.708557Z","end":"2026-05-30T09:51:53.941486Z","steps":["trace[208260562] 'range keys from in-memory index tree' (duration: 2.232716551s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:53.941544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:51.708551Z","time spent":"2.232985088s","remote":"127.0.0.1:43562","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-05-30T09:51:53.941709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.640447472s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-30T09:51:53.941746Z","caller":"traceutil/trace.go:171","msg":"trace[1610661725] linearizableReadLoop","detail":"{readStateIndex:1065; appliedIndex:1064; }","duration":"833.675872ms","start":"2026-05-30T09:51:53.108064Z","end":"2026-05-30T09:51:53.94174Z","steps":["trace[1610661725] 'read index received' (duration: 51.951µs)","trace[1610661725] 'applied index is now lower than readState.Index' (duration: 833.623291ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:51:53.941812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"833.750394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.210\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-30T09:51:53.941844Z","caller":"traceutil/trace.go:171","msg":"trace[726622414] range","detail":"{range_begin:/registry/masterleases/199.204.45.210; range_end:; response_count:1; response_revision:1027; }","duration":"833.817976ms","start":"2026-05-30T09:51:53.108021Z","end":"2026-05-30T09:51:53.941839Z","steps":["trace[726622414] 'agreement among raft nodes before linearized reading' (duration: 833.753494ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:53.941861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:53.107996Z","time spent":"833.860787ms","remote":"127.0.0.1:43616","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.210\" "} {"level":"info","ts":"2026-05-30T09:51:53.941857Z","caller":"traceutil/trace.go:171","msg":"trace[1839699475] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"2.074063909s","start":"2026-05-30T09:51:51.86773Z","end":"2026-05-30T09:51:53.941794Z","steps":["trace[1839699475] 'process raft request' (duration: 433.493164ms)","trace[1839699475] 'compare' (duration: 1.640279678s)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:51:53.942081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:51.867712Z","time spent":"2.074278465s","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:51:54.442366Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305496388595750310,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-30T09:51:54.621276Z","caller":"traceutil/trace.go:171","msg":"trace[1489746368] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"921.065894ms","start":"2026-05-30T09:51:53.700192Z","end":"2026-05-30T09:51:54.621258Z","steps":["trace[1489746368] 'process raft request' (duration: 919.521574ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:54.621406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:53.700178Z","time spent":"921.154856ms","remote":"127.0.0.1:43846","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-05-30T09:51:54.622716Z","caller":"traceutil/trace.go:171","msg":"trace[1499019814] linearizableReadLoop","detail":"{readStateIndex:1070; appliedIndex:1065; }","duration":"680.945502ms","start":"2026-05-30T09:51:53.941759Z","end":"2026-05-30T09:51:54.622705Z","steps":["trace[1499019814] 'read index received' (duration: 677.968336ms)","trace[1499019814] 'applied index is now lower than readState.Index' (duration: 2.976406ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:51:54.622797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.49866691s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-30T09:51:54.622781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:53.94396Z","time spent":"678.813058ms","remote":"127.0.0.1:43616","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-30T09:51:54.622841Z","caller":"traceutil/trace.go:171","msg":"trace[1900804685] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"677.063713ms","start":"2026-05-30T09:51:53.945772Z","end":"2026-05-30T09:51:54.622835Z","steps":["trace[1900804685] 'process raft request' (duration: 676.812477ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:54.622879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:53.945756Z","time spent":"677.100764ms","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:51:54.623049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.026392779s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-30T09:51:54.622819Z","caller":"traceutil/trace.go:171","msg":"trace[1828486872] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1031; }","duration":"1.498717511s","start":"2026-05-30T09:51:53.124095Z","end":"2026-05-30T09:51:54.622812Z","steps":["trace[1828486872] 'agreement among raft nodes before linearized reading' (duration: 1.49865022s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:54.623036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.008297959s","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-05-30T09:51:54.623067Z","caller":"traceutil/trace.go:171","msg":"trace[1551895180] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1031; }","duration":"1.026449331s","start":"2026-05-30T09:51:53.596613Z","end":"2026-05-30T09:51:54.623062Z","steps":["trace[1551895180] 'agreement among raft nodes before linearized reading' (duration: 1.026373629s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:54.6231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:53.596591Z","time spent":"1.026504493s","remote":"127.0.0.1:43846","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-05-30T09:51:54.623105Z","caller":"traceutil/trace.go:171","msg":"trace[37236379] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1031; }","duration":"1.008381721s","start":"2026-05-30T09:51:53.614707Z","end":"2026-05-30T09:51:54.623089Z","steps":["trace[37236379] 'agreement among raft nodes before linearized reading' (duration: 1.008255818s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:54.623109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:53.12408Z","time spent":"1.498998068s","remote":"127.0.0.1:43678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":5,"response size":30,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-05-30T09:51:54.62315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:53.614674Z","time spent":"1.008463605s","remote":"127.0.0.1:44038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-05-30T09:51:54.623229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.223363885s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-30T09:51:54.62325Z","caller":"traceutil/trace.go:171","msg":"trace[147874246] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1031; }","duration":"1.223406156s","start":"2026-05-30T09:51:53.399838Z","end":"2026-05-30T09:51:54.623244Z","steps":["trace[147874246] 'agreement among raft nodes before linearized reading' (duration: 1.223352485s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:54.623266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:53.399822Z","time spent":"1.223441217s","remote":"127.0.0.1:43846","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-05-30T09:51:54.623331Z","caller":"traceutil/trace.go:171","msg":"trace[1406279618] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"676.656834ms","start":"2026-05-30T09:51:53.946663Z","end":"2026-05-30T09:51:54.62332Z","steps":["trace[1406279618] 'process raft request' (duration: 675.986776ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:51:54.62336Z","caller":"traceutil/trace.go:171","msg":"trace[1607265043] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"676.967112ms","start":"2026-05-30T09:51:53.946374Z","end":"2026-05-30T09:51:54.623342Z","steps":["trace[1607265043] 'process raft request' (duration: 676.255303ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:54.623437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:53.94664Z","time spent":"676.715905ms","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:51:54.623357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"914.294992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-30T09:51:54.623474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:53.946368Z","time spent":"677.033083ms","remote":"127.0.0.1:43846","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-05-30T09:51:54.623483Z","caller":"traceutil/trace.go:171","msg":"trace[821192589] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1031; }","duration":"914.436936ms","start":"2026-05-30T09:51:53.709034Z","end":"2026-05-30T09:51:54.623471Z","steps":["trace[821192589] 'agreement among raft nodes before linearized reading' (duration: 914.298203ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:54.623516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:53.709025Z","time spent":"914.482667ms","remote":"127.0.0.1:43572","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-05-30T09:52:09.791601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.422169ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-30T09:52:14.844993Z","caller":"traceutil/trace.go:171","msg":"trace[452161353] linearizableReadLoop","detail":"{readStateIndex:1139; appliedIndex:1138; }","duration":"112.685694ms","start":"2026-05-30T09:52:14.732285Z","end":"2026-05-30T09:52:14.84497Z","steps":["trace[452161353] 'read index received' (duration: 112.477678ms)","trace[452161353] 'applied index is now lower than readState.Index' (duration: 206.906µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:52:14.845125Z","caller":"traceutil/trace.go:171","msg":"trace[1477021191] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"114.341746ms","start":"2026-05-30T09:52:14.73077Z","end":"2026-05-30T09:52:14.845111Z","steps":["trace[1477021191] 'process raft request' (duration: 114.064499ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:14.845694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.393211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-30T09:52:14.845768Z","caller":"traceutil/trace.go:171","msg":"trace[1088712745] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1096; }","duration":"113.503684ms","start":"2026-05-30T09:52:14.732247Z","end":"2026-05-30T09:52:14.845751Z","steps":["trace[1088712745] 'agreement among raft nodes before linearized reading' (duration: 113.377691ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:15.770824Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305496388595750482,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-30T09:52:16.271995Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305496388595750482,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-30T09:52:16.310841Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.317793404s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-30T09:52:17.093644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"782.529333ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-30T09:52:17.09391Z","caller":"traceutil/trace.go:171","msg":"trace[1162557030] linearizableReadLoop","detail":"{readStateIndex:1145; appliedIndex:1143; }","duration":"1.823422531s","start":"2026-05-30T09:52:15.270463Z","end":"2026-05-30T09:52:17.093885Z","steps":["trace[1162557030] 'read index received' (duration: 1.040555899s)","trace[1162557030] 'applied index is now lower than readState.Index' (duration: 782.865572ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:52:17.094057Z","caller":"traceutil/trace.go:171","msg":"trace[605833383] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"1.089107343s","start":"2026-05-30T09:52:16.004932Z","end":"2026-05-30T09:52:17.094039Z","steps":["trace[605833383] 'process raft request' (duration: 1.088800565s)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:52:17.094133Z","caller":"traceutil/trace.go:171","msg":"trace[797800120] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"2.101121777s","start":"2026-05-30T09:52:14.992954Z","end":"2026-05-30T09:52:17.094076Z","steps":["trace[797800120] 'process raft request' (duration: 1.318085141s)","trace[797800120] 'compare' (duration: 782.307898ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:52:17.094382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:52:14.992934Z","time spent":"2.101322682s","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:52:17.094409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.012899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-30T09:52:17.094449Z","caller":"traceutil/trace.go:171","msg":"trace[160311067] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1102; }","duration":"157.05435ms","start":"2026-05-30T09:52:16.937386Z","end":"2026-05-30T09:52:17.09444Z","steps":["trace[160311067] 'agreement among raft nodes before linearized reading' (duration: 156.878796ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:17.094504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.384013826s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-30T09:52:17.094576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.824118599s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-30T09:52:17.094589Z","caller":"traceutil/trace.go:171","msg":"trace[286407828] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1102; }","duration":"1.384150819s","start":"2026-05-30T09:52:15.710414Z","end":"2026-05-30T09:52:17.094565Z","steps":["trace[286407828] 'agreement among raft nodes before linearized reading' (duration: 1.383812071s)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:52:17.094608Z","caller":"traceutil/trace.go:171","msg":"trace[1006794594] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1102; }","duration":"1.824191271s","start":"2026-05-30T09:52:15.270408Z","end":"2026-05-30T09:52:17.094599Z","steps":["trace[1006794594] 'agreement among raft nodes before linearized reading' (duration: 1.82414234s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:17.094188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:52:16.004906Z","time spent":"1.089193986s","remote":"127.0.0.1:43846","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-05-30T09:52:17.094741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.373503ms","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-05-30T09:52:17.09463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:52:15.71039Z","time spent":"1.384231832s","remote":"127.0.0.1:43562","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-05-30T09:52:17.094773Z","caller":"traceutil/trace.go:171","msg":"trace[495748777] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1102; }","duration":"156.443755ms","start":"2026-05-30T09:52:16.938322Z","end":"2026-05-30T09:52:17.094765Z","steps":["trace[495748777] 'agreement among raft nodes before linearized reading' (duration: 156.331252ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:17.09478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.43965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-30T09:52:17.094811Z","caller":"traceutil/trace.go:171","msg":"trace[286385618] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1102; }","duration":"157.491871ms","start":"2026-05-30T09:52:16.93731Z","end":"2026-05-30T09:52:17.094802Z","steps":["trace[286385618] 'agreement among raft nodes before linearized reading' (duration: 157.342887ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:17.094635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:52:15.270386Z","time spent":"1.824241962s","remote":"127.0.0.1:44096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":30,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-05-30T09:52:17.094935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.828784ms","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-05-30T09:52:17.094964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.140138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-30T09:52:17.094993Z","caller":"traceutil/trace.go:171","msg":"trace[1694207635] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1102; }","duration":"245.20488ms","start":"2026-05-30T09:52:16.84978Z","end":"2026-05-30T09:52:17.094985Z","steps":["trace[1694207635] 'agreement among raft nodes before linearized reading' (duration: 245.128638ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:52:17.094995Z","caller":"traceutil/trace.go:171","msg":"trace[315768769] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1102; }","duration":"178.937087ms","start":"2026-05-30T09:52:16.916044Z","end":"2026-05-30T09:52:17.094981Z","steps":["trace[315768769] 'agreement among raft nodes before linearized reading' (duration: 178.66612ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:52:17.391347Z","caller":"traceutil/trace.go:171","msg":"trace[467028724] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"291.252821ms","start":"2026-05-30T09:52:17.100066Z","end":"2026-05-30T09:52:17.391319Z","steps":["trace[467028724] 'process raft request' (duration: 291.073026ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:17.670182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.957508ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-30T09:52:17.670434Z","caller":"traceutil/trace.go:171","msg":"trace[2140747619] linearizableReadLoop","detail":"{readStateIndex:1150; appliedIndex:1145; }","duration":"566.987337ms","start":"2026-05-30T09:52:17.103429Z","end":"2026-05-30T09:52:17.670416Z","steps":["trace[2140747619] 'read index received' (duration: 287.760991ms)","trace[2140747619] 'applied index is now lower than readState.Index' (duration: 279.225176ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:52:17.670535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.10229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:52:17.670547Z","caller":"traceutil/trace.go:171","msg":"trace[91543724] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"569.330557ms","start":"2026-05-30T09:52:17.101183Z","end":"2026-05-30T09:52:17.670514Z","steps":["trace[91543724] 'process raft request' (duration: 569.095391ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:52:17.670567Z","caller":"traceutil/trace.go:171","msg":"trace[762658513] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1107; }","duration":"567.161821ms","start":"2026-05-30T09:52:17.103396Z","end":"2026-05-30T09:52:17.670558Z","steps":["trace[762658513] 'agreement among raft nodes before linearized reading' (duration: 567.076119ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:17.67065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:52:17.103381Z","time spent":"567.259054ms","remote":"127.0.0.1:43562","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-05-30T09:52:17.670724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:52:17.101172Z","time spent":"569.43955ms","remote":"127.0.0.1:43846","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-05-30T09:52:17.670898Z","caller":"traceutil/trace.go:171","msg":"trace[1056521999] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"569.619664ms","start":"2026-05-30T09:52:17.101193Z","end":"2026-05-30T09:52:17.670812Z","steps":["trace[1056521999] 'process raft request' (duration: 569.158482ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:52:17.671069Z","caller":"traceutil/trace.go:171","msg":"trace[1864238630] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"569.962193ms","start":"2026-05-30T09:52:17.10109Z","end":"2026-05-30T09:52:17.671052Z","steps":["trace[1864238630] 'process raft request' (duration: 353.049591ms)","trace[1864238630] 'compare' (duration: 215.830783ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:52:17.671144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:52:17.101058Z","time spent":"570.050115ms","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:52:17.671246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:52:17.101175Z","time spent":"569.811979ms","remote":"127.0.0.1:43846","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-05-30T09:52:17.671026Z","caller":"traceutil/trace.go:171","msg":"trace[1496712451] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"569.283814ms","start":"2026-05-30T09:52:17.101682Z","end":"2026-05-30T09:52:17.670966Z","steps":["trace[1496712451] 'process raft request' (duration: 568.70157ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:17.672286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:52:17.101664Z","time spent":"570.575627ms","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:52:18.538633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.227751ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-30T09:52:19.371857Z","caller":"traceutil/trace.go:171","msg":"trace[852790172] transaction","detail":"{read_only:false; response_revision:1108; number_of_response:1; }","duration":"294.721418ms","start":"2026-05-30T09:52:19.077108Z","end":"2026-05-30T09:52:19.371829Z","steps":["trace[852790172] 'process raft request' (duration: 294.542063ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:52:19.67955Z","caller":"traceutil/trace.go:171","msg":"trace[368605013] linearizableReadLoop","detail":"{readStateIndex:1153; appliedIndex:1152; }","duration":"284.073478ms","start":"2026-05-30T09:52:19.395452Z","end":"2026-05-30T09:52:19.679525Z","steps":["trace[368605013] 'read index received' (duration: 283.880964ms)","trace[368605013] 'applied index is now lower than readState.Index' (duration: 191.394µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:52:19.679858Z","caller":"traceutil/trace.go:171","msg":"trace[317734365] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"578.226371ms","start":"2026-05-30T09:52:19.101618Z","end":"2026-05-30T09:52:19.679845Z","steps":["trace[317734365] 'process raft request' (duration: 577.76527ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:19.679975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:52:19.101596Z","time spent":"578.308825ms","remote":"127.0.0.1:43846","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-05-30T09:52:19.680249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.790326ms","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-05-30T09:52:19.680298Z","caller":"traceutil/trace.go:171","msg":"trace[580449966] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1109; }","duration":"284.874458ms","start":"2026-05-30T09:52:19.39541Z","end":"2026-05-30T09:52:19.680285Z","steps":["trace[580449966] 'agreement among raft nodes before linearized reading' (duration: 284.767886ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:20.851955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"760.973025ms","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-05-30T09:52:20.852029Z","caller":"traceutil/trace.go:171","msg":"trace[802385009] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1114; }","duration":"761.086468ms","start":"2026-05-30T09:52:20.090924Z","end":"2026-05-30T09:52:20.852011Z","steps":["trace[802385009] 'count revisions from in-memory index tree' (duration: 760.907724ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:20.852073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:52:20.090904Z","time spent":"761.15876ms","remote":"127.0.0.1:43864","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-05-30T09:52:20.851756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"795.78646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-30T09:52:20.852116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.747172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:52:20.852198Z","caller":"traceutil/trace.go:171","msg":"trace[1312729710] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1114; }","duration":"143.842015ms","start":"2026-05-30T09:52:20.708334Z","end":"2026-05-30T09:52:20.852176Z","steps":["trace[1312729710] 'range keys from in-memory index tree' (duration: 143.66005ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:52:20.852283Z","caller":"traceutil/trace.go:171","msg":"trace[1126376518] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1114; }","duration":"796.259772ms","start":"2026-05-30T09:52:20.055894Z","end":"2026-05-30T09:52:20.852154Z","steps":["trace[1126376518] 'count revisions from in-memory index tree' (duration: 795.664887ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:20.852349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:52:20.055865Z","time spent":"796.469578ms","remote":"127.0.0.1:43960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":30,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-05-30T09:52:22.209763Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305496388595750514,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-30T09:52:22.71032Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305496388595750514,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-30T09:52:22.731014Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.0448898s","expected-duration":"1s"} {"level":"info","ts":"2026-05-30T09:52:22.731382Z","caller":"traceutil/trace.go:171","msg":"trace[564896332] linearizableReadLoop","detail":"{readStateIndex:1159; appliedIndex:1158; }","duration":"1.022101922s","start":"2026-05-30T09:52:21.709256Z","end":"2026-05-30T09:52:22.731358Z","steps":["trace[564896332] 'read index received' (duration: 1.021916357s)","trace[564896332] 'applied index is now lower than readState.Index' (duration: 184.905µs)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:52:22.731522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.022240345s","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":"info","ts":"2026-05-30T09:52:22.731461Z","caller":"traceutil/trace.go:171","msg":"trace[986611024] transaction","detail":"{read_only:false; response_revision:1115; number_of_response:1; }","duration":"1.045419423s","start":"2026-05-30T09:52:21.686011Z","end":"2026-05-30T09:52:22.73143Z","steps":["trace[986611024] 'process raft request' (duration: 1.045223178s)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:52:22.731588Z","caller":"traceutil/trace.go:171","msg":"trace[1955297773] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:1115; }","duration":"1.022332867s","start":"2026-05-30T09:52:21.70924Z","end":"2026-05-30T09:52:22.731573Z","steps":["trace[1955297773] 'agreement among raft nodes before linearized reading' (duration: 1.022196074s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:22.731622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:52:21.709156Z","time spent":"1.02245869s","remote":"127.0.0.1:36870","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-05-30T09:52:22.73173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"982.124955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-30T09:52:22.73178Z","caller":"traceutil/trace.go:171","msg":"trace[2042967845] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1115; }","duration":"982.351811ms","start":"2026-05-30T09:52:21.749416Z","end":"2026-05-30T09:52:22.731768Z","steps":["trace[2042967845] 'agreement among raft nodes before linearized reading' (duration: 982.186737ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:22.731822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:52:21.749407Z","time spent":"982.406642ms","remote":"127.0.0.1:43846","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-05-30T09:52:22.73179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"983.274425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-30T09:52:22.731881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.944013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:52:22.731907Z","caller":"traceutil/trace.go:171","msg":"trace[132474407] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1115; }","duration":"343.009395ms","start":"2026-05-30T09:52:22.38889Z","end":"2026-05-30T09:52:22.731899Z","steps":["trace[132474407] 'agreement among raft nodes before linearized reading' (duration: 342.969414ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:52:22.731911Z","caller":"traceutil/trace.go:171","msg":"trace[62201973] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1115; }","duration":"983.429939ms","start":"2026-05-30T09:52:21.748456Z","end":"2026-05-30T09:52:22.731886Z","steps":["trace[62201973] 'agreement among raft nodes before linearized reading' (duration: 983.158772ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:22.731959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.766212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:52:22.73198Z","caller":"traceutil/trace.go:171","msg":"trace[2124922406] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1115; }","duration":"117.845764ms","start":"2026-05-30T09:52:22.614129Z","end":"2026-05-30T09:52:22.731975Z","steps":["trace[2124922406] 'agreement among raft nodes before linearized reading' (duration: 117.815023ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:22.731981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:52:21.748443Z","time spent":"983.523021ms","remote":"127.0.0.1:43846","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-05-30T09:52:22.732005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.019811482s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-30T09:52:22.731933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:52:22.388871Z","time spent":"343.056457ms","remote":"127.0.0.1:43726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"info","ts":"2026-05-30T09:52:22.732121Z","caller":"traceutil/trace.go:171","msg":"trace[1442886505] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1115; }","duration":"1.019929805s","start":"2026-05-30T09:52:21.712176Z","end":"2026-05-30T09:52:22.732106Z","steps":["trace[1442886505] 'agreement among raft nodes before linearized reading' (duration: 1.019663169s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:22.732086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"982.368351ms","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-05-30T09:52:22.732187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:52:21.712165Z","time spent":"1.020003878s","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-30T09:52:22.73154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.021114645s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:52:22.73221Z","caller":"traceutil/trace.go:171","msg":"trace[1752039234] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1115; }","duration":"982.526435ms","start":"2026-05-30T09:52:21.749658Z","end":"2026-05-30T09:52:22.732185Z","steps":["trace[1752039234] 'agreement among raft nodes before linearized reading' (duration: 982.229728ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:52:22.732245Z","caller":"traceutil/trace.go:171","msg":"trace[1703365060] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1115; }","duration":"1.021871896s","start":"2026-05-30T09:52:21.710365Z","end":"2026-05-30T09:52:22.732237Z","steps":["trace[1703365060] 'agreement among raft nodes before linearized reading' (duration: 1.021111816s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:22.732276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:52:21.710351Z","time spent":"1.021917756s","remote":"127.0.0.1:43562","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-05-30T09:52:22.732267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:52:21.74965Z","time spent":"982.603048ms","remote":"127.0.0.1:43846","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-05-30T09:52:22.732014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"982.153475ms","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-05-30T09:52:22.732738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:52:21.685987Z","time spent":"1.046667026s","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T09:52:22.732755Z","caller":"traceutil/trace.go:171","msg":"trace[1919290659] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1115; }","duration":"983.09629ms","start":"2026-05-30T09:52:21.74964Z","end":"2026-05-30T09:52:22.732736Z","steps":["trace[1919290659] 'agreement among raft nodes before linearized reading' (duration: 981.985301ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:22.732808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:52:21.749628Z","time spent":"983.165681ms","remote":"127.0.0.1:43846","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-05-30T09:52:23.294761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.451032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.210\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-30T09:52:23.294861Z","caller":"traceutil/trace.go:171","msg":"trace[506913041] range","detail":"{range_begin:/registry/masterleases/199.204.45.210; range_end:; response_count:1; response_revision:1120; }","duration":"185.712259ms","start":"2026-05-30T09:52:23.109123Z","end":"2026-05-30T09:52:23.294835Z","steps":["trace[506913041] 'range keys from in-memory index tree' (duration: 185.247066ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:25.712795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.000958541s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:52:25.712873Z","caller":"traceutil/trace.go:171","msg":"trace[2049709121] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1121; }","duration":"1.001857195s","start":"2026-05-30T09:52:24.710998Z","end":"2026-05-30T09:52:25.712856Z","steps":["trace[2049709121] 'range keys from in-memory index tree' (duration: 1.000740716s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:25.71292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:52:24.710967Z","time spent":"1.001939467s","remote":"127.0.0.1:43562","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-05-30T09:52:25.713762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.695487ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-30T09:52:25.713831Z","caller":"traceutil/trace.go:171","msg":"trace[1461594656] linearizableReadLoop","detail":"{readStateIndex:1167; appliedIndex:1166; }","duration":"909.501698ms","start":"2026-05-30T09:52:24.804319Z","end":"2026-05-30T09:52:25.713821Z","steps":["trace[1461594656] 'read index received' (duration: 464.596296ms)","trace[1461594656] 'applied index is now lower than readState.Index' (duration: 444.904502ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:52:25.714108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"909.711873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-30T09:52:25.714148Z","caller":"traceutil/trace.go:171","msg":"trace[1435908962] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1122; }","duration":"909.767515ms","start":"2026-05-30T09:52:24.804372Z","end":"2026-05-30T09:52:25.714139Z","steps":["trace[1435908962] 'agreement among raft nodes before linearized reading' (duration: 909.639412ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:25.714177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:52:24.804366Z","time spent":"909.804326ms","remote":"127.0.0.1:43846","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-05-30T09:52:25.71431Z","caller":"traceutil/trace.go:171","msg":"trace[1462795798] transaction","detail":"{read_only:false; response_revision:1122; number_of_response:1; }","duration":"974.315635ms","start":"2026-05-30T09:52:24.739968Z","end":"2026-05-30T09:52:25.714284Z","steps":["trace[1462795798] 'process raft request' (duration: 529.037373ms)","trace[1462795798] 'compare' (duration: 444.217615ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:52:25.714465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"909.668432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-30T09:52:25.714087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"909.750204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-30T09:52:25.715426Z","caller":"traceutil/trace.go:171","msg":"trace[1912248317] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1122; }","duration":"911.127429ms","start":"2026-05-30T09:52:24.804253Z","end":"2026-05-30T09:52:25.71538Z","steps":["trace[1912248317] 'agreement among raft nodes before linearized reading' (duration: 909.674513ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:25.715563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:52:24.804224Z","time spent":"911.290802ms","remote":"127.0.0.1:43846","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-05-30T09:52:25.717417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:52:24.739935Z","time spent":"974.51738ms","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:52:25.714365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"909.545599ms","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-05-30T09:52:25.718056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"913.293744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-30T09:52:25.718538Z","caller":"traceutil/trace.go:171","msg":"trace[1887941020] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1122; }","duration":"913.775717ms","start":"2026-05-30T09:52:24.804704Z","end":"2026-05-30T09:52:25.718479Z","steps":["trace[1887941020] 'agreement among raft nodes before linearized reading' (duration: 912.241807ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:52:25.71831Z","caller":"traceutil/trace.go:171","msg":"trace[446523853] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1122; }","duration":"913.51749ms","start":"2026-05-30T09:52:24.804769Z","end":"2026-05-30T09:52:25.718287Z","steps":["trace[446523853] 'agreement among raft nodes before linearized reading' (duration: 909.539459ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:25.718867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:52:24.804747Z","time spent":"914.095245ms","remote":"127.0.0.1:43846","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-05-30T09:52:25.719374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:52:24.804687Z","time spent":"913.944812ms","remote":"127.0.0.1:43846","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-05-30T09:52:25.719981Z","caller":"traceutil/trace.go:171","msg":"trace[255933644] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1122; }","duration":"909.749184ms","start":"2026-05-30T09:52:24.804744Z","end":"2026-05-30T09:52:25.714493Z","steps":["trace[255933644] 'agreement among raft nodes before linearized reading' (duration: 909.641591ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:25.720028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:52:24.804719Z","time spent":"915.297186ms","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-30T09:52:26.364719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.561104ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-30T09:52:26.364821Z","caller":"traceutil/trace.go:171","msg":"trace[368030013] transaction","detail":"{read_only:false; response_revision:1123; number_of_response:1; }","duration":"639.997584ms","start":"2026-05-30T09:52:25.724804Z","end":"2026-05-30T09:52:26.364801Z","steps":["trace[368030013] 'process raft request' (duration: 415.266666ms)","trace[368030013] 'compare' (duration: 224.202745ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:52:26.36488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:52:25.724785Z","time spent":"640.067716ms","remote":"127.0.0.1:43846","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-05-30T09:52:26.945333Z","caller":"traceutil/trace.go:171","msg":"trace[2123352711] transaction","detail":"{read_only:false; response_revision:1126; number_of_response:1; }","duration":"1.220100202s","start":"2026-05-30T09:52:25.725214Z","end":"2026-05-30T09:52:26.945314Z","steps":["trace[2123352711] 'process raft request' (duration: 1.219985329s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:26.945489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:52:25.72521Z","time spent":"1.220199394s","remote":"127.0.0.1:43846","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-05-30T09:52:26.945612Z","caller":"traceutil/trace.go:171","msg":"trace[357169749] transaction","detail":"{read_only:false; response_revision:1127; number_of_response:1; }","duration":"1.220129403s","start":"2026-05-30T09:52:25.725406Z","end":"2026-05-30T09:52:26.945536Z","steps":["trace[357169749] 'process raft request' (duration: 1.219846965s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:26.94572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:52:25.7254Z","time spent":"1.220254266s","remote":"127.0.0.1:43846","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-05-30T09:52:26.945736Z","caller":"traceutil/trace.go:171","msg":"trace[256133893] transaction","detail":"{read_only:false; response_revision:1125; number_of_response:1; }","duration":"1.220528504s","start":"2026-05-30T09:52:25.72519Z","end":"2026-05-30T09:52:26.945719Z","steps":["trace[256133893] 'process raft request' (duration: 1.219951498s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:26.945824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:52:25.725169Z","time spent":"1.220606055s","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:52:26.945496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.217476214s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:52:26.94601Z","caller":"traceutil/trace.go:171","msg":"trace[2106575276] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1127; }","duration":"1.218068241s","start":"2026-05-30T09:52:25.727922Z","end":"2026-05-30T09:52:26.94599Z","steps":["trace[2106575276] 'agreement among raft nodes before linearized reading' (duration: 1.217457194s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:26.946105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:52:25.727913Z","time spent":"1.218173643s","remote":"127.0.0.1:43562","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-05-30T09:52:26.946193Z","caller":"traceutil/trace.go:171","msg":"trace[1310512406] transaction","detail":"{read_only:false; response_revision:1124; number_of_response:1; }","duration":"1.220101622s","start":"2026-05-30T09:52:25.725151Z","end":"2026-05-30T09:52:26.945253Z","steps":["trace[1310512406] 'process raft request' (duration: 1.219819765s)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:52:26.945325Z","caller":"traceutil/trace.go:171","msg":"trace[578938572] linearizableReadLoop","detail":"{readStateIndex:1172; appliedIndex:1167; }","duration":"1.217353871s","start":"2026-05-30T09:52:25.727949Z","end":"2026-05-30T09:52:26.945303Z","steps":["trace[578938572] 'read index received' (duration: 412.128966ms)","trace[578938572] 'applied index is now lower than readState.Index' (duration: 805.223515ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:52:26.946419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:52:25.725132Z","time spent":"1.221137099s","remote":"127.0.0.1:43846","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-05-30T09:52:42.660018Z","caller":"traceutil/trace.go:171","msg":"trace[549248528] transaction","detail":"{read_only:false; response_revision:1228; number_of_response:1; }","duration":"153.158635ms","start":"2026-05-30T09:52:42.506842Z","end":"2026-05-30T09:52:42.660001Z","steps":["trace[549248528] 'process raft request' (duration: 153.068793ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:44.450862Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305496388595750784,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-30T09:52:44.951685Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305496388595750784,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-30T09:52:45.452753Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305496388595750784,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-30T09:52:45.953099Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305496388595750784,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-30T09:52:46.25031Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.426415634s","expected-duration":"1s"} {"level":"info","ts":"2026-05-30T09:52:46.25081Z","caller":"traceutil/trace.go:171","msg":"trace[868793009] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"2.426995368s","start":"2026-05-30T09:52:43.823796Z","end":"2026-05-30T09:52:46.250791Z","steps":["trace[868793009] 'process raft request' (duration: 2.426773552s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:46.250956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:52:43.823773Z","time spent":"2.427088531s","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:52:46.251163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.300649847s","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-05-30T09:52:46.251252Z","caller":"traceutil/trace.go:171","msg":"trace[436614445] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1234; }","duration":"2.3007745s","start":"2026-05-30T09:52:43.95045Z","end":"2026-05-30T09:52:46.251224Z","steps":["trace[436614445] 'agreement among raft nodes before linearized reading' (duration: 2.300633906s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:46.251312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:52:43.950434Z","time spent":"2.300864913s","remote":"127.0.0.1:36928","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/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"info","ts":"2026-05-30T09:52:46.251377Z","caller":"traceutil/trace.go:171","msg":"trace[388115459] linearizableReadLoop","detail":"{readStateIndex:1285; appliedIndex:1284; }","duration":"2.300258197s","start":"2026-05-30T09:52:43.950491Z","end":"2026-05-30T09:52:46.250749Z","steps":["trace[388115459] 'read index received' (duration: 2.29997729s)","trace[388115459] 'applied index is now lower than readState.Index' (duration: 279.997µs)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:52:46.251528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.27605661s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:52:46.251569Z","caller":"traceutil/trace.go:171","msg":"trace[903769105] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1234; }","duration":"2.276111401s","start":"2026-05-30T09:52:43.975443Z","end":"2026-05-30T09:52:46.251555Z","steps":["trace[903769105] 'agreement among raft nodes before linearized reading' (duration: 2.276046219s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:46.251607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:52:43.975433Z","time spent":"2.276166512s","remote":"127.0.0.1:36962","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-05-30T09:52:46.251748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.084561206s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-30T09:52:46.251811Z","caller":"traceutil/trace.go:171","msg":"trace[1076269496] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1234; }","duration":"1.084641109s","start":"2026-05-30T09:52:45.167156Z","end":"2026-05-30T09:52:46.251797Z","steps":["trace[1076269496] 'agreement among raft nodes before linearized reading' (duration: 1.084483745s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:46.251854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:52:45.16715Z","time spent":"1.08469462s","remote":"127.0.0.1:43846","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-05-30T09:52:46.251881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.087667826s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-05-30T09:52:46.251928Z","caller":"traceutil/trace.go:171","msg":"trace[641587360] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1234; }","duration":"1.087754918s","start":"2026-05-30T09:52:45.164161Z","end":"2026-05-30T09:52:46.251916Z","steps":["trace[641587360] 'agreement among raft nodes before linearized reading' (duration: 1.087603344s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:46.251975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:52:45.164139Z","time spent":"1.08782539s","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-30T09:52:46.252074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.800581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-30T09:52:46.252121Z","caller":"traceutil/trace.go:171","msg":"trace[1387983909] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1234; }","duration":"255.876773ms","start":"2026-05-30T09:52:45.996232Z","end":"2026-05-30T09:52:46.252109Z","steps":["trace[1387983909] 'agreement among raft nodes before linearized reading' (duration: 255.802471ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:46.25223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.089054351s","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-05-30T09:52:46.252272Z","caller":"traceutil/trace.go:171","msg":"trace[1719028392] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1234; }","duration":"1.089096152s","start":"2026-05-30T09:52:45.163167Z","end":"2026-05-30T09:52:46.252263Z","steps":["trace[1719028392] 'agreement among raft nodes before linearized reading' (duration: 1.088983749s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:46.252284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.407037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-30T09:52:46.252313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:52:45.163126Z","time spent":"1.089177834s","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-30T09:52:46.252339Z","caller":"traceutil/trace.go:171","msg":"trace[579414742] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1234; }","duration":"402.507319ms","start":"2026-05-30T09:52:45.849815Z","end":"2026-05-30T09:52:46.252322Z","steps":["trace[579414742] 'agreement among raft nodes before linearized reading' (duration: 402.440408ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:46.252406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:52:45.849786Z","time spent":"402.606262ms","remote":"127.0.0.1:43562","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-05-30T09:52:46.252499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.54406389s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:52:46.252549Z","caller":"traceutil/trace.go:171","msg":"trace[558960108] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1234; }","duration":"1.544113442s","start":"2026-05-30T09:52:44.708423Z","end":"2026-05-30T09:52:46.252537Z","steps":["trace[558960108] 'agreement among raft nodes before linearized reading' (duration: 1.54403718s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:46.252587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:52:44.708374Z","time spent":"1.544203554s","remote":"127.0.0.1:43562","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-05-30T09:52:46.252634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.006962509s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-30T09:52:46.252665Z","caller":"traceutil/trace.go:171","msg":"trace[1477790262] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1234; }","duration":"1.007019291s","start":"2026-05-30T09:52:45.245636Z","end":"2026-05-30T09:52:46.252655Z","steps":["trace[1477790262] 'agreement among raft nodes before linearized reading' (duration: 1.006918537s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:46.252695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:52:45.24562Z","time spent":"1.007068212s","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-30T09:52:46.252812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.589972571s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-30T09:52:46.252851Z","caller":"traceutil/trace.go:171","msg":"trace[514348042] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1234; }","duration":"1.590037702s","start":"2026-05-30T09:52:44.662804Z","end":"2026-05-30T09:52:46.252842Z","steps":["trace[514348042] 'agreement among raft nodes before linearized reading' (duration: 1.58994073s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:46.252877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:52:44.662789Z","time spent":"1.590082074s","remote":"127.0.0.1:43846","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-05-30T09:52:48.401407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.324583ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-30T09:52:48.401679Z","caller":"traceutil/trace.go:171","msg":"trace[2083029795] linearizableReadLoop","detail":"{readStateIndex:1293; appliedIndex:1291; }","duration":"135.71106ms","start":"2026-05-30T09:52:48.265951Z","end":"2026-05-30T09:52:48.401662Z","steps":["trace[2083029795] 'read index received' (duration: 74.894259ms)","trace[2083029795] 'applied index is now lower than readState.Index' (duration: 60.815911ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:52:48.401775Z","caller":"traceutil/trace.go:171","msg":"trace[1366317202] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"138.85513ms","start":"2026-05-30T09:52:48.262885Z","end":"2026-05-30T09:52:48.40174Z","steps":["trace[1366317202] 'process raft request' (duration: 138.618884ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:48.401861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.823982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-30T09:52:48.401885Z","caller":"traceutil/trace.go:171","msg":"trace[550501967] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1241; }","duration":"135.913425ms","start":"2026-05-30T09:52:48.265964Z","end":"2026-05-30T09:52:48.401877Z","steps":["trace[550501967] 'agreement among raft nodes before linearized reading' (duration: 135.795532ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:48.40202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.031438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-30T09:52:48.402131Z","caller":"traceutil/trace.go:171","msg":"trace[1613563255] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1241; }","duration":"136.217083ms","start":"2026-05-30T09:52:48.265884Z","end":"2026-05-30T09:52:48.402101Z","steps":["trace[1613563255] 'agreement among raft nodes before linearized reading' (duration: 135.852394ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:48.402179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.250077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-30T09:52:48.402218Z","caller":"traceutil/trace.go:171","msg":"trace[1124322035] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1241; }","duration":"135.321589ms","start":"2026-05-30T09:52:48.266887Z","end":"2026-05-30T09:52:48.402209Z","steps":["trace[1124322035] 'agreement among raft nodes before linearized reading' (duration: 135.143054ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:52:48.645996Z","caller":"traceutil/trace.go:171","msg":"trace[1965196854] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"239.797263ms","start":"2026-05-30T09:52:48.406174Z","end":"2026-05-30T09:52:48.645972Z","steps":["trace[1965196854] 'process raft request' (duration: 233.584265ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:52:48.646238Z","caller":"traceutil/trace.go:171","msg":"trace[1250852005] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"239.981298ms","start":"2026-05-30T09:52:48.406236Z","end":"2026-05-30T09:52:48.646218Z","steps":["trace[1250852005] 'process raft request' (duration: 239.69266ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:52:48.646334Z","caller":"traceutil/trace.go:171","msg":"trace[694515897] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"239.928866ms","start":"2026-05-30T09:52:48.406394Z","end":"2026-05-30T09:52:48.646323Z","steps":["trace[694515897] 'process raft request' (duration: 239.877145ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:52:48.646425Z","caller":"traceutil/trace.go:171","msg":"trace[1519530394] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"240.096541ms","start":"2026-05-30T09:52:48.40632Z","end":"2026-05-30T09:52:48.646417Z","steps":["trace[1519530394] 'process raft request' (duration: 239.796243ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:52:57.997335Z","caller":"traceutil/trace.go:171","msg":"trace[1885275686] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"112.325003ms","start":"2026-05-30T09:52:57.884986Z","end":"2026-05-30T09:52:57.997311Z","steps":["trace[1885275686] 'process raft request' (duration: 112.178489ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:58.414571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.437025ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-30T09:53:01.985593Z","caller":"traceutil/trace.go:171","msg":"trace[2132328170] linearizableReadLoop","detail":"{readStateIndex:1359; appliedIndex:1358; }","duration":"276.15561ms","start":"2026-05-30T09:53:01.709416Z","end":"2026-05-30T09:53:01.985572Z","steps":["trace[2132328170] 'read index received' (duration: 275.237666ms)","trace[2132328170] 'applied index is now lower than readState.Index' (duration: 916.754µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:53:01.985709Z","caller":"traceutil/trace.go:171","msg":"trace[66483671] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"704.152601ms","start":"2026-05-30T09:53:01.281518Z","end":"2026-05-30T09:53:01.985671Z","steps":["trace[66483671] 'process raft request' (duration: 703.873484ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:53:01.985862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.426447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:53:01.985908Z","caller":"traceutil/trace.go:171","msg":"trace[2047119413] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1305; }","duration":"276.52525ms","start":"2026-05-30T09:53:01.709373Z","end":"2026-05-30T09:53:01.985898Z","steps":["trace[2047119413] 'agreement among raft nodes before linearized reading' (duration: 276.288634ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:53:01.985897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:53:01.281501Z","time spent":"704.304824ms","remote":"127.0.0.1:43748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5457,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:53:02.944419Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305496388595750937,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-30T09:53:02.997323Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.004346553s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-30T09:53:03.646563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"648.940473ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-30T09:53:03.646979Z","caller":"traceutil/trace.go:171","msg":"trace[786530925] linearizableReadLoop","detail":"{readStateIndex:1361; appliedIndex:1359; }","duration":"1.203583152s","start":"2026-05-30T09:53:02.443379Z","end":"2026-05-30T09:53:03.646962Z","steps":["trace[786530925] 'read index received' (duration: 554.175577ms)","trace[786530925] 'applied index is now lower than readState.Index' (duration: 649.406645ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:53:03.647161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.203767806s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:53:03.647222Z","caller":"traceutil/trace.go:171","msg":"trace[1825239387] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1307; }","duration":"1.203843199s","start":"2026-05-30T09:53:02.443365Z","end":"2026-05-30T09:53:03.647208Z","steps":["trace[1825239387] 'agreement among raft nodes before linearized reading' (duration: 1.203724816s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:53:03.647252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:53:02.443309Z","time spent":"1.203937781s","remote":"127.0.0.1:36878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"info","ts":"2026-05-30T09:53:03.647391Z","caller":"traceutil/trace.go:171","msg":"trace[374092027] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"1.189442011s","start":"2026-05-30T09:53:02.457942Z","end":"2026-05-30T09:53:03.647384Z","steps":["trace[374092027] 'process raft request' (duration: 1.188949249s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:53:03.647449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:53:02.457913Z","time spent":"1.189495203s","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T09:53:03.647534Z","caller":"traceutil/trace.go:171","msg":"trace[1837206427] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"1.654606799s","start":"2026-05-30T09:53:01.99289Z","end":"2026-05-30T09:53:03.647497Z","steps":["trace[1837206427] 'process raft request' (duration: 1.004652831s)","trace[1837206427] 'compare' (duration: 648.82434ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:53:03.647614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"920.127726ms","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-05-30T09:53:03.647637Z","caller":"traceutil/trace.go:171","msg":"trace[1311804988] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1307; }","duration":"920.188157ms","start":"2026-05-30T09:53:02.727443Z","end":"2026-05-30T09:53:03.647631Z","steps":["trace[1311804988] 'agreement among raft nodes before linearized reading' (duration: 920.132636ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:53:03.647654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:53:02.727422Z","time spent":"920.228639ms","remote":"127.0.0.1:43846","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-05-30T09:53:03.647627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"916.212636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-30T09:53:03.647661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:53:01.992877Z","time spent":"1.654716931s","remote":"127.0.0.1:43960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":797,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:53:03.647646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.628064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.210\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-05-30T09:53:03.647665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"915.901538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-30T09:53:03.647685Z","caller":"traceutil/trace.go:171","msg":"trace[1881545386] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1307; }","duration":"916.294527ms","start":"2026-05-30T09:53:02.731378Z","end":"2026-05-30T09:53:03.647673Z","steps":["trace[1881545386] 'agreement among raft nodes before linearized reading' (duration: 916.142784ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:53:03.647703Z","caller":"traceutil/trace.go:171","msg":"trace[710361799] range","detail":"{range_begin:/registry/masterleases/199.204.45.210; range_end:; response_count:1; response_revision:1307; }","duration":"535.691196ms","start":"2026-05-30T09:53:03.111998Z","end":"2026-05-30T09:53:03.647689Z","steps":["trace[710361799] 'agreement among raft nodes before linearized reading' (duration: 535.539962ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:53:03.647725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"916.733199ms","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-05-30T09:53:03.647726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:53:02.731367Z","time spent":"916.351649ms","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-30T09:53:03.647726Z","caller":"traceutil/trace.go:171","msg":"trace[215654154] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1307; }","duration":"915.985119ms","start":"2026-05-30T09:53:02.731726Z","end":"2026-05-30T09:53:03.647711Z","steps":["trace[215654154] 'agreement among raft nodes before linearized reading' (duration: 915.814695ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:53:03.647736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:53:03.111951Z","time spent":"535.779248ms","remote":"127.0.0.1:43616","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.210\" "} {"level":"info","ts":"2026-05-30T09:53:03.647744Z","caller":"traceutil/trace.go:171","msg":"trace[406782210] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1307; }","duration":"916.775309ms","start":"2026-05-30T09:53:02.730964Z","end":"2026-05-30T09:53:03.647739Z","steps":["trace[406782210] 'agreement among raft nodes before linearized reading' (duration: 916.728438ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:53:03.647761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:53:02.73095Z","time spent":"916.808251ms","remote":"127.0.0.1:43846","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-05-30T09:53:03.647767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:53:02.731718Z","time spent":"916.039202ms","remote":"127.0.0.1:43846","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-05-30T09:53:03.647806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"937.802317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:53:03.647823Z","caller":"traceutil/trace.go:171","msg":"trace[1159022321] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1307; }","duration":"937.852768ms","start":"2026-05-30T09:53:02.709966Z","end":"2026-05-30T09:53:03.647818Z","steps":["trace[1159022321] 'agreement among raft nodes before linearized reading' (duration: 937.827747ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:53:03.647835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:53:02.709945Z","time spent":"937.887068ms","remote":"127.0.0.1:43562","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-05-30T09:53:03.64828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.117434425s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-30T09:53:03.64838Z","caller":"traceutil/trace.go:171","msg":"trace[1167877992] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1307; }","duration":"1.117564129s","start":"2026-05-30T09:53:02.5308Z","end":"2026-05-30T09:53:03.648364Z","steps":["trace[1167877992] 'agreement among raft nodes before linearized reading' (duration: 1.116385689s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:53:03.648482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:53:02.530786Z","time spent":"1.117682591s","remote":"127.0.0.1:43846","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-05-30T09:53:03.882515Z","caller":"traceutil/trace.go:171","msg":"trace[2134377281] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"230.365312ms","start":"2026-05-30T09:53:03.652127Z","end":"2026-05-30T09:53:03.882492Z","steps":["trace[2134377281] 'process raft request' (duration: 230.2887ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:53:03.882552Z","caller":"traceutil/trace.go:171","msg":"trace[1842745857] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"230.468274ms","start":"2026-05-30T09:53:03.652062Z","end":"2026-05-30T09:53:03.88253Z","steps":["trace[1842745857] 'process raft request' (duration: 230.191577ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:53:03.882843Z","caller":"traceutil/trace.go:171","msg":"trace[2059826105] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"230.655349ms","start":"2026-05-30T09:53:03.652178Z","end":"2026-05-30T09:53:03.882834Z","steps":["trace[2059826105] 'process raft request' (duration: 230.28111ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:53:03.882885Z","caller":"traceutil/trace.go:171","msg":"trace[355154816] linearizableReadLoop","detail":"{readStateIndex:1367; appliedIndex:1361; }","duration":"171.794779ms","start":"2026-05-30T09:53:03.711061Z","end":"2026-05-30T09:53:03.882856Z","steps":["trace[355154816] 'read index received' (duration: 16.576662ms)","trace[355154816] 'applied index is now lower than readState.Index' (duration: 155.216977ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:53:03.882896Z","caller":"traceutil/trace.go:171","msg":"trace[1039059713] transaction","detail":"{read_only:false; response_revision:1311; number_of_response:1; }","duration":"230.491025ms","start":"2026-05-30T09:53:03.65237Z","end":"2026-05-30T09:53:03.882861Z","steps":["trace[1039059713] 'process raft request' (duration: 230.358691ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:53:03.882925Z","caller":"traceutil/trace.go:171","msg":"trace[459079430] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"230.092055ms","start":"2026-05-30T09:53:03.652799Z","end":"2026-05-30T09:53:03.882891Z","steps":["trace[459079430] 'process raft request' (duration: 230.013003ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:53:03.883053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.968293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-30T09:53:03.883092Z","caller":"traceutil/trace.go:171","msg":"trace[1791940075] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1312; }","duration":"172.050125ms","start":"2026-05-30T09:53:03.711031Z","end":"2026-05-30T09:53:03.883081Z","steps":["trace[1791940075] 'agreement among raft nodes before linearized reading' (duration: 171.909352ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:53:03.90219Z","caller":"traceutil/trace.go:171","msg":"trace[908166193] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"173.454982ms","start":"2026-05-30T09:53:03.728702Z","end":"2026-05-30T09:53:03.902157Z","steps":["trace[908166193] 'process raft request' (duration: 173.285157ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:53:03.902658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.256069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:53:03.902763Z","caller":"traceutil/trace.go:171","msg":"trace[387648535] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1313; }","duration":"188.410572ms","start":"2026-05-30T09:53:03.714337Z","end":"2026-05-30T09:53:03.902748Z","steps":["trace[387648535] 'agreement among raft nodes before linearized reading' (duration: 187.755496ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:53:06.210647Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305496388595750958,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-30T09:53:06.463849Z","caller":"traceutil/trace.go:171","msg":"trace[1784765881] linearizableReadLoop","detail":"{readStateIndex:1369; appliedIndex:1368; }","duration":"754.087563ms","start":"2026-05-30T09:53:05.709742Z","end":"2026-05-30T09:53:06.46383Z","steps":["trace[1784765881] 'read index received' (duration: 753.94434ms)","trace[1784765881] 'applied index is now lower than readState.Index' (duration: 142.423µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:53:06.463933Z","caller":"traceutil/trace.go:171","msg":"trace[1628193043] transaction","detail":"{read_only:false; response_revision:1314; number_of_response:1; }","duration":"809.172287ms","start":"2026-05-30T09:53:05.654731Z","end":"2026-05-30T09:53:06.463903Z","steps":["trace[1628193043] 'process raft request' (duration: 808.972282ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:53:06.464081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:53:05.654675Z","time spent":"809.329072ms","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:53:06.464342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.276971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-30T09:53:06.464384Z","caller":"traceutil/trace.go:171","msg":"trace[1617086015] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1314; }","duration":"578.354423ms","start":"2026-05-30T09:53:05.886019Z","end":"2026-05-30T09:53:06.464373Z","steps":["trace[1617086015] 'agreement among raft nodes before linearized reading' (duration: 578.200349ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:53:06.464414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:53:05.886Z","time spent":"578.406874ms","remote":"127.0.0.1:43846","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-05-30T09:53:06.464394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.254046ms","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-05-30T09:53:06.464865Z","caller":"traceutil/trace.go:171","msg":"trace[1270747842] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1314; }","duration":"577.735038ms","start":"2026-05-30T09:53:05.887095Z","end":"2026-05-30T09:53:06.46483Z","steps":["trace[1270747842] 'agreement among raft nodes before linearized reading' (duration: 577.131822ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:53:06.464928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:53:05.887085Z","time spent":"577.829371ms","remote":"127.0.0.1:43846","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-05-30T09:53:06.46446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.78922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:53:06.46502Z","caller":"traceutil/trace.go:171","msg":"trace[269801842] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1314; }","duration":"205.403217ms","start":"2026-05-30T09:53:06.259602Z","end":"2026-05-30T09:53:06.465005Z","steps":["trace[269801842] 'agreement among raft nodes before linearized reading' (duration: 204.76753ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:53:06.464538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.176179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-30T09:53:06.465162Z","caller":"traceutil/trace.go:171","msg":"trace[552810442] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1314; }","duration":"578.815305ms","start":"2026-05-30T09:53:05.886333Z","end":"2026-05-30T09:53:06.465148Z","steps":["trace[552810442] 'agreement among raft nodes before linearized reading' (duration: 578.149708ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:53:06.465319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:53:05.886318Z","time spent":"578.98714ms","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-30T09:53:06.464544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.86979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-30T09:53:06.465438Z","caller":"traceutil/trace.go:171","msg":"trace[1205902472] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1314; }","duration":"578.825966ms","start":"2026-05-30T09:53:05.886602Z","end":"2026-05-30T09:53:06.465428Z","steps":["trace[1205902472] 'agreement among raft nodes before linearized reading' (duration: 577.755188ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:53:06.465537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:53:05.886581Z","time spent":"578.944789ms","remote":"127.0.0.1:43846","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-05-30T09:53:06.464598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.0413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-30T09:53:06.465599Z","caller":"traceutil/trace.go:171","msg":"trace[1482107564] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1314; }","duration":"578.051616ms","start":"2026-05-30T09:53:05.887538Z","end":"2026-05-30T09:53:06.465589Z","steps":["trace[1482107564] 'agreement among raft nodes before linearized reading' (duration: 576.969417ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:53:06.465623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:53:05.887532Z","time spent":"578.085386ms","remote":"127.0.0.1:43846","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-05-30T09:53:06.464706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"754.964706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:53:06.46571Z","caller":"traceutil/trace.go:171","msg":"trace[48798035] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1314; }","duration":"755.986652ms","start":"2026-05-30T09:53:05.709703Z","end":"2026-05-30T09:53:06.46569Z","steps":["trace[48798035] 'agreement among raft nodes before linearized reading' (duration: 754.238027ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:53:06.466714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:53:05.709688Z","time spent":"756.965816ms","remote":"127.0.0.1:43562","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-05-30T09:53:06.779845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.661735ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-30T09:53:06.779952Z","caller":"traceutil/trace.go:171","msg":"trace[811740054] transaction","detail":"{read_only:false; response_revision:1315; number_of_response:1; }","duration":"309.870149ms","start":"2026-05-30T09:53:06.470063Z","end":"2026-05-30T09:53:06.779933Z","steps":["trace[811740054] 'process raft request' (duration: 69.03399ms)","trace[811740054] 'compare' (duration: 240.44553ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:53:06.780016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:53:06.47004Z","time spent":"309.944092ms","remote":"127.0.0.1:43846","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-05-30T09:53:06.949357Z","caller":"traceutil/trace.go:171","msg":"trace[1974651856] transaction","detail":"{read_only:false; response_revision:1316; number_of_response:1; }","duration":"479.064132ms","start":"2026-05-30T09:53:06.470259Z","end":"2026-05-30T09:53:06.949323Z","steps":["trace[1974651856] 'process raft request' (duration: 412.660459ms)","trace[1974651856] 'compare' (duration: 66.141475ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:53:06.949471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.096802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-30T09:53:06.949525Z","caller":"traceutil/trace.go:171","msg":"trace[947563756] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1319; }","duration":"475.195833ms","start":"2026-05-30T09:53:06.474318Z","end":"2026-05-30T09:53:06.949514Z","steps":["trace[947563756] 'agreement among raft nodes before linearized reading' (duration: 475.084491ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:53:06.949532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:53:06.47025Z","time spent":"479.189806ms","remote":"127.0.0.1:43846","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-05-30T09:53:06.94957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:53:06.474302Z","time spent":"475.257536ms","remote":"127.0.0.1:43628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":31,"response size":30,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"info","ts":"2026-05-30T09:53:06.949649Z","caller":"traceutil/trace.go:171","msg":"trace[1951394671] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"478.97053ms","start":"2026-05-30T09:53:06.470651Z","end":"2026-05-30T09:53:06.949622Z","steps":["trace[1951394671] 'process raft request' (duration: 478.57916ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:53:06.949693Z","caller":"traceutil/trace.go:171","msg":"trace[1810108598] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"478.377674ms","start":"2026-05-30T09:53:06.471293Z","end":"2026-05-30T09:53:06.949671Z","steps":["trace[1810108598] 'process raft request' (duration: 477.967334ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:53:06.949693Z","caller":"traceutil/trace.go:171","msg":"trace[234535090] transaction","detail":"{read_only:false; response_revision:1317; number_of_response:1; }","duration":"479.297118ms","start":"2026-05-30T09:53:06.470368Z","end":"2026-05-30T09:53:06.949666Z","steps":["trace[234535090] 'process raft request' (duration: 478.820536ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:53:06.949358Z","caller":"traceutil/trace.go:171","msg":"trace[1618067788] linearizableReadLoop","detail":"{readStateIndex:1374; appliedIndex:1369; }","duration":"474.982638ms","start":"2026-05-30T09:53:06.474354Z","end":"2026-05-30T09:53:06.949336Z","steps":["trace[1618067788] 'read index received' (duration: 64.73196ms)","trace[1618067788] 'applied index is now lower than readState.Index' (duration: 410.249538ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:53:06.949796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:53:06.470642Z","time spent":"479.077763ms","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:53:06.94982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:53:06.471287Z","time spent":"478.470648ms","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:53:06.949821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:53:06.470344Z","time spent":"479.415601ms","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:53:08.210066Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305496388595750968,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-30T09:53:08.711141Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305496388595750968,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-30T09:53:08.756469Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.04936868s","expected-duration":"1s"} {"level":"info","ts":"2026-05-30T09:53:08.763942Z","caller":"traceutil/trace.go:171","msg":"trace[1170066501] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"1.056916252s","start":"2026-05-30T09:53:07.707009Z","end":"2026-05-30T09:53:08.763926Z","steps":["trace[1170066501] 'process raft request' (duration: 1.05681836s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:53:08.76404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:53:07.706987Z","time spent":"1.057007375s","remote":"127.0.0.1:43648","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-30T09:53:09.403591Z","caller":"traceutil/trace.go:171","msg":"trace[1103236725] transaction","detail":"{read_only:false; response_revision:1322; number_of_response:1; }","duration":"1.401045395s","start":"2026-05-30T09:53:08.002511Z","end":"2026-05-30T09:53:09.403557Z","steps":["trace[1103236725] 'process raft request' (duration: 1.400770068s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:53:09.403913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:53:08.002488Z","time spent":"1.401182229s","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":671,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T09:53:09.441974Z","caller":"traceutil/trace.go:171","msg":"trace[1211731729] linearizableReadLoop","detail":"{readStateIndex:1379; appliedIndex:1375; }","duration":"1.731994111s","start":"2026-05-30T09:53:07.709965Z","end":"2026-05-30T09:53:09.441959Z","steps":["trace[1211731729] 'read index received' (duration: 1.053876545s)","trace[1211731729] 'applied index is now lower than readState.Index' (duration: 678.116856ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:53:09.442021Z","caller":"traceutil/trace.go:171","msg":"trace[307234791] transaction","detail":"{read_only:false; response_revision:1323; number_of_response:1; }","duration":"971.202377ms","start":"2026-05-30T09:53:08.470788Z","end":"2026-05-30T09:53:09.44199Z","steps":["trace[307234791] 'process raft request' (duration: 971.027482ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:53:09.442168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.656802523s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:53:09.442209Z","caller":"traceutil/trace.go:171","msg":"trace[1433053040] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1324; }","duration":"1.656879886s","start":"2026-05-30T09:53:07.785318Z","end":"2026-05-30T09:53:09.442198Z","steps":["trace[1433053040] 'agreement among raft nodes before linearized reading' (duration: 1.656793054s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:53:09.442242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:53:07.785303Z","time spent":"1.656929627s","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-30T09:53:09.442234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:53:08.470766Z","time spent":"971.37687ms","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:53:09.442298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.382819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-30T09:53:09.442369Z","caller":"traceutil/trace.go:171","msg":"trace[481188246] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1324; }","duration":"488.471921ms","start":"2026-05-30T09:53:08.953878Z","end":"2026-05-30T09:53:09.44235Z","steps":["trace[481188246] 'agreement among raft nodes before linearized reading' (duration: 488.281996ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:53:09.442411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:53:08.953874Z","time spent":"488.529133ms","remote":"127.0.0.1:43846","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-05-30T09:53:09.442444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.515263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-30T09:53:09.442493Z","caller":"traceutil/trace.go:171","msg":"trace[951471429] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1324; }","duration":"488.578645ms","start":"2026-05-30T09:53:08.953901Z","end":"2026-05-30T09:53:09.44248Z","steps":["trace[951471429] 'agreement among raft nodes before linearized reading' (duration: 488.426ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:53:09.442513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.685888ms","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-05-30T09:53:09.442539Z","caller":"traceutil/trace.go:171","msg":"trace[1623679285] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1324; }","duration":"488.744729ms","start":"2026-05-30T09:53:08.953787Z","end":"2026-05-30T09:53:09.442531Z","steps":["trace[1623679285] 'agreement among raft nodes before linearized reading' (duration: 488.684548ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:53:09.44257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.657001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-30T09:53:09.442577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:53:08.953766Z","time spent":"488.79275ms","remote":"127.0.0.1:43846","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-05-30T09:53:09.442597Z","caller":"traceutil/trace.go:171","msg":"trace[526280292] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1324; }","duration":"658.717063ms","start":"2026-05-30T09:53:08.783871Z","end":"2026-05-30T09:53:09.442588Z","steps":["trace[526280292] 'agreement among raft nodes before linearized reading' (duration: 658.640141ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:53:09.442622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:53:08.783854Z","time spent":"658.762763ms","remote":"127.0.0.1:43846","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-05-30T09:53:09.442627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.732639288s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-30T09:53:09.442454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.576325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-30T09:53:09.443057Z","caller":"traceutil/trace.go:171","msg":"trace[877265712] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1324; }","duration":"489.19474ms","start":"2026-05-30T09:53:08.953851Z","end":"2026-05-30T09:53:09.443046Z","steps":["trace[877265712] 'agreement among raft nodes before linearized reading' (duration: 488.539663ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:53:09.443108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:53:08.953846Z","time spent":"489.251741ms","remote":"127.0.0.1:43846","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-05-30T09:53:09.443262Z","caller":"traceutil/trace.go:171","msg":"trace[1523938731] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1324; }","duration":"1.733311624s","start":"2026-05-30T09:53:07.709934Z","end":"2026-05-30T09:53:09.443246Z","steps":["trace[1523938731] 'agreement among raft nodes before linearized reading' (duration: 1.732137924s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:53:09.442526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:53:08.953897Z","time spent":"488.621646ms","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-30T09:53:09.443327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:53:07.709922Z","time spent":"1.733377326s","remote":"127.0.0.1:43562","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-05-30T09:53:09.702098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.009901ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-30T09:53:09.702219Z","caller":"traceutil/trace.go:171","msg":"trace[1941398989] transaction","detail":"{read_only:false; response_revision:1325; number_of_response:1; }","duration":"256.198041ms","start":"2026-05-30T09:53:09.446002Z","end":"2026-05-30T09:53:09.7022Z","steps":["trace[1941398989] 'process raft request' (duration: 41.012355ms)","trace[1941398989] 'compare' (duration: 214.872298ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:53:09.76613Z","caller":"traceutil/trace.go:171","msg":"trace[496089011] linearizableReadLoop","detail":"{readStateIndex:1387; appliedIndex:1380; }","duration":"314.049825ms","start":"2026-05-30T09:53:09.452063Z","end":"2026-05-30T09:53:09.766113Z","steps":["trace[496089011] 'read index received' (duration: 35.040343ms)","trace[496089011] 'applied index is now lower than readState.Index' (duration: 279.008512ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:53:09.766419Z","caller":"traceutil/trace.go:171","msg":"trace[1186006286] transaction","detail":"{read_only:false; response_revision:1330; number_of_response:1; }","duration":"317.793141ms","start":"2026-05-30T09:53:09.448587Z","end":"2026-05-30T09:53:09.76638Z","steps":["trace[1186006286] 'process raft request' (duration: 317.454183ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:53:09.766645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:53:09.448564Z","time spent":"317.937494ms","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T09:53:09.766701Z","caller":"traceutil/trace.go:171","msg":"trace[1641675377] transaction","detail":"{read_only:false; response_revision:1327; number_of_response:1; }","duration":"319.195067ms","start":"2026-05-30T09:53:09.44743Z","end":"2026-05-30T09:53:09.766625Z","steps":["trace[1641675377] 'process raft request' (duration: 318.464759ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:53:09.766744Z","caller":"traceutil/trace.go:171","msg":"trace[805086775] transaction","detail":"{read_only:false; response_revision:1326; number_of_response:1; }","duration":"319.412022ms","start":"2026-05-30T09:53:09.447291Z","end":"2026-05-30T09:53:09.766703Z","steps":["trace[805086775] 'process raft request' (duration: 318.456938ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:53:09.766858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:53:09.447424Z","time spent":"319.352281ms","remote":"127.0.0.1:43846","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-05-30T09:53:09.766857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.780064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-30T09:53:09.766916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:53:09.447273Z","time spent":"319.563777ms","remote":"127.0.0.1:43846","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-05-30T09:53:09.766928Z","caller":"traceutil/trace.go:171","msg":"trace[643255463] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1331; }","duration":"314.878646ms","start":"2026-05-30T09:53:09.452032Z","end":"2026-05-30T09:53:09.766911Z","steps":["trace[643255463] 'agreement among raft nodes before linearized reading' (duration: 314.750853ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:53:09.766884Z","caller":"traceutil/trace.go:171","msg":"trace[1727444430] transaction","detail":"{read_only:false; response_revision:1329; number_of_response:1; }","duration":"318.845497ms","start":"2026-05-30T09:53:09.448018Z","end":"2026-05-30T09:53:09.766863Z","steps":["trace[1727444430] 'process raft request' (duration: 317.970005ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:53:09.766976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:53:09.45202Z","time spent":"314.945188ms","remote":"127.0.0.1:43562","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-05-30T09:53:09.766867Z","caller":"traceutil/trace.go:171","msg":"trace[146969946] transaction","detail":"{read_only:false; response_revision:1328; number_of_response:1; }","duration":"319.32362ms","start":"2026-05-30T09:53:09.447526Z","end":"2026-05-30T09:53:09.76685Z","steps":["trace[146969946] 'process raft request' (duration: 318.421057ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:53:09.767087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:53:09.448012Z","time spent":"318.998021ms","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-30T09:53:09.767264Z","caller":"traceutil/trace.go:171","msg":"trace[1187196228] transaction","detail":"{read_only:false; response_revision:1331; number_of_response:1; }","duration":"318.056188ms","start":"2026-05-30T09:53:09.4487Z","end":"2026-05-30T09:53:09.766756Z","steps":["trace[1187196228] 'process raft request' (duration: 317.36773ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:53:09.767413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:53:09.448693Z","time spent":"318.664453ms","remote":"127.0.0.1:43846","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-05-30T09:53:09.767285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:53:09.447521Z","time spent":"319.591586ms","remote":"127.0.0.1:43846","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-05-30T09:53:11.892986Z","caller":"traceutil/trace.go:171","msg":"trace[1815546005] transaction","detail":"{read_only:false; response_revision:1343; number_of_response:1; }","duration":"116.90983ms","start":"2026-05-30T09:53:11.776055Z","end":"2026-05-30T09:53:11.892965Z","steps":["trace[1815546005] 'process raft request' (duration: 116.767026ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:53:11.897065Z","caller":"traceutil/trace.go:171","msg":"trace[1947683407] transaction","detail":"{read_only:false; response_revision:1344; number_of_response:1; }","duration":"120.701717ms","start":"2026-05-30T09:53:11.776334Z","end":"2026-05-30T09:53:11.897035Z","steps":["trace[1947683407] 'process raft request' (duration: 120.498312ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:53:11.897312Z","caller":"traceutil/trace.go:171","msg":"trace[1669319683] transaction","detail":"{read_only:false; response_revision:1345; number_of_response:1; }","duration":"120.939703ms","start":"2026-05-30T09:53:11.776362Z","end":"2026-05-30T09:53:11.897302Z","steps":["trace[1669319683] 'process raft request' (duration: 120.609665ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:53:11.897526Z","caller":"traceutil/trace.go:171","msg":"trace[995604690] transaction","detail":"{read_only:false; response_revision:1346; number_of_response:1; }","duration":"121.013325ms","start":"2026-05-30T09:53:11.776417Z","end":"2026-05-30T09:53:11.89743Z","steps":["trace[995604690] 'process raft request' (duration: 120.788749ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:53:11.897671Z","caller":"traceutil/trace.go:171","msg":"trace[1221548877] transaction","detail":"{read_only:false; response_revision:1347; number_of_response:1; }","duration":"121.19126ms","start":"2026-05-30T09:53:11.776461Z","end":"2026-05-30T09:53:11.897653Z","steps":["trace[1221548877] 'process raft request' (duration: 121.044186ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:53:14.110103Z","caller":"traceutil/trace.go:171","msg":"trace[962889666] linearizableReadLoop","detail":"{readStateIndex:1414; appliedIndex:1413; }","duration":"207.314305ms","start":"2026-05-30T09:53:13.902767Z","end":"2026-05-30T09:53:14.110081Z","steps":["trace[962889666] 'read index received' (duration: 207.11648ms)","trace[962889666] 'applied index is now lower than readState.Index' (duration: 196.655µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:53:14.110238Z","caller":"traceutil/trace.go:171","msg":"trace[653606992] transaction","detail":"{read_only:false; response_revision:1357; number_of_response:1; }","duration":"210.676451ms","start":"2026-05-30T09:53:13.899551Z","end":"2026-05-30T09:53:14.110228Z","steps":["trace[653606992] 'process raft request' (duration: 210.392224ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:53:14.110407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.567801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-30T09:53:14.110484Z","caller":"traceutil/trace.go:171","msg":"trace[79118762] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1357; }","duration":"207.675364ms","start":"2026-05-30T09:53:13.902796Z","end":"2026-05-30T09:53:14.110471Z","steps":["trace[79118762] 'agreement among raft nodes before linearized reading' (duration: 207.51102ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:53:14.110625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.860139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-30T09:53:14.110671Z","caller":"traceutil/trace.go:171","msg":"trace[581551992] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1357; }","duration":"207.951421ms","start":"2026-05-30T09:53:13.902713Z","end":"2026-05-30T09:53:14.110665Z","steps":["trace[581551992] 'agreement among raft nodes before linearized reading' (duration: 207.870099ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:53:14.110777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.852378ms","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-05-30T09:53:14.110848Z","caller":"traceutil/trace.go:171","msg":"trace[707782364] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1357; }","duration":"207.956091ms","start":"2026-05-30T09:53:13.902875Z","end":"2026-05-30T09:53:14.110831Z","steps":["trace[707782364] 'agreement among raft nodes before linearized reading' (duration: 207.710075ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:53:14.11089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.992802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-30T09:53:14.111077Z","caller":"traceutil/trace.go:171","msg":"trace[2089921384] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1357; }","duration":"208.208987ms","start":"2026-05-30T09:53:13.902819Z","end":"2026-05-30T09:53:14.111028Z","steps":["trace[2089921384] 'agreement among raft nodes before linearized reading' (duration: 207.799657ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:53:14.993609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.224226ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-30T09:53:14.993724Z","caller":"traceutil/trace.go:171","msg":"trace[953241154] transaction","detail":"{read_only:false; response_revision:1358; number_of_response:1; }","duration":"879.692884ms","start":"2026-05-30T09:53:14.114011Z","end":"2026-05-30T09:53:14.993704Z","steps":["trace[953241154] 'process raft request' (duration: 318.285864ms)","trace[953241154] 'compare' (duration: 561.063952ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:53:14.993795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:53:14.11398Z","time spent":"879.784587ms","remote":"127.0.0.1:43846","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-05-30T09:53:15.031336Z","caller":"traceutil/trace.go:171","msg":"trace[266741124] transaction","detail":"{read_only:false; response_revision:1360; number_of_response:1; }","duration":"915.760103ms","start":"2026-05-30T09:53:14.115551Z","end":"2026-05-30T09:53:15.031311Z","steps":["trace[266741124] 'process raft request' (duration: 915.704912ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:53:15.031338Z","caller":"traceutil/trace.go:171","msg":"trace[975291179] transaction","detail":"{read_only:false; response_revision:1359; number_of_response:1; }","duration":"917.273373ms","start":"2026-05-30T09:53:14.114031Z","end":"2026-05-30T09:53:15.031304Z","steps":["trace[975291179] 'process raft request' (duration: 917.074347ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:53:15.031468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:53:14.115531Z","time spent":"915.870177ms","remote":"127.0.0.1:43846","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-05-30T09:53:15.031494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:53:14.114012Z","time spent":"917.406286ms","remote":"127.0.0.1:43846","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-05-30T09:53:15.031571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.198287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:53:15.031614Z","caller":"traceutil/trace.go:171","msg":"trace[1163838534] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1361; }","duration":"321.27328ms","start":"2026-05-30T09:53:14.710329Z","end":"2026-05-30T09:53:15.031602Z","steps":["trace[1163838534] 'agreement among raft nodes before linearized reading' (duration: 321.195688ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:53:15.031656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:53:14.71031Z","time spent":"321.327612ms","remote":"127.0.0.1:43562","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-05-30T09:53:15.031487Z","caller":"traceutil/trace.go:171","msg":"trace[561730355] linearizableReadLoop","detail":"{readStateIndex:1418; appliedIndex:1415; }","duration":"321.099215ms","start":"2026-05-30T09:53:14.710363Z","end":"2026-05-30T09:53:15.031462Z","steps":["trace[561730355] 'read index received' (duration: 320.744845ms)","trace[561730355] 'applied index is now lower than readState.Index' (duration: 352.08µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:53:15.031647Z","caller":"traceutil/trace.go:171","msg":"trace[1803883847] transaction","detail":"{read_only:false; response_revision:1361; number_of_response:1; }","duration":"915.473547ms","start":"2026-05-30T09:53:14.1161Z","end":"2026-05-30T09:53:15.031574Z","steps":["trace[1803883847] 'process raft request' (duration: 915.18349ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:53:15.031875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:53:14.116085Z","time spent":"915.664801ms","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:53:15.031899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.072445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-05-30T09:53:15.031985Z","caller":"traceutil/trace.go:171","msg":"trace[1899243596] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1361; }","duration":"159.200049ms","start":"2026-05-30T09:53:14.872766Z","end":"2026-05-30T09:53:15.031967Z","steps":["trace[1899243596] 'agreement among raft nodes before linearized reading' (duration: 159.013994ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:53:16.255403Z","caller":"traceutil/trace.go:171","msg":"trace[583681549] transaction","detail":"{read_only:false; response_revision:1362; number_of_response:1; }","duration":"1.219809696s","start":"2026-05-30T09:53:15.035571Z","end":"2026-05-30T09:53:16.25538Z","steps":["trace[583681549] 'process raft request' (duration: 1.219666951s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:53:16.255541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:53:15.035555Z","time spent":"1.219914048s","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T09:53:16.259225Z","caller":"traceutil/trace.go:171","msg":"trace[1566095270] linearizableReadLoop","detail":"{readStateIndex:1420; appliedIndex:1418; }","duration":"406.956474ms","start":"2026-05-30T09:53:15.852254Z","end":"2026-05-30T09:53:16.25921Z","steps":["trace[1566095270] 'read index received' (duration: 402.976282ms)","trace[1566095270] 'applied index is now lower than readState.Index' (duration: 3.979092ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:53:16.259316Z","caller":"traceutil/trace.go:171","msg":"trace[2051802759] transaction","detail":"{read_only:false; response_revision:1363; number_of_response:1; }","duration":"798.965707ms","start":"2026-05-30T09:53:15.46034Z","end":"2026-05-30T09:53:16.259306Z","steps":["trace[2051802759] 'process raft request' (duration: 798.792332ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:53:16.259381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.121278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-30T09:53:16.259416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:53:15.460312Z","time spent":"799.039189ms","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:53:16.259459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.363341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-30T09:53:16.259532Z","caller":"traceutil/trace.go:171","msg":"trace[956437245] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1363; }","duration":"144.468953ms","start":"2026-05-30T09:53:16.115045Z","end":"2026-05-30T09:53:16.259514Z","steps":["trace[956437245] 'agreement among raft nodes before linearized reading' (duration: 144.275618ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:53:16.259439Z","caller":"traceutil/trace.go:171","msg":"trace[1306031766] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1363; }","duration":"407.18307ms","start":"2026-05-30T09:53:15.852239Z","end":"2026-05-30T09:53:16.259422Z","steps":["trace[1306031766] 'agreement among raft nodes before linearized reading' (duration: 407.093817ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:53:16.259665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:53:15.852186Z","time spent":"407.461086ms","remote":"127.0.0.1:43562","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-05-30T09:53:26.651322Z","caller":"traceutil/trace.go:171","msg":"trace[919767736] transaction","detail":"{read_only:false; response_revision:1430; number_of_response:1; }","duration":"158.510651ms","start":"2026-05-30T09:53:26.492784Z","end":"2026-05-30T09:53:26.651295Z","steps":["trace[919767736] 'process raft request' (duration: 158.317355ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:53:26.894783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.258429ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-30T09:53:26.895185Z","caller":"traceutil/trace.go:171","msg":"trace[226142002] transaction","detail":"{read_only:false; response_revision:1431; number_of_response:1; }","duration":"168.598989ms","start":"2026-05-30T09:53:26.726549Z","end":"2026-05-30T09:53:26.895148Z","steps":["trace[226142002] 'process raft request' (duration: 50.760284ms)","trace[226142002] 'compare' (duration: 116.996243ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:53:38.39255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.743398ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-05-30T09:53:38.874133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.497728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:53:38.874294Z","caller":"traceutil/trace.go:171","msg":"trace[543587651] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1483; }","duration":"163.704173ms","start":"2026-05-30T09:53:38.710569Z","end":"2026-05-30T09:53:38.874273Z","steps":["trace[543587651] 'range keys from in-memory index tree' (duration: 163.399095ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:53:38.874332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.639855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-30T09:53:38.874415Z","caller":"traceutil/trace.go:171","msg":"trace[1197850622] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1483; }","duration":"191.783489ms","start":"2026-05-30T09:53:38.682612Z","end":"2026-05-30T09:53:38.874395Z","steps":["trace[1197850622] 'range keys from in-memory index tree' (duration: 191.410099ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:53:39.450183Z","caller":"traceutil/trace.go:171","msg":"trace[2105079847] transaction","detail":"{read_only:false; response_revision:1486; number_of_response:1; }","duration":"174.705514ms","start":"2026-05-30T09:53:39.275447Z","end":"2026-05-30T09:53:39.450152Z","steps":["trace[2105079847] 'process raft request' (duration: 174.478628ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:53:39.695575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.989558ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-30T09:53:39.695749Z","caller":"traceutil/trace.go:171","msg":"trace[141642198] linearizableReadLoop","detail":"{readStateIndex:1549; appliedIndex:1547; }","duration":"384.996175ms","start":"2026-05-30T09:53:39.310731Z","end":"2026-05-30T09:53:39.695727Z","steps":["trace[141642198] 'read index received' (duration: 139.178398ms)","trace[141642198] 'applied index is now lower than readState.Index' (duration: 245.816197ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:53:39.6959Z","caller":"traceutil/trace.go:171","msg":"trace[1078934271] transaction","detail":"{read_only:false; response_revision:1487; number_of_response:1; }","duration":"415.948533ms","start":"2026-05-30T09:53:39.279922Z","end":"2026-05-30T09:53:39.69587Z","steps":["trace[1078934271] 'process raft request' (duration: 244.458312ms)","trace[1078934271] 'compare' (duration: 170.823165ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:53:39.695987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.055047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-30T09:53:39.696034Z","caller":"traceutil/trace.go:171","msg":"trace[411317727] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1487; }","duration":"314.135219ms","start":"2026-05-30T09:53:39.381873Z","end":"2026-05-30T09:53:39.696008Z","steps":["trace[411317727] 'agreement among raft nodes before linearized reading' (duration: 313.967134ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:53:39.696052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:53:39.279907Z","time spent":"416.057478ms","remote":"127.0.0.1:43846","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-05-30T09:53:39.69607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:53:39.381839Z","time spent":"314.222441ms","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-30T09:53:39.696076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.675077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-30T09:53:39.696186Z","caller":"traceutil/trace.go:171","msg":"trace[889393844] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1487; }","duration":"293.843061ms","start":"2026-05-30T09:53:39.402317Z","end":"2026-05-30T09:53:39.69616Z","steps":["trace[889393844] 'agreement among raft nodes before linearized reading' (duration: 293.606196ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:53:39.696023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.279262ms","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-05-30T09:53:39.697058Z","caller":"traceutil/trace.go:171","msg":"trace[1922032718] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1487; }","duration":"386.33848ms","start":"2026-05-30T09:53:39.310669Z","end":"2026-05-30T09:53:39.697007Z","steps":["trace[1922032718] 'agreement among raft nodes before linearized reading' (duration: 385.16849ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:53:39.697196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:53:39.310654Z","time spent":"386.483803ms","remote":"127.0.0.1:43846","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-05-30T09:53:40.088048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.09448ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-30T09:53:40.088403Z","caller":"traceutil/trace.go:171","msg":"trace[1516741257] transaction","detail":"{read_only:false; response_revision:1488; number_of_response:1; }","duration":"386.528792ms","start":"2026-05-30T09:53:39.701645Z","end":"2026-05-30T09:53:40.088174Z","steps":["trace[1516741257] 'process raft request' (duration: 160.735405ms)","trace[1516741257] 'compare' (duration: 224.812893ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:53:40.088473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:53:39.701623Z","time spent":"386.819999ms","remote":"127.0.0.1:43846","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-05-30T09:53:40.090968Z","caller":"traceutil/trace.go:171","msg":"trace[1817842081] transaction","detail":"{read_only:false; response_revision:1489; number_of_response:1; }","duration":"389.265962ms","start":"2026-05-30T09:53:39.701669Z","end":"2026-05-30T09:53:40.090935Z","steps":["trace[1817842081] 'process raft request' (duration: 388.923873ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:53:40.091194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:53:39.701638Z","time spent":"389.429126ms","remote":"127.0.0.1:43846","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-05-30T09:53:40.228436Z","caller":"traceutil/trace.go:171","msg":"trace[1824123039] transaction","detail":"{read_only:false; response_revision:1490; number_of_response:1; }","duration":"100.414229ms","start":"2026-05-30T09:53:40.12798Z","end":"2026-05-30T09:53:40.228394Z","steps":["trace[1824123039] 'process raft request' (duration: 76.451379ms)","trace[1824123039] 'compare' (duration: 23.774676ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:56:55.629116Z","caller":"traceutil/trace.go:171","msg":"trace[941414518] transaction","detail":"{read_only:false; response_revision:2409; number_of_response:1; }","duration":"115.507457ms","start":"2026-05-30T09:56:55.51358Z","end":"2026-05-30T09:56:55.629087Z","steps":["trace[941414518] 'process raft request' (duration: 115.327373ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:56:55.946102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.486543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:56:55.946217Z","caller":"traceutil/trace.go:171","msg":"trace[2110460176] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2409; }","duration":"236.644927ms","start":"2026-05-30T09:56:55.709546Z","end":"2026-05-30T09:56:55.946191Z","steps":["trace[2110460176] 'range keys from in-memory index tree' (duration: 236.36986ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:57:02.249532Z","caller":"traceutil/trace.go:171","msg":"trace[1795153592] transaction","detail":"{read_only:false; response_revision:2443; number_of_response:1; }","duration":"119.844807ms","start":"2026-05-30T09:57:02.129664Z","end":"2026-05-30T09:57:02.249508Z","steps":["trace[1795153592] 'process raft request' (duration: 119.332864ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:57:02.402576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.046898ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-30T09:57:02.402838Z","caller":"traceutil/trace.go:171","msg":"trace[318005560] transaction","detail":"{read_only:false; response_revision:2445; number_of_response:1; }","duration":"148.852261ms","start":"2026-05-30T09:57:02.253964Z","end":"2026-05-30T09:57:02.402816Z","steps":["trace[318005560] 'process raft request' (duration: 148.775149ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:57:02.402906Z","caller":"traceutil/trace.go:171","msg":"trace[726457457] transaction","detail":"{read_only:false; response_revision:2444; number_of_response:1; }","duration":"149.425037ms","start":"2026-05-30T09:57:02.253442Z","end":"2026-05-30T09:57:02.402867Z","steps":["trace[726457457] 'process raft request' (duration: 42.98081ms)","trace[726457457] 'compare' (duration: 105.843821ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:57:02.842965Z","caller":"traceutil/trace.go:171","msg":"trace[397302894] transaction","detail":"{read_only:false; response_revision:2447; number_of_response:1; }","duration":"113.602899ms","start":"2026-05-30T09:57:02.729319Z","end":"2026-05-30T09:57:02.842922Z","steps":["trace[397302894] 'process raft request' (duration: 73.934774ms)","trace[397302894] 'compare' (duration: 39.499501ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:57:03.319581Z","caller":"traceutil/trace.go:171","msg":"trace[1646649599] linearizableReadLoop","detail":"{readStateIndex:2551; appliedIndex:2550; }","duration":"198.013458ms","start":"2026-05-30T09:57:03.121545Z","end":"2026-05-30T09:57:03.319558Z","steps":["trace[1646649599] 'read index received' (duration: 197.808963ms)","trace[1646649599] 'applied index is now lower than readState.Index' (duration: 203.395µs)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:57:03.31981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.15232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-30T09:57:03.31985Z","caller":"traceutil/trace.go:171","msg":"trace[59131798] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2448; }","duration":"121.242502ms","start":"2026-05-30T09:57:03.198598Z","end":"2026-05-30T09:57:03.31984Z","steps":["trace[59131798] 'agreement among raft nodes before linearized reading' (duration: 121.102689ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:57:03.32023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.646874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.210\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-30T09:57:03.320283Z","caller":"traceutil/trace.go:171","msg":"trace[2054971455] transaction","detail":"{read_only:false; response_revision:2448; number_of_response:1; }","duration":"200.145163ms","start":"2026-05-30T09:57:03.11948Z","end":"2026-05-30T09:57:03.319625Z","steps":["trace[2054971455] 'process raft request' (duration: 199.910867ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:57:03.320318Z","caller":"traceutil/trace.go:171","msg":"trace[1283897442] range","detail":"{range_begin:/registry/masterleases/199.204.45.210; range_end:; response_count:1; response_revision:2448; }","duration":"198.790078ms","start":"2026-05-30T09:57:03.121512Z","end":"2026-05-30T09:57:03.320302Z","steps":["trace[1283897442] 'agreement among raft nodes before linearized reading' (duration: 198.162912ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:57:03.585635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.743691ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-05-30T09:57:03.585929Z","caller":"traceutil/trace.go:171","msg":"trace[1852074109] transaction","detail":"{read_only:false; response_revision:2449; number_of_response:1; }","duration":"262.124402ms","start":"2026-05-30T09:57:03.323784Z","end":"2026-05-30T09:57:03.585908Z","steps":["trace[1852074109] 'process raft request' (duration: 261.957528ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:57:03.58611Z","caller":"traceutil/trace.go:171","msg":"trace[343741355] transaction","detail":"{read_only:false; response_revision:2450; number_of_response:1; }","duration":"262.255606ms","start":"2026-05-30T09:57:03.323843Z","end":"2026-05-30T09:57:03.586099Z","steps":["trace[343741355] 'process raft request' (duration: 262.02664ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:57:03.587032Z","caller":"traceutil/trace.go:171","msg":"trace[1548731143] transaction","detail":"{read_only:false; response_revision:2451; number_of_response:1; }","duration":"169.369881ms","start":"2026-05-30T09:57:03.417635Z","end":"2026-05-30T09:57:03.587005Z","steps":["trace[1548731143] 'process raft request' (duration: 168.415727ms)"],"step_count":1}