{"level":"warn","ts":"2026-10-16T14:22:26.403595Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-16T14:22:26.403746Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.91: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://162.253.55.91:2380","--initial-cluster=instance=https://162.253.55.91:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.91:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.91: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-10-16T14:22:26.403838Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-16T14:22:26.403858Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.91:2380"]} {"level":"info","ts":"2026-10-16T14:22:26.403887Z","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-10-16T14:22:26.404819Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.91:2379"]} {"level":"info","ts":"2026-10-16T14:22:26.404917Z","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://162.253.55.91:2380"],"listen-peer-urls":["https://162.253.55.91:2380"],"advertise-client-urls":["https://162.253.55.91:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.91:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.91: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-10-16T14:22:26.408385Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.165829ms"} {"level":"info","ts":"2026-10-16T14:22:26.418189Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"8a5b61ed9120d6b3","cluster-id":"9e0c1e8055c016ad"} {"level":"info","ts":"2026-10-16T14:22:26.418278Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 switched to configuration voters=()"} {"level":"info","ts":"2026-10-16T14:22:26.4183Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 became follower at term 0"} {"level":"info","ts":"2026-10-16T14:22:26.418312Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8a5b61ed9120d6b3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-16T14:22:26.418326Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 became follower at term 1"} {"level":"info","ts":"2026-10-16T14:22:26.418365Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 switched to configuration voters=(9969669873084716723)"} {"level":"warn","ts":"2026-10-16T14:22:26.424982Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-16T14:22:26.427139Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-16T14:22:26.42885Z","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-10-16T14:22:26.43119Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"8a5b61ed9120d6b3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-16T14:22:26.431327Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"8a5b61ed9120d6b3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-16T14:22:26.431477Z","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-10-16T14:22:26.431534Z","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-10-16T14:22:26.431544Z","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-10-16T14:22:26.433962Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 switched to configuration voters=(9969669873084716723)"} {"level":"info","ts":"2026-10-16T14:22:26.434068Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9e0c1e8055c016ad","local-member-id":"8a5b61ed9120d6b3","added-peer-id":"8a5b61ed9120d6b3","added-peer-peer-urls":["https://162.253.55.91:2380"]} {"level":"info","ts":"2026-10-16T14:22:26.435187Z","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-10-16T14:22:26.435339Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.91:2380"} {"level":"info","ts":"2026-10-16T14:22:26.435397Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"8a5b61ed9120d6b3","initial-advertise-peer-urls":["https://162.253.55.91:2380"],"listen-peer-urls":["https://162.253.55.91:2380"],"advertise-client-urls":["https://162.253.55.91:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.91:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-16T14:22:26.435429Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.91:2380"} {"level":"info","ts":"2026-10-16T14:22:26.435441Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-16T14:22:27.318933Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 is starting a new election at term 1"} {"level":"info","ts":"2026-10-16T14:22:27.319003Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-16T14:22:27.319038Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 received MsgPreVoteResp from 8a5b61ed9120d6b3 at term 1"} {"level":"info","ts":"2026-10-16T14:22:27.319061Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 became candidate at term 2"} {"level":"info","ts":"2026-10-16T14:22:27.319068Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 received MsgVoteResp from 8a5b61ed9120d6b3 at term 2"} {"level":"info","ts":"2026-10-16T14:22:27.319085Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 became leader at term 2"} {"level":"info","ts":"2026-10-16T14:22:27.319095Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8a5b61ed9120d6b3 elected leader 8a5b61ed9120d6b3 at term 2"} {"level":"info","ts":"2026-10-16T14:22:27.320935Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"8a5b61ed9120d6b3","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.91:2379]}","request-path":"/0/members/8a5b61ed9120d6b3/attributes","cluster-id":"9e0c1e8055c016ad","publish-timeout":"7s"} {"level":"info","ts":"2026-10-16T14:22:27.320982Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-16T14:22:27.321089Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-16T14:22:27.321082Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-16T14:22:27.321356Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-16T14:22:27.321416Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-16T14:22:27.322439Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9e0c1e8055c016ad","local-member-id":"8a5b61ed9120d6b3","cluster-version":"3.5"} {"level":"info","ts":"2026-10-16T14:22:27.322641Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-16T14:22:27.322705Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-16T14:22:27.32475Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.91:2379"} {"level":"info","ts":"2026-10-16T14:22:27.324936Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-16T14:22:29.258891Z","caller":"traceutil/trace.go:171","msg":"trace[1759102331] transaction","detail":"{read_only:false; response_revision:52; number_of_response:1; }","duration":"114.999056ms","start":"2026-10-16T14:22:29.143848Z","end":"2026-10-16T14:22:29.258847Z","steps":["trace[1759102331] 'process raft request' (duration: 114.817772ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:22:29.259044Z","caller":"traceutil/trace.go:171","msg":"trace[724490268] transaction","detail":"{read_only:false; response_revision:53; number_of_response:1; }","duration":"112.40632ms","start":"2026-10-16T14:22:29.146569Z","end":"2026-10-16T14:22:29.258975Z","steps":["trace[724490268] 'process raft request' (duration: 112.139734ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:22:29.259047Z","caller":"traceutil/trace.go:171","msg":"trace[439475839] transaction","detail":"{read_only:false; response_revision:51; number_of_response:1; }","duration":"124.458123ms","start":"2026-10-16T14:22:29.134545Z","end":"2026-10-16T14:22:29.259003Z","steps":["trace[439475839] 'process raft request' (duration: 26.92868ms)","trace[439475839] 'compare' (duration: 97.058693ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:22:42.656904Z","caller":"traceutil/trace.go:171","msg":"trace[1135251559] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"233.470488ms","start":"2026-10-16T14:22:42.423407Z","end":"2026-10-16T14:22:42.656877Z","steps":["trace[1135251559] 'process raft request' (duration: 233.313615ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:22:53.755032Z","caller":"traceutil/trace.go:171","msg":"trace[711817497] transaction","detail":"{read_only:false; response_revision:436; number_of_response:1; }","duration":"119.120047ms","start":"2026-10-16T14:22:53.635872Z","end":"2026-10-16T14:22:53.754992Z","steps":["trace[711817497] 'process raft request' (duration: 118.888471ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:23:14.990248Z","caller":"traceutil/trace.go:171","msg":"trace[857092600] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"130.580013ms","start":"2026-10-16T14:23:14.859647Z","end":"2026-10-16T14:23:14.990227Z","steps":["trace[857092600] 'process raft request' (duration: 130.44803ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:23:21.931175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.670287ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-16T14:23:21.93137Z","caller":"traceutil/trace.go:171","msg":"trace[1793943887] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"383.97809ms","start":"2026-10-16T14:23:21.547352Z","end":"2026-10-16T14:23:21.93133Z","steps":["trace[1793943887] 'process raft request' (duration: 194.511405ms)","trace[1793943887] 'compare' (duration: 188.479653ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T14:23:21.931473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:23:21.547329Z","time spent":"384.099553ms","remote":"127.0.0.1:49592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-16T14:23:22.659534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.951628ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-16T14:23:22.659841Z","caller":"traceutil/trace.go:171","msg":"trace[2002233081] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"672.596104ms","start":"2026-10-16T14:23:21.987202Z","end":"2026-10-16T14:23:22.659798Z","steps":["trace[2002233081] 'process raft request' (duration: 367.976831ms)","trace[2002233081] 'compare' (duration: 303.780704ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:23:22.660016Z","caller":"traceutil/trace.go:171","msg":"trace[1585880774] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"660.553631ms","start":"2026-10-16T14:23:21.999402Z","end":"2026-10-16T14:23:22.659955Z","steps":["trace[1585880774] 'process raft request' (duration: 660.327306ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:23:22.66006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:23:21.987171Z","time spent":"672.776798ms","remote":"127.0.0.1:49376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T14:23:22.660178Z","caller":"traceutil/trace.go:171","msg":"trace[1923090285] linearizableReadLoop","detail":"{readStateIndex:690; appliedIndex:688; }","duration":"290.160548ms","start":"2026-10-16T14:23:22.369954Z","end":"2026-10-16T14:23:22.660114Z","steps":["trace[1923090285] 'read index received' (duration: 252.023907ms)","trace[1923090285] 'applied index is now lower than readState.Index' (duration: 38.12857ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T14:23:22.660254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:23:21.999374Z","time spent":"660.745266ms","remote":"127.0.0.1:49302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T14:23:22.66038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.393662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T14:23:22.660488Z","caller":"traceutil/trace.go:171","msg":"trace[1581597843] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:661; }","duration":"290.559916ms","start":"2026-10-16T14:23:22.369896Z","end":"2026-10-16T14:23:22.660456Z","steps":["trace[1581597843] 'agreement among raft nodes before linearized reading' (duration: 290.349841ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:23:22.661231Z","caller":"traceutil/trace.go:171","msg":"trace[1068572721] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"276.5287ms","start":"2026-10-16T14:23:22.384677Z","end":"2026-10-16T14:23:22.661206Z","steps":["trace[1068572721] 'process raft request' (duration: 275.628371ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:23:56.664023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.467332ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-10-16T14:24:08.393695Z","caller":"traceutil/trace.go:171","msg":"trace[1470849132] transaction","detail":"{read_only:false; response_revision:1231; number_of_response:1; }","duration":"162.995309ms","start":"2026-10-16T14:24:08.230662Z","end":"2026-10-16T14:24:08.393657Z","steps":["trace[1470849132] 'process raft request' (duration: 162.60342ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:24:32.521068Z","caller":"traceutil/trace.go:171","msg":"trace[1411982205] transaction","detail":"{read_only:false; response_revision:1498; number_of_response:1; }","duration":"108.179042ms","start":"2026-10-16T14:24:32.412851Z","end":"2026-10-16T14:24:32.52103Z","steps":["trace[1411982205] 'process raft request' (duration: 99.164255ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:24:33.710413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.315933ms","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-10-16T14:24:33.710511Z","caller":"traceutil/trace.go:171","msg":"trace[32745474] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1503; }","duration":"112.477637ms","start":"2026-10-16T14:24:33.598017Z","end":"2026-10-16T14:24:33.710494Z","steps":["trace[32745474] 'range keys from in-memory index tree' (duration: 112.005266ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:24:33.710591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.676572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-16T14:24:33.71086Z","caller":"traceutil/trace.go:171","msg":"trace[1438730180] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1503; }","duration":"200.826375ms","start":"2026-10-16T14:24:33.509823Z","end":"2026-10-16T14:24:33.71065Z","steps":["trace[1438730180] 'range keys from in-memory index tree' (duration: 200.492458ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:24:33.710899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.876777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-16T14:24:33.711041Z","caller":"traceutil/trace.go:171","msg":"trace[497000299] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1503; }","duration":"185.269336ms","start":"2026-10-16T14:24:33.52574Z","end":"2026-10-16T14:24:33.71101Z","steps":["trace[497000299] 'count revisions from in-memory index tree' (duration: 184.799325ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:24:58.348071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.875672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8e389198-8e2c-4745-bb75-eb20853278a0\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-10-16T14:24:58.34819Z","caller":"traceutil/trace.go:171","msg":"trace[41811383] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8e389198-8e2c-4745-bb75-eb20853278a0; range_end:; response_count:1; response_revision:1911; }","duration":"242.041304ms","start":"2026-10-16T14:24:58.106123Z","end":"2026-10-16T14:24:58.348164Z","steps":["trace[41811383] 'range keys from in-memory index tree' (duration: 241.684447ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:24:58.348367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.86153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-16T14:24:58.348423Z","caller":"traceutil/trace.go:171","msg":"trace[1410708130] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1911; }","duration":"448.965133ms","start":"2026-10-16T14:24:57.899442Z","end":"2026-10-16T14:24:58.348407Z","steps":["trace[1410708130] 'range keys from in-memory index tree' (duration: 448.685517ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:24:58.348178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.36381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-10-16T14:24:58.348477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:24:57.899394Z","time spent":"449.069215ms","remote":"127.0.0.1:49376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-16T14:24:58.348496Z","caller":"traceutil/trace.go:171","msg":"trace[1390302496] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1911; }","duration":"448.746508ms","start":"2026-10-16T14:24:57.89973Z","end":"2026-10-16T14:24:58.348477Z","steps":["trace[1390302496] 'range keys from in-memory index tree' (duration: 448.187206ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:24:58.348597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:24:57.899696Z","time spent":"448.883652ms","remote":"127.0.0.1:49376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-16T14:24:58.524743Z","caller":"traceutil/trace.go:171","msg":"trace[1379444232] transaction","detail":"{read_only:false; response_revision:1912; number_of_response:1; }","duration":"171.659258ms","start":"2026-10-16T14:24:58.353054Z","end":"2026-10-16T14:24:58.524713Z","steps":["trace[1379444232] 'process raft request' (duration: 171.456484ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:24:58.732352Z","caller":"traceutil/trace.go:171","msg":"trace[1918071084] linearizableReadLoop","detail":"{readStateIndex:1989; appliedIndex:1987; }","duration":"376.087422ms","start":"2026-10-16T14:24:58.356241Z","end":"2026-10-16T14:24:58.732328Z","steps":["trace[1918071084] 'read index received' (duration: 168.322105ms)","trace[1918071084] 'applied index is now lower than readState.Index' (duration: 207.764177ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:24:58.732371Z","caller":"traceutil/trace.go:171","msg":"trace[1794504065] transaction","detail":"{read_only:false; response_revision:1913; number_of_response:1; }","duration":"379.227261ms","start":"2026-10-16T14:24:58.353103Z","end":"2026-10-16T14:24:58.732331Z","steps":["trace[1794504065] 'process raft request' (duration: 336.879146ms)","trace[1794504065] 'compare' (duration: 42.024008ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:24:58.73246Z","caller":"traceutil/trace.go:171","msg":"trace[1288692436] transaction","detail":"{read_only:false; response_revision:1914; number_of_response:1; }","duration":"351.052715ms","start":"2026-10-16T14:24:58.381395Z","end":"2026-10-16T14:24:58.732448Z","steps":["trace[1288692436] 'process raft request' (duration: 350.82385ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:24:58.732567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:24:58.381373Z","time spent":"351.107567ms","remote":"127.0.0.1:49376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T14:24:58.732581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:24:58.353079Z","time spent":"379.356653ms","remote":"127.0.0.1:49376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T14:24:58.732594Z","caller":"traceutil/trace.go:171","msg":"trace[261382934] transaction","detail":"{read_only:false; response_revision:1915; number_of_response:1; }","duration":"348.137382ms","start":"2026-10-16T14:24:58.384428Z","end":"2026-10-16T14:24:58.732566Z","steps":["trace[261382934] 'process raft request' (duration: 347.856476ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:24:58.732832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.561133ms","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":"warn","ts":"2026-10-16T14:24:58.73292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:24:58.384402Z","time spent":"348.443919ms","remote":"127.0.0.1:49376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T14:24:58.732961Z","caller":"traceutil/trace.go:171","msg":"trace[349892240] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1915; }","duration":"376.763297ms","start":"2026-10-16T14:24:58.35618Z","end":"2026-10-16T14:24:58.732943Z","steps":["trace[349892240] 'agreement among raft nodes before linearized reading' (duration: 376.546292ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:24:58.733001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:24:58.356164Z","time spent":"376.823928ms","remote":"127.0.0.1:49906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"info","ts":"2026-10-16T14:24:59.105889Z","caller":"traceutil/trace.go:171","msg":"trace[393359232] transaction","detail":"{read_only:false; response_revision:1916; number_of_response:1; }","duration":"446.578261ms","start":"2026-10-16T14:24:58.659276Z","end":"2026-10-16T14:24:59.105855Z","steps":["trace[393359232] 'process raft request' (duration: 446.297255ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:24:59.10604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:24:58.659251Z","time spent":"446.723674ms","remote":"127.0.0.1:49922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2507,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-16T14:24:59.109843Z","caller":"traceutil/trace.go:171","msg":"trace[635755725] linearizableReadLoop","detail":"{readStateIndex:1993; appliedIndex:1991; }","duration":"377.399291ms","start":"2026-10-16T14:24:58.732417Z","end":"2026-10-16T14:24:59.109816Z","steps":["trace[635755725] 'read index received' (duration: 373.182199ms)","trace[635755725] 'applied index is now lower than readState.Index' (duration: 4.215841ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T14:24:59.110099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.720309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-10-16T14:24:59.110144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.795674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T14:24:59.110166Z","caller":"traceutil/trace.go:171","msg":"trace[1101834012] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1917; }","duration":"280.810021ms","start":"2026-10-16T14:24:58.829341Z","end":"2026-10-16T14:24:59.110151Z","steps":["trace[1101834012] 'agreement among raft nodes before linearized reading' (duration: 280.677498ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:24:59.110224Z","caller":"traceutil/trace.go:171","msg":"trace[2088686879] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1917; }","duration":"167.908156ms","start":"2026-10-16T14:24:58.942268Z","end":"2026-10-16T14:24:59.110177Z","steps":["trace[2088686879] 'agreement among raft nodes before linearized reading' (duration: 167.810654ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:24:59.110262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.884684ms","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-10-16T14:24:59.110348Z","caller":"traceutil/trace.go:171","msg":"trace[2042063431] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1917; }","duration":"216.010387ms","start":"2026-10-16T14:24:58.894318Z","end":"2026-10-16T14:24:59.110328Z","steps":["trace[2042063431] 'agreement among raft nodes before linearized reading' (duration: 215.789002ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:24:59.110343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.839013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8e389198-8e2c-4745-bb75-eb20853278a0\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-10-16T14:24:59.110417Z","caller":"traceutil/trace.go:171","msg":"trace[1175427230] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8e389198-8e2c-4745-bb75-eb20853278a0; range_end:; response_count:1; response_revision:1917; }","duration":"564.970726ms","start":"2026-10-16T14:24:58.545427Z","end":"2026-10-16T14:24:59.110397Z","steps":["trace[1175427230] 'agreement among raft nodes before linearized reading' (duration: 564.771992ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:24:59.110514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.494334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-10-16T14:24:59.110542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:24:58.545401Z","time spent":"565.108089ms","remote":"127.0.0.1:49302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8e389198-8e2c-4745-bb75-eb20853278a0\" "} {"level":"info","ts":"2026-10-16T14:24:59.110596Z","caller":"traceutil/trace.go:171","msg":"trace[976155305] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1917; }","duration":"236.623587ms","start":"2026-10-16T14:24:58.873956Z","end":"2026-10-16T14:24:59.110579Z","steps":["trace[976155305] 'agreement among raft nodes before linearized reading' (duration: 236.181948ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:24:59.110887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.450962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T14:24:59.110935Z","caller":"traceutil/trace.go:171","msg":"trace[242181806] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1917; }","duration":"476.540725ms","start":"2026-10-16T14:24:58.634381Z","end":"2026-10-16T14:24:59.110922Z","steps":["trace[242181806] 'agreement among raft nodes before linearized reading' (duration: 475.602264ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:24:59.110805Z","caller":"traceutil/trace.go:171","msg":"trace[256929681] transaction","detail":"{read_only:false; response_revision:1917; number_of_response:1; }","duration":"403.780536ms","start":"2026-10-16T14:24:58.706953Z","end":"2026-10-16T14:24:59.110733Z","steps":["trace[256929681] 'process raft request' (duration: 402.653901ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:24:59.111143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:24:58.706915Z","time spent":"404.143464ms","remote":"127.0.0.1:49376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T14:24:59.111483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:24:58.634362Z","time spent":"476.596035ms","remote":"127.0.0.1:56432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"info","ts":"2026-10-16T14:25:02.635317Z","caller":"traceutil/trace.go:171","msg":"trace[20762504] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1951; }","duration":"160.448933ms","start":"2026-10-16T14:25:02.474829Z","end":"2026-10-16T14:25:02.635278Z","steps":["trace[20762504] 'process raft request' (duration: 160.209738ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:25:43.578353Z","caller":"traceutil/trace.go:171","msg":"trace[1461274696] linearizableReadLoop","detail":"{readStateIndex:2305; appliedIndex:2304; }","duration":"112.135904ms","start":"2026-10-16T14:25:43.46617Z","end":"2026-10-16T14:25:43.578306Z","steps":["trace[1461274696] 'read index received' (duration: 85.227397ms)","trace[1461274696] 'applied index is now lower than readState.Index' (duration: 26.906637ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:25:43.578529Z","caller":"traceutil/trace.go:171","msg":"trace[916722591] transaction","detail":"{read_only:false; response_revision:2218; number_of_response:1; }","duration":"117.549852ms","start":"2026-10-16T14:25:43.460866Z","end":"2026-10-16T14:25:43.578416Z","steps":["trace[916722591] 'process raft request' (duration: 90.514143ms)","trace[916722591] 'compare' (duration: 26.700732ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T14:25:43.578778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.575534ms","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-10-16T14:25:43.578933Z","caller":"traceutil/trace.go:171","msg":"trace[333029352] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2218; }","duration":"112.796979ms","start":"2026-10-16T14:25:43.4661Z","end":"2026-10-16T14:25:43.578897Z","steps":["trace[333029352] 'agreement among raft nodes before linearized reading' (duration: 112.433101ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:25:50.087594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.172368ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-16T14:25:50.087919Z","caller":"traceutil/trace.go:171","msg":"trace[1216311683] linearizableReadLoop","detail":"{readStateIndex:2340; appliedIndex:2338; }","duration":"281.953946ms","start":"2026-10-16T14:25:49.805942Z","end":"2026-10-16T14:25:50.087896Z","steps":["trace[1216311683] 'read index received' (duration: 73.40755ms)","trace[1216311683] 'applied index is now lower than readState.Index' (duration: 208.544806ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:25:50.088102Z","caller":"traceutil/trace.go:171","msg":"trace[976196072] transaction","detail":"{read_only:false; response_revision:2251; number_of_response:1; }","duration":"397.32151ms","start":"2026-10-16T14:25:49.690743Z","end":"2026-10-16T14:25:50.088065Z","steps":["trace[976196072] 'process raft request' (duration: 188.521058ms)","trace[976196072] 'compare' (duration: 207.876592ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:25:50.08816Z","caller":"traceutil/trace.go:171","msg":"trace[758873520] transaction","detail":"{read_only:false; response_revision:2252; number_of_response:1; }","duration":"309.289742ms","start":"2026-10-16T14:25:49.778837Z","end":"2026-10-16T14:25:50.088126Z","steps":["trace[758873520] 'process raft request' (duration: 308.933344ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:25:50.088323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:25:49.690715Z","time spent":"397.492164ms","remote":"127.0.0.1:49376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T14:25:50.088144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.204991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-10-16T14:25:50.088444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:25:49.778803Z","time spent":"309.536907ms","remote":"127.0.0.1:49376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T14:25:50.088428Z","caller":"traceutil/trace.go:171","msg":"trace[877922524] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2252; }","duration":"282.514208ms","start":"2026-10-16T14:25:49.805874Z","end":"2026-10-16T14:25:50.088388Z","steps":["trace[877922524] 'agreement among raft nodes before linearized reading' (duration: 282.089349ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:25:50.440273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.137505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-16T14:25:50.440409Z","caller":"traceutil/trace.go:171","msg":"trace[1398838711] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:2252; }","duration":"151.292198ms","start":"2026-10-16T14:25:50.289086Z","end":"2026-10-16T14:25:50.440378Z","steps":["trace[1398838711] 'count revisions from in-memory index tree' (duration: 151.002871ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:25:55.571734Z","caller":"traceutil/trace.go:171","msg":"trace[1195273838] transaction","detail":"{read_only:false; response_revision:2280; number_of_response:1; }","duration":"113.664638ms","start":"2026-10-16T14:25:55.458029Z","end":"2026-10-16T14:25:55.571693Z","steps":["trace[1195273838] 'process raft request' (duration: 113.372221ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:25:55.898606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.441128ms","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-10-16T14:25:55.89871Z","caller":"traceutil/trace.go:171","msg":"trace[1814046486] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2280; }","duration":"183.629372ms","start":"2026-10-16T14:25:55.715056Z","end":"2026-10-16T14:25:55.898685Z","steps":["trace[1814046486] 'range keys from in-memory index tree' (duration: 183.122962ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:26:54.416803Z","caller":"traceutil/trace.go:171","msg":"trace[271164827] transaction","detail":"{read_only:false; response_revision:2768; number_of_response:1; }","duration":"101.29669ms","start":"2026-10-16T14:26:54.315417Z","end":"2026-10-16T14:26:54.416714Z","steps":["trace[271164827] 'process raft request' (duration: 101.045094ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:27:27.710475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.49981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-16T14:27:27.710925Z","caller":"traceutil/trace.go:171","msg":"trace[210413032] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2968; }","duration":"148.008822ms","start":"2026-10-16T14:27:27.562886Z","end":"2026-10-16T14:27:27.710895Z","steps":["trace[210413032] 'range keys from in-memory index tree' (duration: 146.989689ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:27:28.053047Z","caller":"traceutil/trace.go:171","msg":"trace[305594369] transaction","detail":"{read_only:false; response_revision:2971; number_of_response:1; }","duration":"262.760941ms","start":"2026-10-16T14:27:27.790253Z","end":"2026-10-16T14:27:28.053014Z","steps":["trace[305594369] 'process raft request' (duration: 173.924684ms)","trace[305594369] 'compare' (duration: 88.717235ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:27:33.58407Z","caller":"traceutil/trace.go:171","msg":"trace[104774943] transaction","detail":"{read_only:false; response_revision:3003; number_of_response:1; }","duration":"115.384633ms","start":"2026-10-16T14:27:33.468642Z","end":"2026-10-16T14:27:33.584027Z","steps":["trace[104774943] 'process raft request' (duration: 115.206729ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:27:39.693146Z","caller":"traceutil/trace.go:171","msg":"trace[241192902] linearizableReadLoop","detail":"{readStateIndex:3152; appliedIndex:3151; }","duration":"101.894926ms","start":"2026-10-16T14:27:39.591219Z","end":"2026-10-16T14:27:39.693114Z","steps":["trace[241192902] 'read index received' (duration: 101.820994ms)","trace[241192902] 'applied index is now lower than readState.Index' (duration: 72.732µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:27:39.693194Z","caller":"traceutil/trace.go:171","msg":"trace[1874993277] transaction","detail":"{read_only:false; response_revision:3035; number_of_response:1; }","duration":"163.617246ms","start":"2026-10-16T14:27:39.529551Z","end":"2026-10-16T14:27:39.693168Z","steps":["trace[1874993277] 'process raft request' (duration: 163.3557ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:27:39.693298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.045689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-16T14:27:39.693395Z","caller":"traceutil/trace.go:171","msg":"trace[1761240896] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:3035; }","duration":"102.199582ms","start":"2026-10-16T14:27:39.591179Z","end":"2026-10-16T14:27:39.693379Z","steps":["trace[1761240896] 'agreement among raft nodes before linearized reading' (duration: 102.048749ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:27:39.749792Z","caller":"traceutil/trace.go:171","msg":"trace[669412472] transaction","detail":"{read_only:false; response_revision:3036; number_of_response:1; }","duration":"142.144103ms","start":"2026-10-16T14:27:39.607614Z","end":"2026-10-16T14:27:39.749758Z","steps":["trace[669412472] 'process raft request' (duration: 128.582084ms)","trace[669412472] 'compare' (duration: 13.414356ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:27:39.954247Z","caller":"traceutil/trace.go:171","msg":"trace[1636938874] linearizableReadLoop","detail":"{readStateIndex:3154; appliedIndex:3153; }","duration":"106.361814ms","start":"2026-10-16T14:27:39.847856Z","end":"2026-10-16T14:27:39.954217Z","steps":["trace[1636938874] 'read index received' (duration: 17.853733ms)","trace[1636938874] 'applied index is now lower than readState.Index' (duration: 88.5067ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:27:39.954489Z","caller":"traceutil/trace.go:171","msg":"trace[879513595] transaction","detail":"{read_only:false; response_revision:3037; number_of_response:1; }","duration":"125.584638ms","start":"2026-10-16T14:27:39.828834Z","end":"2026-10-16T14:27:39.954419Z","steps":["trace[879513595] 'process raft request' (duration: 36.887403ms)","trace[879513595] 'compare' (duration: 88.322007ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T14:27:39.954781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.897426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-16T14:27:39.954847Z","caller":"traceutil/trace.go:171","msg":"trace[664846754] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3037; }","duration":"107.016498ms","start":"2026-10-16T14:27:39.847816Z","end":"2026-10-16T14:27:39.954832Z","steps":["trace[664846754] 'agreement among raft nodes before linearized reading' (duration: 106.551138ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:30:09.113795Z","caller":"traceutil/trace.go:171","msg":"trace[1940991069] transaction","detail":"{read_only:false; response_revision:3948; number_of_response:1; }","duration":"118.769879ms","start":"2026-10-16T14:30:08.994992Z","end":"2026-10-16T14:30:09.113762Z","steps":["trace[1940991069] 'process raft request' (duration: 103.080208ms)","trace[1940991069] 'compare' (duration: 15.455396ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:30:09.113985Z","caller":"traceutil/trace.go:171","msg":"trace[1282470533] transaction","detail":"{read_only:false; response_revision:3949; number_of_response:1; }","duration":"115.178688ms","start":"2026-10-16T14:30:08.99879Z","end":"2026-10-16T14:30:09.113969Z","steps":["trace[1282470533] 'process raft request' (duration: 115.040115ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:30:45.450575Z","caller":"traceutil/trace.go:171","msg":"trace[209733832] transaction","detail":"{read_only:false; response_revision:4214; number_of_response:1; }","duration":"118.119391ms","start":"2026-10-16T14:30:45.332432Z","end":"2026-10-16T14:30:45.450552Z","steps":["trace[209733832] 'process raft request' (duration: 117.947617ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:32:02.935865Z","caller":"traceutil/trace.go:171","msg":"trace[2084738470] transaction","detail":"{read_only:false; response_revision:5121; number_of_response:1; }","duration":"109.390136ms","start":"2026-10-16T14:32:02.826432Z","end":"2026-10-16T14:32:02.935822Z","steps":["trace[2084738470] 'process raft request' (duration: 108.592378ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:32:02.937332Z","caller":"traceutil/trace.go:171","msg":"trace[1860543710] transaction","detail":"{read_only:false; response_revision:5123; number_of_response:1; }","duration":"101.788277ms","start":"2026-10-16T14:32:02.835533Z","end":"2026-10-16T14:32:02.937322Z","steps":["trace[1860543710] 'process raft request' (duration: 101.643034ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:32:02.937413Z","caller":"traceutil/trace.go:171","msg":"trace[2089768950] transaction","detail":"{read_only:false; response_revision:5124; number_of_response:1; }","duration":"101.549542ms","start":"2026-10-16T14:32:02.835819Z","end":"2026-10-16T14:32:02.937369Z","steps":["trace[2089768950] 'process raft request' (duration: 101.428939ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:32:02.937475Z","caller":"traceutil/trace.go:171","msg":"trace[214812414] transaction","detail":"{read_only:false; response_revision:5122; number_of_response:1; }","duration":"108.495147ms","start":"2026-10-16T14:32:02.828953Z","end":"2026-10-16T14:32:02.937448Z","steps":["trace[214812414] 'process raft request' (duration: 106.816349ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:32:09.103271Z","caller":"traceutil/trace.go:171","msg":"trace[1714488058] transaction","detail":"{read_only:false; response_revision:5255; number_of_response:1; }","duration":"117.198052ms","start":"2026-10-16T14:32:08.986044Z","end":"2026-10-16T14:32:09.103242Z","steps":["trace[1714488058] 'process raft request' (duration: 105.200473ms)","trace[1714488058] 'compare' (duration: 11.686402ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:32:09.150583Z","caller":"traceutil/trace.go:171","msg":"trace[685833925] transaction","detail":"{read_only:false; response_revision:5256; number_of_response:1; }","duration":"157.683837ms","start":"2026-10-16T14:32:08.992865Z","end":"2026-10-16T14:32:09.150549Z","steps":["trace[685833925] 'process raft request' (duration: 157.37349ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:32:09.408269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.128669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"info","ts":"2026-10-16T14:32:09.408349Z","caller":"traceutil/trace.go:171","msg":"trace[198621744] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:5256; }","duration":"205.262191ms","start":"2026-10-16T14:32:09.203067Z","end":"2026-10-16T14:32:09.40833Z","steps":["trace[198621744] 'range keys from in-memory index tree' (duration: 204.968205ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:32:09.408703Z","caller":"traceutil/trace.go:171","msg":"trace[895455533] transaction","detail":"{read_only:false; response_revision:5257; number_of_response:1; }","duration":"168.808396ms","start":"2026-10-16T14:32:09.239872Z","end":"2026-10-16T14:32:09.408681Z","steps":["trace[895455533] 'process raft request' (duration: 142.005496ms)","trace[895455533] 'compare' (duration: 26.181576ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:32:09.702007Z","caller":"traceutil/trace.go:171","msg":"trace[832811117] linearizableReadLoop","detail":"{readStateIndex:5462; appliedIndex:5461; }","duration":"279.131313ms","start":"2026-10-16T14:32:09.422852Z","end":"2026-10-16T14:32:09.701983Z","steps":["trace[832811117] 'read index received' (duration: 278.919788ms)","trace[832811117] 'applied index is now lower than readState.Index' (duration: 210.195µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:32:09.702178Z","caller":"traceutil/trace.go:171","msg":"trace[1404025721] transaction","detail":"{read_only:false; response_revision:5258; number_of_response:1; }","duration":"284.519363ms","start":"2026-10-16T14:32:09.41758Z","end":"2026-10-16T14:32:09.702099Z","steps":["trace[1404025721] 'process raft request' (duration: 284.217117ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:32:09.702337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.46459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1342"} {"level":"info","ts":"2026-10-16T14:32:09.702395Z","caller":"traceutil/trace.go:171","msg":"trace[2050874206] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:5258; }","duration":"279.561302ms","start":"2026-10-16T14:32:09.422806Z","end":"2026-10-16T14:32:09.702367Z","steps":["trace[2050874206] 'agreement among raft nodes before linearized reading' (duration: 279.360098ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:32:09.702538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.619286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/monitoring/loki-memcached-chunks-cache\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T14:32:09.702689Z","caller":"traceutil/trace.go:171","msg":"trace[1153823727] range","detail":"{range_begin:/registry/poddisruptionbudgets/monitoring/loki-memcached-chunks-cache; range_end:; response_count:0; response_revision:5258; }","duration":"263.80386ms","start":"2026-10-16T14:32:09.438864Z","end":"2026-10-16T14:32:09.702668Z","steps":["trace[1153823727] 'agreement among raft nodes before linearized reading' (duration: 263.535224ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:32:09.70273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.846272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-16T14:32:09.702832Z","caller":"traceutil/trace.go:171","msg":"trace[2042200115] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5258; }","duration":"238.951784ms","start":"2026-10-16T14:32:09.463854Z","end":"2026-10-16T14:32:09.702806Z","steps":["trace[2042200115] 'agreement among raft nodes before linearized reading' (duration: 238.657868ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:32:09.702676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.520627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-16T14:32:09.702958Z","caller":"traceutil/trace.go:171","msg":"trace[445339280] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5258; }","duration":"218.847254ms","start":"2026-10-16T14:32:09.484089Z","end":"2026-10-16T14:32:09.702936Z","steps":["trace[445339280] 'agreement among raft nodes before linearized reading' (duration: 218.334253ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:32:09.955867Z","caller":"traceutil/trace.go:171","msg":"trace[1190391916] linearizableReadLoop","detail":"{readStateIndex:5463; appliedIndex:5462; }","duration":"246.981785ms","start":"2026-10-16T14:32:09.708862Z","end":"2026-10-16T14:32:09.955843Z","steps":["trace[1190391916] 'read index received' (duration: 196.603528ms)","trace[1190391916] 'applied index is now lower than readState.Index' (duration: 50.376887ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:32:09.955988Z","caller":"traceutil/trace.go:171","msg":"trace[1542140002] transaction","detail":"{read_only:false; response_revision:5259; number_of_response:1; }","duration":"247.971357ms","start":"2026-10-16T14:32:09.708003Z","end":"2026-10-16T14:32:09.955974Z","steps":["trace[1542140002] 'process raft request' (duration: 197.382535ms)","trace[1542140002] 'compare' (duration: 50.235304ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T14:32:09.956112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.22755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/loki\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T14:32:09.956222Z","caller":"traceutil/trace.go:171","msg":"trace[1706036849] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/loki; range_end:; response_count:0; response_revision:5259; }","duration":"247.363793ms","start":"2026-10-16T14:32:09.708846Z","end":"2026-10-16T14:32:09.95621Z","steps":["trace[1706036849] 'agreement among raft nodes before linearized reading' (duration: 247.196449ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:32:10.050623Z","caller":"traceutil/trace.go:171","msg":"trace[517825690] transaction","detail":"{read_only:false; response_revision:5260; number_of_response:1; }","duration":"341.261763ms","start":"2026-10-16T14:32:09.709332Z","end":"2026-10-16T14:32:10.050594Z","steps":["trace[517825690] 'process raft request' (duration: 340.962536ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:32:10.051395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.810763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config\" ","response":"range_response_count:1 size:696"} {"level":"warn","ts":"2026-10-16T14:32:10.051493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.228135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-16T14:32:10.051493Z","caller":"traceutil/trace.go:171","msg":"trace[2108556058] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config; range_end:; response_count:1; response_revision:5265; }","duration":"340.962127ms","start":"2026-10-16T14:32:09.710508Z","end":"2026-10-16T14:32:10.05147Z","steps":["trace[2108556058] 'agreement among raft nodes before linearized reading' (duration: 340.66553ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:32:10.051531Z","caller":"traceutil/trace.go:171","msg":"trace[1378515380] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5265; }","duration":"114.313517ms","start":"2026-10-16T14:32:09.937205Z","end":"2026-10-16T14:32:10.051518Z","steps":["trace[1378515380] 'agreement among raft nodes before linearized reading' (duration: 113.796225ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:32:10.051544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:32:09.710493Z","time spent":"341.041338ms","remote":"127.0.0.1:49214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":720,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config\" "} {"level":"warn","ts":"2026-10-16T14:32:10.05157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:32:09.709307Z","time spent":"342.152653ms","remote":"127.0.0.1:49376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T14:32:10.050678Z","caller":"traceutil/trace.go:171","msg":"trace[1702477283] transaction","detail":"{read_only:false; response_revision:5262; number_of_response:1; }","duration":"339.455853ms","start":"2026-10-16T14:32:09.711207Z","end":"2026-10-16T14:32:10.050662Z","steps":["trace[1702477283] 'process raft request' (duration: 339.32358ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:32:10.051794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:32:09.711196Z","time spent":"340.566167ms","remote":"127.0.0.1:49562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4883,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T14:32:10.050697Z","caller":"traceutil/trace.go:171","msg":"trace[841075663] transaction","detail":"{read_only:false; response_revision:5263; number_of_response:1; }","duration":"338.846249ms","start":"2026-10-16T14:32:09.711832Z","end":"2026-10-16T14:32:10.050678Z","steps":["trace[841075663] 'process raft request' (duration: 338.751087ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:32:10.051915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:32:09.711791Z","time spent":"340.059676ms","remote":"127.0.0.1:49384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1854,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T14:32:10.050782Z","caller":"traceutil/trace.go:171","msg":"trace[440295258] transaction","detail":"{read_only:false; response_revision:5261; number_of_response:1; }","duration":"340.843674ms","start":"2026-10-16T14:32:09.709889Z","end":"2026-10-16T14:32:10.050733Z","steps":["trace[440295258] 'process raft request' (duration: 340.547828ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:32:10.050875Z","caller":"traceutil/trace.go:171","msg":"trace[1166467883] transaction","detail":"{read_only:false; response_revision:5264; number_of_response:1; }","duration":"262.915291ms","start":"2026-10-16T14:32:09.787949Z","end":"2026-10-16T14:32:10.050864Z","steps":["trace[1166467883] 'process raft request' (duration: 262.677216ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:32:10.051158Z","caller":"traceutil/trace.go:171","msg":"trace[322592042] transaction","detail":"{read_only:false; response_revision:5265; number_of_response:1; }","duration":"202.305615ms","start":"2026-10-16T14:32:09.848807Z","end":"2026-10-16T14:32:10.051113Z","steps":["trace[322592042] 'process raft request' (duration: 202.015478ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:32:10.052154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:32:09.709877Z","time spent":"342.121363ms","remote":"127.0.0.1:49288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1250,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T14:32:10.051263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.180964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-4nmjs\" ","response":"range_response_count:1 size:9753"} {"level":"info","ts":"2026-10-16T14:32:10.052533Z","caller":"traceutil/trace.go:171","msg":"trace[383585705] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-4nmjs; range_end:; response_count:1; response_revision:5265; }","duration":"204.498574ms","start":"2026-10-16T14:32:09.848011Z","end":"2026-10-16T14:32:10.05251Z","steps":["trace[383585705] 'agreement among raft nodes before linearized reading' (duration: 203.048022ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:32:10.165326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.271082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/loki-alerting-rules\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T14:32:10.165403Z","caller":"traceutil/trace.go:171","msg":"trace[1101211571] range","detail":"{range_begin:/registry/configmaps/monitoring/loki-alerting-rules; range_end:; response_count:0; response_revision:5265; }","duration":"108.396925ms","start":"2026-10-16T14:32:10.05699Z","end":"2026-10-16T14:32:10.165387Z","steps":["trace[1101211571] 'range keys from in-memory index tree' (duration: 108.182399ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:32:10.213308Z","caller":"traceutil/trace.go:171","msg":"trace[782773813] linearizableReadLoop","detail":"{readStateIndex:5470; appliedIndex:5469; }","duration":"155.985879ms","start":"2026-10-16T14:32:10.057264Z","end":"2026-10-16T14:32:10.21325Z","steps":["trace[782773813] 'read index received' (duration: 83.461µs)","trace[782773813] 'applied index is now lower than readState.Index' (duration: 155.901328ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:32:10.213388Z","caller":"traceutil/trace.go:171","msg":"trace[145870535] transaction","detail":"{read_only:false; response_revision:5266; number_of_response:1; }","duration":"156.117682ms","start":"2026-10-16T14:32:10.057233Z","end":"2026-10-16T14:32:10.213351Z","steps":["trace[145870535] 'process raft request' (duration: 155.801965ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:32:10.213571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.298316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config\" ","response":"range_response_count:1 size:696"} {"level":"info","ts":"2026-10-16T14:32:10.213683Z","caller":"traceutil/trace.go:171","msg":"trace[2116310506] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config; range_end:; response_count:1; response_revision:5266; }","duration":"156.47051ms","start":"2026-10-16T14:32:10.057193Z","end":"2026-10-16T14:32:10.213663Z","steps":["trace[2116310506] 'agreement among raft nodes before linearized reading' (duration: 156.222194ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:32:10.561886Z","caller":"traceutil/trace.go:171","msg":"trace[1332426331] transaction","detail":"{read_only:false; response_revision:5267; number_of_response:1; }","duration":"501.248862ms","start":"2026-10-16T14:32:10.060617Z","end":"2026-10-16T14:32:10.561866Z","steps":["trace[1332426331] 'process raft request' (duration: 501.096959ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:32:10.561996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:32:10.060596Z","time spent":"501.341494ms","remote":"127.0.0.1:49538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5114,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T14:32:10.562114Z","caller":"traceutil/trace.go:171","msg":"trace[1882302004] linearizableReadLoop","detail":"{readStateIndex:5472; appliedIndex:5470; }","duration":"348.71588ms","start":"2026-10-16T14:32:10.213391Z","end":"2026-10-16T14:32:10.562107Z","steps":["trace[1882302004] 'read index received' (duration: 348.327691ms)","trace[1882302004] 'applied index is now lower than readState.Index' (duration: 387.239µs)"],"step_count":2} {"level":"warn","ts":"2026-10-16T14:32:10.562398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.850169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/loki-gateway\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T14:32:10.562489Z","caller":"traceutil/trace.go:171","msg":"trace[1238248289] range","detail":"{range_begin:/registry/configmaps/monitoring/loki-gateway; range_end:; response_count:0; response_revision:5268; }","duration":"394.015443ms","start":"2026-10-16T14:32:10.168457Z","end":"2026-10-16T14:32:10.562472Z","steps":["trace[1238248289] 'agreement among raft nodes before linearized reading' (duration: 393.838969ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:32:10.562499Z","caller":"traceutil/trace.go:171","msg":"trace[1746850318] transaction","detail":"{read_only:false; response_revision:5268; number_of_response:1; }","duration":"497.312714ms","start":"2026-10-16T14:32:10.065123Z","end":"2026-10-16T14:32:10.562436Z","steps":["trace[1746850318] 'process raft request' (duration: 496.70237ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:32:10.562528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:32:10.168437Z","time spent":"394.083145ms","remote":"127.0.0.1:49220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":29,"request content":"key:\"/registry/configmaps/monitoring/loki-gateway\" "} {"level":"warn","ts":"2026-10-16T14:32:10.562574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.077375ms","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-10-16T14:32:10.562571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.558043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-55wvn\" ","response":"range_response_count:1 size:6518"} {"level":"warn","ts":"2026-10-16T14:32:10.562563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.129199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/prometheus-operated\" ","response":"range_response_count:1 size:993"} {"level":"info","ts":"2026-10-16T14:32:10.562601Z","caller":"traceutil/trace.go:171","msg":"trace[1246107792] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5268; }","duration":"297.143646ms","start":"2026-10-16T14:32:10.26545Z","end":"2026-10-16T14:32:10.562593Z","steps":["trace[1246107792] 'agreement among raft nodes before linearized reading' (duration: 297.028874ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:32:10.562604Z","caller":"traceutil/trace.go:171","msg":"trace[1382783123] range","detail":"{range_begin:/registry/pods/openstack/keepalived-55wvn; range_end:; response_count:1; response_revision:5268; }","duration":"376.636885ms","start":"2026-10-16T14:32:10.18596Z","end":"2026-10-16T14:32:10.562597Z","steps":["trace[1382783123] 'agreement among raft nodes before linearized reading' (duration: 376.544532ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:32:10.562605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:32:10.065094Z","time spent":"497.446516ms","remote":"127.0.0.1:49302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9750,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T14:32:10.562624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:32:10.185938Z","time spent":"376.681605ms","remote":"127.0.0.1:49302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6542,"request content":"key:\"/registry/pods/openstack/keepalived-55wvn\" "} {"level":"info","ts":"2026-10-16T14:32:10.562628Z","caller":"traceutil/trace.go:171","msg":"trace[1493158599] range","detail":"{range_begin:/registry/services/specs/monitoring/prometheus-operated; range_end:; response_count:1; response_revision:5268; }","duration":"345.230672ms","start":"2026-10-16T14:32:10.217381Z","end":"2026-10-16T14:32:10.562612Z","steps":["trace[1493158599] 'agreement among raft nodes before linearized reading' (duration: 345.016177ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:32:10.562659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:32:10.21736Z","time spent":"345.292484ms","remote":"127.0.0.1:49308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":1017,"request content":"key:\"/registry/services/specs/monitoring/prometheus-operated\" "} {"level":"warn","ts":"2026-10-16T14:32:10.56259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.95351ms","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-10-16T14:32:10.56272Z","caller":"traceutil/trace.go:171","msg":"trace[97232973] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5268; }","duration":"330.097554ms","start":"2026-10-16T14:32:10.232595Z","end":"2026-10-16T14:32:10.562693Z","steps":["trace[97232973] 'agreement among raft nodes before linearized reading' (duration: 329.93496ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:32:10.562744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:32:10.232553Z","time spent":"330.184456ms","remote":"127.0.0.1:49376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-10-16T14:32:11.840097Z","caller":"traceutil/trace.go:171","msg":"trace[236339438] transaction","detail":"{read_only:false; response_revision:5364; number_of_response:1; }","duration":"118.847779ms","start":"2026-10-16T14:32:11.721231Z","end":"2026-10-16T14:32:11.840079Z","steps":["trace[236339438] 'process raft request' (duration: 118.724236ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:32:14.338971Z","caller":"traceutil/trace.go:171","msg":"trace[404616639] transaction","detail":"{read_only:false; response_revision:5407; number_of_response:1; }","duration":"359.415099ms","start":"2026-10-16T14:32:13.979522Z","end":"2026-10-16T14:32:14.338937Z","steps":["trace[404616639] 'process raft request' (duration: 359.264696ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:32:14.339173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:32:13.979498Z","time spent":"359.565793ms","remote":"127.0.0.1:49922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1813,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-16T14:32:14.340088Z","caller":"traceutil/trace.go:171","msg":"trace[1793376952] linearizableReadLoop","detail":"{readStateIndex:5616; appliedIndex:5614; }","duration":"272.313181ms","start":"2026-10-16T14:32:14.067744Z","end":"2026-10-16T14:32:14.340058Z","steps":["trace[1793376952] 'read index received' (duration: 271.136165ms)","trace[1793376952] 'applied index is now lower than readState.Index' (duration: 1.171756ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:32:14.340391Z","caller":"traceutil/trace.go:171","msg":"trace[258189880] transaction","detail":"{read_only:false; response_revision:5408; number_of_response:1; }","duration":"359.332098ms","start":"2026-10-16T14:32:13.981028Z","end":"2026-10-16T14:32:14.34036Z","steps":["trace[258189880] 'process raft request' (duration: 358.809726ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:32:14.340437Z","caller":"traceutil/trace.go:171","msg":"trace[1233644172] transaction","detail":"{read_only:false; response_revision:5409; number_of_response:1; }","duration":"272.654618ms","start":"2026-10-16T14:32:14.067766Z","end":"2026-10-16T14:32:14.340421Z","steps":["trace[1233644172] 'process raft request' (duration: 272.242309ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:32:14.340478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.71475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-16T14:32:14.340542Z","caller":"traceutil/trace.go:171","msg":"trace[478624978] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5409; }","duration":"272.819543ms","start":"2026-10-16T14:32:14.067706Z","end":"2026-10-16T14:32:14.340525Z","steps":["trace[478624978] 'agreement among raft nodes before linearized reading' (duration: 272.603778ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:32:14.340534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:32:13.980985Z","time spent":"359.478791ms","remote":"127.0.0.1:49376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T14:32:14.340601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.392485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8bae9207-367f-4430-b9a0-d6532d240809\" ","response":"range_response_count:1 size:3893"} {"level":"info","ts":"2026-10-16T14:32:14.34063Z","caller":"traceutil/trace.go:171","msg":"trace[1181168307] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8bae9207-367f-4430-b9a0-d6532d240809; range_end:; response_count:1; response_revision:5409; }","duration":"104.435226ms","start":"2026-10-16T14:32:14.236188Z","end":"2026-10-16T14:32:14.340623Z","steps":["trace[1181168307] 'agreement among raft nodes before linearized reading' (duration: 104.341004ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:32:14.340721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.10383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-16T14:32:14.340815Z","caller":"traceutil/trace.go:171","msg":"trace[1656241973] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5409; }","duration":"113.248114ms","start":"2026-10-16T14:32:14.227544Z","end":"2026-10-16T14:32:14.340792Z","steps":["trace[1656241973] 'agreement among raft nodes before linearized reading' (duration: 112.978148ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:32:18.710064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.040331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-55wvn\" ","response":"range_response_count:1 size:6518"} {"level":"info","ts":"2026-10-16T14:32:18.710212Z","caller":"traceutil/trace.go:171","msg":"trace[498927607] range","detail":"{range_begin:/registry/pods/openstack/keepalived-55wvn; range_end:; response_count:1; response_revision:5487; }","duration":"101.223414ms","start":"2026-10-16T14:32:18.608961Z","end":"2026-10-16T14:32:18.710184Z","steps":["trace[498927607] 'range keys from in-memory index tree' (duration: 100.875186ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:32:27.531899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.748311ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-16T14:32:27.532068Z","caller":"traceutil/trace.go:171","msg":"trace[812767655] transaction","detail":"{read_only:false; response_revision:5643; number_of_response:1; }","duration":"177.219343ms","start":"2026-10-16T14:32:27.354821Z","end":"2026-10-16T14:32:27.53204Z","steps":["trace[812767655] 'process raft request' (duration: 30.245137ms)","trace[812767655] 'compare' (duration: 145.976844ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:32:27.532155Z","caller":"traceutil/trace.go:171","msg":"trace[2041017301] transaction","detail":"{read_only:false; response_revision:5644; number_of_response:1; }","duration":"163.026256ms","start":"2026-10-16T14:32:27.36908Z","end":"2026-10-16T14:32:27.532107Z","steps":["trace[2041017301] 'process raft request' (duration: 162.953344ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:32:27.532403Z","caller":"traceutil/trace.go:171","msg":"trace[1747075348] linearizableReadLoop","detail":"{readStateIndex:5860; appliedIndex:5858; }","duration":"176.905716ms","start":"2026-10-16T14:32:27.355467Z","end":"2026-10-16T14:32:27.532373Z","steps":["trace[1747075348] 'read index received' (duration: 10.466984ms)","trace[1747075348] 'applied index is now lower than readState.Index' (duration: 166.436122ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T14:32:27.532658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.158781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T14:32:27.532738Z","caller":"traceutil/trace.go:171","msg":"trace[1456851489] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5644; }","duration":"177.312095ms","start":"2026-10-16T14:32:27.355406Z","end":"2026-10-16T14:32:27.532718Z","steps":["trace[1456851489] 'agreement among raft nodes before linearized reading' (duration: 177.12274ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:32:27.549298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.437873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-16T14:32:27.549344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.735822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T14:32:27.549389Z","caller":"traceutil/trace.go:171","msg":"trace[859728631] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5645; }","duration":"179.552335ms","start":"2026-10-16T14:32:27.369811Z","end":"2026-10-16T14:32:27.549363Z","steps":["trace[859728631] 'agreement among raft nodes before linearized reading' (duration: 179.427172ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:32:27.549428Z","caller":"traceutil/trace.go:171","msg":"trace[1027598047] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:5645; }","duration":"155.885996ms","start":"2026-10-16T14:32:27.393522Z","end":"2026-10-16T14:32:27.549408Z","steps":["trace[1027598047] 'agreement among raft nodes before linearized reading' (duration: 155.714552ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:32:27.626173Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2966} {"level":"warn","ts":"2026-10-16T14:32:27.801371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.259992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:17683"} {"level":"info","ts":"2026-10-16T14:32:27.801457Z","caller":"traceutil/trace.go:171","msg":"trace[1109382801] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:5647; }","duration":"163.369974ms","start":"2026-10-16T14:32:27.638066Z","end":"2026-10-16T14:32:27.801436Z","steps":["trace[1109382801] 'range keys from in-memory index tree' (duration: 163.116538ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:32:28.045844Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2966,"took":"416.402775ms","hash":129060400,"current-db-size-bytes":55996416,"current-db-size":"56 MB","current-db-size-in-use-bytes":31555584,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-16T14:32:28.045964Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":129060400,"revision":2966,"compact-revision":-1} {"level":"warn","ts":"2026-10-16T14:32:36.39085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.49868ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-10-16T14:32:36.65591Z","caller":"traceutil/trace.go:171","msg":"trace[2046880479] transaction","detail":"{read_only:false; response_revision:5789; number_of_response:1; }","duration":"226.029576ms","start":"2026-10-16T14:32:36.429857Z","end":"2026-10-16T14:32:36.655887Z","steps":["trace[2046880479] 'process raft request' (duration: 225.843152ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:32:36.861317Z","caller":"traceutil/trace.go:171","msg":"trace[2033297937] transaction","detail":"{read_only:false; response_revision:5791; number_of_response:1; }","duration":"200.27089ms","start":"2026-10-16T14:32:36.661026Z","end":"2026-10-16T14:32:36.861297Z","steps":["trace[2033297937] 'process raft request' (duration: 200.206678ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:32:36.861413Z","caller":"traceutil/trace.go:171","msg":"trace[745810464] linearizableReadLoop","detail":"{readStateIndex:6012; appliedIndex:6009; }","duration":"108.758733ms","start":"2026-10-16T14:32:36.752643Z","end":"2026-10-16T14:32:36.861401Z","steps":["trace[745810464] 'read index received' (duration: 37.016338ms)","trace[745810464] 'applied index is now lower than readState.Index' (duration: 71.741245ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:32:36.861524Z","caller":"traceutil/trace.go:171","msg":"trace[609571883] transaction","detail":"{read_only:false; response_revision:5790; number_of_response:1; }","duration":"200.812932ms","start":"2026-10-16T14:32:36.660703Z","end":"2026-10-16T14:32:36.861516Z","steps":["trace[609571883] 'process raft request' (duration: 128.872112ms)","trace[609571883] 'compare' (duration: 71.430168ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:32:36.861552Z","caller":"traceutil/trace.go:171","msg":"trace[581831591] transaction","detail":"{read_only:false; response_revision:5792; number_of_response:1; }","duration":"200.389932ms","start":"2026-10-16T14:32:36.661154Z","end":"2026-10-16T14:32:36.861544Z","steps":["trace[581831591] 'process raft request' (duration: 200.117176ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:32:36.861682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.041799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1990"} {"level":"info","ts":"2026-10-16T14:32:36.861704Z","caller":"traceutil/trace.go:171","msg":"trace[260693801] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:5792; }","duration":"109.07417ms","start":"2026-10-16T14:32:36.752621Z","end":"2026-10-16T14:32:36.861695Z","steps":["trace[260693801] 'agreement among raft nodes before linearized reading' (duration: 108.979788ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:32:50.504576Z","caller":"traceutil/trace.go:171","msg":"trace[106232074] linearizableReadLoop","detail":"{readStateIndex:6216; appliedIndex:6215; }","duration":"133.653664ms","start":"2026-10-16T14:32:50.3709Z","end":"2026-10-16T14:32:50.504554Z","steps":["trace[106232074] 'read index received' (duration: 133.48403ms)","trace[106232074] 'applied index is now lower than readState.Index' (duration: 168.674µs)"],"step_count":2} {"level":"warn","ts":"2026-10-16T14:32:50.504744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.846079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T14:32:50.504696Z","caller":"traceutil/trace.go:171","msg":"trace[1176787123] transaction","detail":"{read_only:false; response_revision:5990; number_of_response:1; }","duration":"144.932096ms","start":"2026-10-16T14:32:50.359734Z","end":"2026-10-16T14:32:50.504666Z","steps":["trace[1176787123] 'process raft request' (duration: 144.68602ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:32:50.504891Z","caller":"traceutil/trace.go:171","msg":"trace[1230843637] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5990; }","duration":"134.074503ms","start":"2026-10-16T14:32:50.370801Z","end":"2026-10-16T14:32:50.504875Z","steps":["trace[1230843637] 'agreement among raft nodes before linearized reading' (duration: 133.887449ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:32:50.612079Z","caller":"traceutil/trace.go:171","msg":"trace[487882911] transaction","detail":"{read_only:false; response_revision:5991; number_of_response:1; }","duration":"101.262321ms","start":"2026-10-16T14:32:50.510761Z","end":"2026-10-16T14:32:50.612023Z","steps":["trace[487882911] 'process raft request' (duration: 14.559695ms)","trace[487882911] 'compare' (duration: 86.47785ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:32:50.629215Z","caller":"traceutil/trace.go:171","msg":"trace[722571631] transaction","detail":"{read_only:false; response_revision:5992; number_of_response:1; }","duration":"118.375263ms","start":"2026-10-16T14:32:50.510813Z","end":"2026-10-16T14:32:50.629188Z","steps":["trace[722571631] 'process raft request' (duration: 118.128287ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:32:50.629218Z","caller":"traceutil/trace.go:171","msg":"trace[1575461130] transaction","detail":"{read_only:false; response_revision:5993; number_of_response:1; }","duration":"118.381503ms","start":"2026-10-16T14:32:50.510813Z","end":"2026-10-16T14:32:50.629195Z","steps":["trace[1575461130] 'process raft request' (duration: 118.24477ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:32:50.629377Z","caller":"traceutil/trace.go:171","msg":"trace[1851923438] transaction","detail":"{read_only:false; response_revision:5994; number_of_response:1; }","duration":"118.452935ms","start":"2026-10-16T14:32:50.510901Z","end":"2026-10-16T14:32:50.629354Z","steps":["trace[1851923438] 'process raft request' (duration: 118.253ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:33:41.176198Z","caller":"traceutil/trace.go:171","msg":"trace[327990938] transaction","detail":"{read_only:false; response_revision:6396; number_of_response:1; }","duration":"208.142758ms","start":"2026-10-16T14:33:40.968016Z","end":"2026-10-16T14:33:41.176158Z","steps":["trace[327990938] 'process raft request' (duration: 164.247118ms)","trace[327990938] 'compare' (duration: 43.578083ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:37:27.633959Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5643} {"level":"info","ts":"2026-10-16T14:37:27.726514Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5643,"took":"90.593048ms","hash":352045675,"current-db-size-bytes":55996416,"current-db-size":"56 MB","current-db-size-in-use-bytes":28508160,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-16T14:37:27.726606Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":352045675,"revision":5643,"compact-revision":2966} {"level":"warn","ts":"2026-10-16T14:38:16.948746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.158415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T14:38:16.948872Z","caller":"traceutil/trace.go:171","msg":"trace[1583428088] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:8317; }","duration":"100.36605ms","start":"2026-10-16T14:38:16.848481Z","end":"2026-10-16T14:38:16.948847Z","steps":["trace[1583428088] 'count revisions from in-memory index tree' (duration: 100.054153ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:38:16.949607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.943592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-16T14:38:16.949733Z","caller":"traceutil/trace.go:171","msg":"trace[994978674] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8317; }","duration":"135.124106ms","start":"2026-10-16T14:38:16.81458Z","end":"2026-10-16T14:38:16.949704Z","steps":["trace[994978674] 'range keys from in-memory index tree' (duration: 134.775148ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:39:17.339014Z","caller":"traceutil/trace.go:171","msg":"trace[1986630192] transaction","detail":"{read_only:false; response_revision:8831; number_of_response:1; }","duration":"281.74174ms","start":"2026-10-16T14:39:17.057243Z","end":"2026-10-16T14:39:17.338985Z","steps":["trace[1986630192] 'process raft request' (duration: 281.498514ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:39:17.526176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.536949ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-16T14:39:17.526348Z","caller":"traceutil/trace.go:171","msg":"trace[379384244] linearizableReadLoop","detail":"{readStateIndex:9151; appliedIndex:9149; }","duration":"338.916415ms","start":"2026-10-16T14:39:17.187407Z","end":"2026-10-16T14:39:17.526323Z","steps":["trace[379384244] 'read index received' (duration: 151.324978ms)","trace[379384244] 'applied index is now lower than readState.Index' (duration: 187.589747ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:39:17.526548Z","caller":"traceutil/trace.go:171","msg":"trace[413113748] transaction","detail":"{read_only:false; response_revision:8832; number_of_response:1; }","duration":"466.292009ms","start":"2026-10-16T14:39:17.060137Z","end":"2026-10-16T14:39:17.526429Z","steps":["trace[413113748] 'process raft request' (duration: 311.375081ms)","trace[413113748] 'compare' (duration: 154.402036ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T14:39:17.526765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:39:17.060128Z","time spent":"466.511805ms","remote":"127.0.0.1:49376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T14:39:17.526587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.186792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-10-16T14:39:17.526899Z","caller":"traceutil/trace.go:171","msg":"trace[821269975] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8832; }","duration":"339.54124ms","start":"2026-10-16T14:39:17.187336Z","end":"2026-10-16T14:39:17.526878Z","steps":["trace[821269975] 'agreement among raft nodes before linearized reading' (duration: 339.08263ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:39:17.526964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:39:17.187306Z","time spent":"339.638692ms","remote":"127.0.0.1:49302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12236,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-10-16T14:39:17.530045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.91125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T14:39:17.530109Z","caller":"traceutil/trace.go:171","msg":"trace[1527032996] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:8833; }","duration":"259.020862ms","start":"2026-10-16T14:39:17.27107Z","end":"2026-10-16T14:39:17.530091Z","steps":["trace[1527032996] 'agreement among raft nodes before linearized reading' (duration: 258.90184ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:39:17.530295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.642743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-10-16T14:39:17.530352Z","caller":"traceutil/trace.go:171","msg":"trace[1351410562] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8833; }","duration":"120.714185ms","start":"2026-10-16T14:39:17.409624Z","end":"2026-10-16T14:39:17.530339Z","steps":["trace[1351410562] 'agreement among raft nodes before linearized reading' (duration: 120.516291ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:39:17.530388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.311358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T14:39:17.530432Z","caller":"traceutil/trace.go:171","msg":"trace[793112228] range","detail":"{range_begin:/registry/monitoring.coreos.com/scrapeconfigs/; range_end:/registry/monitoring.coreos.com/scrapeconfigs0; response_count:0; response_revision:8833; }","duration":"121.38995ms","start":"2026-10-16T14:39:17.40903Z","end":"2026-10-16T14:39:17.530419Z","steps":["trace[793112228] 'agreement among raft nodes before linearized reading' (duration: 121.319378ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:39:17.530623Z","caller":"traceutil/trace.go:171","msg":"trace[719469918] transaction","detail":"{read_only:false; response_revision:8833; number_of_response:1; }","duration":"170.149338ms","start":"2026-10-16T14:39:17.360455Z","end":"2026-10-16T14:39:17.530604Z","steps":["trace[719469918] 'process raft request' (duration: 169.405351ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:39:17.530689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.101089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T14:39:17.530723Z","caller":"traceutil/trace.go:171","msg":"trace[1473795940] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8833; }","duration":"162.18359ms","start":"2026-10-16T14:39:17.368529Z","end":"2026-10-16T14:39:17.530712Z","steps":["trace[1473795940] 'agreement among raft nodes before linearized reading' (duration: 162.132059ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:39:17.530892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.623501ms","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-10-16T14:39:17.530927Z","caller":"traceutil/trace.go:171","msg":"trace[1156366096] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8833; }","duration":"102.709243ms","start":"2026-10-16T14:39:17.428208Z","end":"2026-10-16T14:39:17.530917Z","steps":["trace[1156366096] 'agreement among raft nodes before linearized reading' (duration: 102.606831ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:39:17.530945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.938946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-16T14:39:17.530976Z","caller":"traceutil/trace.go:171","msg":"trace[103116467] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8833; }","duration":"210.008128ms","start":"2026-10-16T14:39:17.320959Z","end":"2026-10-16T14:39:17.530967Z","steps":["trace[103116467] 'agreement among raft nodes before linearized reading' (duration: 209.906796ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:39:17.531113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.295924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-16T14:39:17.531153Z","caller":"traceutil/trace.go:171","msg":"trace[956103997] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8833; }","duration":"111.367706ms","start":"2026-10-16T14:39:17.419773Z","end":"2026-10-16T14:39:17.531141Z","steps":["trace[956103997] 'agreement among raft nodes before linearized reading' (duration: 111.274374ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:39:17.531171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.960799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-10-16T14:39:17.531223Z","caller":"traceutil/trace.go:171","msg":"trace[2061331363] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:8833; }","duration":"254.064802ms","start":"2026-10-16T14:39:17.277149Z","end":"2026-10-16T14:39:17.531214Z","steps":["trace[2061331363] 'agreement among raft nodes before linearized reading' (duration: 253.955699ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:39:17.53146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.092074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-16T14:39:17.531511Z","caller":"traceutil/trace.go:171","msg":"trace[1523348951] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8833; }","duration":"117.140725ms","start":"2026-10-16T14:39:17.414356Z","end":"2026-10-16T14:39:17.531497Z","steps":["trace[1523348951] 'agreement among raft nodes before linearized reading' (duration: 117.023512ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:39:27.809037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.113957ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-16T14:39:27.80933Z","caller":"traceutil/trace.go:171","msg":"trace[218126848] transaction","detail":"{read_only:false; response_revision:8885; number_of_response:1; }","duration":"139.640627ms","start":"2026-10-16T14:39:27.669639Z","end":"2026-10-16T14:39:27.809279Z","steps":["trace[218126848] 'compare' (duration: 130.937343ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:39:27.809374Z","caller":"traceutil/trace.go:171","msg":"trace[828944127] transaction","detail":"{read_only:false; response_revision:8886; number_of_response:1; }","duration":"139.690129ms","start":"2026-10-16T14:39:27.669653Z","end":"2026-10-16T14:39:27.809343Z","steps":["trace[828944127] 'process raft request' (duration: 139.533295ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:39:27.809367Z","caller":"traceutil/trace.go:171","msg":"trace[1323037833] linearizableReadLoop","detail":"{readStateIndex:9207; appliedIndex:9205; }","duration":"130.310779ms","start":"2026-10-16T14:39:27.679022Z","end":"2026-10-16T14:39:27.809332Z","steps":["trace[1323037833] 'read index received' (duration: 109.31163ms)","trace[1323037833] 'applied index is now lower than readState.Index' (duration: 20.997368ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T14:39:27.809569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.539745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-16T14:39:27.809627Z","caller":"traceutil/trace.go:171","msg":"trace[1383821429] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8886; }","duration":"130.627666ms","start":"2026-10-16T14:39:27.678986Z","end":"2026-10-16T14:39:27.809614Z","steps":["trace[1383821429] 'agreement among raft nodes before linearized reading' (duration: 130.432691ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:39:27.80973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.173152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-16T14:39:27.80977Z","caller":"traceutil/trace.go:171","msg":"trace[420361010] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8886; }","duration":"124.250594ms","start":"2026-10-16T14:39:27.685509Z","end":"2026-10-16T14:39:27.809759Z","steps":["trace[420361010] 'agreement among raft nodes before linearized reading' (duration: 124.095011ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:39:27.809836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.081798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-55wvn\" ","response":"range_response_count:1 size:6518"} {"level":"info","ts":"2026-10-16T14:39:27.809901Z","caller":"traceutil/trace.go:171","msg":"trace[714453324] range","detail":"{range_begin:/registry/pods/openstack/keepalived-55wvn; range_end:; response_count:1; response_revision:8886; }","duration":"106.1816ms","start":"2026-10-16T14:39:27.703702Z","end":"2026-10-16T14:39:27.809884Z","steps":["trace[714453324] 'agreement among raft nodes before linearized reading' (duration: 105.966945ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:39:27.810676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.0574ms","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-10-16T14:39:27.810784Z","caller":"traceutil/trace.go:171","msg":"trace[312522667] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8886; }","duration":"124.168162ms","start":"2026-10-16T14:39:27.686568Z","end":"2026-10-16T14:39:27.810737Z","steps":["trace[312522667] 'agreement among raft nodes before linearized reading' (duration: 123.259682ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:39:29.530779Z","caller":"traceutil/trace.go:171","msg":"trace[218811904] transaction","detail":"{read_only:false; response_revision:8891; number_of_response:1; }","duration":"143.37172ms","start":"2026-10-16T14:39:29.387371Z","end":"2026-10-16T14:39:29.530743Z","steps":["trace[218811904] 'process raft request' (duration: 143.125445ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:39:29.736082Z","caller":"traceutil/trace.go:171","msg":"trace[228208829] transaction","detail":"{read_only:false; response_revision:8892; number_of_response:1; }","duration":"188.479007ms","start":"2026-10-16T14:39:29.547565Z","end":"2026-10-16T14:39:29.736044Z","steps":["trace[228208829] 'process raft request' (duration: 188.263412ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:39:29.763201Z","caller":"traceutil/trace.go:171","msg":"trace[1127176322] transaction","detail":"{read_only:false; response_revision:8893; number_of_response:1; }","duration":"155.4421ms","start":"2026-10-16T14:39:29.607732Z","end":"2026-10-16T14:39:29.763174Z","steps":["trace[1127176322] 'process raft request' (duration: 155.235875ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:39:29.763543Z","caller":"traceutil/trace.go:171","msg":"trace[1413965637] transaction","detail":"{read_only:false; response_revision:8894; number_of_response:1; }","duration":"150.330726ms","start":"2026-10-16T14:39:29.613201Z","end":"2026-10-16T14:39:29.763532Z","steps":["trace[1413965637] 'process raft request' (duration: 149.900126ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:39:29.763603Z","caller":"traceutil/trace.go:171","msg":"trace[1905426530] transaction","detail":"{read_only:false; response_revision:8895; number_of_response:1; }","duration":"134.574575ms","start":"2026-10-16T14:39:29.628989Z","end":"2026-10-16T14:39:29.763563Z","steps":["trace[1905426530] 'process raft request' (duration: 134.452042ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:39:30.197111Z","caller":"traceutil/trace.go:171","msg":"trace[137183890] transaction","detail":"{read_only:false; response_revision:8901; number_of_response:1; }","duration":"153.043886ms","start":"2026-10-16T14:39:30.044022Z","end":"2026-10-16T14:39:30.197065Z","steps":["trace[137183890] 'process raft request' (duration: 73.423109ms)","trace[137183890] 'compare' (duration: 79.259629ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:39:55.433498Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000249c.wal"} {"level":"info","ts":"2026-10-16T14:39:56.863974Z","caller":"traceutil/trace.go:171","msg":"trace[1689097794] transaction","detail":"{read_only:false; response_revision:9095; number_of_response:1; }","duration":"116.03304ms","start":"2026-10-16T14:39:56.747909Z","end":"2026-10-16T14:39:56.863942Z","steps":["trace[1689097794] 'process raft request' (duration: 115.889057ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:39:56.864036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.720674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/rook-ceph/rook-ceph-csi-config\" ","response":"range_response_count:1 size:512"} {"level":"info","ts":"2026-10-16T14:39:56.86415Z","caller":"traceutil/trace.go:171","msg":"trace[1263673854] range","detail":"{range_begin:/registry/configmaps/rook-ceph/rook-ceph-csi-config; range_end:; response_count:1; response_revision:9094; }","duration":"154.907708ms","start":"2026-10-16T14:39:56.709217Z","end":"2026-10-16T14:39:56.864125Z","steps":["trace[1263673854] 'range keys from in-memory index tree' (duration: 154.472859ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:39:59.896813Z","caller":"traceutil/trace.go:171","msg":"trace[498058245] transaction","detail":"{read_only:false; response_revision:9108; number_of_response:1; }","duration":"203.80652ms","start":"2026-10-16T14:39:59.692971Z","end":"2026-10-16T14:39:59.896777Z","steps":["trace[498058245] 'process raft request' (duration: 193.811597ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:40:02.698752Z","caller":"traceutil/trace.go:171","msg":"trace[274168557] transaction","detail":"{read_only:false; response_revision:9129; number_of_response:1; }","duration":"110.277462ms","start":"2026-10-16T14:40:02.588419Z","end":"2026-10-16T14:40:02.698697Z","steps":["trace[274168557] 'process raft request' (duration: 109.974995ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:40:11.542528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.524527ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-16T14:40:11.542924Z","caller":"traceutil/trace.go:171","msg":"trace[1729626586] transaction","detail":"{read_only:false; response_revision:9173; number_of_response:1; }","duration":"377.18687ms","start":"2026-10-16T14:40:11.16569Z","end":"2026-10-16T14:40:11.542877Z","steps":["trace[1729626586] 'process raft request' (duration: 180.171692ms)","trace[1729626586] 'compare' (duration: 196.040356ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:40:11.542871Z","caller":"traceutil/trace.go:171","msg":"trace[2140873997] linearizableReadLoop","detail":"{readStateIndex:9506; appliedIndex:9505; }","duration":"353.106262ms","start":"2026-10-16T14:40:11.189741Z","end":"2026-10-16T14:40:11.542847Z","steps":["trace[2140873997] 'read index received' (duration: 155.934191ms)","trace[2140873997] 'applied index is now lower than readState.Index' (duration: 197.170251ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T14:40:11.543098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:40:11.165658Z","time spent":"377.346784ms","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T14:40:11.543165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.563137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T14:40:11.543209Z","caller":"traceutil/trace.go:171","msg":"trace[660208020] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:9173; }","duration":"329.652459ms","start":"2026-10-16T14:40:11.213544Z","end":"2026-10-16T14:40:11.543196Z","steps":["trace[660208020] 'agreement among raft nodes before linearized reading' (duration: 329.579637ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:40:11.543241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:40:11.213509Z","time spent":"329.723711ms","remote":"127.0.0.1:52830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true "} {"level":"warn","ts":"2026-10-16T14:40:11.543417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.005743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T14:40:11.543457Z","caller":"traceutil/trace.go:171","msg":"trace[782092847] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:9173; }","duration":"126.067614ms","start":"2026-10-16T14:40:11.417375Z","end":"2026-10-16T14:40:11.543443Z","steps":["trace[782092847] 'agreement among raft nodes before linearized reading' (duration: 126.000403ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:40:11.5435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.629196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T14:40:11.543549Z","caller":"traceutil/trace.go:171","msg":"trace[1276583434] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9173; }","duration":"173.720058ms","start":"2026-10-16T14:40:11.369814Z","end":"2026-10-16T14:40:11.543534Z","steps":["trace[1276583434] 'agreement among raft nodes before linearized reading' (duration: 173.644156ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:40:11.543652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.89572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-16T14:40:11.543909Z","caller":"traceutil/trace.go:171","msg":"trace[106776524] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:9173; }","duration":"354.158316ms","start":"2026-10-16T14:40:11.189709Z","end":"2026-10-16T14:40:11.543867Z","steps":["trace[106776524] 'agreement among raft nodes before linearized reading' (duration: 353.255276ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:40:11.544177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:40:11.189699Z","time spent":"354.454632ms","remote":"127.0.0.1:49198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":888,"response size":32,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-10-16T14:40:12.203928Z","caller":"traceutil/trace.go:171","msg":"trace[1431931441] transaction","detail":"{read_only:false; response_revision:9175; number_of_response:1; }","duration":"226.887995ms","start":"2026-10-16T14:40:11.977013Z","end":"2026-10-16T14:40:12.2039Z","steps":["trace[1431931441] 'process raft request' (duration: 226.66445ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:40:12.20406Z","caller":"traceutil/trace.go:171","msg":"trace[1131081965] transaction","detail":"{read_only:false; response_revision:9176; number_of_response:1; }","duration":"226.886595ms","start":"2026-10-16T14:40:11.977157Z","end":"2026-10-16T14:40:12.204044Z","steps":["trace[1131081965] 'process raft request' (duration: 226.699871ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:40:12.625053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.132173ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-16T14:40:12.625273Z","caller":"traceutil/trace.go:171","msg":"trace[1821744345] transaction","detail":"{read_only:false; response_revision:9179; number_of_response:1; }","duration":"349.040752ms","start":"2026-10-16T14:40:12.276199Z","end":"2026-10-16T14:40:12.62524Z","steps":["trace[1821744345] 'process raft request' (duration: 51.595602ms)","trace[1821744345] 'compare' (duration: 297.00577ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:40:12.625344Z","caller":"traceutil/trace.go:171","msg":"trace[1191293842] linearizableReadLoop","detail":"{readStateIndex:9513; appliedIndex:9511; }","duration":"348.869168ms","start":"2026-10-16T14:40:12.276427Z","end":"2026-10-16T14:40:12.625296Z","steps":["trace[1191293842] 'read index received' (duration: 51.380707ms)","trace[1191293842] 'applied index is now lower than readState.Index' (duration: 297.487411ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T14:40:12.625428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:40:12.276176Z","time spent":"349.194835ms","remote":"127.0.0.1:49376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T14:40:12.625447Z","caller":"traceutil/trace.go:171","msg":"trace[298837531] transaction","detail":"{read_only:false; response_revision:9180; number_of_response:1; }","duration":"349.171075ms","start":"2026-10-16T14:40:12.276254Z","end":"2026-10-16T14:40:12.625425Z","steps":["trace[298837531] 'process raft request' (duration: 348.916449ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:40:12.625544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:40:12.276243Z","time spent":"349.249287ms","remote":"127.0.0.1:49376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T14:40:12.625529Z","caller":"traceutil/trace.go:171","msg":"trace[980039484] transaction","detail":"{read_only:false; response_revision:9181; number_of_response:1; }","duration":"349.042032ms","start":"2026-10-16T14:40:12.276431Z","end":"2026-10-16T14:40:12.625473Z","steps":["trace[980039484] 'process raft request' (duration: 348.815197ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:40:12.625749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:40:12.276395Z","time spent":"349.237967ms","remote":"127.0.0.1:49376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T14:40:12.62583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.39197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228863"} {"level":"info","ts":"2026-10-16T14:40:12.625898Z","caller":"traceutil/trace.go:171","msg":"trace[2017262657] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:9181; }","duration":"349.462591ms","start":"2026-10-16T14:40:12.276423Z","end":"2026-10-16T14:40:12.625886Z","steps":["trace[2017262657] 'agreement among raft nodes before linearized reading' (duration: 348.96424ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:40:12.625929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:40:12.276387Z","time spent":"349.533433ms","remote":"127.0.0.1:49302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":26,"response size":228887,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-10-16T14:40:12.636035Z","caller":"traceutil/trace.go:171","msg":"trace[1044139957] transaction","detail":"{read_only:false; response_revision:9182; number_of_response:1; }","duration":"288.46341ms","start":"2026-10-16T14:40:12.347516Z","end":"2026-10-16T14:40:12.635979Z","steps":["trace[1044139957] 'process raft request' (duration: 288.075381ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:40:13.827823Z","caller":"traceutil/trace.go:171","msg":"trace[543421344] linearizableReadLoop","detail":"{readStateIndex:9528; appliedIndex:9526; }","duration":"132.415166ms","start":"2026-10-16T14:40:13.69537Z","end":"2026-10-16T14:40:13.827786Z","steps":["trace[543421344] 'read index received' (duration: 22.767808ms)","trace[543421344] 'applied index is now lower than readState.Index' (duration: 109.645448ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T14:40:13.828268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.866566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:20182"} {"level":"info","ts":"2026-10-16T14:40:13.828408Z","caller":"traceutil/trace.go:171","msg":"trace[2101372518] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:9194; }","duration":"132.979269ms","start":"2026-10-16T14:40:13.695331Z","end":"2026-10-16T14:40:13.82831Z","steps":["trace[2101372518] 'agreement among raft nodes before linearized reading' (duration: 132.58424ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:40:13.828975Z","caller":"traceutil/trace.go:171","msg":"trace[987941219] transaction","detail":"{read_only:false; response_revision:9194; number_of_response:1; }","duration":"139.854472ms","start":"2026-10-16T14:40:13.689103Z","end":"2026-10-16T14:40:13.828958Z","steps":["trace[987941219] 'process raft request' (duration: 138.460771ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:40:26.868097Z","caller":"traceutil/trace.go:171","msg":"trace[990627274] linearizableReadLoop","detail":"{readStateIndex:9673; appliedIndex:9671; }","duration":"146.985681ms","start":"2026-10-16T14:40:26.721078Z","end":"2026-10-16T14:40:26.868064Z","steps":["trace[990627274] 'read index received' (duration: 7.662311ms)","trace[990627274] 'applied index is now lower than readState.Index' (duration: 139.32209ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:40:26.868265Z","caller":"traceutil/trace.go:171","msg":"trace[2002455783] transaction","detail":"{read_only:false; response_revision:9332; number_of_response:1; }","duration":"155.188125ms","start":"2026-10-16T14:40:26.713032Z","end":"2026-10-16T14:40:26.86822Z","steps":["trace[2002455783] 'process raft request' (duration: 154.247674ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:40:26.868573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.467362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-55wvn\" ","response":"range_response_count:1 size:6518"} {"level":"info","ts":"2026-10-16T14:40:26.868652Z","caller":"traceutil/trace.go:171","msg":"trace[1278257825] range","detail":"{range_begin:/registry/pods/openstack/keepalived-55wvn; range_end:; response_count:1; response_revision:9332; }","duration":"147.611305ms","start":"2026-10-16T14:40:26.721019Z","end":"2026-10-16T14:40:26.86863Z","steps":["trace[1278257825] 'agreement among raft nodes before linearized reading' (duration: 147.173725ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:41:04.931656Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"8a5b61ed9120d6b3","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-16T14:41:04.939183Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-16T14:41:04.939345Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-16T14:41:42.538698Z","caller":"traceutil/trace.go:171","msg":"trace[1580894858] transaction","detail":"{read_only:false; response_revision:10042; number_of_response:1; }","duration":"127.429796ms","start":"2026-10-16T14:41:42.411238Z","end":"2026-10-16T14:41:42.538668Z","steps":["trace[1580894858] 'process raft request' (duration: 127.024257ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:41:42.772865Z","caller":"traceutil/trace.go:171","msg":"trace[843727320] transaction","detail":"{read_only:false; response_revision:10044; number_of_response:1; }","duration":"151.980545ms","start":"2026-10-16T14:41:42.62085Z","end":"2026-10-16T14:41:42.772831Z","steps":["trace[843727320] 'process raft request' (duration: 61.097194ms)","trace[843727320] 'compare' (duration: 90.703206ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:41:42.836563Z","caller":"traceutil/trace.go:171","msg":"trace[13049367] transaction","detail":"{read_only:false; response_revision:10045; number_of_response:1; }","duration":"188.410068ms","start":"2026-10-16T14:41:42.648123Z","end":"2026-10-16T14:41:42.836533Z","steps":["trace[13049367] 'process raft request' (duration: 187.704863ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:41:42.96855Z","caller":"traceutil/trace.go:171","msg":"trace[1484021908] transaction","detail":"{read_only:false; response_revision:10046; number_of_response:1; }","duration":"126.278341ms","start":"2026-10-16T14:41:42.842246Z","end":"2026-10-16T14:41:42.968524Z","steps":["trace[1484021908] 'process raft request' (duration: 126.122057ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:41:43.059956Z","caller":"traceutil/trace.go:171","msg":"trace[437218269] transaction","detail":"{read_only:false; response_revision:10047; number_of_response:1; }","duration":"217.206692ms","start":"2026-10-16T14:41:42.842711Z","end":"2026-10-16T14:41:43.059917Z","steps":["trace[437218269] 'process raft request' (duration: 216.999947ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:42:27.641366Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7978} {"level":"info","ts":"2026-10-16T14:42:27.731702Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7978,"took":"87.30503ms","hash":2260209177,"current-db-size-bytes":55996416,"current-db-size":"56 MB","current-db-size-in-use-bytes":25194496,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-10-16T14:42:27.731771Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2260209177,"revision":7978,"compact-revision":5643} {"level":"warn","ts":"2026-10-16T14:47:27.495296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.46844ms","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-10-16T14:47:27.495418Z","caller":"traceutil/trace.go:171","msg":"trace[1209163229] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:12503; }","duration":"206.644824ms","start":"2026-10-16T14:47:27.288745Z","end":"2026-10-16T14:47:27.49539Z","steps":["trace[1209163229] 'range keys from in-memory index tree' (duration: 206.176923ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:47:27.495445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.882767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-16T14:47:27.495582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.528405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-16T14:47:27.495588Z","caller":"traceutil/trace.go:171","msg":"trace[974624052] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:12503; }","duration":"126.083101ms","start":"2026-10-16T14:47:27.369473Z","end":"2026-10-16T14:47:27.495556Z","steps":["trace[974624052] 'range keys from in-memory index tree' (duration: 125.696563ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:47:27.495643Z","caller":"traceutil/trace.go:171","msg":"trace[1412368958] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:12503; }","duration":"220.642857ms","start":"2026-10-16T14:47:27.274986Z","end":"2026-10-16T14:47:27.495629Z","steps":["trace[1412368958] 'range keys from in-memory index tree' (duration: 220.31381ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:47:27.495744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.971286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-16T14:47:27.495906Z","caller":"traceutil/trace.go:171","msg":"trace[1358384703] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:12503; }","duration":"188.208532ms","start":"2026-10-16T14:47:27.307667Z","end":"2026-10-16T14:47:27.495875Z","steps":["trace[1358384703] 'range keys from in-memory index tree' (duration: 187.682379ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:47:27.575668Z","caller":"traceutil/trace.go:171","msg":"trace[712834242] linearizableReadLoop","detail":"{readStateIndex:12943; appliedIndex:12942; }","duration":"136.73904ms","start":"2026-10-16T14:47:27.438898Z","end":"2026-10-16T14:47:27.575637Z","steps":["trace[712834242] 'read index received' (duration: 136.500824ms)","trace[712834242] 'applied index is now lower than readState.Index' (duration: 237.026µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:47:27.57573Z","caller":"traceutil/trace.go:171","msg":"trace[1573393924] transaction","detail":"{read_only:false; response_revision:12504; number_of_response:1; }","duration":"195.621888ms","start":"2026-10-16T14:47:27.380073Z","end":"2026-10-16T14:47:27.575694Z","steps":["trace[1573393924] 'process raft request' (duration: 195.359602ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:47:27.575864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.911284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T14:47:27.575909Z","caller":"traceutil/trace.go:171","msg":"trace[1287371848] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:12504; }","duration":"137.071827ms","start":"2026-10-16T14:47:27.438821Z","end":"2026-10-16T14:47:27.575893Z","steps":["trace[1287371848] 'agreement among raft nodes before linearized reading' (duration: 136.933364ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:47:27.650654Z","caller":"traceutil/trace.go:171","msg":"trace[1229640028] transaction","detail":"{read_only:false; response_revision:12505; number_of_response:1; }","duration":"149.292991ms","start":"2026-10-16T14:47:27.501323Z","end":"2026-10-16T14:47:27.650616Z","steps":["trace[1229640028] 'process raft request' (duration: 113.410378ms)","trace[1229640028] 'compare' (duration: 35.555525ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:47:27.651053Z","caller":"traceutil/trace.go:171","msg":"trace[2062224920] transaction","detail":"{read_only:false; response_revision:12506; number_of_response:1; }","duration":"149.258591ms","start":"2026-10-16T14:47:27.50176Z","end":"2026-10-16T14:47:27.651018Z","steps":["trace[2062224920] 'process raft request' (duration: 148.78738ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:47:27.651278Z","caller":"traceutil/trace.go:171","msg":"trace[249117643] transaction","detail":"{read_only:false; response_revision:12507; number_of_response:1; }","duration":"149.276201ms","start":"2026-10-16T14:47:27.501978Z","end":"2026-10-16T14:47:27.651255Z","steps":["trace[249117643] 'process raft request' (duration: 148.852101ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:47:27.809048Z","caller":"traceutil/trace.go:171","msg":"trace[1395010378] compact","detail":"{revision:10374; response_revision:12508; }","duration":"118.709596ms","start":"2026-10-16T14:47:27.69031Z","end":"2026-10-16T14:47:27.80902Z","steps":["trace[1395010378] 'process raft request' (duration: 54.356696ms)","trace[1395010378] 'check and update compact revision' (duration: 64.231008ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:47:27.809159Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10374} {"level":"info","ts":"2026-10-16T14:47:28.144085Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10374,"took":"332.363378ms","hash":1459446474,"current-db-size-bytes":55996416,"current-db-size":"56 MB","current-db-size-in-use-bytes":25411584,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-10-16T14:47:28.144176Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1459446474,"revision":10374,"compact-revision":7978} {"level":"info","ts":"2026-10-16T14:52:27.816328Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12508} {"level":"info","ts":"2026-10-16T14:52:27.902682Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12508,"took":"83.966007ms","hash":889697041,"current-db-size-bytes":55996416,"current-db-size":"56 MB","current-db-size-in-use-bytes":28078080,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-16T14:52:27.902769Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":889697041,"revision":12508,"compact-revision":10374} {"level":"info","ts":"2026-10-16T14:53:22.480546Z","caller":"traceutil/trace.go:171","msg":"trace[1364407438] transaction","detail":"{read_only:false; response_revision:14922; number_of_response:1; }","duration":"123.122552ms","start":"2026-10-16T14:53:22.35739Z","end":"2026-10-16T14:53:22.480513Z","steps":["trace[1364407438] 'process raft request' (duration: 122.916418ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:53:25.606334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.701906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T14:53:25.606429Z","caller":"traceutil/trace.go:171","msg":"trace[2031512743] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:14934; }","duration":"115.85809ms","start":"2026-10-16T14:53:25.49055Z","end":"2026-10-16T14:53:25.606408Z","steps":["trace[2031512743] 'count revisions from in-memory index tree' (duration: 115.604585ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:54:03.903972Z","caller":"traceutil/trace.go:171","msg":"trace[570196419] transaction","detail":"{read_only:false; response_revision:15155; number_of_response:1; }","duration":"233.320876ms","start":"2026-10-16T14:54:03.670632Z","end":"2026-10-16T14:54:03.903953Z","steps":["trace[570196419] 'process raft request' (duration: 233.186623ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:54:03.912494Z","caller":"traceutil/trace.go:171","msg":"trace[779144284] transaction","detail":"{read_only:false; response_revision:15156; number_of_response:1; }","duration":"104.983037ms","start":"2026-10-16T14:54:03.807489Z","end":"2026-10-16T14:54:03.912472Z","steps":["trace[779144284] 'process raft request' (duration: 104.758132ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:54:04.768913Z","caller":"traceutil/trace.go:171","msg":"trace[1543613551] transaction","detail":"{read_only:false; response_revision:15162; number_of_response:1; }","duration":"133.044674ms","start":"2026-10-16T14:54:04.635846Z","end":"2026-10-16T14:54:04.768891Z","steps":["trace[1543613551] 'process raft request' (duration: 132.903131ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:54:05.006803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.923469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-16T14:54:05.006932Z","caller":"traceutil/trace.go:171","msg":"trace[454498] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; response_count:0; response_revision:15162; }","duration":"115.104104ms","start":"2026-10-16T14:54:04.891797Z","end":"2026-10-16T14:54:05.006901Z","steps":["trace[454498] 'count revisions from in-memory index tree' (duration: 114.843698ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:54:15.125927Z","caller":"traceutil/trace.go:171","msg":"trace[583840048] transaction","detail":"{read_only:false; response_revision:15332; number_of_response:1; }","duration":"282.727001ms","start":"2026-10-16T14:54:14.843165Z","end":"2026-10-16T14:54:15.125892Z","steps":["trace[583840048] 'process raft request' (duration: 282.473476ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:54:35.046112Z","caller":"traceutil/trace.go:171","msg":"trace[1094123736] transaction","detail":"{read_only:false; response_revision:15659; number_of_response:1; }","duration":"125.475258ms","start":"2026-10-16T14:54:34.920601Z","end":"2026-10-16T14:54:35.046077Z","steps":["trace[1094123736] 'process raft request' (duration: 125.238933ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:55:22.403996Z","caller":"traceutil/trace.go:171","msg":"trace[395021202] linearizableReadLoop","detail":"{readStateIndex:16814; appliedIndex:16812; }","duration":"174.289081ms","start":"2026-10-16T14:55:22.229671Z","end":"2026-10-16T14:55:22.40396Z","steps":["trace[395021202] 'read index received' (duration: 13.074852ms)","trace[395021202] 'applied index is now lower than readState.Index' (duration: 161.212379ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:55:22.404069Z","caller":"traceutil/trace.go:171","msg":"trace[1244378566] transaction","detail":"{read_only:false; response_revision:16247; number_of_response:1; }","duration":"192.615321ms","start":"2026-10-16T14:55:22.21142Z","end":"2026-10-16T14:55:22.404035Z","steps":["trace[1244378566] 'process raft request' (duration: 191.328102ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:55:22.404412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.713291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-tr4mw\" ","response":"range_response_count:1 size:9795"} {"level":"warn","ts":"2026-10-16T14:55:22.404454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.029233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"warn","ts":"2026-10-16T14:55:22.404441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.73775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T14:55:22.404496Z","caller":"traceutil/trace.go:171","msg":"trace[1036828694] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16247; }","duration":"173.140155ms","start":"2026-10-16T14:55:22.231342Z","end":"2026-10-16T14:55:22.404482Z","steps":["trace[1036828694] 'agreement among raft nodes before linearized reading' (duration: 172.949561ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:55:22.40449Z","caller":"traceutil/trace.go:171","msg":"trace[1776151558] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-tr4mw; range_end:; response_count:1; response_revision:16247; }","duration":"174.881814ms","start":"2026-10-16T14:55:22.229582Z","end":"2026-10-16T14:55:22.404464Z","steps":["trace[1776151558] 'agreement among raft nodes before linearized reading' (duration: 174.580597ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:55:22.404531Z","caller":"traceutil/trace.go:171","msg":"trace[437603424] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:16247; }","duration":"106.902313ms","start":"2026-10-16T14:55:22.297609Z","end":"2026-10-16T14:55:22.404512Z","steps":["trace[437603424] 'agreement among raft nodes before linearized reading' (duration: 106.733029ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:55:22.404494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.721451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T14:55:22.40463Z","caller":"traceutil/trace.go:171","msg":"trace[253081702] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:16247; }","duration":"103.915726ms","start":"2026-10-16T14:55:22.300683Z","end":"2026-10-16T14:55:22.404599Z","steps":["trace[253081702] 'agreement among raft nodes before linearized reading' (duration: 103.6534ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:55:22.404789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.084387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T14:55:22.404829Z","caller":"traceutil/trace.go:171","msg":"trace[1519662] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:16247; }","duration":"107.122438ms","start":"2026-10-16T14:55:22.297695Z","end":"2026-10-16T14:55:22.404818Z","steps":["trace[1519662] 'agreement among raft nodes before linearized reading' (duration: 107.066907ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:55:22.40487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.022688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-16T14:55:22.405031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.116463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-10-16T14:55:22.405123Z","caller":"traceutil/trace.go:171","msg":"trace[112203494] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16247; }","duration":"101.228946ms","start":"2026-10-16T14:55:22.303869Z","end":"2026-10-16T14:55:22.405098Z","steps":["trace[112203494] 'agreement among raft nodes before linearized reading' (duration: 101.003081ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:55:22.405067Z","caller":"traceutil/trace.go:171","msg":"trace[1901655660] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16247; }","duration":"108.287494ms","start":"2026-10-16T14:55:22.296757Z","end":"2026-10-16T14:55:22.405045Z","steps":["trace[1901655660] 'agreement among raft nodes before linearized reading' (duration: 107.834724ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:55:53.063751Z","caller":"traceutil/trace.go:171","msg":"trace[161686081] transaction","detail":"{read_only:false; response_revision:16406; number_of_response:1; }","duration":"539.855733ms","start":"2026-10-16T14:55:52.52387Z","end":"2026-10-16T14:55:53.063725Z","steps":["trace[161686081] 'process raft request' (duration: 539.700079ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:55:53.063922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:55:52.523851Z","time spent":"539.985086ms","remote":"127.0.0.1:49376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T14:55:53.064562Z","caller":"traceutil/trace.go:171","msg":"trace[2033351321] linearizableReadLoop","detail":"{readStateIndex:16980; appliedIndex:16978; }","duration":"341.484303ms","start":"2026-10-16T14:55:52.723062Z","end":"2026-10-16T14:55:53.064547Z","steps":["trace[2033351321] 'read index received' (duration: 340.527572ms)","trace[2033351321] 'applied index is now lower than readState.Index' (duration: 955.661µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:55:53.064772Z","caller":"traceutil/trace.go:171","msg":"trace[189307544] transaction","detail":"{read_only:false; response_revision:16407; number_of_response:1; }","duration":"530.920233ms","start":"2026-10-16T14:55:52.53384Z","end":"2026-10-16T14:55:53.06476Z","steps":["trace[189307544] 'process raft request' (duration: 530.644237ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:55:53.06483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:55:52.53381Z","time spent":"530.984054ms","remote":"127.0.0.1:49376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T14:55:53.065061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.000324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-16T14:55:53.065101Z","caller":"traceutil/trace.go:171","msg":"trace[2020261197] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:16407; }","duration":"342.075716ms","start":"2026-10-16T14:55:52.723016Z","end":"2026-10-16T14:55:53.065092Z","steps":["trace[2020261197] 'agreement among raft nodes before linearized reading' (duration: 341.940123ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:55:53.065127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:55:52.722996Z","time spent":"342.125107ms","remote":"127.0.0.1:49376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-16T14:55:53.065347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.52083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-tr4mw\" ","response":"range_response_count:1 size:9796"} {"level":"info","ts":"2026-10-16T14:55:53.065383Z","caller":"traceutil/trace.go:171","msg":"trace[1277908936] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-tr4mw; range_end:; response_count:1; response_revision:16407; }","duration":"268.607732ms","start":"2026-10-16T14:55:52.796767Z","end":"2026-10-16T14:55:53.065374Z","steps":["trace[1277908936] 'agreement among raft nodes before linearized reading' (duration: 268.473769ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:55:53.065662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.202177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-16T14:55:53.0657Z","caller":"traceutil/trace.go:171","msg":"trace[2053471351] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:16407; }","duration":"110.282379ms","start":"2026-10-16T14:55:52.955407Z","end":"2026-10-16T14:55:53.065689Z","steps":["trace[2053471351] 'agreement among raft nodes before linearized reading' (duration: 110.177736ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:55:53.06586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.632216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-16T14:55:53.065892Z","caller":"traceutil/trace.go:171","msg":"trace[1799132439] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:16407; }","duration":"194.706528ms","start":"2026-10-16T14:55:52.871178Z","end":"2026-10-16T14:55:53.065885Z","steps":["trace[1799132439] 'agreement among raft nodes before linearized reading' (duration: 194.628396ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:55:53.066056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.967792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T14:55:53.0661Z","caller":"traceutil/trace.go:171","msg":"trace[859829583] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16407; }","duration":"232.087155ms","start":"2026-10-16T14:55:52.834003Z","end":"2026-10-16T14:55:53.06609Z","steps":["trace[859829583] 'agreement among raft nodes before linearized reading' (duration: 232.021613ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:55:53.066391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.376431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-10-16T14:55:53.066446Z","caller":"traceutil/trace.go:171","msg":"trace[2050653490] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16407; }","duration":"232.464073ms","start":"2026-10-16T14:55:52.833969Z","end":"2026-10-16T14:55:53.066433Z","steps":["trace[2050653490] 'agreement among raft nodes before linearized reading' (duration: 232.32367ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:55:53.066711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.707128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5524"} {"level":"info","ts":"2026-10-16T14:55:53.06675Z","caller":"traceutil/trace.go:171","msg":"trace[1398130096] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16407; }","duration":"232.79209ms","start":"2026-10-16T14:55:52.833947Z","end":"2026-10-16T14:55:53.066739Z","steps":["trace[1398130096] 'agreement among raft nodes before linearized reading' (duration: 232.693918ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:57:27.825333Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14564} {"level":"info","ts":"2026-10-16T14:57:27.92184Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14564,"took":"93.662686ms","hash":2803892425,"current-db-size-bytes":55996416,"current-db-size":"56 MB","current-db-size-in-use-bytes":31645696,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-16T14:57:27.921927Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2803892425,"revision":14564,"compact-revision":12508} {"level":"warn","ts":"2026-10-16T15:01:33.967871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.769679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-10-16T15:01:33.968044Z","caller":"traceutil/trace.go:171","msg":"trace[1926189350] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:18892; }","duration":"165.012944ms","start":"2026-10-16T15:01:33.803001Z","end":"2026-10-16T15:01:33.968014Z","steps":["trace[1926189350] 'range keys from in-memory index tree' (duration: 164.467212ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:01:39.413385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.606854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1818"} {"level":"warn","ts":"2026-10-16T15:01:39.413479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.457786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-bhvsl\" ","response":"range_response_count:1 size:22121"} {"level":"info","ts":"2026-10-16T15:01:39.413588Z","caller":"traceutil/trace.go:171","msg":"trace[42134943] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-bhvsl; range_end:; response_count:1; response_revision:18957; }","duration":"321.664551ms","start":"2026-10-16T15:01:39.091899Z","end":"2026-10-16T15:01:39.413564Z","steps":["trace[42134943] 'range keys from in-memory index tree' (duration: 321.143039ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:01:39.413599Z","caller":"traceutil/trace.go:171","msg":"trace[412277851] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:18957; }","duration":"306.840959ms","start":"2026-10-16T15:01:39.106652Z","end":"2026-10-16T15:01:39.413493Z","steps":["trace[412277851] 'range keys from in-memory index tree' (duration: 306.377759ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:01:39.413627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T15:01:39.091863Z","time spent":"321.755713ms","remote":"127.0.0.1:49302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22145,"request content":"key:\"/registry/pods/openstack/nova-compute-default-bhvsl\" "} {"level":"warn","ts":"2026-10-16T15:01:39.413671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.473794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-16T15:01:39.413708Z","caller":"traceutil/trace.go:171","msg":"trace[1147763966] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18957; }","duration":"232.583117ms","start":"2026-10-16T15:01:39.181114Z","end":"2026-10-16T15:01:39.413698Z","steps":["trace[1147763966] 'range keys from in-memory index tree' (duration: 232.117496ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:01:39.41368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T15:01:39.106625Z","time spent":"307.038613ms","remote":"127.0.0.1:49214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":1842,"request content":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" "} {"level":"warn","ts":"2026-10-16T15:01:39.413485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.503299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-10-16T15:01:39.413919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.863734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-10-16T15:01:39.414136Z","caller":"traceutil/trace.go:171","msg":"trace[2027409162] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18957; }","duration":"162.108148ms","start":"2026-10-16T15:01:39.252015Z","end":"2026-10-16T15:01:39.414123Z","steps":["trace[2027409162] 'range keys from in-memory index tree' (duration: 161.157857ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:01:39.413385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.418114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-10-16T15:01:39.414233Z","caller":"traceutil/trace.go:171","msg":"trace[1885501386] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:18957; }","duration":"179.403296ms","start":"2026-10-16T15:01:39.234823Z","end":"2026-10-16T15:01:39.414226Z","steps":["trace[1885501386] 'range keys from in-memory index tree' (duration: 178.180518ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:01:39.414023Z","caller":"traceutil/trace.go:171","msg":"trace[219010080] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18957; }","duration":"172.037101ms","start":"2026-10-16T15:01:39.241946Z","end":"2026-10-16T15:01:39.413983Z","steps":["trace[219010080] 'range keys from in-memory index tree' (duration: 171.253984ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:01:39.652752Z","caller":"traceutil/trace.go:171","msg":"trace[87928985] transaction","detail":"{read_only:false; response_revision:18958; number_of_response:1; }","duration":"233.115199ms","start":"2026-10-16T15:01:39.4196Z","end":"2026-10-16T15:01:39.652715Z","steps":["trace[87928985] 'process raft request' (duration: 232.899044ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:01:40.166129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.407255ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-16T15:01:40.166534Z","caller":"traceutil/trace.go:171","msg":"trace[1902211143] transaction","detail":"{read_only:false; response_revision:18962; number_of_response:1; }","duration":"121.265184ms","start":"2026-10-16T15:01:40.045245Z","end":"2026-10-16T15:01:40.16651Z","steps":["trace[1902211143] 'process raft request' (duration: 121.164902ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:01:40.166649Z","caller":"traceutil/trace.go:171","msg":"trace[1737759864] transaction","detail":"{read_only:false; response_revision:18960; number_of_response:1; }","duration":"744.855594ms","start":"2026-10-16T15:01:39.421728Z","end":"2026-10-16T15:01:40.166584Z","steps":["trace[1737759864] 'process raft request' (duration: 744.534447ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:01:40.166772Z","caller":"traceutil/trace.go:171","msg":"trace[109957924] transaction","detail":"{read_only:false; response_revision:18961; number_of_response:1; }","duration":"690.418895ms","start":"2026-10-16T15:01:39.476267Z","end":"2026-10-16T15:01:40.166686Z","steps":["trace[109957924] 'process raft request' (duration: 690.057937ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:01:40.166878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T15:01:39.421704Z","time spent":"745.036138ms","remote":"127.0.0.1:49376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T15:01:40.166977Z","caller":"traceutil/trace.go:171","msg":"trace[392288022] linearizableReadLoop","detail":"{readStateIndex:19617; appliedIndex:19616; }","duration":"746.193955ms","start":"2026-10-16T15:01:39.420637Z","end":"2026-10-16T15:01:40.166831Z","steps":["trace[392288022] 'read index received' (duration: 232.132427ms)","trace[392288022] 'applied index is now lower than readState.Index' (duration: 514.055997ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T15:01:40.16709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T15:01:39.476239Z","time spent":"690.678041ms","remote":"127.0.0.1:49376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T15:01:40.167095Z","caller":"traceutil/trace.go:171","msg":"trace[1558526086] transaction","detail":"{read_only:false; response_revision:18959; number_of_response:1; }","duration":"746.246086ms","start":"2026-10-16T15:01:39.420595Z","end":"2026-10-16T15:01:40.166841Z","steps":["trace[1558526086] 'process raft request' (duration: 511.998882ms)","trace[1558526086] 'compare' (duration: 233.263452ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T15:01:40.167206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T15:01:39.420574Z","time spent":"746.579833ms","remote":"127.0.0.1:49376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T15:01:40.167333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.543757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T15:01:40.167408Z","caller":"traceutil/trace.go:171","msg":"trace[1758828611] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:18962; }","duration":"610.66042ms","start":"2026-10-16T15:01:39.556729Z","end":"2026-10-16T15:01:40.16739Z","steps":["trace[1758828611] 'agreement among raft nodes before linearized reading' (duration: 610.549868ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:01:40.167445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T15:01:39.556704Z","time spent":"610.729862ms","remote":"127.0.0.1:52646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":30,"request content":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true "} {"level":"warn","ts":"2026-10-16T15:01:40.167476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"712.777887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3685"} {"level":"warn","ts":"2026-10-16T15:01:40.167494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.7541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-27nt2\" ","response":"range_response_count:1 size:20752"} {"level":"info","ts":"2026-10-16T15:01:40.167614Z","caller":"traceutil/trace.go:171","msg":"trace[1531525375] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:18962; }","duration":"712.97207ms","start":"2026-10-16T15:01:39.454602Z","end":"2026-10-16T15:01:40.167575Z","steps":["trace[1531525375] 'agreement among raft nodes before linearized reading' (duration: 712.569381ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:01:40.167687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T15:01:39.454583Z","time spent":"713.087193ms","remote":"127.0.0.1:49214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":3709,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" "} {"level":"info","ts":"2026-10-16T15:01:40.167623Z","caller":"traceutil/trace.go:171","msg":"trace[777687090] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-27nt2; range_end:; response_count:1; response_revision:18962; }","duration":"450.963675ms","start":"2026-10-16T15:01:39.716622Z","end":"2026-10-16T15:01:40.167586Z","steps":["trace[777687090] 'agreement among raft nodes before linearized reading' (duration: 450.528716ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:01:40.167784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T15:01:39.716589Z","time spent":"451.184ms","remote":"127.0.0.1:49302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":20776,"request content":"key:\"/registry/pods/openstack/libvirt-libvirt-default-27nt2\" "} {"level":"warn","ts":"2026-10-16T15:01:40.169622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"748.936386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:97 size:956094"} {"level":"info","ts":"2026-10-16T15:01:40.169851Z","caller":"traceutil/trace.go:171","msg":"trace[1866969545] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:97; response_revision:18962; }","duration":"749.239493ms","start":"2026-10-16T15:01:39.420583Z","end":"2026-10-16T15:01:40.169822Z","steps":["trace[1866969545] 'agreement among raft nodes before linearized reading' (duration: 746.387629ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:01:40.170086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T15:01:39.42056Z","time spent":"749.492128ms","remote":"127.0.0.1:49302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":97,"response size":956118,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-10-16T15:01:55.334035Z","caller":"traceutil/trace.go:171","msg":"trace[787419575] transaction","detail":"{read_only:false; response_revision:19146; number_of_response:1; }","duration":"149.970148ms","start":"2026-10-16T15:01:55.184022Z","end":"2026-10-16T15:01:55.333992Z","steps":["trace[787419575] 'process raft request' (duration: 149.704012ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:02:12.251139Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"8a5b61ed9120d6b3","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-16T15:02:12.258325Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-16T15:02:12.258499Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-16T15:02:27.835457Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17022} {"level":"info","ts":"2026-10-16T15:02:27.949818Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17022,"took":"109.599613ms","hash":2024963777,"current-db-size-bytes":55996416,"current-db-size":"56 MB","current-db-size-in-use-bytes":33193984,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-16T15:02:27.949912Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2024963777,"revision":17022,"compact-revision":14564} {"level":"info","ts":"2026-10-16T15:07:27.843492Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19435} {"level":"info","ts":"2026-10-16T15:07:27.975852Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19435,"took":"127.444742ms","hash":2337225603,"current-db-size-bytes":55996416,"current-db-size":"56 MB","current-db-size-in-use-bytes":30543872,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-16T15:07:27.978893Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2337225603,"revision":19435,"compact-revision":17022} {"level":"info","ts":"2026-10-16T15:08:20.094992Z","caller":"traceutil/trace.go:171","msg":"trace[1254865701] transaction","detail":"{read_only:false; response_revision:22025; number_of_response:1; }","duration":"109.463439ms","start":"2026-10-16T15:08:19.985485Z","end":"2026-10-16T15:08:20.094949Z","steps":["trace[1254865701] 'process raft request' (duration: 109.219663ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:08:20.323225Z","caller":"traceutil/trace.go:171","msg":"trace[2090790554] transaction","detail":"{read_only:false; response_revision:22026; number_of_response:1; }","duration":"166.899474ms","start":"2026-10-16T15:08:20.156293Z","end":"2026-10-16T15:08:20.323192Z","steps":["trace[2090790554] 'process raft request' (duration: 146.368304ms)","trace[2090790554] 'compare' (duration: 20.393837ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T15:08:30.766234Z","caller":"traceutil/trace.go:171","msg":"trace[1079062079] transaction","detail":"{read_only:false; response_revision:22092; number_of_response:1; }","duration":"153.526785ms","start":"2026-10-16T15:08:30.612671Z","end":"2026-10-16T15:08:30.766198Z","steps":["trace[1079062079] 'process raft request' (duration: 153.215458ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:08:30.766694Z","caller":"traceutil/trace.go:171","msg":"trace[909414047] transaction","detail":"{read_only:false; response_revision:22093; number_of_response:1; }","duration":"134.08236ms","start":"2026-10-16T15:08:30.632576Z","end":"2026-10-16T15:08:30.766659Z","steps":["trace[909414047] 'process raft request' (duration: 133.865915ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:08:36.603433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.239906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T15:08:36.603531Z","caller":"traceutil/trace.go:171","msg":"trace[801238420] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:22125; }","duration":"232.40742ms","start":"2026-10-16T15:08:36.3711Z","end":"2026-10-16T15:08:36.603507Z","steps":["trace[801238420] 'range keys from in-memory index tree' (duration: 232.123123ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:08:36.618977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.21312ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-10-16T15:08:36.619518Z","caller":"traceutil/trace.go:171","msg":"trace[521278350] linearizableReadLoop","detail":"{readStateIndex:22886; appliedIndex:22885; }","duration":"178.712558ms","start":"2026-10-16T15:08:36.440766Z","end":"2026-10-16T15:08:36.619479Z","steps":["trace[521278350] 'read index received' (duration: 6.989167ms)","trace[521278350] 'applied index is now lower than readState.Index' (duration: 171.721051ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T15:08:36.62002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.064046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T15:08:36.620082Z","caller":"traceutil/trace.go:171","msg":"trace[191896090] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:22126; }","duration":"107.185478ms","start":"2026-10-16T15:08:36.512883Z","end":"2026-10-16T15:08:36.620068Z","steps":["trace[191896090] 'agreement among raft nodes before linearized reading' (duration: 107.012004ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:08:36.620864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.873174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-16T15:08:36.620999Z","caller":"traceutil/trace.go:171","msg":"trace[1409294499] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:22125; }","duration":"180.204001ms","start":"2026-10-16T15:08:36.44076Z","end":"2026-10-16T15:08:36.620964Z","steps":["trace[1409294499] 'agreement among raft nodes before linearized reading' (duration: 178.840781ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:08:36.864108Z","caller":"traceutil/trace.go:171","msg":"trace[1528717903] linearizableReadLoop","detail":"{readStateIndex:22889; appliedIndex:22888; }","duration":"163.721694ms","start":"2026-10-16T15:08:36.700354Z","end":"2026-10-16T15:08:36.864076Z","steps":["trace[1528717903] 'read index received' (duration: 101.89097ms)","trace[1528717903] 'applied index is now lower than readState.Index' (duration: 61.829204ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T15:08:36.864153Z","caller":"traceutil/trace.go:171","msg":"trace[1649100413] transaction","detail":"{read_only:false; response_revision:22128; number_of_response:1; }","duration":"207.572944ms","start":"2026-10-16T15:08:36.656553Z","end":"2026-10-16T15:08:36.864125Z","steps":["trace[1649100413] 'process raft request' (duration: 145.675919ms)","trace[1649100413] 'compare' (duration: 61.69694ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T15:08:36.864358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.973239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-16T15:08:36.864453Z","caller":"traceutil/trace.go:171","msg":"trace[1140418746] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:22128; }","duration":"164.149073ms","start":"2026-10-16T15:08:36.700283Z","end":"2026-10-16T15:08:36.864432Z","steps":["trace[1140418746] 'agreement among raft nodes before linearized reading' (duration: 163.920448ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:08:36.888939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.87813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1991"} {"level":"info","ts":"2026-10-16T15:08:36.889085Z","caller":"traceutil/trace.go:171","msg":"trace[46526959] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:22129; }","duration":"136.037333ms","start":"2026-10-16T15:08:36.752963Z","end":"2026-10-16T15:08:36.889001Z","steps":["trace[46526959] 'agreement among raft nodes before linearized reading' (duration: 135.763897ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:08:37.123299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.847373ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-16T15:08:37.123479Z","caller":"traceutil/trace.go:171","msg":"trace[403409875] transaction","detail":"{read_only:false; response_revision:22130; number_of_response:1; }","duration":"229.668618ms","start":"2026-10-16T15:08:36.893788Z","end":"2026-10-16T15:08:37.123457Z","steps":["trace[403409875] 'process raft request' (duration: 87.579839ms)","trace[403409875] 'compare' (duration: 141.744151ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T15:08:37.123532Z","caller":"traceutil/trace.go:171","msg":"trace[303182786] linearizableReadLoop","detail":"{readStateIndex:22892; appliedIndex:22890; }","duration":"201.868467ms","start":"2026-10-16T15:08:36.921653Z","end":"2026-10-16T15:08:37.123522Z","steps":["trace[303182786] 'read index received' (duration: 59.734276ms)","trace[303182786] 'applied index is now lower than readState.Index' (duration: 142.133371ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T15:08:37.123593Z","caller":"traceutil/trace.go:171","msg":"trace[1998786880] transaction","detail":"{read_only:false; response_revision:22131; number_of_response:1; }","duration":"228.184686ms","start":"2026-10-16T15:08:36.895395Z","end":"2026-10-16T15:08:37.12358Z","steps":["trace[1998786880] 'process raft request' (duration: 227.989541ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:08:37.12382Z","caller":"traceutil/trace.go:171","msg":"trace[85710681] transaction","detail":"{read_only:false; response_revision:22132; number_of_response:1; }","duration":"165.257807ms","start":"2026-10-16T15:08:36.958506Z","end":"2026-10-16T15:08:37.123763Z","steps":["trace[85710681] 'process raft request' (duration: 164.922399ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:08:37.123721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.086861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-16T15:08:37.123952Z","caller":"traceutil/trace.go:171","msg":"trace[2112972433] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:22132; }","duration":"202.380708ms","start":"2026-10-16T15:08:36.92156Z","end":"2026-10-16T15:08:37.123941Z","steps":["trace[2112972433] 'agreement among raft nodes before linearized reading' (duration: 202.059491ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:08:37.124039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.963226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-16T15:08:37.124087Z","caller":"traceutil/trace.go:171","msg":"trace[1353600057] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:22132; }","duration":"201.059008ms","start":"2026-10-16T15:08:36.923021Z","end":"2026-10-16T15:08:37.12408Z","steps":["trace[1353600057] 'agreement among raft nodes before linearized reading' (duration: 200.849873ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:12:27.85118Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21756} {"level":"info","ts":"2026-10-16T15:12:27.97615Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21756,"took":"119.37645ms","hash":4227383411,"current-db-size-bytes":55996416,"current-db-size":"56 MB","current-db-size-in-use-bytes":31907840,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-16T15:12:27.976237Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4227383411,"revision":21756,"compact-revision":19435} {"level":"info","ts":"2026-10-16T15:12:41.302671Z","caller":"traceutil/trace.go:171","msg":"trace[172836490] transaction","detail":"{read_only:false; response_revision:24623; number_of_response:1; }","duration":"101.780276ms","start":"2026-10-16T15:12:41.200848Z","end":"2026-10-16T15:12:41.302629Z","steps":["trace[172836490] 'process raft request' (duration: 101.378627ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:12:41.727098Z","caller":"traceutil/trace.go:171","msg":"trace[366246546] linearizableReadLoop","detail":"{readStateIndex:25462; appliedIndex:25461; }","duration":"378.35619ms","start":"2026-10-16T15:12:41.348697Z","end":"2026-10-16T15:12:41.727054Z","steps":["trace[366246546] 'read index received' (duration: 295.303713ms)","trace[366246546] 'applied index is now lower than readState.Index' (duration: 83.050707ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T15:12:41.727111Z","caller":"traceutil/trace.go:171","msg":"trace[660951349] transaction","detail":"{read_only:false; response_revision:24625; number_of_response:1; }","duration":"390.595344ms","start":"2026-10-16T15:12:41.336485Z","end":"2026-10-16T15:12:41.72708Z","steps":["trace[660951349] 'process raft request' (duration: 307.583538ms)","trace[660951349] 'compare' (duration: 82.795611ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T15:12:41.727379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T15:12:41.33645Z","time spent":"390.785769ms","remote":"127.0.0.1:49376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T15:12:41.727369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.662778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-10-16T15:12:41.727458Z","caller":"traceutil/trace.go:171","msg":"trace[2032663585] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:24625; }","duration":"378.804141ms","start":"2026-10-16T15:12:41.348635Z","end":"2026-10-16T15:12:41.727439Z","steps":["trace[2032663585] 'agreement among raft nodes before linearized reading' (duration: 378.529325ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:12:41.727517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T15:12:41.348611Z","time spent":"378.893043ms","remote":"127.0.0.1:49288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":446,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-10-16T15:14:30.88356Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000067fa.wal"} {"level":"info","ts":"2026-10-16T15:16:01.56697Z","caller":"traceutil/trace.go:171","msg":"trace[346176136] transaction","detail":"{read_only:false; response_revision:26607; number_of_response:1; }","duration":"188.843963ms","start":"2026-10-16T15:16:01.378082Z","end":"2026-10-16T15:16:01.566926Z","steps":["trace[346176136] 'process raft request' (duration: 187.829531ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:16:01.567478Z","caller":"traceutil/trace.go:171","msg":"trace[1742915107] transaction","detail":"{read_only:false; response_revision:26608; number_of_response:1; }","duration":"144.164184ms","start":"2026-10-16T15:16:01.423279Z","end":"2026-10-16T15:16:01.567443Z","steps":["trace[1742915107] 'process raft request' (duration: 143.876958ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:16:01.567623Z","caller":"traceutil/trace.go:171","msg":"trace[939908737] transaction","detail":"{read_only:false; response_revision:26609; number_of_response:1; }","duration":"135.580672ms","start":"2026-10-16T15:16:01.43203Z","end":"2026-10-16T15:16:01.567611Z","steps":["trace[939908737] 'process raft request' (duration: 135.374107ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:16:07.425007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.794138ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-16T15:16:07.42521Z","caller":"traceutil/trace.go:171","msg":"trace[317078352] transaction","detail":"{read_only:false; response_revision:26655; number_of_response:1; }","duration":"275.589302ms","start":"2026-10-16T15:16:07.149582Z","end":"2026-10-16T15:16:07.425172Z","steps":["trace[317078352] 'process raft request' (duration: 275.531411ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:16:07.425551Z","caller":"traceutil/trace.go:171","msg":"trace[609094511] transaction","detail":"{read_only:false; response_revision:26654; number_of_response:1; }","duration":"412.464654ms","start":"2026-10-16T15:16:07.013071Z","end":"2026-10-16T15:16:07.425535Z","steps":["trace[609094511] 'process raft request' (duration: 199.034881ms)","trace[609094511] 'compare' (duration: 212.099803ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T15:16:07.425614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T15:16:07.013037Z","time spent":"412.542965ms","remote":"127.0.0.1:49376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T15:16:07.426791Z","caller":"traceutil/trace.go:171","msg":"trace[642221176] linearizableReadLoop","detail":"{readStateIndex:27539; appliedIndex:27538; }","duration":"357.267289ms","start":"2026-10-16T15:16:07.068474Z","end":"2026-10-16T15:16:07.425742Z","steps":["trace[642221176] 'read index received' (duration: 143.655003ms)","trace[642221176] 'applied index is now lower than readState.Index' (duration: 213.611326ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T15:16:07.426888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.362683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-16T15:16:07.427374Z","caller":"traceutil/trace.go:171","msg":"trace[1504714359] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:26655; }","duration":"358.897755ms","start":"2026-10-16T15:16:07.068416Z","end":"2026-10-16T15:16:07.427314Z","steps":["trace[1504714359] 'agreement among raft nodes before linearized reading' (duration: 358.315972ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:16:07.427441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T15:16:07.068389Z","time spent":"359.035469ms","remote":"127.0.0.1:52654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":2,"response size":32,"request content":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true "} {"level":"info","ts":"2026-10-16T15:16:07.442497Z","caller":"traceutil/trace.go:171","msg":"trace[474849070] transaction","detail":"{read_only:false; response_revision:26656; number_of_response:1; }","duration":"135.795117ms","start":"2026-10-16T15:16:07.306647Z","end":"2026-10-16T15:16:07.442443Z","steps":["trace[474849070] 'process raft request' (duration: 134.039568ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:16:08.018487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.102422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-16T15:16:08.0186Z","caller":"traceutil/trace.go:171","msg":"trace[208872193] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26659; }","duration":"161.320457ms","start":"2026-10-16T15:16:07.857256Z","end":"2026-10-16T15:16:08.018576Z","steps":["trace[208872193] 'range keys from in-memory index tree' (duration: 160.878117ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:16:08.018779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.398538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-16T15:16:08.018874Z","caller":"traceutil/trace.go:171","msg":"trace[1467675424] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26659; }","duration":"173.541521ms","start":"2026-10-16T15:16:07.845312Z","end":"2026-10-16T15:16:08.018853Z","steps":["trace[1467675424] 'range keys from in-memory index tree' (duration: 173.136791ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:17:04.039318Z","caller":"traceutil/trace.go:171","msg":"trace[1428458703] transaction","detail":"{read_only:false; response_revision:27172; number_of_response:1; }","duration":"132.050753ms","start":"2026-10-16T15:17:03.907244Z","end":"2026-10-16T15:17:04.039295Z","steps":["trace[1428458703] 'process raft request' (duration: 131.89875ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:17:04.039953Z","caller":"traceutil/trace.go:171","msg":"trace[580762173] transaction","detail":"{read_only:false; response_revision:27173; number_of_response:1; }","duration":"130.787055ms","start":"2026-10-16T15:17:03.909152Z","end":"2026-10-16T15:17:04.039939Z","steps":["trace[580762173] 'process raft request' (duration: 130.686902ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:17:04.040152Z","caller":"traceutil/trace.go:171","msg":"trace[205377722] transaction","detail":"{read_only:false; response_revision:27174; number_of_response:1; }","duration":"106.152604ms","start":"2026-10-16T15:17:03.933986Z","end":"2026-10-16T15:17:04.040139Z","steps":["trace[205377722] 'process raft request' (duration: 105.915219ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:17:09.721815Z","caller":"traceutil/trace.go:171","msg":"trace[1507911044] transaction","detail":"{read_only:false; response_revision:27212; number_of_response:1; }","duration":"153.903412ms","start":"2026-10-16T15:17:09.567857Z","end":"2026-10-16T15:17:09.721761Z","steps":["trace[1507911044] 'process raft request' (duration: 153.728568ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:17:09.911811Z","caller":"traceutil/trace.go:171","msg":"trace[1448471809] transaction","detail":"{read_only:false; response_revision:27213; number_of_response:1; }","duration":"216.785598ms","start":"2026-10-16T15:17:09.695004Z","end":"2026-10-16T15:17:09.91179Z","steps":["trace[1448471809] 'process raft request' (duration: 216.508672ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:17:09.911871Z","caller":"traceutil/trace.go:171","msg":"trace[1311939082] transaction","detail":"{read_only:false; response_revision:27214; number_of_response:1; }","duration":"192.169558ms","start":"2026-10-16T15:17:09.719692Z","end":"2026-10-16T15:17:09.911861Z","steps":["trace[1311939082] 'process raft request' (duration: 191.976773ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:17:09.911814Z","caller":"traceutil/trace.go:171","msg":"trace[169131125] transaction","detail":"{read_only:false; response_revision:27215; number_of_response:1; }","duration":"191.064582ms","start":"2026-10-16T15:17:09.720693Z","end":"2026-10-16T15:17:09.911757Z","steps":["trace[169131125] 'process raft request' (duration: 191.016761ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:17:10.166096Z","caller":"traceutil/trace.go:171","msg":"trace[191415805] transaction","detail":"{read_only:false; response_revision:27216; number_of_response:1; }","duration":"104.279072ms","start":"2026-10-16T15:17:10.061757Z","end":"2026-10-16T15:17:10.166036Z","steps":["trace[191415805] 'process raft request' (duration: 71.716744ms)","trace[191415805] 'compare' (duration: 32.111238ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T15:17:10.617465Z","caller":"traceutil/trace.go:171","msg":"trace[370772285] transaction","detail":"{read_only:false; response_revision:27218; number_of_response:1; }","duration":"446.168867ms","start":"2026-10-16T15:17:10.171269Z","end":"2026-10-16T15:17:10.617438Z","steps":["trace[370772285] 'process raft request' (duration: 383.392164ms)","trace[370772285] 'compare' (duration: 62.564128ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T15:17:10.617463Z","caller":"traceutil/trace.go:171","msg":"trace[180545303] linearizableReadLoop","detail":"{readStateIndex:28117; appliedIndex:28116; }","duration":"356.038721ms","start":"2026-10-16T15:17:10.261391Z","end":"2026-10-16T15:17:10.617429Z","steps":["trace[180545303] 'read index received' (duration: 293.277528ms)","trace[180545303] 'applied index is now lower than readState.Index' (duration: 62.760013ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T15:17:10.617648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T15:17:10.171234Z","time spent":"446.31153ms","remote":"127.0.0.1:49376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T15:17:10.617864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.755475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-16T15:17:10.617958Z","caller":"traceutil/trace.go:171","msg":"trace[1151007004] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:27218; }","duration":"304.929568ms","start":"2026-10-16T15:17:10.313003Z","end":"2026-10-16T15:17:10.617933Z","steps":["trace[1151007004] 'agreement among raft nodes before linearized reading' (duration: 304.751894ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:17:10.617959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.352499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-16T15:17:10.618021Z","caller":"traceutil/trace.go:171","msg":"trace[377236406] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:27218; }","duration":"238.459112ms","start":"2026-10-16T15:17:10.379547Z","end":"2026-10-16T15:17:10.618006Z","steps":["trace[377236406] 'agreement among raft nodes before linearized reading' (duration: 238.245747ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:17:10.618021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T15:17:10.312972Z","time spent":"305.033781ms","remote":"127.0.0.1:49522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":32,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-10-16T15:17:10.618097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.45019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-16T15:17:10.618146Z","caller":"traceutil/trace.go:171","msg":"trace[1538663512] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27218; }","duration":"254.577853ms","start":"2026-10-16T15:17:10.363553Z","end":"2026-10-16T15:17:10.618131Z","steps":["trace[1538663512] 'agreement among raft nodes before linearized reading' (duration: 254.408829ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:17:10.617581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.160315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-16T15:17:10.618256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.660438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T15:17:10.618373Z","caller":"traceutil/trace.go:171","msg":"trace[171678703] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27218; }","duration":"247.807501ms","start":"2026-10-16T15:17:10.370512Z","end":"2026-10-16T15:17:10.61832Z","steps":["trace[171678703] 'agreement among raft nodes before linearized reading' (duration: 247.448843ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:17:10.618226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.832087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-16T15:17:10.61837Z","caller":"traceutil/trace.go:171","msg":"trace[1869240272] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:27218; }","duration":"356.982103ms","start":"2026-10-16T15:17:10.261333Z","end":"2026-10-16T15:17:10.618314Z","steps":["trace[1869240272] 'agreement among raft nodes before linearized reading' (duration: 356.181365ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:17:10.618437Z","caller":"traceutil/trace.go:171","msg":"trace[157291888] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27218; }","duration":"246.086572ms","start":"2026-10-16T15:17:10.372333Z","end":"2026-10-16T15:17:10.61842Z","steps":["trace[157291888] 'agreement among raft nodes before linearized reading' (duration: 245.635362ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:17:10.61854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T15:17:10.261308Z","time spent":"357.141026ms","remote":"127.0.0.1:49266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":30,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-10-16T15:17:10.618746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.693921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-16T15:17:10.618951Z","caller":"traceutil/trace.go:171","msg":"trace[420480348] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27218; }","duration":"227.924167ms","start":"2026-10-16T15:17:10.391011Z","end":"2026-10-16T15:17:10.618935Z","steps":["trace[420480348] 'agreement among raft nodes before linearized reading' (duration: 226.904114ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:17:10.733184Z","caller":"traceutil/trace.go:171","msg":"trace[1150430462] transaction","detail":"{read_only:false; response_revision:27219; number_of_response:1; }","duration":"110.789967ms","start":"2026-10-16T15:17:10.622364Z","end":"2026-10-16T15:17:10.733154Z","steps":["trace[1150430462] 'process raft request' (duration: 109.259903ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:17:10.733358Z","caller":"traceutil/trace.go:171","msg":"trace[1163283226] transaction","detail":"{read_only:false; response_revision:27222; number_of_response:1; }","duration":"109.178081ms","start":"2026-10-16T15:17:10.624163Z","end":"2026-10-16T15:17:10.733341Z","steps":["trace[1163283226] 'process raft request' (duration: 109.085319ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:17:10.733395Z","caller":"traceutil/trace.go:171","msg":"trace[212295618] transaction","detail":"{read_only:false; response_revision:27221; number_of_response:1; }","duration":"109.686262ms","start":"2026-10-16T15:17:10.623672Z","end":"2026-10-16T15:17:10.733358Z","steps":["trace[212295618] 'process raft request' (duration: 109.444437ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:17:10.733205Z","caller":"traceutil/trace.go:171","msg":"trace[1734216253] transaction","detail":"{read_only:false; response_revision:27220; number_of_response:1; }","duration":"109.601861ms","start":"2026-10-16T15:17:10.623567Z","end":"2026-10-16T15:17:10.733169Z","steps":["trace[1734216253] 'process raft request' (duration: 109.475338ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:17:27.865589Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24046} {"level":"info","ts":"2026-10-16T15:17:27.996324Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24046,"took":"126.345545ms","hash":1003675813,"current-db-size-bytes":58527744,"current-db-size":"58 MB","current-db-size-in-use-bytes":45301760,"current-db-size-in-use":"45 MB"} {"level":"info","ts":"2026-10-16T15:17:27.996457Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1003675813,"revision":24046,"compact-revision":21756} {"level":"info","ts":"2026-10-16T15:20:21.707697Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"8a5b61ed9120d6b3","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-16T15:20:21.714244Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-16T15:20:21.714353Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-16T15:22:27.873795Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27344} {"level":"info","ts":"2026-10-16T15:22:28.039211Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27344,"took":"161.222895ms","hash":1039182952,"current-db-size-bytes":60223488,"current-db-size":"60 MB","current-db-size-in-use-bytes":36413440,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-16T15:22:28.039285Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1039182952,"revision":27344,"compact-revision":24046} {"level":"info","ts":"2026-10-16T15:27:27.884145Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30219} {"level":"info","ts":"2026-10-16T15:27:28.036423Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30219,"took":"146.882844ms","hash":3930459619,"current-db-size-bytes":60223488,"current-db-size":"60 MB","current-db-size-in-use-bytes":31223808,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-16T15:27:28.036491Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3930459619,"revision":30219,"compact-revision":27344} {"level":"info","ts":"2026-10-16T15:32:27.890891Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32472} {"level":"info","ts":"2026-10-16T15:32:28.055203Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32472,"took":"159.802108ms","hash":3407429847,"current-db-size-bytes":60223488,"current-db-size":"60 MB","current-db-size-in-use-bytes":30208000,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-16T15:32:28.055307Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3407429847,"revision":32472,"compact-revision":30219} {"level":"info","ts":"2026-10-16T15:37:27.89754Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34701} {"level":"info","ts":"2026-10-16T15:37:28.031991Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34701,"took":"130.376839ms","hash":2451609483,"current-db-size-bytes":60223488,"current-db-size":"60 MB","current-db-size-in-use-bytes":31154176,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-16T15:37:28.032066Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2451609483,"revision":34701,"compact-revision":32472} {"level":"info","ts":"2026-10-16T15:41:21.621387Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"8a5b61ed9120d6b3","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-16T15:41:21.631127Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-16T15:41:21.631232Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-16T15:42:27.909106Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":36997} {"level":"info","ts":"2026-10-16T15:42:28.041033Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":36997,"took":"127.873323ms","hash":3423268113,"current-db-size-bytes":61071360,"current-db-size":"61 MB","current-db-size-in-use-bytes":33034240,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-16T15:42:28.041201Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3423268113,"revision":36997,"compact-revision":34701} {"level":"info","ts":"2026-10-16T15:47:27.924232Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39269} {"level":"info","ts":"2026-10-16T15:47:28.058299Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39269,"took":"128.333857ms","hash":1217164915,"current-db-size-bytes":62345216,"current-db-size":"62 MB","current-db-size-in-use-bytes":31535104,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-16T15:47:28.058377Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1217164915,"revision":39269,"compact-revision":36997} {"level":"info","ts":"2026-10-16T15:52:27.932926Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":41498} {"level":"info","ts":"2026-10-16T15:52:28.058078Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":41498,"took":"121.236199ms","hash":2856550137,"current-db-size-bytes":62345216,"current-db-size":"62 MB","current-db-size-in-use-bytes":30814208,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-16T15:52:28.058151Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2856550137,"revision":41498,"compact-revision":39269}