{"level":"warn","ts":"2026-07-30T23:04:13.162194Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-30T23:04:13.162274Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.177: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.177:2380","--initial-cluster=instance=https://199.204.45.177:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.177:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.177:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-30T23:04:13.162333Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-30T23:04:13.162346Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.177:2380"]} {"level":"info","ts":"2026-07-30T23:04:13.162364Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-30T23:04:13.162944Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.177:2379"]} {"level":"info","ts":"2026-07-30T23:04:13.163042Z","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":16,"max-cpu-available":16,"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.177:2380"],"listen-peer-urls":["https://199.204.45.177:2380"],"advertise-client-urls":["https://199.204.45.177:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.177:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.177:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-30T23:04:13.166757Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.516476ms"} {"level":"info","ts":"2026-07-30T23:04:13.174315Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"7c53a18098324dc9","cluster-id":"4717e8aa221973c8"} {"level":"info","ts":"2026-07-30T23:04:13.174432Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 switched to configuration voters=()"} {"level":"info","ts":"2026-07-30T23:04:13.17447Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 became follower at term 0"} {"level":"info","ts":"2026-07-30T23:04:13.17449Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 7c53a18098324dc9 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-30T23:04:13.174509Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 became follower at term 1"} {"level":"info","ts":"2026-07-30T23:04:13.174564Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 switched to configuration voters=(8958681657451367881)"} {"level":"warn","ts":"2026-07-30T23:04:13.184848Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-30T23:04:13.188149Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-30T23:04:13.189687Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-30T23:04:13.19234Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"7c53a18098324dc9","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-30T23:04:13.192623Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-30T23:04:13.192721Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-30T23:04:13.192738Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-30T23:04:13.192879Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"7c53a18098324dc9","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-30T23:04:13.193677Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 switched to configuration voters=(8958681657451367881)"} {"level":"info","ts":"2026-07-30T23:04:13.193939Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4717e8aa221973c8","local-member-id":"7c53a18098324dc9","added-peer-id":"7c53a18098324dc9","added-peer-peer-urls":["https://199.204.45.177:2380"]} {"level":"info","ts":"2026-07-30T23:04:13.195312Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-30T23:04:13.195501Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.177:2380"} {"level":"info","ts":"2026-07-30T23:04:13.195551Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"7c53a18098324dc9","initial-advertise-peer-urls":["https://199.204.45.177:2380"],"listen-peer-urls":["https://199.204.45.177:2380"],"advertise-client-urls":["https://199.204.45.177:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.177:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-30T23:04:13.195552Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.177:2380"} {"level":"info","ts":"2026-07-30T23:04:13.195612Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-30T23:04:13.974949Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 is starting a new election at term 1"} {"level":"info","ts":"2026-07-30T23:04:13.975025Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-30T23:04:13.975071Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 received MsgPreVoteResp from 7c53a18098324dc9 at term 1"} {"level":"info","ts":"2026-07-30T23:04:13.975093Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 became candidate at term 2"} {"level":"info","ts":"2026-07-30T23:04:13.975105Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 received MsgVoteResp from 7c53a18098324dc9 at term 2"} {"level":"info","ts":"2026-07-30T23:04:13.975163Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 became leader at term 2"} {"level":"info","ts":"2026-07-30T23:04:13.975186Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 7c53a18098324dc9 elected leader 7c53a18098324dc9 at term 2"} {"level":"info","ts":"2026-07-30T23:04:13.976448Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T23:04:13.977435Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-30T23:04:13.977437Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"7c53a18098324dc9","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.177:2379]}","request-path":"/0/members/7c53a18098324dc9/attributes","cluster-id":"4717e8aa221973c8","publish-timeout":"7s"} {"level":"info","ts":"2026-07-30T23:04:13.977817Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-30T23:04:13.978037Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4717e8aa221973c8","local-member-id":"7c53a18098324dc9","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T23:04:13.978192Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T23:04:13.978241Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T23:04:13.978299Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-30T23:04:13.978326Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-30T23:04:13.981473Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.177:2379"} {"level":"info","ts":"2026-07-30T23:04:13.982106Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-07-30T23:04:50.123816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.887209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:04:50.123954Z","caller":"traceutil/trace.go:171","msg":"trace[467334996] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:523; }","duration":"163.077134ms","start":"2026-07-30T23:04:49.960854Z","end":"2026-07-30T23:04:50.123931Z","steps":["trace[467334996] 'range keys from in-memory index tree' (duration: 162.806097ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:04:50.123864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.22687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-30T23:04:50.124084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.654905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-30T23:04:50.124116Z","caller":"traceutil/trace.go:171","msg":"trace[1721581170] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:523; }","duration":"289.522208ms","start":"2026-07-30T23:04:49.834572Z","end":"2026-07-30T23:04:50.124094Z","steps":["trace[1721581170] 'range keys from in-memory index tree' (duration: 289.132507ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:04:50.124187Z","caller":"traceutil/trace.go:171","msg":"trace[1580387172] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:523; }","duration":"433.774288ms","start":"2026-07-30T23:04:49.690389Z","end":"2026-07-30T23:04:50.124163Z","steps":["trace[1580387172] 'range keys from in-memory index tree' (duration: 433.554312ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:04:50.124244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:04:49.690347Z","time spent":"433.887431ms","remote":"127.0.0.1:60028","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-07-30T23:04:50.345097Z","caller":"traceutil/trace.go:171","msg":"trace[1745795705] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"216.242536ms","start":"2026-07-30T23:04:50.128824Z","end":"2026-07-30T23:04:50.345067Z","steps":["trace[1745795705] 'process raft request' (duration: 216.103852ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:04:50.346142Z","caller":"traceutil/trace.go:171","msg":"trace[621478589] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"217.275405ms","start":"2026-07-30T23:04:50.128844Z","end":"2026-07-30T23:04:50.346119Z","steps":["trace[621478589] 'process raft request' (duration: 217.10671ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:04:50.34632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.333027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:04:50.346415Z","caller":"traceutil/trace.go:171","msg":"trace[801108944] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:525; }","duration":"161.467321ms","start":"2026-07-30T23:04:50.184909Z","end":"2026-07-30T23:04:50.346376Z","steps":["trace[801108944] 'agreement among raft nodes before linearized reading' (duration: 161.336087ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:04:50.346083Z","caller":"traceutil/trace.go:171","msg":"trace[1294870488] linearizableReadLoop","detail":"{readStateIndex:543; appliedIndex:541; }","duration":"161.07859ms","start":"2026-07-30T23:04:50.184965Z","end":"2026-07-30T23:04:50.346044Z","steps":["trace[1294870488] 'read index received' (duration: 159.884217ms)","trace[1294870488] 'applied index is now lower than readState.Index' (duration: 1.193313ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:04:50.856414Z","caller":"traceutil/trace.go:171","msg":"trace[1922045843] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"254.347976ms","start":"2026-07-30T23:04:50.602029Z","end":"2026-07-30T23:04:50.856377Z","steps":["trace[1922045843] 'process raft request' (duration: 193.913196ms)","trace[1922045843] 'compare' (duration: 60.135462ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:04:50.856456Z","caller":"traceutil/trace.go:171","msg":"trace[1918014108] transaction","detail":"{read_only:false; number_of_response:0; response_revision:545; }","duration":"254.320156ms","start":"2026-07-30T23:04:50.602081Z","end":"2026-07-30T23:04:50.856401Z","steps":["trace[1918014108] 'process raft request' (duration: 254.155631ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:04:50.856562Z","caller":"traceutil/trace.go:171","msg":"trace[1420939441] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"245.848834ms","start":"2026-07-30T23:04:50.610671Z","end":"2026-07-30T23:04:50.85652Z","steps":["trace[1420939441] 'process raft request' (duration: 245.609777ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:04:50.856639Z","caller":"traceutil/trace.go:171","msg":"trace[1913225] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"244.655982ms","start":"2026-07-30T23:04:50.611972Z","end":"2026-07-30T23:04:50.856628Z","steps":["trace[1913225] 'process raft request' (duration: 244.497328ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:04:50.856591Z","caller":"traceutil/trace.go:171","msg":"trace[1756962499] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"245.026162ms","start":"2026-07-30T23:04:50.611532Z","end":"2026-07-30T23:04:50.856558Z","steps":["trace[1756962499] 'process raft request' (duration: 244.821466ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:04:50.856704Z","caller":"traceutil/trace.go:171","msg":"trace[1639556745] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"244.362674ms","start":"2026-07-30T23:04:50.612335Z","end":"2026-07-30T23:04:50.856698Z","steps":["trace[1639556745] 'process raft request' (duration: 244.20834ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:04:50.856807Z","caller":"traceutil/trace.go:171","msg":"trace[1597368521] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"232.094898ms","start":"2026-07-30T23:04:50.624697Z","end":"2026-07-30T23:04:50.856791Z","steps":["trace[1597368521] 'process raft request' (duration: 231.921024ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:04:50.856991Z","caller":"traceutil/trace.go:171","msg":"trace[1938716524] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"219.137655ms","start":"2026-07-30T23:04:50.63778Z","end":"2026-07-30T23:04:50.856918Z","steps":["trace[1938716524] 'process raft request' (duration: 218.892228ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:04:50.857043Z","caller":"traceutil/trace.go:171","msg":"trace[1677292536] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"143.1764ms","start":"2026-07-30T23:04:50.713857Z","end":"2026-07-30T23:04:50.857034Z","steps":["trace[1677292536] 'process raft request' (duration: 143.027626ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:04:51.756958Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5605186812141796035,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-30T23:04:51.783249Z","caller":"traceutil/trace.go:171","msg":"trace[2033668189] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"583.291302ms","start":"2026-07-30T23:04:51.199932Z","end":"2026-07-30T23:04:51.783224Z","steps":["trace[2033668189] 'process raft request' (duration: 583.126158ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:04:51.784061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:04:51.199913Z","time spent":"583.421106ms","remote":"127.0.0.1:59826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":98689,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T23:04:51.793902Z","caller":"traceutil/trace.go:171","msg":"trace[2067528496] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"562.204266ms","start":"2026-07-30T23:04:51.231681Z","end":"2026-07-30T23:04:51.793885Z","steps":["trace[2067528496] 'process raft request' (duration: 561.801855ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:04:51.794029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:04:51.231659Z","time spent":"562.288538ms","remote":"127.0.0.1:59826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":151221,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T23:04:51.794538Z","caller":"traceutil/trace.go:171","msg":"trace[714588254] linearizableReadLoop","detail":"{readStateIndex:607; appliedIndex:604; }","duration":"538.632912ms","start":"2026-07-30T23:04:51.255875Z","end":"2026-07-30T23:04:51.794508Z","steps":["trace[714588254] 'read index received' (duration: 527.21025ms)","trace[714588254] 'applied index is now lower than readState.Index' (duration: 11.421482ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:04:51.794727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.839778ms","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-07-30T23:04:51.794734Z","caller":"traceutil/trace.go:171","msg":"trace[1934586496] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"318.968002ms","start":"2026-07-30T23:04:51.47573Z","end":"2026-07-30T23:04:51.794698Z","steps":["trace[1934586496] 'process raft request' (duration: 318.377626ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:04:51.794826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.515704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-30T23:04:51.794858Z","caller":"traceutil/trace.go:171","msg":"trace[1250280025] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:584; }","duration":"216.576536ms","start":"2026-07-30T23:04:51.578275Z","end":"2026-07-30T23:04:51.794851Z","steps":["trace[1250280025] 'agreement among raft nodes before linearized reading' (duration: 216.524104ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:04:51.794771Z","caller":"traceutil/trace.go:171","msg":"trace[1334525792] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:584; }","duration":"538.91994ms","start":"2026-07-30T23:04:51.255837Z","end":"2026-07-30T23:04:51.794757Z","steps":["trace[1334525792] 'agreement among raft nodes before linearized reading' (duration: 538.802847ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:04:51.795025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:04:51.475692Z","time spent":"319.122577ms","remote":"127.0.0.1:59826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-30T23:04:51.795071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:04:51.255825Z","time spent":"539.223269ms","remote":"127.0.0.1:60028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-30T23:04:53.277279Z","caller":"traceutil/trace.go:171","msg":"trace[122101330] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"200.075505ms","start":"2026-07-30T23:04:53.077161Z","end":"2026-07-30T23:04:53.277237Z","steps":["trace[122101330] 'process raft request' (duration: 199.308814ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:04:58.022128Z","caller":"traceutil/trace.go:171","msg":"trace[97243014] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"171.043121ms","start":"2026-07-30T23:04:57.851053Z","end":"2026-07-30T23:04:58.022096Z","steps":["trace[97243014] 'process raft request' (duration: 123.594516ms)","trace[97243014] 'compare' (duration: 47.240849ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:04:58.022382Z","caller":"traceutil/trace.go:171","msg":"trace[935958115] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"113.967642ms","start":"2026-07-30T23:04:57.908394Z","end":"2026-07-30T23:04:58.022361Z","steps":["trace[935958115] 'process raft request' (duration: 113.637423ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:04:58.592696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.44087ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T23:04:58.592864Z","caller":"traceutil/trace.go:171","msg":"trace[1095032442] linearizableReadLoop","detail":"{readStateIndex:655; appliedIndex:654; }","duration":"407.86896ms","start":"2026-07-30T23:04:58.184931Z","end":"2026-07-30T23:04:58.5928Z","steps":["trace[1095032442] 'read index received' (duration: 81.927467ms)","trace[1095032442] 'applied index is now lower than readState.Index' (duration: 325.939883ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:04:58.592968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.033866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:04:58.592947Z","caller":"traceutil/trace.go:171","msg":"trace[1180317583] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"555.73018ms","start":"2026-07-30T23:04:58.037167Z","end":"2026-07-30T23:04:58.592897Z","steps":["trace[1180317583] 'process raft request' (duration: 229.845639ms)","trace[1180317583] 'compare' (duration: 325.191682ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:04:58.593004Z","caller":"traceutil/trace.go:171","msg":"trace[546504527] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:630; }","duration":"408.108628ms","start":"2026-07-30T23:04:58.184885Z","end":"2026-07-30T23:04:58.592994Z","steps":["trace[546504527] 'agreement among raft nodes before linearized reading' (duration: 408.041296ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:04:58.593046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:04:58.184865Z","time spent":"408.173399ms","remote":"127.0.0.1:59756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-30T23:04:58.59317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:04:58.037139Z","time spent":"555.881753ms","remote":"127.0.0.1:59952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T23:04:58.606515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.950877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-30T23:04:58.606601Z","caller":"traceutil/trace.go:171","msg":"trace[1890141410] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:631; }","duration":"198.081521ms","start":"2026-07-30T23:04:58.408501Z","end":"2026-07-30T23:04:58.606582Z","steps":["trace[1890141410] 'agreement among raft nodes before linearized reading' (duration: 197.868025ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:04:58.606507Z","caller":"traceutil/trace.go:171","msg":"trace[504451897] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"180.358637ms","start":"2026-07-30T23:04:58.426116Z","end":"2026-07-30T23:04:58.606475Z","steps":["trace[504451897] 'process raft request' (duration: 179.674178ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:04:58.606649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.827465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-30T23:04:58.606764Z","caller":"traceutil/trace.go:171","msg":"trace[37317036] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:631; }","duration":"189.981009ms","start":"2026-07-30T23:04:58.416762Z","end":"2026-07-30T23:04:58.606743Z","steps":["trace[37317036] 'agreement among raft nodes before linearized reading' (duration: 189.748513ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:05:00.017318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.140345468s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-30T23:05:00.017452Z","caller":"traceutil/trace.go:171","msg":"trace[794179763] linearizableReadLoop","detail":"{readStateIndex:659; appliedIndex:658; }","duration":"832.458398ms","start":"2026-07-30T23:04:59.184971Z","end":"2026-07-30T23:05:00.01743Z","steps":["trace[794179763] 'read index received' (duration: 35.661µs)","trace[794179763] 'applied index is now lower than readState.Index' (duration: 832.417127ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:05:00.01757Z","caller":"traceutil/trace.go:171","msg":"trace[1665376621] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"1.34980505s","start":"2026-07-30T23:04:58.667686Z","end":"2026-07-30T23:05:00.017491Z","steps":["trace[1665376621] 'process raft request' (duration: 209.207975ms)","trace[1665376621] 'compare' (duration: 1.140194964s)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:05:00.017638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"832.650093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:05:00.017702Z","caller":"traceutil/trace.go:171","msg":"trace[1526816408] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:634; }","duration":"832.762806ms","start":"2026-07-30T23:04:59.184921Z","end":"2026-07-30T23:05:00.017683Z","steps":["trace[1526816408] 'agreement among raft nodes before linearized reading' (duration: 832.607812ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:05:00.017731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:04:58.667662Z","time spent":"1.350001474s","remote":"127.0.0.1:60134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1092,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-30T23:05:00.017788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:04:59.184899Z","time spent":"832.853049ms","remote":"127.0.0.1:59760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-30T23:05:00.051114Z","caller":"traceutil/trace.go:171","msg":"trace[2062764155] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"251.13176ms","start":"2026-07-30T23:04:59.799947Z","end":"2026-07-30T23:05:00.051079Z","steps":["trace[2062764155] 'process raft request' (duration: 250.929564ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:05:00.051245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.354688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-30T23:05:00.051301Z","caller":"traceutil/trace.go:171","msg":"trace[2079402382] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:635; }","duration":"131.42089ms","start":"2026-07-30T23:04:59.919868Z","end":"2026-07-30T23:05:00.051289Z","steps":["trace[2079402382] 'agreement among raft nodes before linearized reading' (duration: 131.221194ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:05:00.719272Z","caller":"traceutil/trace.go:171","msg":"trace[447614812] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"279.200456ms","start":"2026-07-30T23:05:00.440042Z","end":"2026-07-30T23:05:00.719243Z","steps":["trace[447614812] 'process raft request' (duration: 277.263263ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:05:01.344132Z","caller":"traceutil/trace.go:171","msg":"trace[675824362] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"159.331072ms","start":"2026-07-30T23:05:01.18478Z","end":"2026-07-30T23:05:01.344111Z","steps":["trace[675824362] 'process raft request' (duration: 158.839318ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:05:01.625203Z","caller":"traceutil/trace.go:171","msg":"trace[1964321049] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"229.243691ms","start":"2026-07-30T23:05:01.395631Z","end":"2026-07-30T23:05:01.624875Z","steps":["trace[1964321049] 'process raft request' (duration: 226.27782ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:05:08.225089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.973349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-30T23:05:08.22524Z","caller":"traceutil/trace.go:171","msg":"trace[1340088663] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:747; }","duration":"134.125653ms","start":"2026-07-30T23:05:08.091056Z","end":"2026-07-30T23:05:08.225181Z","steps":["trace[1340088663] 'agreement among raft nodes before linearized reading' (duration: 133.536577ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:05:16.08544Z","caller":"traceutil/trace.go:171","msg":"trace[640578705] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"139.285714ms","start":"2026-07-30T23:05:15.94613Z","end":"2026-07-30T23:05:16.085415Z","steps":["trace[640578705] 'process raft request' (duration: 139.15944ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:05:16.2528Z","caller":"traceutil/trace.go:171","msg":"trace[1991580959] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"211.308712ms","start":"2026-07-30T23:05:16.041472Z","end":"2026-07-30T23:05:16.252781Z","steps":["trace[1991580959] 'process raft request' (duration: 210.990853ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:05:55.993354Z","caller":"traceutil/trace.go:171","msg":"trace[867568350] linearizableReadLoop","detail":"{readStateIndex:1129; appliedIndex:1128; }","duration":"340.2535ms","start":"2026-07-30T23:05:55.653075Z","end":"2026-07-30T23:05:55.993328Z","steps":["trace[867568350] 'read index received' (duration: 340.001323ms)","trace[867568350] 'applied index is now lower than readState.Index' (duration: 251.377µs)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:05:55.993482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.391594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:05:55.99352Z","caller":"traceutil/trace.go:171","msg":"trace[1635602168] range","detail":"{range_begin:/registry/secrets/cert-manager/; range_end:/registry/secrets/cert-manager0; response_count:0; response_revision:1089; }","duration":"340.487467ms","start":"2026-07-30T23:05:55.653017Z","end":"2026-07-30T23:05:55.993505Z","steps":["trace[1635602168] 'agreement among raft nodes before linearized reading' (duration: 340.416335ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:05:55.993557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:05:55.652988Z","time spent":"340.560509ms","remote":"127.0.0.1:59866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":27,"request content":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" "} {"level":"info","ts":"2026-07-30T23:05:55.993533Z","caller":"traceutil/trace.go:171","msg":"trace[344509408] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"717.762769ms","start":"2026-07-30T23:05:55.275696Z","end":"2026-07-30T23:05:55.993458Z","steps":["trace[344509408] 'process raft request' (duration: 717.487942ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:05:55.993878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:05:55.275668Z","time spent":"718.019857ms","remote":"127.0.0.1:60028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T23:05:56.540402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.061158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-30T23:05:56.540532Z","caller":"traceutil/trace.go:171","msg":"trace[1396672463] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1089; }","duration":"263.245113ms","start":"2026-07-30T23:05:56.277261Z","end":"2026-07-30T23:05:56.540506Z","steps":["trace[1396672463] 'range keys from in-memory index tree' (duration: 262.831282ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:05:56.541047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.950965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:05:56.541177Z","caller":"traceutil/trace.go:171","msg":"trace[1201425671] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1089; }","duration":"356.148561ms","start":"2026-07-30T23:05:56.185001Z","end":"2026-07-30T23:05:56.541149Z","steps":["trace[1201425671] 'range keys from in-memory index tree' (duration: 355.753109ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:05:56.541236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:05:56.184982Z","time spent":"356.240673ms","remote":"127.0.0.1:59760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-30T23:06:06.600198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.666917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:06:06.600346Z","caller":"traceutil/trace.go:171","msg":"trace[1532565271] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1258; }","duration":"414.878842ms","start":"2026-07-30T23:06:06.18544Z","end":"2026-07-30T23:06:06.600319Z","steps":["trace[1532565271] 'range keys from in-memory index tree' (duration: 414.571164ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:06:06.600425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:06:06.185419Z","time spent":"414.992435ms","remote":"127.0.0.1:59760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-30T23:06:20.295886Z","caller":"traceutil/trace.go:171","msg":"trace[849540629] transaction","detail":"{read_only:false; response_revision:1378; number_of_response:1; }","duration":"128.486432ms","start":"2026-07-30T23:06:20.167379Z","end":"2026-07-30T23:06:20.295865Z","steps":["trace[849540629] 'process raft request' (duration: 128.353278ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:06:20.93546Z","caller":"traceutil/trace.go:171","msg":"trace[338739712] transaction","detail":"{read_only:false; response_revision:1381; number_of_response:1; }","duration":"156.330275ms","start":"2026-07-30T23:06:20.779087Z","end":"2026-07-30T23:06:20.935417Z","steps":["trace[338739712] 'process raft request' (duration: 140.6239ms)","trace[338739712] 'compare' (duration: 15.550831ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:06:21.89236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"733.552487ms","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-07-30T23:06:21.892447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.480293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:06:21.892523Z","caller":"traceutil/trace.go:171","msg":"trace[2089811734] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1381; }","duration":"379.608807ms","start":"2026-07-30T23:06:21.512897Z","end":"2026-07-30T23:06:21.892506Z","steps":["trace[2089811734] 'count revisions from in-memory index tree' (duration: 379.416762ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:06:21.892546Z","caller":"traceutil/trace.go:171","msg":"trace[557265390] transaction","detail":"{read_only:false; response_revision:1382; number_of_response:1; }","duration":"314.943325ms","start":"2026-07-30T23:06:21.577586Z","end":"2026-07-30T23:06:21.892529Z","steps":["trace[557265390] 'process raft request' (duration: 240.026704ms)","trace[557265390] 'compare' (duration: 74.53474ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:06:21.892561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:06:21.512884Z","time spent":"379.668258ms","remote":"127.0.0.1:35864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"info","ts":"2026-07-30T23:06:21.8925Z","caller":"traceutil/trace.go:171","msg":"trace[2146530218] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1381; }","duration":"733.740862ms","start":"2026-07-30T23:06:21.158739Z","end":"2026-07-30T23:06:21.89248Z","steps":["trace[2146530218] 'range keys from in-memory index tree' (duration: 733.409473ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:06:21.89265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:06:21.577554Z","time spent":"315.034047ms","remote":"127.0.0.1:60028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T23:06:21.892784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:06:21.15872Z","time spent":"734.010789ms","remote":"127.0.0.1:60028","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-07-30T23:06:21.892383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"707.81637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:06:21.893164Z","caller":"traceutil/trace.go:171","msg":"trace[469698053] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1381; }","duration":"708.632052ms","start":"2026-07-30T23:06:21.184515Z","end":"2026-07-30T23:06:21.893147Z","steps":["trace[469698053] 'range keys from in-memory index tree' (duration: 707.756208ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:06:21.893198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:06:21.184503Z","time spent":"708.686123ms","remote":"127.0.0.1:59760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-30T23:06:21.892438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"728.953853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:06:21.893283Z","caller":"traceutil/trace.go:171","msg":"trace[210443374] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1381; }","duration":"729.798105ms","start":"2026-07-30T23:06:21.163455Z","end":"2026-07-30T23:06:21.893253Z","steps":["trace[210443374] 'count revisions from in-memory index tree' (duration: 728.912912ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:06:21.893347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:06:21.163444Z","time spent":"729.883358ms","remote":"127.0.0.1:35888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-30T23:06:21.89236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.439459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-30T23:06:21.893414Z","caller":"traceutil/trace.go:171","msg":"trace[294897028] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1381; }","duration":"715.548839ms","start":"2026-07-30T23:06:21.177844Z","end":"2026-07-30T23:06:21.893393Z","steps":["trace[294897028] 'range keys from in-memory index tree' (duration: 714.267954ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:06:21.893483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:06:21.177818Z","time spent":"715.647162ms","remote":"127.0.0.1:60028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-30T23:06:22.07283Z","caller":"traceutil/trace.go:171","msg":"trace[720891143] transaction","detail":"{read_only:false; response_revision:1383; number_of_response:1; }","duration":"344.425743ms","start":"2026-07-30T23:06:21.728379Z","end":"2026-07-30T23:06:22.072804Z","steps":["trace[720891143] 'process raft request' (duration: 344.260949ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:06:22.073069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:06:21.728345Z","time spent":"344.640268ms","remote":"127.0.0.1:59946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7196,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T23:06:22.129078Z","caller":"traceutil/trace.go:171","msg":"trace[1343896065] linearizableReadLoop","detail":"{readStateIndex:1429; appliedIndex:1427; }","duration":"235.003858ms","start":"2026-07-30T23:06:21.894059Z","end":"2026-07-30T23:06:22.129063Z","steps":["trace[1343896065] 'read index received' (duration: 178.563388ms)","trace[1343896065] 'applied index is now lower than readState.Index' (duration: 56.43931ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:06:22.129183Z","caller":"traceutil/trace.go:171","msg":"trace[1779253534] transaction","detail":"{read_only:false; response_revision:1385; number_of_response:1; }","duration":"231.689508ms","start":"2026-07-30T23:06:21.897481Z","end":"2026-07-30T23:06:22.12917Z","steps":["trace[1779253534] 'process raft request' (duration: 231.542924ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:06:22.129161Z","caller":"traceutil/trace.go:171","msg":"trace[999636055] transaction","detail":"{read_only:false; response_revision:1384; number_of_response:1; }","duration":"231.740909ms","start":"2026-07-30T23:06:21.897388Z","end":"2026-07-30T23:06:22.129129Z","steps":["trace[999636055] 'process raft request' (duration: 231.566955ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:06:22.129437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.368147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:06:22.129495Z","caller":"traceutil/trace.go:171","msg":"trace[123316969] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1385; }","duration":"235.45798ms","start":"2026-07-30T23:06:21.894018Z","end":"2026-07-30T23:06:22.129476Z","steps":["trace[123316969] 'agreement among raft nodes before linearized reading' (duration: 235.360777ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:06:22.129566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.174245ms","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-07-30T23:06:22.129667Z","caller":"traceutil/trace.go:171","msg":"trace[303074871] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1385; }","duration":"210.304518ms","start":"2026-07-30T23:06:21.919338Z","end":"2026-07-30T23:06:22.129642Z","steps":["trace[303074871] 'agreement among raft nodes before linearized reading' (duration: 210.044091ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:06:22.282022Z","caller":"traceutil/trace.go:171","msg":"trace[1024470487] transaction","detail":"{read_only:false; response_revision:1386; number_of_response:1; }","duration":"151.447484ms","start":"2026-07-30T23:06:22.130547Z","end":"2026-07-30T23:06:22.281995Z","steps":["trace[1024470487] 'process raft request' (duration: 60.491019ms)","trace[1024470487] 'compare' (duration: 90.686647ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:06:22.282025Z","caller":"traceutil/trace.go:171","msg":"trace[435449036] linearizableReadLoop","detail":"{readStateIndex:1433; appliedIndex:1431; }","duration":"119.246631ms","start":"2026-07-30T23:06:22.162755Z","end":"2026-07-30T23:06:22.282001Z","steps":["trace[435449036] 'read index received' (duration: 28.294336ms)","trace[435449036] 'applied index is now lower than readState.Index' (duration: 90.951295ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:06:22.282121Z","caller":"traceutil/trace.go:171","msg":"trace[433668284] transaction","detail":"{read_only:false; response_revision:1387; number_of_response:1; }","duration":"147.826216ms","start":"2026-07-30T23:06:22.134274Z","end":"2026-07-30T23:06:22.2821Z","steps":["trace[433668284] 'process raft request' (duration: 147.661061ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:06:22.282193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.417246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:06:22.282245Z","caller":"traceutil/trace.go:171","msg":"trace[11897128] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:1387; }","duration":"119.524719ms","start":"2026-07-30T23:06:22.162706Z","end":"2026-07-30T23:06:22.282231Z","steps":["trace[11897128] 'agreement among raft nodes before linearized reading' (duration: 119.428206ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:06:22.779552Z","caller":"traceutil/trace.go:171","msg":"trace[1103064093] linearizableReadLoop","detail":"{readStateIndex:1439; appliedIndex:1438; }","duration":"236.94227ms","start":"2026-07-30T23:06:22.542587Z","end":"2026-07-30T23:06:22.779529Z","steps":["trace[1103064093] 'read index received' (duration: 226.027795ms)","trace[1103064093] 'applied index is now lower than readState.Index' (duration: 10.913455ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:06:22.779654Z","caller":"traceutil/trace.go:171","msg":"trace[904414483] transaction","detail":"{read_only:false; response_revision:1392; number_of_response:1; }","duration":"252.41892ms","start":"2026-07-30T23:06:22.527217Z","end":"2026-07-30T23:06:22.779636Z","steps":["trace[904414483] 'process raft request' (duration: 241.294589ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:06:22.779841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.228528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-07-30T23:06:22.779917Z","caller":"traceutil/trace.go:171","msg":"trace[728561335] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1392; }","duration":"237.351471ms","start":"2026-07-30T23:06:22.542546Z","end":"2026-07-30T23:06:22.779898Z","steps":["trace[728561335] 'agreement among raft nodes before linearized reading' (duration: 237.109785ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:06:22.815464Z","caller":"traceutil/trace.go:171","msg":"trace[1161162994] transaction","detail":"{read_only:false; response_revision:1393; number_of_response:1; }","duration":"270.979972ms","start":"2026-07-30T23:06:22.544454Z","end":"2026-07-30T23:06:22.815434Z","steps":["trace[1161162994] 'process raft request' (duration: 270.888ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:06:23.465864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.581584ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T23:06:23.466028Z","caller":"traceutil/trace.go:171","msg":"trace[219685068] transaction","detail":"{read_only:false; response_revision:1394; number_of_response:1; }","duration":"681.580059ms","start":"2026-07-30T23:06:22.784421Z","end":"2026-07-30T23:06:23.466001Z","steps":["trace[219685068] 'process raft request' (duration: 514.661155ms)","trace[219685068] 'compare' (duration: 166.149523ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:06:23.466106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:06:22.784395Z","time spent":"681.673071ms","remote":"127.0.0.1:60028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T23:06:23.482455Z","caller":"traceutil/trace.go:171","msg":"trace[660482749] linearizableReadLoop","detail":"{readStateIndex:1442; appliedIndex:1440; }","duration":"657.652361ms","start":"2026-07-30T23:06:22.824778Z","end":"2026-07-30T23:06:23.482431Z","steps":["trace[660482749] 'read index received' (duration: 474.295552ms)","trace[660482749] 'applied index is now lower than readState.Index' (duration: 183.355779ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:06:23.482515Z","caller":"traceutil/trace.go:171","msg":"trace[2090646672] transaction","detail":"{read_only:false; response_revision:1395; number_of_response:1; }","duration":"661.838154ms","start":"2026-07-30T23:06:22.820644Z","end":"2026-07-30T23:06:23.482482Z","steps":["trace[2090646672] 'process raft request' (duration: 661.65987ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:06:23.482678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.789277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:06:23.482742Z","caller":"traceutil/trace.go:171","msg":"trace[1705185586] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1395; }","duration":"298.91889ms","start":"2026-07-30T23:06:23.183812Z","end":"2026-07-30T23:06:23.482731Z","steps":["trace[1705185586] 'agreement among raft nodes before linearized reading' (duration: 298.805337ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:06:23.482734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:06:22.820624Z","time spent":"661.977368ms","remote":"127.0.0.1:60028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T23:06:23.482741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"657.961169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-07-30T23:06:23.482805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.578022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-30T23:06:23.482932Z","caller":"traceutil/trace.go:171","msg":"trace[153122638] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1395; }","duration":"658.191105ms","start":"2026-07-30T23:06:22.824715Z","end":"2026-07-30T23:06:23.482906Z","steps":["trace[153122638] 'agreement among raft nodes before linearized reading' (duration: 657.823245ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:06:23.483025Z","caller":"traceutil/trace.go:171","msg":"trace[137295002] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1395; }","duration":"542.83598ms","start":"2026-07-30T23:06:22.94017Z","end":"2026-07-30T23:06:23.483006Z","steps":["trace[137295002] 'agreement among raft nodes before linearized reading' (duration: 542.451509ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:06:23.483084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:06:22.940155Z","time spent":"542.914971ms","remote":"127.0.0.1:60028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-30T23:06:23.483573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:06:22.824691Z","time spent":"658.335639ms","remote":"127.0.0.1:60028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-30T23:06:24.25255Z","caller":"traceutil/trace.go:171","msg":"trace[2060538401] transaction","detail":"{read_only:false; response_revision:1399; number_of_response:1; }","duration":"115.728986ms","start":"2026-07-30T23:06:24.136797Z","end":"2026-07-30T23:06:24.252526Z","steps":["trace[2060538401] 'process raft request' (duration: 115.554311ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:06:24.288186Z","caller":"traceutil/trace.go:171","msg":"trace[503427373] linearizableReadLoop","detail":"{readStateIndex:1447; appliedIndex:1445; }","duration":"103.461454ms","start":"2026-07-30T23:06:24.184709Z","end":"2026-07-30T23:06:24.28817Z","steps":["trace[503427373] 'read index received' (duration: 67.626663ms)","trace[503427373] 'applied index is now lower than readState.Index' (duration: 35.833761ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:06:24.288287Z","caller":"traceutil/trace.go:171","msg":"trace[1424550889] transaction","detail":"{read_only:false; response_revision:1400; number_of_response:1; }","duration":"150.428416ms","start":"2026-07-30T23:06:24.13783Z","end":"2026-07-30T23:06:24.288259Z","steps":["trace[1424550889] 'process raft request' (duration: 150.246171ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:06:24.288314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.583697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:06:24.288371Z","caller":"traceutil/trace.go:171","msg":"trace[1282468062] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1400; }","duration":"103.68148ms","start":"2026-07-30T23:06:24.184678Z","end":"2026-07-30T23:06:24.28836Z","steps":["trace[1282468062] 'agreement among raft nodes before linearized reading' (duration: 103.553336ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:06:24.61847Z","caller":"traceutil/trace.go:171","msg":"trace[1904277920] transaction","detail":"{read_only:false; response_revision:1401; number_of_response:1; }","duration":"251.548256ms","start":"2026-07-30T23:06:24.366887Z","end":"2026-07-30T23:06:24.618435Z","steps":["trace[1904277920] 'process raft request' (duration: 201.008247ms)","trace[1904277920] 'compare' (duration: 50.297312ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:06:25.016958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.241131ms","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":"warn","ts":"2026-07-30T23:06:25.017026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.18604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:06:25.017078Z","caller":"traceutil/trace.go:171","msg":"trace[1044974604] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1401; }","duration":"291.378435ms","start":"2026-07-30T23:06:24.725668Z","end":"2026-07-30T23:06:25.017046Z","steps":["trace[1044974604] 'count revisions from in-memory index tree' (duration: 291.096878ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:06:25.017107Z","caller":"traceutil/trace.go:171","msg":"trace[255919094] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1401; }","duration":"246.336545ms","start":"2026-07-30T23:06:24.770751Z","end":"2026-07-30T23:06:25.017087Z","steps":["trace[255919094] 'count revisions from in-memory index tree' (duration: 246.047867ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:06:25.997808Z","caller":"traceutil/trace.go:171","msg":"trace[1960333929] linearizableReadLoop","detail":"{readStateIndex:1449; appliedIndex:1448; }","duration":"482.568676ms","start":"2026-07-30T23:06:25.515219Z","end":"2026-07-30T23:06:25.997788Z","steps":["trace[1960333929] 'read index received' (duration: 482.373541ms)","trace[1960333929] 'applied index is now lower than readState.Index' (duration: 194.235µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:06:25.997851Z","caller":"traceutil/trace.go:171","msg":"trace[280740307] transaction","detail":"{read_only:false; response_revision:1402; number_of_response:1; }","duration":"505.20889ms","start":"2026-07-30T23:06:25.492608Z","end":"2026-07-30T23:06:25.997817Z","steps":["trace[280740307] 'process raft request' (duration: 505.005354ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:06:25.997994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:06:25.492589Z","time spent":"505.327393ms","remote":"127.0.0.1:60028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T23:06:25.998005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.774572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-30T23:06:25.998049Z","caller":"traceutil/trace.go:171","msg":"trace[1509073526] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1402; }","duration":"482.852344ms","start":"2026-07-30T23:06:25.515186Z","end":"2026-07-30T23:06:25.998039Z","steps":["trace[1509073526] 'agreement among raft nodes before linearized reading' (duration: 482.71307ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:06:25.998049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.07092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-07-30T23:06:25.998086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:06:25.515171Z","time spent":"482.905415ms","remote":"127.0.0.1:60028","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-07-30T23:06:25.998088Z","caller":"traceutil/trace.go:171","msg":"trace[1598205099] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1402; }","duration":"445.149932ms","start":"2026-07-30T23:06:25.552928Z","end":"2026-07-30T23:06:25.998077Z","steps":["trace[1598205099] 'agreement among raft nodes before linearized reading' (duration: 444.996448ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:06:25.998127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:06:25.552917Z","time spent":"445.201843ms","remote":"127.0.0.1:60028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-30T23:06:26.685459Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5605186812141797757,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-30T23:06:27.068941Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.066840488s","expected-duration":"1s"} {"level":"info","ts":"2026-07-30T23:06:27.069504Z","caller":"traceutil/trace.go:171","msg":"trace[19953226] transaction","detail":"{read_only:false; response_revision:1403; number_of_response:1; }","duration":"1.067494206s","start":"2026-07-30T23:06:26.001984Z","end":"2026-07-30T23:06:27.069478Z","steps":["trace[19953226] 'process raft request' (duration: 1.067326431s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:06:27.069672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:06:26.001962Z","time spent":"1.06762274s","remote":"127.0.0.1:60028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T23:06:27.214583Z","caller":"traceutil/trace.go:171","msg":"trace[1997706031] linearizableReadLoop","detail":"{readStateIndex:1453; appliedIndex:1449; }","duration":"1.029741063s","start":"2026-07-30T23:06:26.184814Z","end":"2026-07-30T23:06:27.214556Z","steps":["trace[1997706031] 'read index received' (duration: 884.59578ms)","trace[1997706031] 'applied index is now lower than readState.Index' (duration: 145.144153ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:06:27.214677Z","caller":"traceutil/trace.go:171","msg":"trace[2045952443] transaction","detail":"{read_only:false; response_revision:1405; number_of_response:1; }","duration":"983.908231ms","start":"2026-07-30T23:06:26.230735Z","end":"2026-07-30T23:06:27.214643Z","steps":["trace[2045952443] 'process raft request' (duration: 983.689275ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:06:27.214701Z","caller":"traceutil/trace.go:171","msg":"trace[796940125] transaction","detail":"{read_only:false; response_revision:1406; number_of_response:1; }","duration":"590.47079ms","start":"2026-07-30T23:06:26.624212Z","end":"2026-07-30T23:06:27.214682Z","steps":["trace[796940125] 'process raft request' (duration: 590.300646ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:06:27.214775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.029942878s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:06:27.214821Z","caller":"traceutil/trace.go:171","msg":"trace[1959306350] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1406; }","duration":"1.030047181s","start":"2026-07-30T23:06:26.184763Z","end":"2026-07-30T23:06:27.21481Z","steps":["trace[1959306350] 'agreement among raft nodes before linearized reading' (duration: 1.029953888s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:06:27.21483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:06:26.624184Z","time spent":"590.566482ms","remote":"127.0.0.1:60028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T23:06:27.214835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:06:26.230705Z","time spent":"984.044285ms","remote":"127.0.0.1:60028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":513,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T23:06:27.214856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:06:26.184742Z","time spent":"1.030106163s","remote":"127.0.0.1:59756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-30T23:06:27.214682Z","caller":"traceutil/trace.go:171","msg":"trace[497076551] transaction","detail":"{read_only:false; response_revision:1404; number_of_response:1; }","duration":"1.211860808s","start":"2026-07-30T23:06:26.002797Z","end":"2026-07-30T23:06:27.214658Z","steps":["trace[497076551] 'process raft request' (duration: 1.210392268s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:06:27.214903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"923.460123ms","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-07-30T23:06:27.214935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"958.824941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-30T23:06:27.214981Z","caller":"traceutil/trace.go:171","msg":"trace[1449516363] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1406; }","duration":"923.558676ms","start":"2026-07-30T23:06:26.291407Z","end":"2026-07-30T23:06:27.214966Z","steps":["trace[1449516363] 'agreement among raft nodes before linearized reading' (duration: 923.366341ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:06:27.215011Z","caller":"traceutil/trace.go:171","msg":"trace[1363851555] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1406; }","duration":"958.942305ms","start":"2026-07-30T23:06:26.256051Z","end":"2026-07-30T23:06:27.214994Z","steps":["trace[1363851555] 'agreement among raft nodes before linearized reading' (duration: 958.716219ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:06:27.215046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:06:26.291397Z","time spent":"923.638908ms","remote":"127.0.0.1:60028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-30T23:06:27.21506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:06:26.256036Z","time spent":"959.015876ms","remote":"127.0.0.1:60028","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-07-30T23:06:27.215196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:06:26.002777Z","time spent":"1.212255269s","remote":"127.0.0.1:60028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T23:06:28.699215Z","caller":"traceutil/trace.go:171","msg":"trace[1739819412] linearizableReadLoop","detail":"{readStateIndex:1462; appliedIndex:1461; }","duration":"228.668309ms","start":"2026-07-30T23:06:28.470523Z","end":"2026-07-30T23:06:28.699192Z","steps":["trace[1739819412] 'read index received' (duration: 228.570476ms)","trace[1739819412] 'applied index is now lower than readState.Index' (duration: 96.473µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:06:28.699345Z","caller":"traceutil/trace.go:171","msg":"trace[1269208158] transaction","detail":"{read_only:false; response_revision:1414; number_of_response:1; }","duration":"325.665711ms","start":"2026-07-30T23:06:28.373648Z","end":"2026-07-30T23:06:28.699313Z","steps":["trace[1269208158] 'process raft request' (duration: 325.352862ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:06:28.699462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.922556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"warn","ts":"2026-07-30T23:06:28.699515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:06:28.373624Z","time spent":"325.798795ms","remote":"127.0.0.1:60010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3224,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T23:06:28.699529Z","caller":"traceutil/trace.go:171","msg":"trace[821470535] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1414; }","duration":"229.042889ms","start":"2026-07-30T23:06:28.470469Z","end":"2026-07-30T23:06:28.699512Z","steps":["trace[821470535] 'agreement among raft nodes before linearized reading' (duration: 228.858724ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:06:38.832809Z","caller":"traceutil/trace.go:171","msg":"trace[1786135760] transaction","detail":"{read_only:false; response_revision:1579; number_of_response:1; }","duration":"109.627743ms","start":"2026-07-30T23:06:38.723156Z","end":"2026-07-30T23:06:38.832784Z","steps":["trace[1786135760] 'process raft request' (duration: 109.51903ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:06:43.053074Z","caller":"traceutil/trace.go:171","msg":"trace[1807013879] transaction","detail":"{read_only:false; response_revision:1649; number_of_response:1; }","duration":"120.415064ms","start":"2026-07-30T23:06:42.932637Z","end":"2026-07-30T23:06:43.053052Z","steps":["trace[1807013879] 'process raft request' (duration: 120.26977ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:06:43.054157Z","caller":"traceutil/trace.go:171","msg":"trace[1840477539] transaction","detail":"{read_only:false; response_revision:1650; number_of_response:1; }","duration":"118.016437ms","start":"2026-07-30T23:06:42.936114Z","end":"2026-07-30T23:06:43.05413Z","steps":["trace[1840477539] 'process raft request' (duration: 117.861853ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:06:43.439818Z","caller":"traceutil/trace.go:171","msg":"trace[680232052] linearizableReadLoop","detail":"{readStateIndex:1721; appliedIndex:1720; }","duration":"100.350576ms","start":"2026-07-30T23:06:43.339425Z","end":"2026-07-30T23:06:43.439775Z","steps":["trace[680232052] 'read index received' (duration: 55.053164ms)","trace[680232052] 'applied index is now lower than readState.Index' (duration: 45.295962ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:06:43.439979Z","caller":"traceutil/trace.go:171","msg":"trace[1705995489] transaction","detail":"{read_only:false; response_revision:1661; number_of_response:1; }","duration":"127.157722ms","start":"2026-07-30T23:06:43.312777Z","end":"2026-07-30T23:06:43.439935Z","steps":["trace[1705995489] 'process raft request' (duration: 81.434208ms)","trace[1705995489] 'compare' (duration: 45.344284ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:06:43.440122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.678664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-30T23:06:43.440196Z","caller":"traceutil/trace.go:171","msg":"trace[404402915] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1661; }","duration":"100.809888ms","start":"2026-07-30T23:06:43.339368Z","end":"2026-07-30T23:06:43.440178Z","steps":["trace[404402915] 'agreement among raft nodes before linearized reading' (duration: 100.561511ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:06:48.386831Z","caller":"traceutil/trace.go:171","msg":"trace[456949171] transaction","detail":"{read_only:false; response_revision:1690; number_of_response:1; }","duration":"155.343677ms","start":"2026-07-30T23:06:48.231465Z","end":"2026-07-30T23:06:48.386808Z","steps":["trace[456949171] 'process raft request' (duration: 155.209493ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:06:51.722685Z","caller":"traceutil/trace.go:171","msg":"trace[25164298] transaction","detail":"{read_only:false; response_revision:1708; number_of_response:1; }","duration":"123.405407ms","start":"2026-07-30T23:06:51.599248Z","end":"2026-07-30T23:06:51.722653Z","steps":["trace[25164298] 'process raft request' (duration: 122.218904ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:06:51.723128Z","caller":"traceutil/trace.go:171","msg":"trace[2106673073] transaction","detail":"{read_only:false; response_revision:1709; number_of_response:1; }","duration":"108.140521ms","start":"2026-07-30T23:06:51.614959Z","end":"2026-07-30T23:06:51.723099Z","steps":["trace[2106673073] 'process raft request' (duration: 107.806592ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:06:59.450693Z","caller":"traceutil/trace.go:171","msg":"trace[1663596060] transaction","detail":"{read_only:false; response_revision:1760; number_of_response:1; }","duration":"145.329488ms","start":"2026-07-30T23:06:59.305338Z","end":"2026-07-30T23:06:59.450667Z","steps":["trace[1663596060] 'process raft request' (duration: 145.179094ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:07:14.038985Z","caller":"traceutil/trace.go:171","msg":"trace[182721922] linearizableReadLoop","detail":"{readStateIndex:1896; appliedIndex:1894; }","duration":"212.182249ms","start":"2026-07-30T23:07:13.826775Z","end":"2026-07-30T23:07:14.038957Z","steps":["trace[182721922] 'read index received' (duration: 211.924772ms)","trace[182721922] 'applied index is now lower than readState.Index' (duration: 256.287µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:07:14.039363Z","caller":"traceutil/trace.go:171","msg":"trace[1643713358] transaction","detail":"{read_only:false; response_revision:1829; number_of_response:1; }","duration":"314.54545ms","start":"2026-07-30T23:07:13.724805Z","end":"2026-07-30T23:07:14.03935Z","steps":["trace[1643713358] 'process raft request' (duration: 313.969404ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:07:14.039508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:07:13.724778Z","time spent":"314.624243ms","remote":"127.0.0.1:60028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T23:07:14.039922Z","caller":"traceutil/trace.go:171","msg":"trace[1650081183] transaction","detail":"{read_only:false; response_revision:1830; number_of_response:1; }","duration":"313.311666ms","start":"2026-07-30T23:07:13.726597Z","end":"2026-07-30T23:07:14.039908Z","steps":["trace[1650081183] 'process raft request' (duration: 312.320088ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:07:14.040067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:07:13.726543Z","time spent":"313.4702ms","remote":"127.0.0.1:60028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T23:07:14.042849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.054157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-30T23:07:14.042934Z","caller":"traceutil/trace.go:171","msg":"trace[1524532768] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1830; }","duration":"216.16928ms","start":"2026-07-30T23:07:13.826746Z","end":"2026-07-30T23:07:14.042916Z","steps":["trace[1524532768] 'agreement among raft nodes before linearized reading' (duration: 213.422713ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:07:14.043158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.393795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-07-30T23:07:14.043195Z","caller":"traceutil/trace.go:171","msg":"trace[703231898] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1830; }","duration":"207.477518ms","start":"2026-07-30T23:07:13.835708Z","end":"2026-07-30T23:07:14.043185Z","steps":["trace[703231898] 'agreement among raft nodes before linearized reading' (duration: 204.540306ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:07:14.0432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.436983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-07-30T23:07:14.043269Z","caller":"traceutil/trace.go:171","msg":"trace[972992620] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1830; }","duration":"111.526385ms","start":"2026-07-30T23:07:13.931724Z","end":"2026-07-30T23:07:14.04325Z","steps":["trace[972992620] 'agreement among raft nodes before linearized reading' (duration: 108.514861ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:07:14.04346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.634236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-30T23:07:14.043504Z","caller":"traceutil/trace.go:171","msg":"trace[1926959687] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1830; }","duration":"103.694157ms","start":"2026-07-30T23:07:13.939797Z","end":"2026-07-30T23:07:14.043491Z","steps":["trace[1926959687] 'agreement among raft nodes before linearized reading' (duration: 100.433486ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:07:14.043735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.421769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-30T23:07:14.04378Z","caller":"traceutil/trace.go:171","msg":"trace[831415242] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1830; }","duration":"215.495962ms","start":"2026-07-30T23:07:13.828275Z","end":"2026-07-30T23:07:14.043771Z","steps":["trace[831415242] 'agreement among raft nodes before linearized reading' (duration: 211.926762ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:07:15.805946Z","caller":"traceutil/trace.go:171","msg":"trace[782041489] transaction","detail":"{read_only:false; response_revision:1838; number_of_response:1; }","duration":"282.510738ms","start":"2026-07-30T23:07:15.523394Z","end":"2026-07-30T23:07:15.805905Z","steps":["trace[782041489] 'process raft request' (duration: 282.126308ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:07:15.80696Z","caller":"traceutil/trace.go:171","msg":"trace[1316890751] transaction","detail":"{read_only:false; response_revision:1839; number_of_response:1; }","duration":"244.403228ms","start":"2026-07-30T23:07:15.56253Z","end":"2026-07-30T23:07:15.806933Z","steps":["trace[1316890751] 'process raft request' (duration: 244.280435ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:07:39.219452Z","caller":"traceutil/trace.go:171","msg":"trace[892382390] transaction","detail":"{read_only:false; response_revision:1956; number_of_response:1; }","duration":"119.454568ms","start":"2026-07-30T23:07:39.099964Z","end":"2026-07-30T23:07:39.219419Z","steps":["trace[892382390] 'process raft request' (duration: 119.216352ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:07:40.510459Z","caller":"traceutil/trace.go:171","msg":"trace[1736125575] transaction","detail":"{read_only:false; response_revision:1964; number_of_response:1; }","duration":"113.638548ms","start":"2026-07-30T23:07:40.3968Z","end":"2026-07-30T23:07:40.510438Z","steps":["trace[1736125575] 'process raft request' (duration: 113.594497ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:07:40.510499Z","caller":"traceutil/trace.go:171","msg":"trace[527586270] transaction","detail":"{read_only:false; response_revision:1963; number_of_response:1; }","duration":"113.887345ms","start":"2026-07-30T23:07:40.396554Z","end":"2026-07-30T23:07:40.510441Z","steps":["trace[527586270] 'process raft request' (duration: 112.528598ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:07:52.638192Z","caller":"traceutil/trace.go:171","msg":"trace[891954567] transaction","detail":"{read_only:false; response_revision:2020; number_of_response:1; }","duration":"141.337451ms","start":"2026-07-30T23:07:52.496811Z","end":"2026-07-30T23:07:52.638148Z","steps":["trace[891954567] 'process raft request' (duration: 43.166898ms)","trace[891954567] 'compare' (duration: 97.938567ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:07:52.824587Z","caller":"traceutil/trace.go:171","msg":"trace[1767999985] transaction","detail":"{read_only:false; response_revision:2021; number_of_response:1; }","duration":"181.275141ms","start":"2026-07-30T23:07:52.643266Z","end":"2026-07-30T23:07:52.824541Z","steps":["trace[1767999985] 'process raft request' (duration: 98.44907ms)","trace[1767999985] 'compare' (duration: 82.611315ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:07:52.824716Z","caller":"traceutil/trace.go:171","msg":"trace[428212851] transaction","detail":"{read_only:false; response_revision:2022; number_of_response:1; }","duration":"181.24451ms","start":"2026-07-30T23:07:52.643416Z","end":"2026-07-30T23:07:52.824661Z","steps":["trace[428212851] 'process raft request' (duration: 181.057335ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:07:53.746065Z","caller":"traceutil/trace.go:171","msg":"trace[1425470502] transaction","detail":"{read_only:false; response_revision:2024; number_of_response:1; }","duration":"138.243266ms","start":"2026-07-30T23:07:53.607794Z","end":"2026-07-30T23:07:53.746038Z","steps":["trace[1425470502] 'process raft request' (duration: 138.079952ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:08:14.626603Z","caller":"traceutil/trace.go:171","msg":"trace[1798368273] transaction","detail":"{read_only:false; response_revision:2120; number_of_response:1; }","duration":"657.777219ms","start":"2026-07-30T23:08:13.968796Z","end":"2026-07-30T23:08:14.626574Z","steps":["trace[1798368273] 'process raft request' (duration: 657.598954ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:08:14.62679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:08:13.968772Z","time spent":"657.922043ms","remote":"127.0.0.1:60028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T23:08:14.62757Z","caller":"traceutil/trace.go:171","msg":"trace[1858881403] transaction","detail":"{read_only:false; response_revision:2121; number_of_response:1; }","duration":"535.271136ms","start":"2026-07-30T23:08:14.092276Z","end":"2026-07-30T23:08:14.627547Z","steps":["trace[1858881403] 'process raft request' (duration: 535.113812ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:08:14.627698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:08:14.092248Z","time spent":"535.391169ms","remote":"127.0.0.1:60028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T23:08:14.627968Z","caller":"traceutil/trace.go:171","msg":"trace[972575264] linearizableReadLoop","detail":"{readStateIndex:2201; appliedIndex:2198; }","duration":"474.136913ms","start":"2026-07-30T23:08:14.153792Z","end":"2026-07-30T23:08:14.627929Z","steps":["trace[972575264] 'read index received' (duration: 472.673543ms)","trace[972575264] 'applied index is now lower than readState.Index' (duration: 1.4603ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:08:14.628219Z","caller":"traceutil/trace.go:171","msg":"trace[1875018056] transaction","detail":"{read_only:false; response_revision:2122; number_of_response:1; }","duration":"496.641343ms","start":"2026-07-30T23:08:14.131525Z","end":"2026-07-30T23:08:14.628166Z","steps":["trace[1875018056] 'process raft request' (duration: 495.965274ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:08:14.628361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.319791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-30T23:08:14.628435Z","caller":"traceutil/trace.go:171","msg":"trace[175396704] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2122; }","duration":"408.417904ms","start":"2026-07-30T23:08:14.220001Z","end":"2026-07-30T23:08:14.628419Z","steps":["trace[175396704] 'agreement among raft nodes before linearized reading' (duration: 408.226179ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:08:14.628471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:08:14.21999Z","time spent":"408.472196ms","remote":"127.0.0.1:60028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-30T23:08:14.628402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.788819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-30T23:08:14.628551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:08:14.131499Z","time spent":"496.790147ms","remote":"127.0.0.1:60028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T23:08:14.628692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.921594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-30T23:08:14.628706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.247113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-30T23:08:14.628754Z","caller":"traceutil/trace.go:171","msg":"trace[981054831] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2122; }","duration":"350.339265ms","start":"2026-07-30T23:08:14.278403Z","end":"2026-07-30T23:08:14.628742Z","steps":["trace[981054831] 'agreement among raft nodes before linearized reading' (duration: 350.226622ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:08:14.628753Z","caller":"traceutil/trace.go:171","msg":"trace[1526247637] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:2122; }","duration":"475.042878ms","start":"2026-07-30T23:08:14.153694Z","end":"2026-07-30T23:08:14.628737Z","steps":["trace[1526247637] 'agreement among raft nodes before linearized reading' (duration: 474.861613ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:08:14.628786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.102191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"warn","ts":"2026-07-30T23:08:14.628819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:08:14.153669Z","time spent":"475.131261ms","remote":"127.0.0.1:60134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":83,"response size":29,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"info","ts":"2026-07-30T23:08:14.628847Z","caller":"traceutil/trace.go:171","msg":"trace[1223629668] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2122; }","duration":"353.183924ms","start":"2026-07-30T23:08:14.275644Z","end":"2026-07-30T23:08:14.628828Z","steps":["trace[1223629668] 'agreement among raft nodes before linearized reading' (duration: 353.010329ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:08:14.6287Z","caller":"traceutil/trace.go:171","msg":"trace[816896400] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:2122; }","duration":"326.068007ms","start":"2026-07-30T23:08:14.302549Z","end":"2026-07-30T23:08:14.628617Z","steps":["trace[816896400] 'agreement among raft nodes before linearized reading' (duration: 325.704337ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:08:14.628891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:08:14.275631Z","time spent":"353.250745ms","remote":"127.0.0.1:60028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-30T23:08:14.628914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:08:14.302538Z","time spent":"326.358715ms","remote":"127.0.0.1:35852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-07-30T23:08:14.628801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:08:14.27838Z","time spent":"350.410767ms","remote":"127.0.0.1:60028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-30T23:08:16.926324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.094252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-30T23:08:16.926411Z","caller":"traceutil/trace.go:171","msg":"trace[1383800768] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2134; }","duration":"146.226486ms","start":"2026-07-30T23:08:16.780164Z","end":"2026-07-30T23:08:16.92639Z","steps":["trace[1383800768] 'range keys from in-memory index tree' (duration: 145.916988ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:08:17.147703Z","caller":"traceutil/trace.go:171","msg":"trace[1268256845] transaction","detail":"{read_only:false; response_revision:2137; number_of_response:1; }","duration":"157.685821ms","start":"2026-07-30T23:08:16.989986Z","end":"2026-07-30T23:08:17.147671Z","steps":["trace[1268256845] 'process raft request' (duration: 112.746794ms)","trace[1268256845] 'compare' (duration: 44.648239ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:08:17.14772Z","caller":"traceutil/trace.go:171","msg":"trace[1054218441] transaction","detail":"{read_only:false; response_revision:2138; number_of_response:1; }","duration":"157.215968ms","start":"2026-07-30T23:08:16.990475Z","end":"2026-07-30T23:08:17.147691Z","steps":["trace[1054218441] 'process raft request' (duration: 157.125405ms)"],"step_count":1}