{"level":"warn","ts":"2026-08-11T12:55:52.898758Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-11T12:55:52.898876Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.162:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.162:2380","--initial-cluster=instance=https://199.204.45.162:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.162:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.162: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-08-11T12:55:52.898969Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-11T12:55:52.898979Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.162:2380"]} {"level":"info","ts":"2026-08-11T12:55:52.898998Z","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-08-11T12:55:52.899734Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"]} {"level":"info","ts":"2026-08-11T12:55:52.899817Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.162:2380"],"listen-peer-urls":["https://199.204.45.162:2380"],"advertise-client-urls":["https://199.204.45.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.162: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-08-11T12:55:52.904308Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.242216ms"} {"level":"info","ts":"2026-08-11T12:55:52.915961Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2d372260bb4c37a","cluster-id":"52bb6ec15e8722ff"} {"level":"info","ts":"2026-08-11T12:55:52.916137Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=()"} {"level":"info","ts":"2026-08-11T12:55:52.916186Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became follower at term 0"} {"level":"info","ts":"2026-08-11T12:55:52.916215Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2d372260bb4c37a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-11T12:55:52.916236Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became follower at term 1"} {"level":"info","ts":"2026-08-11T12:55:52.916314Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=(203631915892523898)"} {"level":"warn","ts":"2026-08-11T12:55:52.923566Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-11T12:55:52.925271Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-11T12:55:52.926787Z","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-08-11T12:55:52.929234Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2d372260bb4c37a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-11T12:55:52.92954Z","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-08-11T12:55:52.929689Z","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-08-11T12:55:52.929643Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2d372260bb4c37a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-11T12:55:52.929736Z","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-08-11T12:55:52.930931Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=(203631915892523898)"} {"level":"info","ts":"2026-08-11T12:55:52.931369Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"52bb6ec15e8722ff","local-member-id":"2d372260bb4c37a","added-peer-id":"2d372260bb4c37a","added-peer-peer-urls":["https://199.204.45.162:2380"]} {"level":"info","ts":"2026-08-11T12:55:52.933601Z","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-08-11T12:55:52.933776Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.162:2380"} {"level":"info","ts":"2026-08-11T12:55:52.933858Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.162:2380"} {"level":"info","ts":"2026-08-11T12:55:52.933912Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2d372260bb4c37a","initial-advertise-peer-urls":["https://199.204.45.162:2380"],"listen-peer-urls":["https://199.204.45.162:2380"],"advertise-client-urls":["https://199.204.45.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-11T12:55:52.933967Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-11T12:55:53.516695Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a is starting a new election at term 1"} {"level":"info","ts":"2026-08-11T12:55:53.516794Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became pre-candidate at term 1"} {"level":"info","ts":"2026-08-11T12:55:53.516831Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a received MsgPreVoteResp from 2d372260bb4c37a at term 1"} {"level":"info","ts":"2026-08-11T12:55:53.516852Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became candidate at term 2"} {"level":"info","ts":"2026-08-11T12:55:53.516864Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a received MsgVoteResp from 2d372260bb4c37a at term 2"} {"level":"info","ts":"2026-08-11T12:55:53.516881Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became leader at term 2"} {"level":"info","ts":"2026-08-11T12:55:53.516895Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2d372260bb4c37a elected leader 2d372260bb4c37a at term 2"} {"level":"info","ts":"2026-08-11T12:55:53.517759Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-11T12:55:53.519499Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"52bb6ec15e8722ff","local-member-id":"2d372260bb4c37a","cluster-version":"3.5"} {"level":"info","ts":"2026-08-11T12:55:53.51955Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2d372260bb4c37a","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.162:2379]}","request-path":"/0/members/2d372260bb4c37a/attributes","cluster-id":"52bb6ec15e8722ff","publish-timeout":"7s"} {"level":"info","ts":"2026-08-11T12:55:53.519596Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-11T12:55:53.519629Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-11T12:55:53.519701Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-11T12:55:53.51973Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-11T12:55:53.519977Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-11T12:55:53.520121Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-11T12:55:53.522274Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-11T12:55:53.524093Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.162:2379"} {"level":"info","ts":"2026-08-11T12:56:08.71049Z","caller":"traceutil/trace.go:171","msg":"trace[45710441] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"212.346964ms","start":"2026-08-11T12:56:08.498107Z","end":"2026-08-11T12:56:08.710454Z","steps":["trace[45710441] 'process raft request' (duration: 212.110788ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:56:31.400929Z","caller":"traceutil/trace.go:171","msg":"trace[147498652] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"110.523113ms","start":"2026-08-11T12:56:31.290367Z","end":"2026-08-11T12:56:31.40089Z","steps":["trace[147498652] 'process raft request' (duration: 103.760557ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:56:31.401303Z","caller":"traceutil/trace.go:171","msg":"trace[1031500946] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"103.029475ms","start":"2026-08-11T12:56:31.29825Z","end":"2026-08-11T12:56:31.40128Z","steps":["trace[1031500946] 'process raft request' (duration: 102.586693ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:56:31.401433Z","caller":"traceutil/trace.go:171","msg":"trace[1648202520] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"101.254696ms","start":"2026-08-11T12:56:31.300142Z","end":"2026-08-11T12:56:31.401397Z","steps":["trace[1648202520] 'process raft request' (duration: 100.991469ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:56:31.608932Z","caller":"traceutil/trace.go:171","msg":"trace[2124336072] transaction","detail":"{read_only:false; number_of_response:1; response_revision:540; }","duration":"120.581142ms","start":"2026-08-11T12:56:31.488319Z","end":"2026-08-11T12:56:31.6089Z","steps":["trace[2124336072] 'process raft request' (duration: 44.512694ms)","trace[2124336072] 'compare' (duration: 75.969745ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T12:56:37.389205Z","caller":"traceutil/trace.go:171","msg":"trace[653949061] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"118.584077ms","start":"2026-08-11T12:56:37.270579Z","end":"2026-08-11T12:56:37.389163Z","steps":["trace[653949061] 'process raft request' (duration: 117.833257ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:56:37.548226Z","caller":"traceutil/trace.go:171","msg":"trace[1360116837] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"154.113921ms","start":"2026-08-11T12:56:37.394085Z","end":"2026-08-11T12:56:37.548199Z","steps":["trace[1360116837] 'process raft request' (duration: 111.548301ms)","trace[1360116837] 'compare' (duration: 42.429305ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T12:56:37.564593Z","caller":"traceutil/trace.go:171","msg":"trace[1123656803] linearizableReadLoop","detail":"{readStateIndex:621; appliedIndex:619; }","duration":"103.480418ms","start":"2026-08-11T12:56:37.461089Z","end":"2026-08-11T12:56:37.564569Z","steps":["trace[1123656803] 'read index received' (duration: 44.555125ms)","trace[1123656803] 'applied index is now lower than readState.Index' (duration: 58.923983ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T12:56:37.564662Z","caller":"traceutil/trace.go:171","msg":"trace[1412641314] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"115.773138ms","start":"2026-08-11T12:56:37.448876Z","end":"2026-08-11T12:56:37.564649Z","steps":["trace[1412641314] 'process raft request' (duration: 115.536822ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:56:37.564863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.734155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-11T12:56:37.564969Z","caller":"traceutil/trace.go:171","msg":"trace[1382999626] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:597; }","duration":"103.927731ms","start":"2026-08-11T12:56:37.461024Z","end":"2026-08-11T12:56:37.564951Z","steps":["trace[1382999626] 'agreement among raft nodes before linearized reading' (duration: 103.644582ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:56:38.039124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.631608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-599vm\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-08-11T12:56:38.039225Z","caller":"traceutil/trace.go:171","msg":"trace[1267892296] range","detail":"{range_begin:/registry/pods/kube-system/cilium-599vm; range_end:; response_count:1; response_revision:598; }","duration":"293.788473ms","start":"2026-08-11T12:56:37.745414Z","end":"2026-08-11T12:56:38.039203Z","steps":["trace[1267892296] 'range keys from in-memory index tree' (duration: 293.422473ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:58:12.285796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.467762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-08-11T12:58:12.285861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.427703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-11T12:58:12.285935Z","caller":"traceutil/trace.go:171","msg":"trace[1796036771] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1840; }","duration":"258.664747ms","start":"2026-08-11T12:58:12.027244Z","end":"2026-08-11T12:58:12.285909Z","steps":["trace[1796036771] 'range keys from in-memory index tree' (duration: 258.281837ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:58:12.285947Z","caller":"traceutil/trace.go:171","msg":"trace[253808050] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1840; }","duration":"257.560876ms","start":"2026-08-11T12:58:12.028367Z","end":"2026-08-11T12:58:12.285927Z","steps":["trace[253808050] 'range keys from in-memory index tree' (duration: 257.298079ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:58:12.311503Z","caller":"traceutil/trace.go:171","msg":"trace[1826680924] transaction","detail":"{read_only:false; response_revision:1841; number_of_response:1; }","duration":"222.511345ms","start":"2026-08-11T12:58:12.088963Z","end":"2026-08-11T12:58:12.311474Z","steps":["trace[1826680924] 'process raft request' (duration: 222.31485ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:58:12.353156Z","caller":"traceutil/trace.go:171","msg":"trace[894652465] transaction","detail":"{read_only:false; response_revision:1842; number_of_response:1; }","duration":"206.505842ms","start":"2026-08-11T12:58:12.146629Z","end":"2026-08-11T12:58:12.353135Z","steps":["trace[894652465] 'process raft request' (duration: 206.252175ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:58:12.353486Z","caller":"traceutil/trace.go:171","msg":"trace[1520989236] linearizableReadLoop","detail":"{readStateIndex:1913; appliedIndex:1911; }","duration":"250.820849ms","start":"2026-08-11T12:58:12.102638Z","end":"2026-08-11T12:58:12.353459Z","steps":["trace[1520989236] 'read index received' (duration: 208.59408ms)","trace[1520989236] 'applied index is now lower than readState.Index' (duration: 42.225719ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T12:58:12.353772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.113628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9e0d3d61-ade7-4cfe-9231-1345f7cea527\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-08-11T12:58:12.353939Z","caller":"traceutil/trace.go:171","msg":"trace[170805344] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9e0d3d61-ade7-4cfe-9231-1345f7cea527; range_end:; response_count:1; response_revision:1844; }","duration":"251.360904ms","start":"2026-08-11T12:58:12.102557Z","end":"2026-08-11T12:58:12.353918Z","steps":["trace[170805344] 'agreement among raft nodes before linearized reading' (duration: 250.996334ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:58:12.743811Z","caller":"traceutil/trace.go:171","msg":"trace[743654818] transaction","detail":"{read_only:false; response_revision:1848; number_of_response:1; }","duration":"309.462523ms","start":"2026-08-11T12:58:12.43432Z","end":"2026-08-11T12:58:12.743783Z","steps":["trace[743654818] 'process raft request' (duration: 309.230847ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:58:12.743893Z","caller":"traceutil/trace.go:171","msg":"trace[2042637704] linearizableReadLoop","detail":"{readStateIndex:1923; appliedIndex:1919; }","duration":"103.863988ms","start":"2026-08-11T12:58:12.640013Z","end":"2026-08-11T12:58:12.743877Z","steps":["trace[2042637704] 'read index received' (duration: 103.489808ms)","trace[2042637704] 'applied index is now lower than readState.Index' (duration: 373.47µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T12:58:12.743883Z","caller":"traceutil/trace.go:171","msg":"trace[1905385601] transaction","detail":"{read_only:false; response_revision:1849; number_of_response:1; }","duration":"309.423652ms","start":"2026-08-11T12:58:12.434419Z","end":"2026-08-11T12:58:12.743842Z","steps":["trace[1905385601] 'process raft request' (duration: 309.289208ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:58:12.744085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.061563ms","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":"info","ts":"2026-08-11T12:58:12.744137Z","caller":"traceutil/trace.go:171","msg":"trace[1173128738] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1851; }","duration":"104.167306ms","start":"2026-08-11T12:58:12.639954Z","end":"2026-08-11T12:58:12.744121Z","steps":["trace[1173128738] 'agreement among raft nodes before linearized reading' (duration: 103.986241ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:58:12.744226Z","caller":"traceutil/trace.go:171","msg":"trace[894293659] transaction","detail":"{read_only:false; response_revision:1850; number_of_response:1; }","duration":"308.628771ms","start":"2026-08-11T12:58:12.435567Z","end":"2026-08-11T12:58:12.744196Z","steps":["trace[894293659] 'process raft request' (duration: 308.194799ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:58:12.744277Z","caller":"traceutil/trace.go:171","msg":"trace[2132207365] transaction","detail":"{read_only:false; response_revision:1851; number_of_response:1; }","duration":"302.382779ms","start":"2026-08-11T12:58:12.441878Z","end":"2026-08-11T12:58:12.74426Z","steps":["trace[2132207365] 'process raft request' (duration: 301.927136ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:58:12.744791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:58:12.43441Z","time spent":"309.564706ms","remote":"127.0.0.1:34222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1530,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T12:58:12.744764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:58:12.434305Z","time spent":"309.597437ms","remote":"127.0.0.1:34120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":978,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T12:58:12.745124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:58:12.435547Z","time spent":"308.737943ms","remote":"127.0.0.1:34120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":894,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T12:58:12.7453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:58:12.441868Z","time spent":"302.43803ms","remote":"127.0.0.1:34128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T12:58:23.959438Z","caller":"traceutil/trace.go:171","msg":"trace[351369488] linearizableReadLoop","detail":"{readStateIndex:2019; appliedIndex:2018; }","duration":"135.32648ms","start":"2026-08-11T12:58:23.824087Z","end":"2026-08-11T12:58:23.959413Z","steps":["trace[351369488] 'read index received' (duration: 135.090914ms)","trace[351369488] 'applied index is now lower than readState.Index' (duration: 234.566µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T12:58:23.959536Z","caller":"traceutil/trace.go:171","msg":"trace[1677149255] transaction","detail":"{read_only:false; response_revision:1945; number_of_response:1; }","duration":"140.107142ms","start":"2026-08-11T12:58:23.819379Z","end":"2026-08-11T12:58:23.959486Z","steps":["trace[1677149255] 'process raft request' (duration: 139.860245ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:58:23.959715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.476162ms","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":"info","ts":"2026-08-11T12:58:23.959762Z","caller":"traceutil/trace.go:171","msg":"trace[122517537] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1945; }","duration":"127.607136ms","start":"2026-08-11T12:58:23.832142Z","end":"2026-08-11T12:58:23.959749Z","steps":["trace[122517537] 'agreement among raft nodes before linearized reading' (duration: 127.418221ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:58:23.959721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.622739ms","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-08-11T12:58:23.959829Z","caller":"traceutil/trace.go:171","msg":"trace[523761455] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1945; }","duration":"135.805803ms","start":"2026-08-11T12:58:23.824001Z","end":"2026-08-11T12:58:23.959807Z","steps":["trace[523761455] 'agreement among raft nodes before linearized reading' (duration: 135.514525ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:58:57.032133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.814034ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-11T12:58:57.032214Z","caller":"traceutil/trace.go:171","msg":"trace[668716738] transaction","detail":"{read_only:false; response_revision:2140; number_of_response:1; }","duration":"146.954981ms","start":"2026-08-11T12:58:56.885245Z","end":"2026-08-11T12:58:57.0322Z","steps":["trace[668716738] 'process raft request' (duration: 19.904351ms)","trace[668716738] 'compare' (duration: 126.646958ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T12:59:02.620924Z","caller":"traceutil/trace.go:171","msg":"trace[727959065] transaction","detail":"{read_only:false; response_revision:2169; number_of_response:1; }","duration":"159.220721ms","start":"2026-08-11T12:59:02.46168Z","end":"2026-08-11T12:59:02.620901Z","steps":["trace[727959065] 'process raft request' (duration: 159.068987ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:59:02.869425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.400611ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-11T12:59:02.869602Z","caller":"traceutil/trace.go:171","msg":"trace[2098060624] linearizableReadLoop","detail":"{readStateIndex:2254; appliedIndex:2252; }","duration":"204.107666ms","start":"2026-08-11T12:59:02.66548Z","end":"2026-08-11T12:59:02.869588Z","steps":["trace[2098060624] 'read index received' (duration: 47.903µs)","trace[2098060624] 'applied index is now lower than readState.Index' (duration: 204.058993ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T12:59:02.869605Z","caller":"traceutil/trace.go:171","msg":"trace[654771763] transaction","detail":"{read_only:false; response_revision:2170; number_of_response:1; }","duration":"266.192895ms","start":"2026-08-11T12:59:02.603387Z","end":"2026-08-11T12:59:02.86958Z","steps":["trace[654771763] 'process raft request' (duration: 34.549818ms)","trace[654771763] 'compare' (duration: 231.241176ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T12:59:02.869804Z","caller":"traceutil/trace.go:171","msg":"trace[1404766885] transaction","detail":"{read_only:false; response_revision:2171; number_of_response:1; }","duration":"259.026787ms","start":"2026-08-11T12:59:02.61075Z","end":"2026-08-11T12:59:02.869776Z","steps":["trace[1404766885] 'process raft request' (duration: 258.77876ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:59:02.869869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.372053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-11T12:59:02.869935Z","caller":"traceutil/trace.go:171","msg":"trace[1703232745] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2171; }","duration":"204.490616ms","start":"2026-08-11T12:59:02.66543Z","end":"2026-08-11T12:59:02.869921Z","steps":["trace[1703232745] 'agreement among raft nodes before linearized reading' (duration: 204.27022ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:59:02.869948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.997827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-11T12:59:02.870047Z","caller":"traceutil/trace.go:171","msg":"trace[788190213] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2171; }","duration":"193.129831ms","start":"2026-08-11T12:59:02.676879Z","end":"2026-08-11T12:59:02.870009Z","steps":["trace[788190213] 'agreement among raft nodes before linearized reading' (duration: 192.877963ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:59:03.064386Z","caller":"traceutil/trace.go:171","msg":"trace[1336704539] transaction","detail":"{read_only:false; response_revision:2173; number_of_response:1; }","duration":"189.989324ms","start":"2026-08-11T12:59:02.874371Z","end":"2026-08-11T12:59:03.06436Z","steps":["trace[1336704539] 'process raft request' (duration: 164.831417ms)","trace[1336704539] 'compare' (duration: 24.684034ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T13:00:26.977285Z","caller":"traceutil/trace.go:171","msg":"trace[1587785524] linearizableReadLoop","detail":"{readStateIndex:2925; appliedIndex:2924; }","duration":"116.802387ms","start":"2026-08-11T13:00:26.860457Z","end":"2026-08-11T13:00:26.977259Z","steps":["trace[1587785524] 'read index received' (duration: 24.58844ms)","trace[1587785524] 'applied index is now lower than readState.Index' (duration: 92.213077ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T13:00:26.977412Z","caller":"traceutil/trace.go:171","msg":"trace[1068584980] transaction","detail":"{read_only:false; response_revision:2818; number_of_response:1; }","duration":"147.89967ms","start":"2026-08-11T13:00:26.8295Z","end":"2026-08-11T13:00:26.977399Z","steps":["trace[1068584980] 'process raft request' (duration: 55.618421ms)","trace[1068584980] 'compare' (duration: 91.963661ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T13:00:26.977569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.072365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-11T13:00:26.977674Z","caller":"traceutil/trace.go:171","msg":"trace[24145054] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:2818; }","duration":"117.239499ms","start":"2026-08-11T13:00:26.860408Z","end":"2026-08-11T13:00:26.977648Z","steps":["trace[24145054] 'agreement among raft nodes before linearized reading' (duration: 117.060184ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:04:46.81189Z","caller":"traceutil/trace.go:171","msg":"trace[393938313] transaction","detail":"{read_only:false; response_revision:5314; number_of_response:1; }","duration":"129.837719ms","start":"2026-08-11T13:04:46.682015Z","end":"2026-08-11T13:04:46.811853Z","steps":["trace[393938313] 'process raft request' (duration: 50.024118ms)","trace[393938313] 'compare' (duration: 79.238645ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T13:04:46.812269Z","caller":"traceutil/trace.go:171","msg":"trace[2044310710] transaction","detail":"{read_only:false; response_revision:5315; number_of_response:1; }","duration":"127.04932ms","start":"2026-08-11T13:04:46.685187Z","end":"2026-08-11T13:04:46.812236Z","steps":["trace[2044310710] 'process raft request' (duration: 126.331591ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:04:47.505289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.34754ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-11T13:04:47.50561Z","caller":"traceutil/trace.go:171","msg":"trace[1611382839] transaction","detail":"{read_only:false; response_revision:5322; number_of_response:1; }","duration":"379.93302ms","start":"2026-08-11T13:04:47.12565Z","end":"2026-08-11T13:04:47.505583Z","steps":["trace[1611382839] 'process raft request' (duration: 379.813237ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:04:47.5057Z","caller":"traceutil/trace.go:171","msg":"trace[321235802] linearizableReadLoop","detail":"{readStateIndex:5521; appliedIndex:5520; }","duration":"491.419985ms","start":"2026-08-11T13:04:47.014253Z","end":"2026-08-11T13:04:47.505673Z","steps":["trace[321235802] 'read index received' (duration: 202.536811ms)","trace[321235802] 'applied index is now lower than readState.Index' (duration: 288.879124ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T13:04:47.505729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T13:04:47.125615Z","time spent":"380.062583ms","remote":"127.0.0.1:34220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T13:04:47.505802Z","caller":"traceutil/trace.go:171","msg":"trace[1005311848] transaction","detail":"{read_only:false; response_revision:5321; number_of_response:1; }","duration":"521.339831ms","start":"2026-08-11T13:04:46.984395Z","end":"2026-08-11T13:04:47.505735Z","steps":["trace[1005311848] 'process raft request' (duration: 232.409926ms)","trace[1005311848] 'compare' (duration: 287.890695ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T13:04:47.505942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.653723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T13:04:47.506053Z","caller":"traceutil/trace.go:171","msg":"trace[1934690590] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5322; }","duration":"491.809206ms","start":"2026-08-11T13:04:47.014215Z","end":"2026-08-11T13:04:47.506024Z","steps":["trace[1934690590] 'agreement among raft nodes before linearized reading' (duration: 491.534738ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:04:47.506079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T13:04:46.984354Z","time spent":"521.591678ms","remote":"127.0.0.1:57340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T13:04:47.506103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T13:04:47.0142Z","time spent":"491.894489ms","remote":"127.0.0.1:57302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-11T13:04:55.101188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.505609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:130046"} {"level":"info","ts":"2026-08-11T13:04:55.101301Z","caller":"traceutil/trace.go:171","msg":"trace[803663710] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:5524; }","duration":"100.696595ms","start":"2026-08-11T13:04:55.000582Z","end":"2026-08-11T13:04:55.101279Z","steps":["trace[803663710] 'range keys from in-memory index tree' (duration: 100.214181ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:04:55.101447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.310607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-08-11T13:04:55.101586Z","caller":"traceutil/trace.go:171","msg":"trace[2002305549] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:5524; }","duration":"108.522743ms","start":"2026-08-11T13:04:54.993036Z","end":"2026-08-11T13:04:55.101559Z","steps":["trace[2002305549] 'range keys from in-memory index tree' (duration: 108.196034ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:05:53.730204Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2968} {"level":"info","ts":"2026-08-11T13:05:53.804226Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2968,"took":"72.285271ms","hash":292090043,"current-db-size-bytes":58769408,"current-db-size":"59 MB","current-db-size-in-use-bytes":34099200,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-11T13:05:53.804311Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":292090043,"revision":2968,"compact-revision":-1} {"level":"info","ts":"2026-08-11T13:10:53.736988Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6044} {"level":"info","ts":"2026-08-11T13:10:53.833597Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6044,"took":"94.326794ms","hash":598943633,"current-db-size-bytes":58769408,"current-db-size":"59 MB","current-db-size-in-use-bytes":21893120,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-08-11T13:10:53.83366Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":598943633,"revision":6044,"compact-revision":2968} {"level":"info","ts":"2026-08-11T13:11:05.697873Z","caller":"traceutil/trace.go:171","msg":"trace[2087898923] transaction","detail":"{read_only:false; response_revision:8330; number_of_response:1; }","duration":"239.422622ms","start":"2026-08-11T13:11:05.458416Z","end":"2026-08-11T13:11:05.697838Z","steps":["trace[2087898923] 'process raft request' (duration: 239.093803ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:11:05.88682Z","caller":"traceutil/trace.go:171","msg":"trace[2086740287] transaction","detail":"{read_only:false; response_revision:8331; number_of_response:1; }","duration":"183.753134ms","start":"2026-08-11T13:11:05.703033Z","end":"2026-08-11T13:11:05.886786Z","steps":["trace[2086740287] 'process raft request' (duration: 183.289421ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:11:05.887704Z","caller":"traceutil/trace.go:171","msg":"trace[1529801823] transaction","detail":"{read_only:false; response_revision:8332; number_of_response:1; }","duration":"184.539905ms","start":"2026-08-11T13:11:05.703137Z","end":"2026-08-11T13:11:05.887677Z","steps":["trace[1529801823] 'process raft request' (duration: 184.276388ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:11:05.887885Z","caller":"traceutil/trace.go:171","msg":"trace[139753320] transaction","detail":"{read_only:false; response_revision:8333; number_of_response:1; }","duration":"184.665299ms","start":"2026-08-11T13:11:05.703201Z","end":"2026-08-11T13:11:05.887866Z","steps":["trace[139753320] 'process raft request' (duration: 184.416132ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:11:18.460318Z","caller":"traceutil/trace.go:171","msg":"trace[1016157933] linearizableReadLoop","detail":"{readStateIndex:8696; appliedIndex:8695; }","duration":"104.144988ms","start":"2026-08-11T13:11:18.356136Z","end":"2026-08-11T13:11:18.460281Z","steps":["trace[1016157933] 'read index received' (duration: 103.85955ms)","trace[1016157933] 'applied index is now lower than readState.Index' (duration: 283.838µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T13:11:18.460414Z","caller":"traceutil/trace.go:171","msg":"trace[1031654920] transaction","detail":"{read_only:false; response_revision:8399; number_of_response:1; }","duration":"110.856054ms","start":"2026-08-11T13:11:18.349531Z","end":"2026-08-11T13:11:18.460387Z","steps":["trace[1031654920] 'process raft request' (duration: 110.463713ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:11:18.460696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.518658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-tnmx6\" ","response":"range_response_count:1 size:6445"} {"level":"info","ts":"2026-08-11T13:11:18.460817Z","caller":"traceutil/trace.go:171","msg":"trace[539138217] range","detail":"{range_begin:/registry/pods/openstack/keepalived-tnmx6; range_end:; response_count:1; response_revision:8399; }","duration":"104.714944ms","start":"2026-08-11T13:11:18.356073Z","end":"2026-08-11T13:11:18.460788Z","steps":["trace[539138217] 'agreement among raft nodes before linearized reading' (duration: 104.341353ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:11:32.292376Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000224b.wal"} {"level":"info","ts":"2026-08-11T13:11:48.290201Z","caller":"traceutil/trace.go:171","msg":"trace[1664004191] transaction","detail":"{read_only:false; response_revision:8609; number_of_response:1; }","duration":"196.155958ms","start":"2026-08-11T13:11:48.094016Z","end":"2026-08-11T13:11:48.290172Z","steps":["trace[1664004191] 'process raft request' (duration: 195.830709ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:11:48.32419Z","caller":"traceutil/trace.go:171","msg":"trace[1192684245] linearizableReadLoop","detail":"{readStateIndex:8915; appliedIndex:8913; }","duration":"200.287574ms","start":"2026-08-11T13:11:48.123871Z","end":"2026-08-11T13:11:48.324159Z","steps":["trace[1192684245] 'read index received' (duration: 165.874535ms)","trace[1192684245] 'applied index is now lower than readState.Index' (duration: 34.411689ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T13:11:48.324466Z","caller":"traceutil/trace.go:171","msg":"trace[391835443] transaction","detail":"{read_only:false; response_revision:8610; number_of_response:1; }","duration":"227.699227ms","start":"2026-08-11T13:11:48.096725Z","end":"2026-08-11T13:11:48.324425Z","steps":["trace[391835443] 'process raft request' (duration: 226.309408ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:11:48.324797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.91387ms","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-08-11T13:11:48.324838Z","caller":"traceutil/trace.go:171","msg":"trace[1932039143] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8610; }","duration":"201.030684ms","start":"2026-08-11T13:11:48.123798Z","end":"2026-08-11T13:11:48.324828Z","steps":["trace[1932039143] 'agreement among raft nodes before linearized reading' (duration: 200.465469ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:11:56.601134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.948124ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-11T13:11:56.601443Z","caller":"traceutil/trace.go:171","msg":"trace[90105698] transaction","detail":"{read_only:false; response_revision:8652; number_of_response:1; }","duration":"227.919601ms","start":"2026-08-11T13:11:56.373494Z","end":"2026-08-11T13:11:56.601414Z","steps":["trace[90105698] 'process raft request' (duration: 110.572876ms)","trace[90105698] 'compare' (duration: 116.806771ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T13:11:56.606486Z","caller":"traceutil/trace.go:171","msg":"trace[1418151483] linearizableReadLoop","detail":"{readStateIndex:8960; appliedIndex:8958; }","duration":"168.255282ms","start":"2026-08-11T13:11:56.438196Z","end":"2026-08-11T13:11:56.606452Z","steps":["trace[1418151483] 'read index received' (duration: 45.882627ms)","trace[1418151483] 'applied index is now lower than readState.Index' (duration: 122.370835ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T13:11:56.606678Z","caller":"traceutil/trace.go:171","msg":"trace[534221851] transaction","detail":"{read_only:false; response_revision:8653; number_of_response:1; }","duration":"231.852141ms","start":"2026-08-11T13:11:56.374718Z","end":"2026-08-11T13:11:56.60657Z","steps":["trace[534221851] 'process raft request' (duration: 231.542003ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:11:56.606829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.696653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-08-11T13:11:56.606904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.0536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T13:11:56.607032Z","caller":"traceutil/trace.go:171","msg":"trace[518439601] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:8653; }","duration":"134.224625ms","start":"2026-08-11T13:11:56.472783Z","end":"2026-08-11T13:11:56.607007Z","steps":["trace[518439601] 'agreement among raft nodes before linearized reading' (duration: 133.957818ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:11:56.607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.761446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-11T13:11:56.606892Z","caller":"traceutil/trace.go:171","msg":"trace[2109992220] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8653; }","duration":"164.833547ms","start":"2026-08-11T13:11:56.442043Z","end":"2026-08-11T13:11:56.606877Z","steps":["trace[2109992220] 'agreement among raft nodes before linearized reading' (duration: 164.59636ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:11:56.6072Z","caller":"traceutil/trace.go:171","msg":"trace[798652026] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:8653; }","duration":"169.040703ms","start":"2026-08-11T13:11:56.438132Z","end":"2026-08-11T13:11:56.607173Z","steps":["trace[798652026] 'agreement among raft nodes before linearized reading' (duration: 168.449807ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:12:00.634838Z","caller":"traceutil/trace.go:171","msg":"trace[1674557356] transaction","detail":"{read_only:false; response_revision:8687; number_of_response:1; }","duration":"141.725444ms","start":"2026-08-11T13:12:00.493087Z","end":"2026-08-11T13:12:00.634813Z","steps":["trace[1674557356] 'process raft request' (duration: 85.130979ms)","trace[1674557356] 'compare' (duration: 56.488892ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T13:12:00.764121Z","caller":"traceutil/trace.go:171","msg":"trace[1869959646] transaction","detail":"{read_only:false; response_revision:8689; number_of_response:1; }","duration":"114.391893ms","start":"2026-08-11T13:12:00.649689Z","end":"2026-08-11T13:12:00.764081Z","steps":["trace[1869959646] 'process raft request' (duration: 85.473129ms)","trace[1869959646] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/plndr-svcs-lock; req_size:420; } (duration: 28.717659ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T13:12:00.960592Z","caller":"traceutil/trace.go:171","msg":"trace[1153360122] linearizableReadLoop","detail":"{readStateIndex:8999; appliedIndex:8998; }","duration":"196.450726ms","start":"2026-08-11T13:12:00.76411Z","end":"2026-08-11T13:12:00.960561Z","steps":["trace[1153360122] 'read index received' (duration: 153.007448ms)","trace[1153360122] 'applied index is now lower than readState.Index' (duration: 43.441778ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T13:12:00.960624Z","caller":"traceutil/trace.go:171","msg":"trace[2005172960] transaction","detail":"{read_only:false; response_revision:8691; number_of_response:1; }","duration":"214.185109ms","start":"2026-08-11T13:12:00.746405Z","end":"2026-08-11T13:12:00.96059Z","steps":["trace[2005172960] 'process raft request' (duration: 170.726731ms)","trace[2005172960] 'compare' (duration: 43.264873ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T13:12:00.960905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.717444ms","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-08-11T13:12:00.96095Z","caller":"traceutil/trace.go:171","msg":"trace[302324442] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8691; }","duration":"209.816597ms","start":"2026-08-11T13:12:00.751123Z","end":"2026-08-11T13:12:00.960939Z","steps":["trace[302324442] 'agreement among raft nodes before linearized reading' (duration: 209.611121ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:12:00.961246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.88338ms","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-08-11T13:12:00.961313Z","caller":"traceutil/trace.go:171","msg":"trace[1265612847] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:8691; }","duration":"108.988643ms","start":"2026-08-11T13:12:00.852309Z","end":"2026-08-11T13:12:00.961297Z","steps":["trace[1265612847] 'agreement among raft nodes before linearized reading' (duration: 108.8724ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:12:00.961321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.095329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-tnmx6\" ","response":"range_response_count:1 size:6445"} {"level":"info","ts":"2026-08-11T13:12:00.961368Z","caller":"traceutil/trace.go:171","msg":"trace[1502643525] range","detail":"{range_begin:/registry/pods/openstack/keepalived-tnmx6; range_end:; response_count:1; response_revision:8691; }","duration":"190.191662ms","start":"2026-08-11T13:12:00.771164Z","end":"2026-08-11T13:12:00.961355Z","steps":["trace[1502643525] 'agreement among raft nodes before linearized reading' (duration: 190.047099ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:12:00.961232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.546046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-9e0d3d61-ade7-4cfe-9231-1345f7cea527\" ","response":"range_response_count:1 size:1303"} {"level":"info","ts":"2026-08-11T13:12:00.961664Z","caller":"traceutil/trace.go:171","msg":"trace[1694135813] range","detail":"{range_begin:/registry/persistentvolumes/pvc-9e0d3d61-ade7-4cfe-9231-1345f7cea527; range_end:; response_count:1; response_revision:8691; }","duration":"193.949627ms","start":"2026-08-11T13:12:00.767624Z","end":"2026-08-11T13:12:00.961574Z","steps":["trace[1694135813] 'agreement among raft nodes before linearized reading' (duration: 193.476044ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:12:00.961809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.59885ms","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-08-11T13:12:00.961901Z","caller":"traceutil/trace.go:171","msg":"trace[39719903] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8691; }","duration":"262.33928ms","start":"2026-08-11T13:12:00.699549Z","end":"2026-08-11T13:12:00.961889Z","steps":["trace[39719903] 'agreement among raft nodes before linearized reading' (duration: 261.465766ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:13:23.557715Z","caller":"traceutil/trace.go:171","msg":"trace[1615306118] transaction","detail":"{read_only:false; response_revision:9502; number_of_response:1; }","duration":"138.805489ms","start":"2026-08-11T13:13:23.418858Z","end":"2026-08-11T13:13:23.557663Z","steps":["trace[1615306118] 'process raft request' (duration: 138.542372ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:13:43.658528Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"2d372260bb4c37a","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-11T13:13:43.664203Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-11T13:13:43.664316Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-11T13:15:53.743Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8133} {"level":"info","ts":"2026-08-11T13:15:53.826503Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8133,"took":"81.10524ms","hash":857828583,"current-db-size-bytes":58769408,"current-db-size":"59 MB","current-db-size-in-use-bytes":29192192,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-08-11T13:15:53.826603Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":857828583,"revision":8133,"compact-revision":6044} {"level":"info","ts":"2026-08-11T13:17:15.504612Z","caller":"traceutil/trace.go:171","msg":"trace[1657753394] transaction","detail":"{read_only:false; response_revision:11392; number_of_response:1; }","duration":"120.252041ms","start":"2026-08-11T13:17:15.384332Z","end":"2026-08-11T13:17:15.504584Z","steps":["trace[1657753394] 'process raft request' (duration: 119.948763ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:17:16.03775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.533446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-11T13:17:16.037765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.381811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T13:17:16.03786Z","caller":"traceutil/trace.go:171","msg":"trace[214650735] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:11394; }","duration":"164.652829ms","start":"2026-08-11T13:17:15.873167Z","end":"2026-08-11T13:17:16.03782Z","steps":["trace[214650735] 'range keys from in-memory index tree' (duration: 164.419042ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:17:16.037869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.830664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T13:17:16.037948Z","caller":"traceutil/trace.go:171","msg":"trace[2073226974] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:11394; }","duration":"164.963628ms","start":"2026-08-11T13:17:15.872969Z","end":"2026-08-11T13:17:16.037932Z","steps":["trace[2073226974] 'range keys from in-memory index tree' (duration: 164.70546ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:17:16.037888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.837824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T13:17:16.038037Z","caller":"traceutil/trace.go:171","msg":"trace[916388845] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:11394; }","duration":"165.02932ms","start":"2026-08-11T13:17:15.872976Z","end":"2026-08-11T13:17:16.038005Z","steps":["trace[916388845] 'range keys from in-memory index tree' (duration: 164.70001ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:17:16.037957Z","caller":"traceutil/trace.go:171","msg":"trace[2051464484] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:11394; }","duration":"164.585357ms","start":"2026-08-11T13:17:15.873295Z","end":"2026-08-11T13:17:16.037881Z","steps":["trace[2051464484] 'range keys from in-memory index tree' (duration: 164.273117ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:17:16.038225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.997809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/cinder-api\" ","response":"range_response_count:1 size:666"} {"level":"info","ts":"2026-08-11T13:17:16.038308Z","caller":"traceutil/trace.go:171","msg":"trace[2142546710] range","detail":"{range_begin:/registry/services/endpoints/openstack/cinder-api; range_end:; response_count:1; response_revision:11394; }","duration":"165.110231ms","start":"2026-08-11T13:17:15.873179Z","end":"2026-08-11T13:17:16.038289Z","steps":["trace[2142546710] 'range keys from in-memory index tree' (duration: 164.828674ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:17:16.038211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.403508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5004"} {"level":"info","ts":"2026-08-11T13:17:16.038367Z","caller":"traceutil/trace.go:171","msg":"trace[221104084] transaction","detail":"{read_only:false; response_revision:11395; number_of_response:1; }","duration":"158.809775ms","start":"2026-08-11T13:17:15.8795Z","end":"2026-08-11T13:17:16.03831Z","steps":["trace[221104084] 'process raft request' (duration: 116.032373ms)","trace[221104084] 'compare' (duration: 42.070192ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T13:17:16.038459Z","caller":"traceutil/trace.go:171","msg":"trace[949429437] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:11394; }","duration":"161.713677ms","start":"2026-08-11T13:17:15.876729Z","end":"2026-08-11T13:17:16.038443Z","steps":["trace[949429437] 'range keys from in-memory index tree' (duration: 161.188241ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:20:53.749408Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10641} {"level":"info","ts":"2026-08-11T13:20:53.834032Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10641,"took":"81.752687ms","hash":2435547477,"current-db-size-bytes":58769408,"current-db-size":"59 MB","current-db-size-in-use-bytes":26869760,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-08-11T13:20:53.83412Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2435547477,"revision":10641,"compact-revision":8133} {"level":"info","ts":"2026-08-11T13:23:19.524301Z","caller":"traceutil/trace.go:171","msg":"trace[734413473] transaction","detail":"{read_only:false; response_revision:13858; number_of_response:1; }","duration":"101.517131ms","start":"2026-08-11T13:23:19.422755Z","end":"2026-08-11T13:23:19.524272Z","steps":["trace[734413473] 'process raft request' (duration: 101.309725ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:23:59.465195Z","caller":"traceutil/trace.go:171","msg":"trace[302458759] transaction","detail":"{read_only:false; response_revision:14074; number_of_response:1; }","duration":"341.5385ms","start":"2026-08-11T13:23:59.123632Z","end":"2026-08-11T13:23:59.465171Z","steps":["trace[302458759] 'process raft request' (duration: 341.352545ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:23:59.465346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T13:23:59.123618Z","time spent":"341.651883ms","remote":"127.0.0.1:34220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T13:23:59.498137Z","caller":"traceutil/trace.go:171","msg":"trace[1222092868] transaction","detail":"{read_only:false; response_revision:14075; number_of_response:1; }","duration":"373.573458ms","start":"2026-08-11T13:23:59.12454Z","end":"2026-08-11T13:23:59.498114Z","steps":["trace[1222092868] 'process raft request' (duration: 350.097083ms)","trace[1222092868] 'compare' (duration: 23.124745ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T13:23:59.498163Z","caller":"traceutil/trace.go:171","msg":"trace[2000981754] transaction","detail":"{read_only:false; response_revision:14078; number_of_response:1; }","duration":"167.314948ms","start":"2026-08-11T13:23:59.330812Z","end":"2026-08-11T13:23:59.498126Z","steps":["trace[2000981754] 'process raft request' (duration: 167.247456ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:23:59.498223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T13:23:59.124505Z","time spent":"373.677611ms","remote":"127.0.0.1:34220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T13:23:59.498361Z","caller":"traceutil/trace.go:171","msg":"trace[1757618512] transaction","detail":"{read_only:false; response_revision:14076; number_of_response:1; }","duration":"373.699822ms","start":"2026-08-11T13:23:59.124624Z","end":"2026-08-11T13:23:59.498323Z","steps":["trace[1757618512] 'process raft request' (duration: 373.337291ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:23:59.49846Z","caller":"traceutil/trace.go:171","msg":"trace[1502308611] transaction","detail":"{read_only:false; response_revision:14077; number_of_response:1; }","duration":"168.208525ms","start":"2026-08-11T13:23:59.330238Z","end":"2026-08-11T13:23:59.498447Z","steps":["trace[1502308611] 'process raft request' (duration: 167.779052ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:23:59.498562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T13:23:59.124593Z","time spent":"373.858257ms","remote":"127.0.0.1:34220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T13:24:09.707388Z","caller":"traceutil/trace.go:171","msg":"trace[1170873766] linearizableReadLoop","detail":"{readStateIndex:14733; appliedIndex:14730; }","duration":"172.80708ms","start":"2026-08-11T13:24:09.534556Z","end":"2026-08-11T13:24:09.707363Z","steps":["trace[1170873766] 'read index received' (duration: 54.516962ms)","trace[1170873766] 'applied index is now lower than readState.Index' (duration: 118.288998ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T13:24:09.707445Z","caller":"traceutil/trace.go:171","msg":"trace[1440586443] transaction","detail":"{read_only:false; response_revision:14247; number_of_response:1; }","duration":"175.963303ms","start":"2026-08-11T13:24:09.531448Z","end":"2026-08-11T13:24:09.707412Z","steps":["trace[1440586443] 'process raft request' (duration: 175.869091ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:24:09.707435Z","caller":"traceutil/trace.go:171","msg":"trace[1711059783] transaction","detail":"{read_only:false; response_revision:14246; number_of_response:1; }","duration":"176.944832ms","start":"2026-08-11T13:24:09.53046Z","end":"2026-08-11T13:24:09.707405Z","steps":["trace[1711059783] 'process raft request' (duration: 173.637015ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:24:09.70764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.974487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-08-11T13:24:09.707678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.100489ms","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-08-11T13:24:09.707831Z","caller":"traceutil/trace.go:171","msg":"trace[813384074] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14247; }","duration":"167.204823ms","start":"2026-08-11T13:24:09.54061Z","end":"2026-08-11T13:24:09.707815Z","steps":["trace[813384074] 'agreement among raft nodes before linearized reading' (duration: 166.907935ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:24:09.707962Z","caller":"traceutil/trace.go:171","msg":"trace[127595576] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14247; }","duration":"173.333866ms","start":"2026-08-11T13:24:09.534495Z","end":"2026-08-11T13:24:09.707829Z","steps":["trace[127595576] 'agreement among raft nodes before linearized reading' (duration: 172.992466ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:25:53.756339Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12803} {"level":"info","ts":"2026-08-11T13:25:53.836726Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12803,"took":"77.270811ms","hash":520691101,"current-db-size-bytes":58769408,"current-db-size":"59 MB","current-db-size-in-use-bytes":30367744,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-11T13:25:53.836796Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":520691101,"revision":12803,"compact-revision":10641} {"level":"info","ts":"2026-08-11T13:30:53.763063Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15352} {"level":"info","ts":"2026-08-11T13:30:53.859671Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15352,"took":"92.682536ms","hash":292388277,"current-db-size-bytes":58769408,"current-db-size":"59 MB","current-db-size-in-use-bytes":31694848,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-11T13:30:53.859768Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":292388277,"revision":15352,"compact-revision":12803} {"level":"info","ts":"2026-08-11T13:31:31.098525Z","caller":"traceutil/trace.go:171","msg":"trace[1639550881] transaction","detail":"{read_only:false; response_revision:17862; number_of_response:1; }","duration":"111.285377ms","start":"2026-08-11T13:31:30.987189Z","end":"2026-08-11T13:31:31.098475Z","steps":["trace[1639550881] 'process raft request' (duration: 111.02265ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:31:31.774835Z","caller":"traceutil/trace.go:171","msg":"trace[1514398481] transaction","detail":"{read_only:false; response_revision:17865; number_of_response:1; }","duration":"216.672048ms","start":"2026-08-11T13:31:31.558112Z","end":"2026-08-11T13:31:31.774784Z","steps":["trace[1514398481] 'process raft request' (duration: 216.314779ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:31:32.036662Z","caller":"traceutil/trace.go:171","msg":"trace[1831826986] linearizableReadLoop","detail":"{readStateIndex:18477; appliedIndex:18473; }","duration":"360.438087ms","start":"2026-08-11T13:31:31.676193Z","end":"2026-08-11T13:31:32.036631Z","steps":["trace[1831826986] 'read index received' (duration: 98.461468ms)","trace[1831826986] 'applied index is now lower than readState.Index' (duration: 261.975109ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T13:31:32.03703Z","caller":"traceutil/trace.go:171","msg":"trace[236708576] transaction","detail":"{read_only:false; response_revision:17868; number_of_response:1; }","duration":"466.280211ms","start":"2026-08-11T13:31:31.570699Z","end":"2026-08-11T13:31:32.036979Z","steps":["trace[236708576] 'process raft request' (duration: 465.858419ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:31:32.037127Z","caller":"traceutil/trace.go:171","msg":"trace[369406822] transaction","detail":"{read_only:false; response_revision:17867; number_of_response:1; }","duration":"469.359068ms","start":"2026-08-11T13:31:31.567751Z","end":"2026-08-11T13:31:32.03711Z","steps":["trace[369406822] 'process raft request' (duration: 468.75318ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:31:32.03716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.93884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-08-11T13:31:32.037201Z","caller":"traceutil/trace.go:171","msg":"trace[738045862] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17868; }","duration":"361.051643ms","start":"2026-08-11T13:31:31.676139Z","end":"2026-08-11T13:31:32.03719Z","steps":["trace[738045862] 'agreement among raft nodes before linearized reading' (duration: 360.830767ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:31:32.037235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T13:31:31.676115Z","time spent":"361.110685ms","remote":"127.0.0.1:34220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-11T13:31:32.037261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T13:31:31.570665Z","time spent":"466.497157ms","remote":"127.0.0.1:34220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T13:31:32.037323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T13:31:31.567699Z","time spent":"469.473491ms","remote":"127.0.0.1:34220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T13:31:32.037051Z","caller":"traceutil/trace.go:171","msg":"trace[860842918] transaction","detail":"{read_only:false; response_revision:17866; number_of_response:1; }","duration":"471.404655ms","start":"2026-08-11T13:31:31.565607Z","end":"2026-08-11T13:31:32.037012Z","steps":["trace[860842918] 'process raft request' (duration: 470.698615ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:31:32.03742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T13:31:31.565593Z","time spent":"471.794855ms","remote":"127.0.0.1:34220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T13:31:32.106667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.317304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:417126"} {"level":"info","ts":"2026-08-11T13:31:32.10673Z","caller":"traceutil/trace.go:171","msg":"trace[792591242] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:17868; }","duration":"174.449478ms","start":"2026-08-11T13:31:31.932266Z","end":"2026-08-11T13:31:32.106716Z","steps":["trace[792591242] 'agreement among raft nodes before linearized reading' (duration: 174.20146ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:31:38.357527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.159133ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-11T13:31:38.35795Z","caller":"traceutil/trace.go:171","msg":"trace[259385758] transaction","detail":"{read_only:false; response_revision:17976; number_of_response:1; }","duration":"112.482581ms","start":"2026-08-11T13:31:38.245429Z","end":"2026-08-11T13:31:38.357911Z","steps":["trace[259385758] 'compare' (duration: 105.991649ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:31:38.358101Z","caller":"traceutil/trace.go:171","msg":"trace[574136973] transaction","detail":"{read_only:false; response_revision:17977; number_of_response:1; }","duration":"110.886496ms","start":"2026-08-11T13:31:38.247168Z","end":"2026-08-11T13:31:38.358055Z","steps":["trace[574136973] 'process raft request' (duration: 110.498495ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:34:22.465154Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"2d372260bb4c37a","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-11T13:34:22.471247Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-11T13:34:22.471388Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-11T13:35:53.770162Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17570} {"level":"info","ts":"2026-08-11T13:35:53.863766Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17570,"took":"90.018491ms","hash":1958778962,"current-db-size-bytes":58769408,"current-db-size":"59 MB","current-db-size-in-use-bytes":33955840,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-11T13:35:53.863837Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1958778962,"revision":17570,"compact-revision":15352} {"level":"warn","ts":"2026-08-11T13:37:08.203658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.184257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-11T13:37:08.203806Z","caller":"traceutil/trace.go:171","msg":"trace[1494795609] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:20494; }","duration":"110.423004ms","start":"2026-08-11T13:37:08.093353Z","end":"2026-08-11T13:37:08.203776Z","steps":["trace[1494795609] 'count revisions from in-memory index tree' (duration: 110.033994ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:37:24.344794Z","caller":"traceutil/trace.go:171","msg":"trace[181389893] transaction","detail":"{read_only:false; response_revision:20575; number_of_response:1; }","duration":"231.298299ms","start":"2026-08-11T13:37:24.113455Z","end":"2026-08-11T13:37:24.344753Z","steps":["trace[181389893] 'process raft request' (duration: 231.086662ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:37:24.377558Z","caller":"traceutil/trace.go:171","msg":"trace[1833314234] linearizableReadLoop","detail":"{readStateIndex:21277; appliedIndex:21275; }","duration":"118.16517ms","start":"2026-08-11T13:37:24.259352Z","end":"2026-08-11T13:37:24.377517Z","steps":["trace[1833314234] 'read index received' (duration: 85.216497ms)","trace[1833314234] 'applied index is now lower than readState.Index' (duration: 32.947423ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T13:37:24.377628Z","caller":"traceutil/trace.go:171","msg":"trace[975215868] transaction","detail":"{read_only:false; response_revision:20576; number_of_response:1; }","duration":"260.293952ms","start":"2026-08-11T13:37:24.11731Z","end":"2026-08-11T13:37:24.377604Z","steps":["trace[975215868] 'process raft request' (duration: 260.055865ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:37:24.377753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.367276ms","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:6"} {"level":"info","ts":"2026-08-11T13:37:24.37787Z","caller":"traceutil/trace.go:171","msg":"trace[2082564935] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:20576; }","duration":"118.538891ms","start":"2026-08-11T13:37:24.259317Z","end":"2026-08-11T13:37:24.377856Z","steps":["trace[2082564935] 'agreement among raft nodes before linearized reading' (duration: 118.352945ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:37:24.506655Z","caller":"traceutil/trace.go:171","msg":"trace[1208775573] linearizableReadLoop","detail":"{readStateIndex:21278; appliedIndex:21277; }","duration":"128.972513ms","start":"2026-08-11T13:37:24.37763Z","end":"2026-08-11T13:37:24.506603Z","steps":["trace[1208775573] 'read index received' (duration: 115.851436ms)","trace[1208775573] 'applied index is now lower than readState.Index' (duration: 13.118767ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T13:37:24.506667Z","caller":"traceutil/trace.go:171","msg":"trace[1478998951] transaction","detail":"{read_only:false; response_revision:20577; number_of_response:1; }","duration":"150.611748ms","start":"2026-08-11T13:37:24.35602Z","end":"2026-08-11T13:37:24.506632Z","steps":["trace[1478998951] 'process raft request' (duration: 137.433449ms)","trace[1478998951] 'compare' (duration: 12.696445ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T13:37:24.507163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.854461ms","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":"warn","ts":"2026-08-11T13:37:24.507188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.245209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-08-11T13:37:24.5072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.659031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-11T13:37:24.5073Z","caller":"traceutil/trace.go:171","msg":"trace[136551806] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20577; }","duration":"202.978716ms","start":"2026-08-11T13:37:24.304271Z","end":"2026-08-11T13:37:24.50725Z","steps":["trace[136551806] 'agreement among raft nodes before linearized reading' (duration: 202.722668ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:37:24.507295Z","caller":"traceutil/trace.go:171","msg":"trace[1512225601] range","detail":"{range_begin:/registry/monitoring.coreos.com/servicemonitors/; range_end:/registry/monitoring.coreos.com/servicemonitors0; response_count:0; response_revision:20577; }","duration":"129.820767ms","start":"2026-08-11T13:37:24.377455Z","end":"2026-08-11T13:37:24.507276Z","steps":["trace[1512225601] 'agreement among raft nodes before linearized reading' (duration: 129.615091ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:37:24.507293Z","caller":"traceutil/trace.go:171","msg":"trace[1722217890] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20577; }","duration":"200.384333ms","start":"2026-08-11T13:37:24.306883Z","end":"2026-08-11T13:37:24.507267Z","steps":["trace[1722217890] 'agreement among raft nodes before linearized reading' (duration: 200.108354ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:37:24.764683Z","caller":"traceutil/trace.go:171","msg":"trace[1265393159] transaction","detail":"{read_only:false; response_revision:20578; number_of_response:1; }","duration":"251.898066ms","start":"2026-08-11T13:37:24.512758Z","end":"2026-08-11T13:37:24.764656Z","steps":["trace[1265393159] 'process raft request' (duration: 193.737437ms)","trace[1265393159] 'compare' (duration: 57.699736ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T13:37:24.764887Z","caller":"traceutil/trace.go:171","msg":"trace[517547551] transaction","detail":"{read_only:false; response_revision:20579; number_of_response:1; }","duration":"252.07081ms","start":"2026-08-11T13:37:24.512789Z","end":"2026-08-11T13:37:24.76486Z","steps":["trace[517547551] 'process raft request' (duration: 251.649528ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:37:25.161201Z","caller":"traceutil/trace.go:171","msg":"trace[484556442] transaction","detail":"{read_only:false; response_revision:20580; number_of_response:1; }","duration":"125.998729ms","start":"2026-08-11T13:37:25.035164Z","end":"2026-08-11T13:37:25.161163Z","steps":["trace[484556442] 'process raft request' (duration: 125.487575ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:40:53.777386Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20053} {"level":"info","ts":"2026-08-11T13:40:53.890307Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20053,"took":"108.337476ms","hash":3598536720,"current-db-size-bytes":58769408,"current-db-size":"59 MB","current-db-size-in-use-bytes":30715904,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-11T13:40:53.890388Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3598536720,"revision":20053,"compact-revision":17570} {"level":"warn","ts":"2026-08-11T13:41:23.983763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.010399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-08-11T13:41:23.983864Z","caller":"traceutil/trace.go:171","msg":"trace[1157614263] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:23041; }","duration":"148.147194ms","start":"2026-08-11T13:41:23.835697Z","end":"2026-08-11T13:41:23.983844Z","steps":["trace[1157614263] 'range keys from in-memory index tree' (duration: 147.851064ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:41:23.9841Z","caller":"traceutil/trace.go:171","msg":"trace[492308764] transaction","detail":"{read_only:false; response_revision:23042; number_of_response:1; }","duration":"120.974831ms","start":"2026-08-11T13:41:23.863082Z","end":"2026-08-11T13:41:23.984056Z","steps":["trace[492308764] 'process raft request' (duration: 117.799721ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:45:03.465764Z","caller":"traceutil/trace.go:171","msg":"trace[461933870] transaction","detail":"{read_only:false; response_revision:25229; number_of_response:1; }","duration":"138.966956ms","start":"2026-08-11T13:45:03.326691Z","end":"2026-08-11T13:45:03.465658Z","steps":["trace[461933870] 'process raft request' (duration: 138.706068ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:45:03.600258Z","caller":"traceutil/trace.go:171","msg":"trace[1564851162] linearizableReadLoop","detail":"{readStateIndex:26059; appliedIndex:26056; }","duration":"214.624442ms","start":"2026-08-11T13:45:03.385597Z","end":"2026-08-11T13:45:03.600221Z","steps":["trace[1564851162] 'read index received' (duration: 79.713951ms)","trace[1564851162] 'applied index is now lower than readState.Index' (duration: 134.909061ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T13:45:03.600668Z","caller":"traceutil/trace.go:171","msg":"trace[1357554708] transaction","detail":"{read_only:false; response_revision:25230; number_of_response:1; }","duration":"271.84177ms","start":"2026-08-11T13:45:03.328799Z","end":"2026-08-11T13:45:03.600641Z","steps":["trace[1357554708] 'process raft request' (duration: 271.10552ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:45:03.60091Z","caller":"traceutil/trace.go:171","msg":"trace[1715417510] transaction","detail":"{read_only:false; response_revision:25231; number_of_response:1; }","duration":"258.059363ms","start":"2026-08-11T13:45:03.342821Z","end":"2026-08-11T13:45:03.600881Z","steps":["trace[1715417510] 'process raft request' (duration: 257.295422ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:45:03.601258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.404038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-11T13:45:03.601257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.812618ms","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-08-11T13:45:03.601315Z","caller":"traceutil/trace.go:171","msg":"trace[1909372593] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinepools/; range_end:/registry/cluster.x-k8s.io/machinepools0; response_count:0; response_revision:25232; }","duration":"160.595563ms","start":"2026-08-11T13:45:03.440703Z","end":"2026-08-11T13:45:03.601299Z","steps":["trace[1909372593] 'agreement among raft nodes before linearized reading' (duration: 160.46832ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:45:03.601351Z","caller":"traceutil/trace.go:171","msg":"trace[161416801] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25232; }","duration":"208.951472ms","start":"2026-08-11T13:45:03.392378Z","end":"2026-08-11T13:45:03.601329Z","steps":["trace[161416801] 'agreement among raft nodes before linearized reading' (duration: 208.667934ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:45:03.601425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.821145ms","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-08-11T13:45:03.601468Z","caller":"traceutil/trace.go:171","msg":"trace[1911259041] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25232; }","duration":"215.92001ms","start":"2026-08-11T13:45:03.385534Z","end":"2026-08-11T13:45:03.601454Z","steps":["trace[1911259041] 'agreement among raft nodes before linearized reading' (duration: 215.505057ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:45:04.389858Z","caller":"traceutil/trace.go:171","msg":"trace[1799922291] transaction","detail":"{read_only:false; response_revision:25237; number_of_response:1; }","duration":"251.048546ms","start":"2026-08-11T13:45:04.138752Z","end":"2026-08-11T13:45:04.389801Z","steps":["trace[1799922291] 'process raft request' (duration: 250.84449ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:45:09.654938Z","caller":"traceutil/trace.go:171","msg":"trace[1097236596] transaction","detail":"{read_only:false; response_revision:25284; number_of_response:1; }","duration":"161.379455ms","start":"2026-08-11T13:45:09.493525Z","end":"2026-08-11T13:45:09.654905Z","steps":["trace[1097236596] 'process raft request' (duration: 161.111168ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:45:09.800438Z","caller":"traceutil/trace.go:171","msg":"trace[637331517] linearizableReadLoop","detail":"{readStateIndex:26115; appliedIndex:26113; }","duration":"145.55624ms","start":"2026-08-11T13:45:09.654841Z","end":"2026-08-11T13:45:09.800398Z","steps":["trace[637331517] 'read index received' (duration: 129.758417ms)","trace[637331517] 'applied index is now lower than readState.Index' (duration: 15.796373ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T13:45:09.800594Z","caller":"traceutil/trace.go:171","msg":"trace[562223632] transaction","detail":"{read_only:false; response_revision:25285; number_of_response:1; }","duration":"180.547074ms","start":"2026-08-11T13:45:09.620011Z","end":"2026-08-11T13:45:09.800559Z","steps":["trace[562223632] 'process raft request' (duration: 164.706579ms)","trace[562223632] 'compare' (duration: 15.480125ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T13:45:09.800617Z","caller":"traceutil/trace.go:171","msg":"trace[812883056] transaction","detail":"{read_only:false; response_revision:25286; number_of_response:1; }","duration":"178.378422ms","start":"2026-08-11T13:45:09.622195Z","end":"2026-08-11T13:45:09.800574Z","steps":["trace[812883056] 'process raft request' (duration: 178.145956ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:45:09.800856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.275014ms","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-08-11T13:45:09.800931Z","caller":"traceutil/trace.go:171","msg":"trace[1059330732] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25286; }","duration":"144.410478ms","start":"2026-08-11T13:45:09.656498Z","end":"2026-08-11T13:45:09.800908Z","steps":["trace[1059330732] 'agreement among raft nodes before linearized reading' (duration: 144.171221ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:45:09.800865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.054445ms","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-08-11T13:45:09.801029Z","caller":"traceutil/trace.go:171","msg":"trace[949550699] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25286; }","duration":"146.295192ms","start":"2026-08-11T13:45:09.654718Z","end":"2026-08-11T13:45:09.801013Z","steps":["trace[949550699] 'agreement among raft nodes before linearized reading' (duration: 145.839309ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:45:10.135397Z","caller":"traceutil/trace.go:171","msg":"trace[1102059776] transaction","detail":"{read_only:false; response_revision:25289; number_of_response:1; }","duration":"327.416862ms","start":"2026-08-11T13:45:09.807951Z","end":"2026-08-11T13:45:10.135368Z","steps":["trace[1102059776] 'process raft request' (duration: 327.35615ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:45:10.13558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T13:45:09.807944Z","time spent":"327.538745ms","remote":"127.0.0.1:34220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T13:45:10.136012Z","caller":"traceutil/trace.go:171","msg":"trace[1997010366] transaction","detail":"{read_only:false; response_revision:25288; number_of_response:1; }","duration":"329.430627ms","start":"2026-08-11T13:45:09.806568Z","end":"2026-08-11T13:45:10.135998Z","steps":["trace[1997010366] 'process raft request' (duration: 328.546093ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:45:10.136077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T13:45:09.806548Z","time spent":"329.49077ms","remote":"127.0.0.1:34220","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-08-11T13:45:10.136115Z","caller":"traceutil/trace.go:171","msg":"trace[101708391] linearizableReadLoop","detail":"{readStateIndex:26119; appliedIndex:26116; }","duration":"126.445544ms","start":"2026-08-11T13:45:10.009644Z","end":"2026-08-11T13:45:10.136089Z","steps":["trace[101708391] 'read index received' (duration: 125.416955ms)","trace[101708391] 'applied index is now lower than readState.Index' (duration: 1.027349ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T13:45:10.136224Z","caller":"traceutil/trace.go:171","msg":"trace[756618639] transaction","detail":"{read_only:false; response_revision:25290; number_of_response:1; }","duration":"243.791061ms","start":"2026-08-11T13:45:09.892423Z","end":"2026-08-11T13:45:10.136214Z","steps":["trace[756618639] 'process raft request' (duration: 243.464772ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:45:10.136406Z","caller":"traceutil/trace.go:171","msg":"trace[1187807975] transaction","detail":"{read_only:false; response_revision:25291; number_of_response:1; }","duration":"106.545844ms","start":"2026-08-11T13:45:10.029852Z","end":"2026-08-11T13:45:10.136397Z","steps":["trace[1187807975] 'process raft request' (duration: 106.185154ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:45:10.136542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.882436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-11T13:45:10.136572Z","caller":"traceutil/trace.go:171","msg":"trace[1534062852] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:25291; }","duration":"126.989169ms","start":"2026-08-11T13:45:10.009571Z","end":"2026-08-11T13:45:10.13656Z","steps":["trace[1534062852] 'agreement among raft nodes before linearized reading' (duration: 126.901337ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:45:10.136778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.75815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-11T13:45:10.136818Z","caller":"traceutil/trace.go:171","msg":"trace[1776951071] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25291; }","duration":"122.843722ms","start":"2026-08-11T13:45:10.013963Z","end":"2026-08-11T13:45:10.136807Z","steps":["trace[1776951071] 'agreement among raft nodes before linearized reading' (duration: 122.77012ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:45:10.557302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.431735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/users/\" range_end:\"/registry/openstack.k-orc.cloud/users0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-11T13:45:10.557389Z","caller":"traceutil/trace.go:171","msg":"trace[1074663108] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/users/; range_end:/registry/openstack.k-orc.cloud/users0; response_count:0; response_revision:25291; }","duration":"156.602231ms","start":"2026-08-11T13:45:10.400766Z","end":"2026-08-11T13:45:10.557368Z","steps":["trace[1074663108] 'count revisions from in-memory index tree' (duration: 156.278091ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:45:10.558306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.161255ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-11T13:45:10.558925Z","caller":"traceutil/trace.go:171","msg":"trace[1008207803] transaction","detail":"{read_only:false; response_revision:25292; number_of_response:1; }","duration":"143.642567ms","start":"2026-08-11T13:45:10.415251Z","end":"2026-08-11T13:45:10.558894Z","steps":["trace[1008207803] 'process raft request' (duration: 16.750181ms)","trace[1008207803] 'compare' (duration: 125.717083ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T13:45:10.580726Z","caller":"traceutil/trace.go:171","msg":"trace[1560282629] transaction","detail":"{read_only:false; response_revision:25293; number_of_response:1; }","duration":"146.846397ms","start":"2026-08-11T13:45:10.433594Z","end":"2026-08-11T13:45:10.580441Z","steps":["trace[1560282629] 'process raft request' (duration: 146.689163ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:45:11.040744Z","caller":"traceutil/trace.go:171","msg":"trace[1788701387] transaction","detail":"{read_only:false; response_revision:25294; number_of_response:1; }","duration":"183.295881ms","start":"2026-08-11T13:45:10.857412Z","end":"2026-08-11T13:45:11.040708Z","steps":["trace[1788701387] 'process raft request' (duration: 183.094115ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:45:11.041579Z","caller":"traceutil/trace.go:171","msg":"trace[1844708619] transaction","detail":"{read_only:false; response_revision:25295; number_of_response:1; }","duration":"177.817577ms","start":"2026-08-11T13:45:10.863744Z","end":"2026-08-11T13:45:11.041562Z","steps":["trace[1844708619] 'process raft request' (duration: 177.409296ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:45:11.041965Z","caller":"traceutil/trace.go:171","msg":"trace[1863835265] linearizableReadLoop","detail":"{readStateIndex:26124; appliedIndex:26122; }","duration":"119.716474ms","start":"2026-08-11T13:45:10.921758Z","end":"2026-08-11T13:45:11.041475Z","steps":["trace[1863835265] 'read index received' (duration: 118.761737ms)","trace[1863835265] 'applied index is now lower than readState.Index' (duration: 953.657µs)"],"step_count":2} {"level":"warn","ts":"2026-08-11T13:45:11.042168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.399404ms","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-08-11T13:45:11.042224Z","caller":"traceutil/trace.go:171","msg":"trace[1065456145] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25295; }","duration":"120.542708ms","start":"2026-08-11T13:45:10.921668Z","end":"2026-08-11T13:45:11.042211Z","steps":["trace[1065456145] 'agreement among raft nodes before linearized reading' (duration: 120.340483ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:45:11.042664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.464151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-08-11T13:45:11.042735Z","caller":"traceutil/trace.go:171","msg":"trace[1680876847] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25295; }","duration":"117.812191ms","start":"2026-08-11T13:45:10.924909Z","end":"2026-08-11T13:45:11.042721Z","steps":["trace[1680876847] 'agreement among raft nodes before linearized reading' (duration: 117.195984ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:45:11.238619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.565522ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-11T13:45:11.238818Z","caller":"traceutil/trace.go:171","msg":"trace[240463307] transaction","detail":"{read_only:false; response_revision:25298; number_of_response:1; }","duration":"145.853969ms","start":"2026-08-11T13:45:11.092946Z","end":"2026-08-11T13:45:11.2388Z","steps":["trace[240463307] 'process raft request' (duration: 145.789297ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:45:11.238872Z","caller":"traceutil/trace.go:171","msg":"trace[119947877] transaction","detail":"{read_only:false; response_revision:25297; number_of_response:1; }","duration":"191.457231ms","start":"2026-08-11T13:45:11.04738Z","end":"2026-08-11T13:45:11.238837Z","steps":["trace[119947877] 'process raft request' (duration: 14.56182ms)","trace[119947877] 'compare' (duration: 176.390637ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T13:45:11.480537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.317465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-11T13:45:11.480665Z","caller":"traceutil/trace.go:171","msg":"trace[1835124416] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddressclaims/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddressclaims0; response_count:0; response_revision:25298; }","duration":"102.459719ms","start":"2026-08-11T13:45:11.37816Z","end":"2026-08-11T13:45:11.480619Z","steps":["trace[1835124416] 'count revisions from in-memory index tree' (duration: 102.197072ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:45:12.172438Z","caller":"traceutil/trace.go:171","msg":"trace[1426780803] transaction","detail":"{read_only:false; response_revision:25299; number_of_response:1; }","duration":"510.824066ms","start":"2026-08-11T13:45:11.661583Z","end":"2026-08-11T13:45:12.172407Z","steps":["trace[1426780803] 'process raft request' (duration: 510.632821ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:45:12.172614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T13:45:11.661547Z","time spent":"510.96985ms","remote":"127.0.0.1:34220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T13:45:12.292496Z","caller":"traceutil/trace.go:171","msg":"trace[492152825] transaction","detail":"{read_only:false; response_revision:25300; number_of_response:1; }","duration":"484.446245ms","start":"2026-08-11T13:45:11.80801Z","end":"2026-08-11T13:45:12.292456Z","steps":["trace[492152825] 'process raft request' (duration: 482.472519ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:45:12.292697Z","caller":"traceutil/trace.go:171","msg":"trace[1265027852] transaction","detail":"{read_only:false; response_revision:25301; number_of_response:1; }","duration":"484.325911ms","start":"2026-08-11T13:45:11.808349Z","end":"2026-08-11T13:45:12.292675Z","steps":["trace[1265027852] 'process raft request' (duration: 484.008432ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:45:12.292946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T13:45:11.807974Z","time spent":"484.861047ms","remote":"127.0.0.1:34220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T13:45:12.293111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T13:45:11.808329Z","time spent":"484.63905ms","remote":"127.0.0.1:34220","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-08-11T13:45:12.304724Z","caller":"traceutil/trace.go:171","msg":"trace[572041481] linearizableReadLoop","detail":"{readStateIndex:26130; appliedIndex:26127; }","duration":"468.758454ms","start":"2026-08-11T13:45:11.835942Z","end":"2026-08-11T13:45:12.304701Z","steps":["trace[572041481] 'read index received' (duration: 336.302532ms)","trace[572041481] 'applied index is now lower than readState.Index' (duration: 132.454632ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T13:45:12.304781Z","caller":"traceutil/trace.go:171","msg":"trace[646017435] transaction","detail":"{read_only:false; response_revision:25302; number_of_response:1; }","duration":"162.23228ms","start":"2026-08-11T13:45:12.142532Z","end":"2026-08-11T13:45:12.304764Z","steps":["trace[646017435] 'process raft request' (duration: 161.981943ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:45:12.305006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.066962ms","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-08-11T13:45:12.305052Z","caller":"traceutil/trace.go:171","msg":"trace[344024102] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25302; }","duration":"469.159805ms","start":"2026-08-11T13:45:11.83588Z","end":"2026-08-11T13:45:12.30504Z","steps":["trace[344024102] 'agreement among raft nodes before linearized reading' (duration: 468.97494ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:45:12.30509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T13:45:11.835864Z","time spent":"469.219236ms","remote":"127.0.0.1:34220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-11T13:45:12.305209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.018438ms","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-08-11T13:45:12.305249Z","caller":"traceutil/trace.go:171","msg":"trace[1274488118] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25302; }","duration":"164.095461ms","start":"2026-08-11T13:45:12.141144Z","end":"2026-08-11T13:45:12.305239Z","steps":["trace[1274488118] 'agreement among raft nodes before linearized reading' (duration: 163.954647ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:45:12.305325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.947447ms","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":"warn","ts":"2026-08-11T13:45:12.305385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.169328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-11T13:45:12.305415Z","caller":"traceutil/trace.go:171","msg":"trace[1271060181] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25302; }","duration":"164.099132ms","start":"2026-08-11T13:45:12.141295Z","end":"2026-08-11T13:45:12.305394Z","steps":["trace[1271060181] 'agreement among raft nodes before linearized reading' (duration: 163.795783ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:45:12.305436Z","caller":"traceutil/trace.go:171","msg":"trace[1963296802] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25302; }","duration":"294.255211ms","start":"2026-08-11T13:45:12.011171Z","end":"2026-08-11T13:45:12.305426Z","steps":["trace[1963296802] 'agreement among raft nodes before linearized reading' (duration: 293.89921ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:45:14.64789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.429117ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-11T13:45:14.648148Z","caller":"traceutil/trace.go:171","msg":"trace[566856203] transaction","detail":"{read_only:false; response_revision:25319; number_of_response:1; }","duration":"259.422971ms","start":"2026-08-11T13:45:14.388679Z","end":"2026-08-11T13:45:14.648102Z","steps":["trace[566856203] 'process raft request' (duration: 123.620244ms)","trace[566856203] 'compare' (duration: 135.19218ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T13:45:14.648214Z","caller":"traceutil/trace.go:171","msg":"trace[2024440045] linearizableReadLoop","detail":"{readStateIndex:26151; appliedIndex:26148; }","duration":"257.401504ms","start":"2026-08-11T13:45:14.390776Z","end":"2026-08-11T13:45:14.648177Z","steps":["trace[2024440045] 'read index received' (duration: 121.670569ms)","trace[2024440045] 'applied index is now lower than readState.Index' (duration: 135.728755ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T13:45:14.648353Z","caller":"traceutil/trace.go:171","msg":"trace[1684538917] transaction","detail":"{read_only:false; response_revision:25320; number_of_response:1; }","duration":"259.612207ms","start":"2026-08-11T13:45:14.388692Z","end":"2026-08-11T13:45:14.648304Z","steps":["trace[1684538917] 'process raft request' (duration: 259.34656ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:45:14.648594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.779325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-11T13:45:14.648688Z","caller":"traceutil/trace.go:171","msg":"trace[412759860] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25321; }","duration":"257.903999ms","start":"2026-08-11T13:45:14.390763Z","end":"2026-08-11T13:45:14.648667Z","steps":["trace[412759860] 'agreement among raft nodes before linearized reading' (duration: 257.56548ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:45:14.649006Z","caller":"traceutil/trace.go:171","msg":"trace[1519636927] transaction","detail":"{read_only:false; response_revision:25321; number_of_response:1; }","duration":"260.217314ms","start":"2026-08-11T13:45:14.388727Z","end":"2026-08-11T13:45:14.648944Z","steps":["trace[1519636927] 'process raft request' (duration: 259.390391ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:45:14.91515Z","caller":"traceutil/trace.go:171","msg":"trace[1400986670] transaction","detail":"{read_only:false; response_revision:25322; number_of_response:1; }","duration":"343.082172ms","start":"2026-08-11T13:45:14.572025Z","end":"2026-08-11T13:45:14.915107Z","steps":["trace[1400986670] 'process raft request' (duration: 341.601271ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:45:14.915345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T13:45:14.571985Z","time spent":"343.259857ms","remote":"127.0.0.1:34220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T13:45:14.927437Z","caller":"traceutil/trace.go:171","msg":"trace[1500965779] linearizableReadLoop","detail":"{readStateIndex:26153; appliedIndex:26151; }","duration":"279.135554ms","start":"2026-08-11T13:45:14.648271Z","end":"2026-08-11T13:45:14.927407Z","steps":["trace[1500965779] 'read index received' (duration: 265.40835ms)","trace[1500965779] 'applied index is now lower than readState.Index' (duration: 13.725804ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T13:45:14.927554Z","caller":"traceutil/trace.go:171","msg":"trace[1719113974] transaction","detail":"{read_only:false; response_revision:25323; number_of_response:1; }","duration":"309.517169ms","start":"2026-08-11T13:45:14.617997Z","end":"2026-08-11T13:45:14.927514Z","steps":["trace[1719113974] 'process raft request' (duration: 309.287602ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:45:14.927631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.324892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-11T13:45:14.927677Z","caller":"traceutil/trace.go:171","msg":"trace[997210591] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0; response_count:0; response_revision:25323; }","duration":"373.454747ms","start":"2026-08-11T13:45:14.554209Z","end":"2026-08-11T13:45:14.927664Z","steps":["trace[997210591] 'agreement among raft nodes before linearized reading' (duration: 373.340793ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:45:14.927727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T13:45:14.55418Z","time spent":"373.534068ms","remote":"127.0.0.1:47974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true "} {"level":"warn","ts":"2026-08-11T13:45:14.927738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T13:45:14.617977Z","time spent":"309.670353ms","remote":"127.0.0.1:34220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T13:45:14.927692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.930615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-08-11T13:45:14.927824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.49818ms","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":"warn","ts":"2026-08-11T13:45:14.927877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.144903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-08-11T13:45:14.927871Z","caller":"traceutil/trace.go:171","msg":"trace[801694132] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:25323; }","duration":"292.225704ms","start":"2026-08-11T13:45:14.635621Z","end":"2026-08-11T13:45:14.927847Z","steps":["trace[801694132] 'agreement among raft nodes before linearized reading' (duration: 291.845303ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:45:14.927962Z","caller":"traceutil/trace.go:171","msg":"trace[793776538] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25323; }","duration":"275.291007ms","start":"2026-08-11T13:45:14.652652Z","end":"2026-08-11T13:45:14.927943Z","steps":["trace[793776538] 'agreement among raft nodes before linearized reading' (duration: 275.02937ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:45:14.927947Z","caller":"traceutil/trace.go:171","msg":"trace[661404452] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:25323; }","duration":"392.647955ms","start":"2026-08-11T13:45:14.535253Z","end":"2026-08-11T13:45:14.927901Z","steps":["trace[661404452] 'agreement among raft nodes before linearized reading' (duration: 392.45125ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:45:14.928109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T13:45:14.535222Z","time spent":"392.871582ms","remote":"127.0.0.1:47042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true "} {"level":"warn","ts":"2026-08-11T13:45:15.12757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.976174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-11T13:45:15.127671Z","caller":"traceutil/trace.go:171","msg":"trace[933992234] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25323; }","duration":"113.092487ms","start":"2026-08-11T13:45:15.014556Z","end":"2026-08-11T13:45:15.127649Z","steps":["trace[933992234] 'range keys from in-memory index tree' (duration: 112.862862ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:45:15.202004Z","caller":"traceutil/trace.go:171","msg":"trace[870492324] linearizableReadLoop","detail":"{readStateIndex:26155; appliedIndex:26153; }","duration":"138.758619ms","start":"2026-08-11T13:45:15.063214Z","end":"2026-08-11T13:45:15.201973Z","steps":["trace[870492324] 'read index received' (duration: 26.36699ms)","trace[870492324] 'applied index is now lower than readState.Index' (duration: 112.389709ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T13:45:15.202118Z","caller":"traceutil/trace.go:171","msg":"trace[1144940555] transaction","detail":"{read_only:false; response_revision:25325; number_of_response:1; }","duration":"144.067529ms","start":"2026-08-11T13:45:15.058006Z","end":"2026-08-11T13:45:15.202073Z","steps":["trace[1144940555] 'process raft request' (duration: 143.809001ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:45:15.202313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.080699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-08-11T13:45:15.202371Z","caller":"traceutil/trace.go:171","msg":"trace[1408351361] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25325; }","duration":"139.213133ms","start":"2026-08-11T13:45:15.063134Z","end":"2026-08-11T13:45:15.202347Z","steps":["trace[1408351361] 'agreement among raft nodes before linearized reading' (duration: 138.961626ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:45:25.579159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.522683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-08-11T13:45:25.579246Z","caller":"traceutil/trace.go:171","msg":"trace[1637574149] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25408; }","duration":"270.630856ms","start":"2026-08-11T13:45:25.308589Z","end":"2026-08-11T13:45:25.57922Z","steps":["trace[1637574149] 'range keys from in-memory index tree' (duration: 270.289967ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:45:25.579998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.914927ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-11T13:45:25.580131Z","caller":"traceutil/trace.go:171","msg":"trace[1673364890] transaction","detail":"{read_only:false; response_revision:25409; number_of_response:1; }","duration":"270.350098ms","start":"2026-08-11T13:45:25.309757Z","end":"2026-08-11T13:45:25.580107Z","steps":["trace[1673364890] 'process raft request' (duration: 138.203304ms)","trace[1673364890] 'compare' (duration: 131.751023ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T13:45:25.867913Z","caller":"traceutil/trace.go:171","msg":"trace[130511459] transaction","detail":"{read_only:false; response_revision:25411; number_of_response:1; }","duration":"184.158726ms","start":"2026-08-11T13:45:25.683703Z","end":"2026-08-11T13:45:25.867862Z","steps":["trace[130511459] 'process raft request' (duration: 162.702743ms)","trace[130511459] 'compare' (duration: 21.184116ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T13:45:28.815561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.207341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-11T13:45:28.815744Z","caller":"traceutil/trace.go:171","msg":"trace[846365225] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:25426; }","duration":"451.407336ms","start":"2026-08-11T13:45:28.364311Z","end":"2026-08-11T13:45:28.815718Z","steps":["trace[846365225] 'count revisions from in-memory index tree' (duration: 450.968334ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:45:28.815821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T13:45:28.364211Z","time spent":"451.594402ms","remote":"127.0.0.1:44940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":37,"response size":30,"request content":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true "} {"level":"warn","ts":"2026-08-11T13:45:28.81673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.012226ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-11T13:45:28.81689Z","caller":"traceutil/trace.go:171","msg":"trace[822215486] transaction","detail":"{read_only:false; response_revision:25427; number_of_response:1; }","duration":"403.669636ms","start":"2026-08-11T13:45:28.413193Z","end":"2026-08-11T13:45:28.816863Z","steps":["trace[822215486] 'process raft request' (duration: 185.367131ms)","trace[822215486] 'compare' (duration: 217.734378ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T13:45:28.817087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T13:45:28.413161Z","time spent":"403.781809ms","remote":"127.0.0.1:34220","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-08-11T13:45:28.838001Z","caller":"traceutil/trace.go:171","msg":"trace[301471065] transaction","detail":"{read_only:false; response_revision:25428; number_of_response:1; }","duration":"388.005175ms","start":"2026-08-11T13:45:28.449963Z","end":"2026-08-11T13:45:28.837968Z","steps":["trace[301471065] 'process raft request' (duration: 387.756408ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:45:28.838201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T13:45:28.449923Z","time spent":"388.170059ms","remote":"127.0.0.1:34220","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-08-11T13:45:28.838245Z","caller":"traceutil/trace.go:171","msg":"trace[1011164386] transaction","detail":"{read_only:false; response_revision:25429; number_of_response:1; }","duration":"386.528073ms","start":"2026-08-11T13:45:28.451669Z","end":"2026-08-11T13:45:28.838197Z","steps":["trace[1011164386] 'process raft request' (duration: 386.255275ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:45:28.838622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T13:45:28.451656Z","time spent":"386.699548ms","remote":"127.0.0.1:34220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T13:45:28.844138Z","caller":"traceutil/trace.go:171","msg":"trace[790345310] transaction","detail":"{read_only:false; response_revision:25430; number_of_response:1; }","duration":"193.763976ms","start":"2026-08-11T13:45:28.650338Z","end":"2026-08-11T13:45:28.844102Z","steps":["trace[790345310] 'process raft request' (duration: 193.495228ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:45:28.844378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.871669ms","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-08-11T13:45:28.844455Z","caller":"traceutil/trace.go:171","msg":"trace[386608440] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25430; }","duration":"113.999913ms","start":"2026-08-11T13:45:28.730435Z","end":"2026-08-11T13:45:28.844435Z","steps":["trace[386608440] 'agreement among raft nodes before linearized reading' (duration: 113.750496ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:45:28.844057Z","caller":"traceutil/trace.go:171","msg":"trace[1655316200] linearizableReadLoop","detail":"{readStateIndex:26263; appliedIndex:26260; }","duration":"113.50802ms","start":"2026-08-11T13:45:28.730484Z","end":"2026-08-11T13:45:28.843992Z","steps":["trace[1655316200] 'read index received' (duration: 107.419309ms)","trace[1655316200] 'applied index is now lower than readState.Index' (duration: 6.087511ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T13:45:28.845097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.141411ms","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-08-11T13:45:28.845169Z","caller":"traceutil/trace.go:171","msg":"trace[585128568] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25430; }","duration":"112.323127ms","start":"2026-08-11T13:45:28.732825Z","end":"2026-08-11T13:45:28.845149Z","steps":["trace[585128568] 'agreement among raft nodes before linearized reading' (duration: 112.022418ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:45:28.845369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.189603ms","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-08-11T13:45:28.845453Z","caller":"traceutil/trace.go:171","msg":"trace[765203133] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25430; }","duration":"112.340247ms","start":"2026-08-11T13:45:28.733093Z","end":"2026-08-11T13:45:28.845433Z","steps":["trace[765203133] 'agreement among raft nodes before linearized reading' (duration: 112.042748ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:45:29.173529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.2793ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-11T13:45:29.173787Z","caller":"traceutil/trace.go:171","msg":"trace[1612697129] transaction","detail":"{read_only:false; response_revision:25431; number_of_response:1; }","duration":"324.397017ms","start":"2026-08-11T13:45:28.84932Z","end":"2026-08-11T13:45:29.173717Z","steps":["trace[1612697129] 'process raft request' (duration: 81.814439ms)","trace[1612697129] 'compare' (duration: 242.083564ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T13:45:29.173902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T13:45:28.849294Z","time spent":"324.572682ms","remote":"127.0.0.1:34220","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-08-11T13:45:29.174139Z","caller":"traceutil/trace.go:171","msg":"trace[846086244] transaction","detail":"{read_only:false; response_revision:25433; number_of_response:1; }","duration":"323.252194ms","start":"2026-08-11T13:45:28.850847Z","end":"2026-08-11T13:45:29.174099Z","steps":["trace[846086244] 'process raft request' (duration: 323.062189ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:45:29.174151Z","caller":"traceutil/trace.go:171","msg":"trace[1999296580] transaction","detail":"{read_only:false; response_revision:25432; number_of_response:1; }","duration":"324.563442ms","start":"2026-08-11T13:45:28.849529Z","end":"2026-08-11T13:45:29.174093Z","steps":["trace[1999296580] 'process raft request' (duration: 324.13536ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:45:29.174131Z","caller":"traceutil/trace.go:171","msg":"trace[2120803089] linearizableReadLoop","detail":"{readStateIndex:26266; appliedIndex:26263; }","duration":"308.388617ms","start":"2026-08-11T13:45:28.865708Z","end":"2026-08-11T13:45:29.174096Z","steps":["trace[2120803089] 'read index received' (duration: 65.504721ms)","trace[2120803089] 'applied index is now lower than readState.Index' (duration: 242.882146ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T13:45:29.174337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T13:45:28.849508Z","time spent":"324.740396ms","remote":"127.0.0.1:34220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T13:45:29.174337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T13:45:28.850827Z","time spent":"323.397249ms","remote":"127.0.0.1:34220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T13:45:29.174292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.555092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-11T13:45:29.174457Z","caller":"traceutil/trace.go:171","msg":"trace[1516570518] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:0; response_revision:25433; }","duration":"308.699756ms","start":"2026-08-11T13:45:28.865704Z","end":"2026-08-11T13:45:29.174404Z","steps":["trace[1516570518] 'agreement among raft nodes before linearized reading' (duration: 308.49347ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:45:29.174565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T13:45:28.865679Z","time spent":"308.85264ms","remote":"127.0.0.1:33850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"info","ts":"2026-08-11T13:45:29.244159Z","caller":"traceutil/trace.go:171","msg":"trace[1818126673] transaction","detail":"{read_only:false; response_revision:25434; number_of_response:1; }","duration":"280.061901ms","start":"2026-08-11T13:45:28.964065Z","end":"2026-08-11T13:45:29.244127Z","steps":["trace[1818126673] 'process raft request' (duration: 279.825955ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:45:29.244227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.723981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/images/\" range_end:\"/registry/openstack.k-orc.cloud/images0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-11T13:45:29.244465Z","caller":"traceutil/trace.go:171","msg":"trace[29757674] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/images/; range_end:/registry/openstack.k-orc.cloud/images0; response_count:0; response_revision:25434; }","duration":"116.927636ms","start":"2026-08-11T13:45:29.127374Z","end":"2026-08-11T13:45:29.244302Z","steps":["trace[29757674] 'agreement among raft nodes before linearized reading' (duration: 116.70748ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:45:29.734992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.735967ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-11T13:45:29.735204Z","caller":"traceutil/trace.go:171","msg":"trace[168017351] linearizableReadLoop","detail":"{readStateIndex:26269; appliedIndex:26267; }","duration":"451.203381ms","start":"2026-08-11T13:45:29.283983Z","end":"2026-08-11T13:45:29.735186Z","steps":["trace[168017351] 'read index received' (duration: 184.071844ms)","trace[168017351] 'applied index is now lower than readState.Index' (duration: 267.130157ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T13:45:29.735281Z","caller":"traceutil/trace.go:171","msg":"trace[1451030124] transaction","detail":"{read_only:false; response_revision:25436; number_of_response:1; }","duration":"459.386382ms","start":"2026-08-11T13:45:29.275884Z","end":"2026-08-11T13:45:29.735271Z","steps":["trace[1451030124] 'process raft request' (duration: 459.223617ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:45:29.735344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T13:45:29.275851Z","time spent":"459.455863ms","remote":"127.0.0.1:34220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T13:45:29.73549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.488009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-08-11T13:45:29.735563Z","caller":"traceutil/trace.go:171","msg":"trace[864868602] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25436; }","duration":"451.623342ms","start":"2026-08-11T13:45:29.283923Z","end":"2026-08-11T13:45:29.735547Z","steps":["trace[864868602] 'agreement among raft nodes before linearized reading' (duration: 451.404596ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:45:29.735599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T13:45:29.283898Z","time spent":"451.694935ms","remote":"127.0.0.1:34220","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-08-11T13:45:29.736065Z","caller":"traceutil/trace.go:171","msg":"trace[764014746] transaction","detail":"{read_only:false; response_revision:25437; number_of_response:1; }","duration":"142.467014ms","start":"2026-08-11T13:45:29.593557Z","end":"2026-08-11T13:45:29.736024Z","steps":["trace[764014746] 'process raft request' (duration: 142.285919ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:45:29.736197Z","caller":"traceutil/trace.go:171","msg":"trace[1024433174] transaction","detail":"{read_only:false; response_revision:25435; number_of_response:1; }","duration":"558.502257ms","start":"2026-08-11T13:45:29.177628Z","end":"2026-08-11T13:45:29.73613Z","steps":["trace[1024433174] 'process raft request' (duration: 290.526325ms)","trace[1024433174] 'compare' (duration: 266.220873ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T13:45:29.736236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.713647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-08-11T13:45:29.736329Z","caller":"traceutil/trace.go:171","msg":"trace[41706315] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25437; }","duration":"102.871211ms","start":"2026-08-11T13:45:29.633435Z","end":"2026-08-11T13:45:29.736306Z","steps":["trace[41706315] 'agreement among raft nodes before linearized reading' (duration: 102.564713ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:45:29.736514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T13:45:29.177598Z","time spent":"558.715363ms","remote":"127.0.0.1:34220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T13:45:30.331049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.982887ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-11T13:45:30.331463Z","caller":"traceutil/trace.go:171","msg":"trace[555791326] transaction","detail":"{read_only:false; response_revision:25439; number_of_response:1; }","duration":"589.806265ms","start":"2026-08-11T13:45:29.741598Z","end":"2026-08-11T13:45:30.331404Z","steps":["trace[555791326] 'process raft request' (duration: 319.363846ms)","trace[555791326] 'compare' (duration: 269.551525ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T13:45:30.331655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T13:45:29.741571Z","time spent":"589.97916ms","remote":"127.0.0.1:34220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T13:45:30.332938Z","caller":"traceutil/trace.go:171","msg":"trace[1780357207] transaction","detail":"{read_only:false; response_revision:25440; number_of_response:1; }","duration":"398.017616ms","start":"2026-08-11T13:45:29.934882Z","end":"2026-08-11T13:45:30.3329Z","steps":["trace[1780357207] 'process raft request' (duration: 397.749899ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:45:30.333133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.098086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-11T13:45:30.333135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T13:45:29.93484Z","time spent":"398.187821ms","remote":"127.0.0.1:34220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T13:45:30.33318Z","caller":"traceutil/trace.go:171","msg":"trace[688510257] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25440; }","duration":"320.147937ms","start":"2026-08-11T13:45:30.013017Z","end":"2026-08-11T13:45:30.333165Z","steps":["trace[688510257] 'agreement among raft nodes before linearized reading' (duration: 320.062435ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:45:30.33323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T13:45:30.012965Z","time spent":"320.257161ms","remote":"127.0.0.1:57302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-11T13:45:30.333032Z","caller":"traceutil/trace.go:171","msg":"trace[334397898] linearizableReadLoop","detail":"{readStateIndex:26273; appliedIndex:26271; }","duration":"319.777607ms","start":"2026-08-11T13:45:30.013026Z","end":"2026-08-11T13:45:30.332804Z","steps":["trace[334397898] 'read index received' (duration: 47.948938ms)","trace[334397898] 'applied index is now lower than readState.Index' (duration: 271.827209ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T13:45:30.666867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.910409ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-11T13:45:30.667048Z","caller":"traceutil/trace.go:171","msg":"trace[18632709] transaction","detail":"{read_only:false; response_revision:25441; number_of_response:1; }","duration":"425.808378ms","start":"2026-08-11T13:45:30.241202Z","end":"2026-08-11T13:45:30.66701Z","steps":["trace[18632709] 'process raft request' (duration: 220.653492ms)","trace[18632709] 'compare' (duration: 204.776005ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T13:45:30.667112Z","caller":"traceutil/trace.go:171","msg":"trace[1916416695] transaction","detail":"{read_only:false; response_revision:25442; number_of_response:1; }","duration":"189.583978ms","start":"2026-08-11T13:45:30.47751Z","end":"2026-08-11T13:45:30.667093Z","steps":["trace[1916416695] 'process raft request' (duration: 189.510766ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:45:30.667135Z","caller":"traceutil/trace.go:171","msg":"trace[1864205059] linearizableReadLoop","detail":"{readStateIndex:26274; appliedIndex:26273; }","duration":"333.609636ms","start":"2026-08-11T13:45:30.333486Z","end":"2026-08-11T13:45:30.667096Z","steps":["trace[1864205059] 'read index received' (duration: 128.243064ms)","trace[1864205059] 'applied index is now lower than readState.Index' (duration: 205.363722ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T13:45:30.667216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T13:45:30.241151Z","time spent":"425.969342ms","remote":"127.0.0.1:34220","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-08-11T13:45:30.667433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.503016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-11T13:45:30.66745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.151912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-08-11T13:45:30.667468Z","caller":"traceutil/trace.go:171","msg":"trace[175732473] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25442; }","duration":"331.581988ms","start":"2026-08-11T13:45:30.335875Z","end":"2026-08-11T13:45:30.667457Z","steps":["trace[175732473] 'agreement among raft nodes before linearized reading' (duration: 331.519196ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:45:30.667488Z","caller":"traceutil/trace.go:171","msg":"trace[797212216] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25442; }","duration":"502.232485ms","start":"2026-08-11T13:45:30.165242Z","end":"2026-08-11T13:45:30.667474Z","steps":["trace[797212216] 'agreement among raft nodes before linearized reading' (duration: 502.032279ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:45:30.667503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T13:45:30.335851Z","time spent":"331.64514ms","remote":"127.0.0.1:57318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-11T13:45:30.667527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T13:45:30.165227Z","time spent":"502.293476ms","remote":"127.0.0.1:34128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-08-11T13:45:30.667788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.488356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-11T13:45:30.667904Z","caller":"traceutil/trace.go:171","msg":"trace[2130507594] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:0; response_revision:25442; }","duration":"321.623629ms","start":"2026-08-11T13:45:30.346243Z","end":"2026-08-11T13:45:30.667867Z","steps":["trace[2130507594] 'agreement among raft nodes before linearized reading' (duration: 321.411734ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:45:30.667969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T13:45:30.346179Z","time spent":"321.770644ms","remote":"127.0.0.1:33850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"warn","ts":"2026-08-11T13:45:30.668163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.428513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-11T13:45:30.668192Z","caller":"traceutil/trace.go:171","msg":"trace[1441756190] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:25442; }","duration":"553.494935ms","start":"2026-08-11T13:45:30.11469Z","end":"2026-08-11T13:45:30.668185Z","steps":["trace[1441756190] 'agreement among raft nodes before linearized reading' (duration: 553.379252ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:45:30.668211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T13:45:30.114671Z","time spent":"553.535056ms","remote":"127.0.0.1:34418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":60,"response size":30,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"info","ts":"2026-08-11T13:45:30.9353Z","caller":"traceutil/trace.go:171","msg":"trace[69354099] linearizableReadLoop","detail":"{readStateIndex:26276; appliedIndex:26275; }","duration":"243.777401ms","start":"2026-08-11T13:45:30.691494Z","end":"2026-08-11T13:45:30.935272Z","steps":["trace[69354099] 'read index received' (duration: 243.656128ms)","trace[69354099] 'applied index is now lower than readState.Index' (duration: 119.783µs)"],"step_count":2} {"level":"warn","ts":"2026-08-11T13:45:30.935518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.988877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/subnets/\" range_end:\"/registry/openstack.k-orc.cloud/subnets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-11T13:45:30.935579Z","caller":"traceutil/trace.go:171","msg":"trace[939930955] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/subnets/; range_end:/registry/openstack.k-orc.cloud/subnets0; response_count:0; response_revision:25443; }","duration":"244.1027ms","start":"2026-08-11T13:45:30.691461Z","end":"2026-08-11T13:45:30.935564Z","steps":["trace[939930955] 'agreement among raft nodes before linearized reading' (duration: 243.956516ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:45:30.935412Z","caller":"traceutil/trace.go:171","msg":"trace[256229992] transaction","detail":"{read_only:false; response_revision:25443; number_of_response:1; }","duration":"254.718079ms","start":"2026-08-11T13:45:30.680657Z","end":"2026-08-11T13:45:30.935375Z","steps":["trace[256229992] 'process raft request' (duration: 254.418771ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:45:30.95797Z","caller":"traceutil/trace.go:171","msg":"trace[1820063997] transaction","detail":"{read_only:false; response_revision:25444; number_of_response:1; }","duration":"134.50199ms","start":"2026-08-11T13:45:30.823438Z","end":"2026-08-11T13:45:30.95794Z","steps":["trace[1820063997] 'process raft request' (duration: 134.244823ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:45:30.958354Z","caller":"traceutil/trace.go:171","msg":"trace[334753189] transaction","detail":"{read_only:false; response_revision:25445; number_of_response:1; }","duration":"114.091857ms","start":"2026-08-11T13:45:30.84425Z","end":"2026-08-11T13:45:30.958342Z","steps":["trace[334753189] 'process raft request' (duration: 113.637905ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:45:30.958449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.21376ms","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-08-11T13:45:30.958528Z","caller":"traceutil/trace.go:171","msg":"trace[1548254420] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:25446; }","duration":"251.368215ms","start":"2026-08-11T13:45:30.707142Z","end":"2026-08-11T13:45:30.95851Z","steps":["trace[1548254420] 'agreement among raft nodes before linearized reading' (duration: 251.237721ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:45:30.958579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.966155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-nb-0\" ","response":"range_response_count:1 size:1566"} {"level":"info","ts":"2026-08-11T13:45:30.958624Z","caller":"traceutil/trace.go:171","msg":"trace[536429123] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-nb-0; range_end:; response_count:1; response_revision:25446; }","duration":"266.063948ms","start":"2026-08-11T13:45:30.692545Z","end":"2026-08-11T13:45:30.958608Z","steps":["trace[536429123] 'agreement among raft nodes before linearized reading' (duration: 265.899953ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:45:30.958664Z","caller":"traceutil/trace.go:171","msg":"trace[2070351622] transaction","detail":"{read_only:false; response_revision:25446; number_of_response:1; }","duration":"112.903593ms","start":"2026-08-11T13:45:30.845725Z","end":"2026-08-11T13:45:30.958629Z","steps":["trace[2070351622] 'process raft request' (duration: 112.508142ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:45:30.958756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.748316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-11T13:45:30.958795Z","caller":"traceutil/trace.go:171","msg":"trace[2016973328] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25446; }","duration":"219.833228ms","start":"2026-08-11T13:45:30.738952Z","end":"2026-08-11T13:45:30.958785Z","steps":["trace[2016973328] 'agreement among raft nodes before linearized reading' (duration: 219.768166ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:45:31.478746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.905097ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-11T13:45:31.479193Z","caller":"traceutil/trace.go:171","msg":"trace[777719891] transaction","detail":"{read_only:false; response_revision:25452; number_of_response:1; }","duration":"371.195173ms","start":"2026-08-11T13:45:31.107973Z","end":"2026-08-11T13:45:31.479168Z","steps":["trace[777719891] 'process raft request' (duration: 371.125131ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:45:31.479272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T13:45:31.10795Z","time spent":"371.292465ms","remote":"127.0.0.1:34222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1237,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-11T13:45:31.479415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T13:45:31.107745Z","time spent":"371.662225ms","remote":"127.0.0.1:57366","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-08-11T13:45:31.479462Z","caller":"traceutil/trace.go:171","msg":"trace[357432853] transaction","detail":"{read_only:false; response_revision:25450; number_of_response:1; }","duration":"378.442186ms","start":"2026-08-11T13:45:31.100989Z","end":"2026-08-11T13:45:31.479431Z","steps":["trace[357432853] 'process raft request' (duration: 66.725056ms)","trace[357432853] 'compare' (duration: 310.712742ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T13:45:31.479484Z","caller":"traceutil/trace.go:171","msg":"trace[1592668418] transaction","detail":"{read_only:false; response_revision:25451; number_of_response:1; }","duration":"371.674616ms","start":"2026-08-11T13:45:31.107766Z","end":"2026-08-11T13:45:31.479441Z","steps":["trace[1592668418] 'process raft request' (duration: 371.121411ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:45:31.479635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T13:45:31.100965Z","time spent":"378.573709ms","remote":"127.0.0.1:34220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T13:45:31.479461Z","caller":"traceutil/trace.go:171","msg":"trace[510810499] linearizableReadLoop","detail":"{readStateIndex:26283; appliedIndex:26282; }","duration":"373.336582ms","start":"2026-08-11T13:45:31.106093Z","end":"2026-08-11T13:45:31.47943Z","steps":["trace[510810499] 'read index received' (duration: 61.618362ms)","trace[510810499] 'applied index is now lower than readState.Index' (duration: 311.7146ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T13:45:31.479689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T13:45:31.10775Z","time spent":"371.83422ms","remote":"127.0.0.1:34120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":744,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-11T13:45:31.479788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.651671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:130048"} {"level":"info","ts":"2026-08-11T13:45:31.479838Z","caller":"traceutil/trace.go:171","msg":"trace[1007830099] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:25452; }","duration":"373.794435ms","start":"2026-08-11T13:45:31.106034Z","end":"2026-08-11T13:45:31.479829Z","steps":["trace[1007830099] 'agreement among raft nodes before linearized reading' (duration: 373.478026ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:45:31.479876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T13:45:31.106016Z","time spent":"373.849996ms","remote":"127.0.0.1:57352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":130070,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"warn","ts":"2026-08-11T13:45:31.764781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.865561ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-11T13:45:31.764933Z","caller":"traceutil/trace.go:171","msg":"trace[1955961272] linearizableReadLoop","detail":"{readStateIndex:26287; appliedIndex:26286; }","duration":"285.231466ms","start":"2026-08-11T13:45:31.479677Z","end":"2026-08-11T13:45:31.764908Z","steps":["trace[1955961272] 'read index received' (duration: 129.15614ms)","trace[1955961272] 'applied index is now lower than readState.Index' (duration: 156.072886ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T13:45:31.765093Z","caller":"traceutil/trace.go:171","msg":"trace[1662930857] transaction","detail":"{read_only:false; response_revision:25453; number_of_response:1; }","duration":"514.191681ms","start":"2026-08-11T13:45:31.250743Z","end":"2026-08-11T13:45:31.764935Z","steps":["trace[1662930857] 'process raft request' (duration: 358.029222ms)","trace[1662930857] 'compare' (duration: 155.684516ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T13:45:31.765193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.765242ms","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-08-11T13:45:31.765305Z","caller":"traceutil/trace.go:171","msg":"trace[706062385] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25453; }","duration":"585.908797ms","start":"2026-08-11T13:45:31.179362Z","end":"2026-08-11T13:45:31.765271Z","steps":["trace[706062385] 'agreement among raft nodes before linearized reading' (duration: 585.614008ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:45:31.765337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.991997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-08-11T13:45:31.765365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T13:45:31.17935Z","time spent":"586.005079ms","remote":"127.0.0.1:34220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-11T13:45:31.765379Z","caller":"traceutil/trace.go:171","msg":"trace[590352730] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25454; }","duration":"587.069269ms","start":"2026-08-11T13:45:31.178297Z","end":"2026-08-11T13:45:31.765366Z","steps":["trace[590352730] 'agreement among raft nodes before linearized reading' (duration: 586.677378ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:45:31.765373Z","caller":"traceutil/trace.go:171","msg":"trace[167233231] transaction","detail":"{read_only:false; response_revision:25454; number_of_response:1; }","duration":"284.421493ms","start":"2026-08-11T13:45:31.480914Z","end":"2026-08-11T13:45:31.765336Z","steps":["trace[167233231] 'process raft request' (duration: 284.026422ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:45:31.765429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T13:45:31.17828Z","time spent":"587.139972ms","remote":"127.0.0.1:34220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-11T13:45:31.765411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.970843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-08-11T13:45:31.765413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T13:45:31.250699Z","time spent":"514.571052ms","remote":"127.0.0.1:34220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T13:45:31.765437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.691698ms","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-08-11T13:45:31.765567Z","caller":"traceutil/trace.go:171","msg":"trace[1144174658] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25454; }","duration":"430.189299ms","start":"2026-08-11T13:45:31.335325Z","end":"2026-08-11T13:45:31.765514Z","steps":["trace[1144174658] 'agreement among raft nodes before linearized reading' (duration: 429.87126ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:45:31.765646Z","caller":"traceutil/trace.go:171","msg":"trace[757606771] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25454; }","duration":"586.940305ms","start":"2026-08-11T13:45:31.17868Z","end":"2026-08-11T13:45:31.765621Z","steps":["trace[757606771] 'agreement among raft nodes before linearized reading' (duration: 586.485813ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:45:31.765679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T13:45:31.335299Z","time spent":"430.363364ms","remote":"127.0.0.1:40828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-08-11T13:45:31.765714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T13:45:31.178671Z","time spent":"587.029727ms","remote":"127.0.0.1:34220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-11T13:45:31.765508Z","caller":"traceutil/trace.go:171","msg":"trace[2088976514] transaction","detail":"{read_only:false; response_revision:25455; number_of_response:1; }","duration":"282.564231ms","start":"2026-08-11T13:45:31.482923Z","end":"2026-08-11T13:45:31.765487Z","steps":["trace[2088976514] 'process raft request' (duration: 282.179511ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:45:32.142798Z","caller":"traceutil/trace.go:171","msg":"trace[59333930] transaction","detail":"{read_only:false; response_revision:25464; number_of_response:1; }","duration":"318.594235ms","start":"2026-08-11T13:45:31.824167Z","end":"2026-08-11T13:45:32.142761Z","steps":["trace[59333930] 'process raft request' (duration: 230.55198ms)","trace[59333930] 'compare' (duration: 87.695805ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T13:45:32.143334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T13:45:31.824143Z","time spent":"319.097968ms","remote":"127.0.0.1:34120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":658,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-11T13:45:32.142888Z","caller":"traceutil/trace.go:171","msg":"trace[1343428050] transaction","detail":"{read_only:false; response_revision:25465; number_of_response:1; }","duration":"318.637066ms","start":"2026-08-11T13:45:31.824199Z","end":"2026-08-11T13:45:32.142836Z","steps":["trace[1343428050] 'process raft request' (duration: 318.477131ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:45:32.142945Z","caller":"traceutil/trace.go:171","msg":"trace[43491844] transaction","detail":"{read_only:false; response_revision:25467; number_of_response:1; }","duration":"318.099221ms","start":"2026-08-11T13:45:31.824812Z","end":"2026-08-11T13:45:32.142912Z","steps":["trace[43491844] 'process raft request' (duration: 318.004178ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:45:32.143653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T13:45:31.824182Z","time spent":"319.362536ms","remote":"127.0.0.1:34222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1133,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-11T13:45:32.143724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T13:45:31.824803Z","time spent":"318.833621ms","remote":"127.0.0.1:34220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T13:45:32.143035Z","caller":"traceutil/trace.go:171","msg":"trace[1676626099] transaction","detail":"{read_only:false; response_revision:25466; number_of_response:1; }","duration":"318.372129ms","start":"2026-08-11T13:45:31.824637Z","end":"2026-08-11T13:45:32.143009Z","steps":["trace[1676626099] 'process raft request' (duration: 318.120032ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:45:32.143887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T13:45:31.824624Z","time spent":"319.222352ms","remote":"127.0.0.1:57366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":718,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-11T13:45:32.228835Z","caller":"traceutil/trace.go:171","msg":"trace[1067023891] linearizableReadLoop","detail":"{readStateIndex:26302; appliedIndex:26297; }","duration":"374.269268ms","start":"2026-08-11T13:45:31.854536Z","end":"2026-08-11T13:45:32.228805Z","steps":["trace[1067023891] 'read index received' (duration: 200.196166ms)","trace[1067023891] 'applied index is now lower than readState.Index' (duration: 174.071702ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T13:45:32.228954Z","caller":"traceutil/trace.go:171","msg":"trace[1321812476] transaction","detail":"{read_only:false; response_revision:25468; number_of_response:1; }","duration":"374.994109ms","start":"2026-08-11T13:45:31.85395Z","end":"2026-08-11T13:45:32.228944Z","steps":["trace[1321812476] 'process raft request' (duration: 374.69782ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:45:32.229078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T13:45:31.853928Z","time spent":"375.0573ms","remote":"127.0.0.1:57394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1294,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-11T13:45:32.229239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1125"} {"level":"info","ts":"2026-08-11T13:45:32.229335Z","caller":"traceutil/trace.go:171","msg":"trace[1630383004] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:25468; }","duration":"374.835854ms","start":"2026-08-11T13:45:31.854477Z","end":"2026-08-11T13:45:32.229313Z","steps":["trace[1630383004] 'agreement among raft nodes before linearized reading' (duration: 374.520136ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:45:32.229391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T13:45:31.854457Z","time spent":"374.922057ms","remote":"127.0.0.1:34314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":1147,"request content":"key:\"/registry/storageclasses/general\" "} {"level":"info","ts":"2026-08-11T13:45:32.316472Z","caller":"traceutil/trace.go:171","msg":"trace[1651414846] transaction","detail":"{read_only:false; response_revision:25469; number_of_response:1; }","duration":"166.088699ms","start":"2026-08-11T13:45:32.150336Z","end":"2026-08-11T13:45:32.316425Z","steps":["trace[1651414846] 'process raft request' (duration: 165.77173ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:45:32.316545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.3648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-11T13:45:32.316585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.382175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-11T13:45:32.316642Z","caller":"traceutil/trace.go:171","msg":"trace[14533833] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25470; }","duration":"301.546895ms","start":"2026-08-11T13:45:32.015079Z","end":"2026-08-11T13:45:32.316626Z","steps":["trace[14533833] 'agreement among raft nodes before linearized reading' (duration: 301.36245ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:45:32.316665Z","caller":"traceutil/trace.go:171","msg":"trace[420638967] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:25470; }","duration":"225.55697ms","start":"2026-08-11T13:45:32.091089Z","end":"2026-08-11T13:45:32.316646Z","steps":["trace[420638967] 'agreement among raft nodes before linearized reading' (duration: 225.336834ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:45:32.316676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T13:45:32.015044Z","time spent":"301.627267ms","remote":"127.0.0.1:57302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-11T13:45:32.316594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.636424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1125"} {"level":"warn","ts":"2026-08-11T13:45:32.316758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.997874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:418304"} {"level":"info","ts":"2026-08-11T13:45:32.316758Z","caller":"traceutil/trace.go:171","msg":"trace[1862433975] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:25470; }","duration":"461.82734ms","start":"2026-08-11T13:45:31.854899Z","end":"2026-08-11T13:45:32.316727Z","steps":["trace[1862433975] 'agreement among raft nodes before linearized reading' (duration: 461.45495ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:45:32.316829Z","caller":"traceutil/trace.go:171","msg":"trace[741872220] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:25470; }","duration":"404.110467ms","start":"2026-08-11T13:45:31.912699Z","end":"2026-08-11T13:45:32.31681Z","steps":["trace[741872220] 'agreement among raft nodes before linearized reading' (duration: 403.738177ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:45:32.316869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T13:45:31.912684Z","time spent":"404.178729ms","remote":"127.0.0.1:47016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":418326,"request content":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" "} {"level":"warn","ts":"2026-08-11T13:45:32.31687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T13:45:31.854874Z","time spent":"461.958403ms","remote":"127.0.0.1:34314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":1147,"request content":"key:\"/registry/storageclasses/general\" "} {"level":"info","ts":"2026-08-11T13:45:32.47025Z","caller":"traceutil/trace.go:171","msg":"trace[1893536276] transaction","detail":"{read_only:false; response_revision:25471; number_of_response:1; }","duration":"148.417862ms","start":"2026-08-11T13:45:32.321802Z","end":"2026-08-11T13:45:32.470219Z","steps":["trace[1893536276] 'process raft request' (duration: 148.221006ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:45:32.47291Z","caller":"traceutil/trace.go:171","msg":"trace[2070143602] linearizableReadLoop","detail":"{readStateIndex:26307; appliedIndex:26306; }","duration":"149.40285ms","start":"2026-08-11T13:45:32.323487Z","end":"2026-08-11T13:45:32.47289Z","steps":["trace[2070143602] 'read index received' (duration: 147.150316ms)","trace[2070143602] 'applied index is now lower than readState.Index' (duration: 2.251394ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T13:45:32.473153Z","caller":"traceutil/trace.go:171","msg":"trace[744719674] transaction","detail":"{read_only:false; response_revision:25472; number_of_response:1; }","duration":"151.174409ms","start":"2026-08-11T13:45:32.321966Z","end":"2026-08-11T13:45:32.473141Z","steps":["trace[744719674] 'process raft request' (duration: 150.604773ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:45:32.473429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.901864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:3959"} {"level":"info","ts":"2026-08-11T13:45:32.473476Z","caller":"traceutil/trace.go:171","msg":"trace[1289298655] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:25472; }","duration":"150.010377ms","start":"2026-08-11T13:45:32.323452Z","end":"2026-08-11T13:45:32.473462Z","steps":["trace[1289298655] 'agreement among raft nodes before linearized reading' (duration: 149.853493ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:45:32.473752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.155379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-08-11T13:45:32.473789Z","caller":"traceutil/trace.go:171","msg":"trace[200717206] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25472; }","duration":"135.234611ms","start":"2026-08-11T13:45:32.338546Z","end":"2026-08-11T13:45:32.47378Z","steps":["trace[200717206] 'agreement among raft nodes before linearized reading' (duration: 135.100927ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:45:32.705288Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000066e7.wal"} {"level":"warn","ts":"2026-08-11T13:45:33.741869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.672106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e6be6ea1-4aff-4c77-a110-b2174aace032\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-08-11T13:45:33.741996Z","caller":"traceutil/trace.go:171","msg":"trace[1012674983] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e6be6ea1-4aff-4c77-a110-b2174aace032; range_end:; response_count:1; response_revision:25521; }","duration":"102.881351ms","start":"2026-08-11T13:45:33.639086Z","end":"2026-08-11T13:45:33.741967Z","steps":["trace[1012674983] 'range keys from in-memory index tree' (duration: 102.422888ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:45:36.016715Z","caller":"traceutil/trace.go:171","msg":"trace[601921969] transaction","detail":"{read_only:false; response_revision:25541; number_of_response:1; }","duration":"181.126431ms","start":"2026-08-11T13:45:35.835564Z","end":"2026-08-11T13:45:36.01669Z","steps":["trace[601921969] 'process raft request' (duration: 180.950686ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:45:36.035712Z","caller":"traceutil/trace.go:171","msg":"trace[100162206] transaction","detail":"{read_only:false; response_revision:25542; number_of_response:1; }","duration":"199.667961ms","start":"2026-08-11T13:45:35.83602Z","end":"2026-08-11T13:45:36.035688Z","steps":["trace[100162206] 'process raft request' (duration: 199.350872ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:45:36.035684Z","caller":"traceutil/trace.go:171","msg":"trace[106323442] transaction","detail":"{read_only:false; response_revision:25543; number_of_response:1; }","duration":"199.500057ms","start":"2026-08-11T13:45:35.836147Z","end":"2026-08-11T13:45:36.035647Z","steps":["trace[106323442] 'process raft request' (duration: 199.406844ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:45:36.036378Z","caller":"traceutil/trace.go:171","msg":"trace[14511580] linearizableReadLoop","detail":"{readStateIndex:26379; appliedIndex:26375; }","duration":"197.935353ms","start":"2026-08-11T13:45:35.838429Z","end":"2026-08-11T13:45:36.036364Z","steps":["trace[14511580] 'read index received' (duration: 178.106416ms)","trace[14511580] 'applied index is now lower than readState.Index' (duration: 19.827677ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T13:45:36.036487Z","caller":"traceutil/trace.go:171","msg":"trace[48807114] transaction","detail":"{read_only:false; response_revision:25544; number_of_response:1; }","duration":"199.164678ms","start":"2026-08-11T13:45:35.837311Z","end":"2026-08-11T13:45:36.036476Z","steps":["trace[48807114] 'process raft request' (duration: 198.291103ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:45:36.036726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.838561ms","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-08-11T13:45:36.0368Z","caller":"traceutil/trace.go:171","msg":"trace[712714809] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25544; }","duration":"197.939114ms","start":"2026-08-11T13:45:35.838842Z","end":"2026-08-11T13:45:36.036781Z","steps":["trace[712714809] 'agreement among raft nodes before linearized reading' (duration: 197.728518ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:45:36.037007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.551941ms","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-08-11T13:45:36.037048Z","caller":"traceutil/trace.go:171","msg":"trace[1070349334] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25544; }","duration":"198.631663ms","start":"2026-08-11T13:45:35.838405Z","end":"2026-08-11T13:45:36.037036Z","steps":["trace[1070349334] 'agreement among raft nodes before linearized reading' (duration: 198.482198ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:45:36.03719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.086368ms","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-08-11T13:45:36.037225Z","caller":"traceutil/trace.go:171","msg":"trace[1623704733] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25544; }","duration":"198.129269ms","start":"2026-08-11T13:45:35.839085Z","end":"2026-08-11T13:45:36.037214Z","steps":["trace[1623704733] 'agreement among raft nodes before linearized reading' (duration: 198.036346ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:45:36.173074Z","caller":"traceutil/trace.go:171","msg":"trace[438280042] linearizableReadLoop","detail":"{readStateIndex:26380; appliedIndex:26379; }","duration":"130.089535ms","start":"2026-08-11T13:45:36.042956Z","end":"2026-08-11T13:45:36.173045Z","steps":["trace[438280042] 'read index received' (duration: 79.118513ms)","trace[438280042] 'applied index is now lower than readState.Index' (duration: 50.969602ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T13:45:36.173363Z","caller":"traceutil/trace.go:171","msg":"trace[733458157] transaction","detail":"{read_only:false; response_revision:25545; number_of_response:1; }","duration":"130.406976ms","start":"2026-08-11T13:45:36.042939Z","end":"2026-08-11T13:45:36.173346Z","steps":["trace[733458157] 'process raft request' (duration: 79.131984ms)","trace[733458157] 'compare' (duration: 50.828138ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T13:45:36.1737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.723665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-08-11T13:45:36.173741Z","caller":"traceutil/trace.go:171","msg":"trace[1218197718] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25545; }","duration":"130.833727ms","start":"2026-08-11T13:45:36.042897Z","end":"2026-08-11T13:45:36.173731Z","steps":["trace[1218197718] 'agreement among raft nodes before linearized reading' (duration: 130.668053ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:45:36.191781Z","caller":"traceutil/trace.go:171","msg":"trace[1443781618] transaction","detail":"{read_only:false; response_revision:25546; number_of_response:1; }","duration":"147.432863ms","start":"2026-08-11T13:45:36.044308Z","end":"2026-08-11T13:45:36.191741Z","steps":["trace[1443781618] 'process raft request' (duration: 147.175496ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:45:36.191805Z","caller":"traceutil/trace.go:171","msg":"trace[1956711238] transaction","detail":"{read_only:false; response_revision:25547; number_of_response:1; }","duration":"145.111889ms","start":"2026-08-11T13:45:36.046654Z","end":"2026-08-11T13:45:36.191766Z","steps":["trace[1956711238] 'process raft request' (duration: 145.018116ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:45:40.40722Z","caller":"traceutil/trace.go:171","msg":"trace[1406304938] linearizableReadLoop","detail":"{readStateIndex:26432; appliedIndex:26431; }","duration":"219.258022ms","start":"2026-08-11T13:45:40.187922Z","end":"2026-08-11T13:45:40.40718Z","steps":["trace[1406304938] 'read index received' (duration: 162.829606ms)","trace[1406304938] 'applied index is now lower than readState.Index' (duration: 56.424766ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T13:45:40.407398Z","caller":"traceutil/trace.go:171","msg":"trace[2013196860] transaction","detail":"{read_only:false; response_revision:25596; number_of_response:1; }","duration":"233.405699ms","start":"2026-08-11T13:45:40.173973Z","end":"2026-08-11T13:45:40.407379Z","steps":["trace[2013196860] 'process raft request' (duration: 176.87179ms)","trace[2013196860] 'compare' (duration: 55.774928ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T13:45:40.407638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.059937ms","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-08-11T13:45:40.407678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.924091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/flavors/\" range_end:\"/registry/openstack.k-orc.cloud/flavors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-11T13:45:40.407727Z","caller":"traceutil/trace.go:171","msg":"trace[980158244] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25597; }","duration":"203.222641ms","start":"2026-08-11T13:45:40.204485Z","end":"2026-08-11T13:45:40.407708Z","steps":["trace[980158244] 'agreement among raft nodes before linearized reading' (duration: 202.987125ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:45:40.407744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.162983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-08-11T13:45:40.407789Z","caller":"traceutil/trace.go:171","msg":"trace[538988521] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/flavors/; range_end:/registry/openstack.k-orc.cloud/flavors0; response_count:0; response_revision:25597; }","duration":"199.107526ms","start":"2026-08-11T13:45:40.208651Z","end":"2026-08-11T13:45:40.407759Z","steps":["trace[538988521] 'agreement among raft nodes before linearized reading' (duration: 198.854349ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:45:40.407773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.848249ms","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-08-11T13:45:40.407866Z","caller":"traceutil/trace.go:171","msg":"trace[171551927] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25597; }","duration":"219.988732ms","start":"2026-08-11T13:45:40.187856Z","end":"2026-08-11T13:45:40.407845Z","steps":["trace[171551927] 'agreement among raft nodes before linearized reading' (duration: 219.671993ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:45:40.407848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.548245ms","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-08-11T13:45:40.407866Z","caller":"traceutil/trace.go:171","msg":"trace[1550614611] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25597; }","duration":"196.344478ms","start":"2026-08-11T13:45:40.211488Z","end":"2026-08-11T13:45:40.407833Z","steps":["trace[1550614611] 'agreement among raft nodes before linearized reading' (duration: 196.001538ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:45:40.407943Z","caller":"traceutil/trace.go:171","msg":"trace[1396311393] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25597; }","duration":"201.681068ms","start":"2026-08-11T13:45:40.206238Z","end":"2026-08-11T13:45:40.407919Z","steps":["trace[1396311393] 'agreement among raft nodes before linearized reading' (duration: 201.323018ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:45:40.665501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.661479ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-11T13:45:40.665672Z","caller":"traceutil/trace.go:171","msg":"trace[30870661] transaction","detail":"{read_only:false; response_revision:25598; number_of_response:1; }","duration":"251.968931ms","start":"2026-08-11T13:45:40.413675Z","end":"2026-08-11T13:45:40.665644Z","steps":["trace[30870661] 'process raft request' (duration: 119.034686ms)","trace[30870661] 'compare' (duration: 132.36581ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T13:45:40.83125Z","caller":"traceutil/trace.go:171","msg":"trace[1099502022] transaction","detail":"{read_only:false; response_revision:25599; number_of_response:1; }","duration":"416.480304ms","start":"2026-08-11T13:45:40.414718Z","end":"2026-08-11T13:45:40.831199Z","steps":["trace[1099502022] 'process raft request' (duration: 359.246306ms)","trace[1099502022] 'compare' (duration: 56.830117ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T13:45:40.83139Z","caller":"traceutil/trace.go:171","msg":"trace[1042406214] transaction","detail":"{read_only:false; response_revision:25602; number_of_response:1; }","duration":"127.030479ms","start":"2026-08-11T13:45:40.704324Z","end":"2026-08-11T13:45:40.831354Z","steps":["trace[1042406214] 'process raft request' (duration: 126.978598ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:45:40.831573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T13:45:40.414683Z","time spent":"416.767722ms","remote":"127.0.0.1:34220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T13:45:40.831603Z","caller":"traceutil/trace.go:171","msg":"trace[123453968] transaction","detail":"{read_only:false; response_revision:25601; number_of_response:1; }","duration":"415.817735ms","start":"2026-08-11T13:45:40.415759Z","end":"2026-08-11T13:45:40.831577Z","steps":["trace[123453968] 'process raft request' (duration: 415.483666ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:45:40.831615Z","caller":"traceutil/trace.go:171","msg":"trace[2100336879] linearizableReadLoop","detail":"{readStateIndex:26436; appliedIndex:26433; }","duration":"415.899508ms","start":"2026-08-11T13:45:40.415689Z","end":"2026-08-11T13:45:40.831588Z","steps":["trace[2100336879] 'read index received' (duration: 117.038589ms)","trace[2100336879] 'applied index is now lower than readState.Index' (duration: 298.859119ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T13:45:40.831812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T13:45:40.415499Z","time spent":"416.199807ms","remote":"127.0.0.1:34220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T13:45:40.83183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.154706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:18600"} {"level":"info","ts":"2026-08-11T13:45:40.831879Z","caller":"traceutil/trace.go:171","msg":"trace[151171726] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:25602; }","duration":"416.248267ms","start":"2026-08-11T13:45:40.415616Z","end":"2026-08-11T13:45:40.831865Z","steps":["trace[151171726] 'agreement among raft nodes before linearized reading' (duration: 416.078223ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:45:40.831924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T13:45:40.415596Z","time spent":"416.319081ms","remote":"127.0.0.1:34124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":1,"response size":18622,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" "} {"level":"warn","ts":"2026-08-11T13:45:40.831931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.944145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-08-11T13:45:40.832046Z","caller":"traceutil/trace.go:171","msg":"trace[85101210] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25602; }","duration":"313.069869ms","start":"2026-08-11T13:45:40.518901Z","end":"2026-08-11T13:45:40.83197Z","steps":["trace[85101210] 'agreement among raft nodes before linearized reading' (duration: 312.877103ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:45:40.832081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.506001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-11T13:45:40.832113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T13:45:40.518867Z","time spent":"313.232633ms","remote":"127.0.0.1:34220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-11T13:45:40.832198Z","caller":"traceutil/trace.go:171","msg":"trace[1218649586] transaction","detail":"{read_only:false; response_revision:25600; number_of_response:1; }","duration":"415.778494ms","start":"2026-08-11T13:45:40.415548Z","end":"2026-08-11T13:45:40.831326Z","steps":["trace[1218649586] 'process raft request' (duration: 415.577969ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:45:40.832651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T13:45:40.415467Z","time spent":"417.069672ms","remote":"127.0.0.1:34220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T13:45:40.832336Z","caller":"traceutil/trace.go:171","msg":"trace[942668187] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:25602; }","duration":"139.924863ms","start":"2026-08-11T13:45:40.69239Z","end":"2026-08-11T13:45:40.832314Z","steps":["trace[942668187] 'agreement among raft nodes before linearized reading' (duration: 139.449099ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:45:50.326286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.50265ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-11T13:45:50.326527Z","caller":"traceutil/trace.go:171","msg":"trace[1663225194] transaction","detail":"{read_only:false; response_revision:25677; number_of_response:1; }","duration":"230.349983ms","start":"2026-08-11T13:45:50.096155Z","end":"2026-08-11T13:45:50.326505Z","steps":["trace[1663225194] 'process raft request' (duration: 230.24594ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:45:50.326541Z","caller":"traceutil/trace.go:171","msg":"trace[1037882939] transaction","detail":"{read_only:false; response_revision:25676; number_of_response:1; }","duration":"232.775841ms","start":"2026-08-11T13:45:50.093734Z","end":"2026-08-11T13:45:50.32651Z","steps":["trace[1037882939] 'process raft request' (duration: 102.928302ms)","trace[1037882939] 'compare' (duration: 129.272673ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T13:45:50.361805Z","caller":"traceutil/trace.go:171","msg":"trace[2131534827] linearizableReadLoop","detail":"{readStateIndex:26516; appliedIndex:26515; }","duration":"141.26888ms","start":"2026-08-11T13:45:50.220507Z","end":"2026-08-11T13:45:50.361776Z","steps":["trace[2131534827] 'read index received' (duration: 141.073514ms)","trace[2131534827] 'applied index is now lower than readState.Index' (duration: 193.886µs)"],"step_count":2} {"level":"warn","ts":"2026-08-11T13:45:50.36202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.487646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-11T13:45:50.362086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.559428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-11T13:45:50.362119Z","caller":"traceutil/trace.go:171","msg":"trace[1124263082] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesets/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesets0; response_count:0; response_revision:25678; }","duration":"141.61707ms","start":"2026-08-11T13:45:50.220482Z","end":"2026-08-11T13:45:50.362099Z","steps":["trace[1124263082] 'agreement among raft nodes before linearized reading' (duration: 141.404064ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:45:50.362145Z","caller":"traceutil/trace.go:171","msg":"trace[799013843] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:25678; }","duration":"141.674911ms","start":"2026-08-11T13:45:50.220452Z","end":"2026-08-11T13:45:50.362127Z","steps":["trace[799013843] 'agreement among raft nodes before linearized reading' (duration: 141.545137ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:45:50.362593Z","caller":"traceutil/trace.go:171","msg":"trace[1865036052] transaction","detail":"{read_only:false; response_revision:25678; number_of_response:1; }","duration":"264.170064ms","start":"2026-08-11T13:45:50.097686Z","end":"2026-08-11T13:45:50.361857Z","steps":["trace[1865036052] 'process raft request' (duration: 263.926537ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:45:50.923307Z","caller":"traceutil/trace.go:171","msg":"trace[1548223397] linearizableReadLoop","detail":"{readStateIndex:26517; appliedIndex:26516; }","duration":"151.23951ms","start":"2026-08-11T13:45:50.772041Z","end":"2026-08-11T13:45:50.92328Z","steps":["trace[1548223397] 'read index received' (duration: 151.095146ms)","trace[1548223397] 'applied index is now lower than readState.Index' (duration: 143.414µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T13:45:50.923395Z","caller":"traceutil/trace.go:171","msg":"trace[2069256259] transaction","detail":"{read_only:false; response_revision:25679; number_of_response:1; }","duration":"197.81861ms","start":"2026-08-11T13:45:50.725558Z","end":"2026-08-11T13:45:50.923376Z","steps":["trace[2069256259] 'process raft request' (duration: 197.537062ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:45:50.923632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.561669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-08-11T13:45:50.923675Z","caller":"traceutil/trace.go:171","msg":"trace[1891664215] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:25679; }","duration":"151.676903ms","start":"2026-08-11T13:45:50.771987Z","end":"2026-08-11T13:45:50.923664Z","steps":["trace[1891664215] 'agreement among raft nodes before linearized reading' (duration: 151.455517ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:45:51.026787Z","caller":"traceutil/trace.go:171","msg":"trace[1946171576] linearizableReadLoop","detail":"{readStateIndex:26518; appliedIndex:26517; }","duration":"103.344775ms","start":"2026-08-11T13:45:50.923411Z","end":"2026-08-11T13:45:51.026756Z","steps":["trace[1946171576] 'read index received' (duration: 93.754464ms)","trace[1946171576] 'applied index is now lower than readState.Index' (duration: 9.588881ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T13:45:51.02702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.436569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-08-11T13:45:51.027077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.087879ms","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-08-11T13:45:51.027123Z","caller":"traceutil/trace.go:171","msg":"trace[1845573103] transaction","detail":"{read_only:false; response_revision:25680; number_of_response:1; }","duration":"161.402407ms","start":"2026-08-11T13:45:50.865678Z","end":"2026-08-11T13:45:51.027081Z","steps":["trace[1845573103] 'process raft request' (duration: 151.436546ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:45:51.027184Z","caller":"traceutil/trace.go:171","msg":"trace[1441888156] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25680; }","duration":"139.264325ms","start":"2026-08-11T13:45:50.887891Z","end":"2026-08-11T13:45:51.027156Z","steps":["trace[1441888156] 'agreement among raft nodes before linearized reading' (duration: 138.888254ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:45:51.02713Z","caller":"traceutil/trace.go:171","msg":"trace[1216920479] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25680; }","duration":"139.604143ms","start":"2026-08-11T13:45:50.887504Z","end":"2026-08-11T13:45:51.027108Z","steps":["trace[1216920479] 'agreement among raft nodes before linearized reading' (duration: 139.283974ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:45:51.027265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.110338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-08-11T13:45:51.027053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.790306ms","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-08-11T13:45:51.027404Z","caller":"traceutil/trace.go:171","msg":"trace[177452333] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25680; }","duration":"140.292943ms","start":"2026-08-11T13:45:50.887089Z","end":"2026-08-11T13:45:51.027382Z","steps":["trace[177452333] 'agreement among raft nodes before linearized reading' (duration: 139.954894ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:45:51.027481Z","caller":"traceutil/trace.go:171","msg":"trace[1810619373] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25680; }","duration":"142.230717ms","start":"2026-08-11T13:45:50.885203Z","end":"2026-08-11T13:45:51.027433Z","steps":["trace[1810619373] 'agreement among raft nodes before linearized reading' (duration: 141.663372ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:45:53.784012Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22262} {"level":"info","ts":"2026-08-11T13:45:53.895373Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22262,"took":"106.216025ms","hash":2345003703,"current-db-size-bytes":58769408,"current-db-size":"59 MB","current-db-size-in-use-bytes":45510656,"current-db-size-in-use":"46 MB"} {"level":"info","ts":"2026-08-11T13:45:53.895438Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2345003703,"revision":22262,"compact-revision":20053} {"level":"info","ts":"2026-08-11T13:45:55.267693Z","caller":"traceutil/trace.go:171","msg":"trace[429418134] linearizableReadLoop","detail":"{readStateIndex:26554; appliedIndex:26553; }","duration":"207.567414ms","start":"2026-08-11T13:45:55.060097Z","end":"2026-08-11T13:45:55.267664Z","steps":["trace[429418134] 'read index received' (duration: 136.585508ms)","trace[429418134] 'applied index is now lower than readState.Index' (duration: 70.980776ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T13:45:55.267705Z","caller":"traceutil/trace.go:171","msg":"trace[836207442] transaction","detail":"{read_only:false; response_revision:25714; number_of_response:1; }","duration":"209.335233ms","start":"2026-08-11T13:45:55.058348Z","end":"2026-08-11T13:45:55.267683Z","steps":["trace[836207442] 'process raft request' (duration: 138.201914ms)","trace[836207442] 'compare' (duration: 71.004846ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T13:45:55.268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.866853ms","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-08-11T13:45:55.268104Z","caller":"traceutil/trace.go:171","msg":"trace[583488762] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25714; }","duration":"208.026847ms","start":"2026-08-11T13:45:55.060054Z","end":"2026-08-11T13:45:55.268081Z","steps":["trace[583488762] 'agreement among raft nodes before linearized reading' (duration: 207.699688ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:45:55.398857Z","caller":"traceutil/trace.go:171","msg":"trace[668864235] transaction","detail":"{read_only:false; response_revision:25715; number_of_response:1; }","duration":"283.313042ms","start":"2026-08-11T13:45:55.115503Z","end":"2026-08-11T13:45:55.398816Z","steps":["trace[668864235] 'process raft request' (duration: 270.936324ms)","trace[668864235] 'compare' (duration: 12.153242ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T13:45:55.398875Z","caller":"traceutil/trace.go:171","msg":"trace[269841489] transaction","detail":"{read_only:false; response_revision:25718; number_of_response:1; }","duration":"120.767704ms","start":"2026-08-11T13:45:55.278082Z","end":"2026-08-11T13:45:55.39885Z","steps":["trace[269841489] 'process raft request' (duration: 120.714923ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:45:55.398951Z","caller":"traceutil/trace.go:171","msg":"trace[1799360651] transaction","detail":"{read_only:false; response_revision:25717; number_of_response:1; }","duration":"281.664025ms","start":"2026-08-11T13:45:55.117228Z","end":"2026-08-11T13:45:55.398892Z","steps":["trace[1799360651] 'process raft request' (duration: 281.525572ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:45:55.398978Z","caller":"traceutil/trace.go:171","msg":"trace[311935401] transaction","detail":"{read_only:false; response_revision:25716; number_of_response:1; }","duration":"282.094408ms","start":"2026-08-11T13:45:55.116847Z","end":"2026-08-11T13:45:55.398941Z","steps":["trace[311935401] 'process raft request' (duration: 281.858031ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:45:55.399058Z","caller":"traceutil/trace.go:171","msg":"trace[266097834] linearizableReadLoop","detail":"{readStateIndex:26557; appliedIndex:26554; }","duration":"131.280209ms","start":"2026-08-11T13:45:55.267766Z","end":"2026-08-11T13:45:55.399046Z","steps":["trace[266097834] 'read index received' (duration: 118.687645ms)","trace[266097834] 'applied index is now lower than readState.Index' (duration: 12.591804ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T13:45:55.399283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.0962ms","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-08-11T13:45:55.399325Z","caller":"traceutil/trace.go:171","msg":"trace[1241642060] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25718; }","duration":"339.161202ms","start":"2026-08-11T13:45:55.060154Z","end":"2026-08-11T13:45:55.399315Z","steps":["trace[1241642060] 'agreement among raft nodes before linearized reading' (duration: 338.946576ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:45:55.399366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T13:45:55.060063Z","time spent":"339.293596ms","remote":"127.0.0.1:34220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-11T13:45:55.399388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.642117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-08-11T13:45:55.399431Z","caller":"traceutil/trace.go:171","msg":"trace[823749679] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25718; }","duration":"338.71299ms","start":"2026-08-11T13:45:55.060703Z","end":"2026-08-11T13:45:55.399416Z","steps":["trace[823749679] 'agreement among raft nodes before linearized reading' (duration: 338.500484ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:45:55.399473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T13:45:55.060696Z","time spent":"338.768421ms","remote":"127.0.0.1:34220","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-08-11T13:46:04.676563Z","caller":"traceutil/trace.go:171","msg":"trace[995187966] transaction","detail":"{read_only:false; response_revision:25796; number_of_response:1; }","duration":"266.594893ms","start":"2026-08-11T13:46:04.409936Z","end":"2026-08-11T13:46:04.676531Z","steps":["trace[995187966] 'process raft request' (duration: 266.389887ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:46:04.676656Z","caller":"traceutil/trace.go:171","msg":"trace[1612035966] linearizableReadLoop","detail":"{readStateIndex:26638; appliedIndex:26637; }","duration":"153.457793ms","start":"2026-08-11T13:46:04.523164Z","end":"2026-08-11T13:46:04.676622Z","steps":["trace[1612035966] 'read index received' (duration: 153.117524ms)","trace[1612035966] 'applied index is now lower than readState.Index' (duration: 337.409µs)"],"step_count":2} {"level":"warn","ts":"2026-08-11T13:46:04.67689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.699909ms","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:6"} {"level":"info","ts":"2026-08-11T13:46:04.676983Z","caller":"traceutil/trace.go:171","msg":"trace[665735350] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:25796; }","duration":"153.868955ms","start":"2026-08-11T13:46:04.523094Z","end":"2026-08-11T13:46:04.676963Z","steps":["trace[665735350] 'agreement among raft nodes before linearized reading' (duration: 153.70023ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:46:08.963508Z","caller":"traceutil/trace.go:171","msg":"trace[264867175] transaction","detail":"{read_only:false; response_revision:25824; number_of_response:1; }","duration":"198.395646ms","start":"2026-08-11T13:46:08.765079Z","end":"2026-08-11T13:46:08.963475Z","steps":["trace[264867175] 'process raft request' (duration: 198.178279ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:46:09.567202Z","caller":"traceutil/trace.go:171","msg":"trace[1666616152] transaction","detail":"{read_only:false; response_revision:25830; number_of_response:1; }","duration":"112.997326ms","start":"2026-08-11T13:46:09.454178Z","end":"2026-08-11T13:46:09.567175Z","steps":["trace[1666616152] 'process raft request' (duration: 112.767939ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:46:09.567243Z","caller":"traceutil/trace.go:171","msg":"trace[18343566] transaction","detail":"{read_only:false; response_revision:25831; number_of_response:1; }","duration":"110.243428ms","start":"2026-08-11T13:46:09.456963Z","end":"2026-08-11T13:46:09.567206Z","steps":["trace[18343566] 'process raft request' (duration: 110.137815ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:46:09.873661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.757377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-11T13:46:09.873742Z","caller":"traceutil/trace.go:171","msg":"trace[409021643] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25834; }","duration":"118.88779ms","start":"2026-08-11T13:46:09.754826Z","end":"2026-08-11T13:46:09.873713Z","steps":["trace[409021643] 'count revisions from in-memory index tree' (duration: 118.684175ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:46:11.28094Z","caller":"traceutil/trace.go:171","msg":"trace[1110186467] transaction","detail":"{read_only:false; response_revision:25840; number_of_response:1; }","duration":"126.810165ms","start":"2026-08-11T13:46:11.154088Z","end":"2026-08-11T13:46:11.280898Z","steps":["trace[1110186467] 'process raft request' (duration: 126.528537ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:46:13.577011Z","caller":"traceutil/trace.go:171","msg":"trace[164333480] transaction","detail":"{read_only:false; response_revision:25856; number_of_response:1; }","duration":"183.194828ms","start":"2026-08-11T13:46:13.393784Z","end":"2026-08-11T13:46:13.576979Z","steps":["trace[164333480] 'process raft request' (duration: 182.89385ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:46:13.754692Z","caller":"traceutil/trace.go:171","msg":"trace[125290259] transaction","detail":"{read_only:false; response_revision:25858; number_of_response:1; }","duration":"174.622968ms","start":"2026-08-11T13:46:13.580038Z","end":"2026-08-11T13:46:13.754661Z","steps":["trace[125290259] 'process raft request' (duration: 78.431984ms)","trace[125290259] 'compare' (duration: 95.927417ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T13:46:13.754896Z","caller":"traceutil/trace.go:171","msg":"trace[993630785] linearizableReadLoop","detail":"{readStateIndex:26703; appliedIndex:26700; }","duration":"164.345498ms","start":"2026-08-11T13:46:13.590512Z","end":"2026-08-11T13:46:13.754858Z","steps":["trace[993630785] 'read index received' (duration: 13.760226ms)","trace[993630785] 'applied index is now lower than readState.Index' (duration: 150.583662ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T13:46:13.755107Z","caller":"traceutil/trace.go:171","msg":"trace[1916527533] transaction","detail":"{read_only:false; response_revision:25859; number_of_response:1; }","duration":"171.832909ms","start":"2026-08-11T13:46:13.583264Z","end":"2026-08-11T13:46:13.755097Z","steps":["trace[1916527533] 'process raft request' (duration: 171.340855ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:46:13.755344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.820932ms","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-08-11T13:46:13.755418Z","caller":"traceutil/trace.go:171","msg":"trace[780273558] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25859; }","duration":"164.957556ms","start":"2026-08-11T13:46:13.590429Z","end":"2026-08-11T13:46:13.755387Z","steps":["trace[780273558] 'agreement among raft nodes before linearized reading' (duration: 164.74725ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:46:13.755362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.168395ms","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-08-11T13:46:13.755463Z","caller":"traceutil/trace.go:171","msg":"trace[1206853747] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25859; }","duration":"116.346589ms","start":"2026-08-11T13:46:13.639109Z","end":"2026-08-11T13:46:13.755455Z","steps":["trace[1206853747] 'agreement among raft nodes before linearized reading' (duration: 116.111233ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:46:13.755511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.492263ms","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-08-11T13:46:13.755628Z","caller":"traceutil/trace.go:171","msg":"trace[2124322863] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25859; }","duration":"116.68705ms","start":"2026-08-11T13:46:13.638909Z","end":"2026-08-11T13:46:13.755596Z","steps":["trace[2124322863] 'agreement among raft nodes before linearized reading' (duration: 116.31474ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:46:19.936988Z","caller":"traceutil/trace.go:171","msg":"trace[664713300] transaction","detail":"{read_only:false; response_revision:25904; number_of_response:1; }","duration":"163.926167ms","start":"2026-08-11T13:46:19.77303Z","end":"2026-08-11T13:46:19.936956Z","steps":["trace[664713300] 'process raft request' (duration: 163.712241ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:46:20.01533Z","caller":"traceutil/trace.go:171","msg":"trace[1626747871] linearizableReadLoop","detail":"{readStateIndex:26750; appliedIndex:26748; }","duration":"122.114692ms","start":"2026-08-11T13:46:19.893196Z","end":"2026-08-11T13:46:20.01531Z","steps":["trace[1626747871] 'read index received' (duration: 43.598666ms)","trace[1626747871] 'applied index is now lower than readState.Index' (duration: 78.514966ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T13:46:20.015456Z","caller":"traceutil/trace.go:171","msg":"trace[561241833] transaction","detail":"{read_only:false; response_revision:25905; number_of_response:1; }","duration":"240.272413ms","start":"2026-08-11T13:46:19.775173Z","end":"2026-08-11T13:46:20.015445Z","steps":["trace[561241833] 'process raft request' (duration: 213.939652ms)","trace[561241833] 'compare' (duration: 26.073994ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T13:46:20.015632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.290909ms","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-08-11T13:46:20.015699Z","caller":"traceutil/trace.go:171","msg":"trace[1743874360] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25905; }","duration":"121.390753ms","start":"2026-08-11T13:46:19.894294Z","end":"2026-08-11T13:46:20.015684Z","steps":["trace[1743874360] 'agreement among raft nodes before linearized reading' (duration: 121.216418ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:46:20.015885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.192572ms","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-08-11T13:46:20.01591Z","caller":"traceutil/trace.go:171","msg":"trace[868633448] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25905; }","duration":"118.239333ms","start":"2026-08-11T13:46:19.897664Z","end":"2026-08-11T13:46:20.015903Z","steps":["trace[868633448] 'agreement among raft nodes before linearized reading' (duration: 118.176721ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:46:20.015913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.698328ms","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-08-11T13:46:20.015944Z","caller":"traceutil/trace.go:171","msg":"trace[2032982219] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25905; }","duration":"122.804442ms","start":"2026-08-11T13:46:19.893131Z","end":"2026-08-11T13:46:20.015935Z","steps":["trace[2032982219] 'agreement among raft nodes before linearized reading' (duration: 122.681688ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:46:23.777398Z","caller":"traceutil/trace.go:171","msg":"trace[121451193] linearizableReadLoop","detail":"{readStateIndex:26776; appliedIndex:26774; }","duration":"107.477861ms","start":"2026-08-11T13:46:23.669888Z","end":"2026-08-11T13:46:23.777366Z","steps":["trace[121451193] 'read index received' (duration: 1.244605ms)","trace[121451193] 'applied index is now lower than readState.Index' (duration: 106.231506ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T13:46:23.777563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.646236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-11T13:46:23.777613Z","caller":"traceutil/trace.go:171","msg":"trace[1263565636] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters0; response_count:0; response_revision:25930; }","duration":"107.78455ms","start":"2026-08-11T13:46:23.669812Z","end":"2026-08-11T13:46:23.777597Z","steps":["trace[1263565636] 'agreement among raft nodes before linearized reading' (duration: 107.669436ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:46:23.777663Z","caller":"traceutil/trace.go:171","msg":"trace[217794099] transaction","detail":"{read_only:false; response_revision:25930; number_of_response:1; }","duration":"126.422413ms","start":"2026-08-11T13:46:23.65121Z","end":"2026-08-11T13:46:23.777632Z","steps":["trace[217794099] 'process raft request' (duration: 74.874704ms)","trace[217794099] 'compare' (duration: 51.139458ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T13:46:24.089478Z","caller":"traceutil/trace.go:171","msg":"trace[118718517] transaction","detail":"{read_only:false; response_revision:25931; number_of_response:1; }","duration":"138.742179ms","start":"2026-08-11T13:46:23.950695Z","end":"2026-08-11T13:46:24.089437Z","steps":["trace[118718517] 'process raft request' (duration: 138.492352ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:46:24.127796Z","caller":"traceutil/trace.go:171","msg":"trace[947398569] transaction","detail":"{read_only:false; response_revision:25933; number_of_response:1; }","duration":"101.969946ms","start":"2026-08-11T13:46:24.025781Z","end":"2026-08-11T13:46:24.12775Z","steps":["trace[947398569] 'process raft request' (duration: 101.860523ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:46:24.127876Z","caller":"traceutil/trace.go:171","msg":"trace[1093245347] transaction","detail":"{read_only:false; response_revision:25932; number_of_response:1; }","duration":"112.614506ms","start":"2026-08-11T13:46:24.015211Z","end":"2026-08-11T13:46:24.127826Z","steps":["trace[1093245347] 'process raft request' (duration: 111.818352ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:46:24.263958Z","caller":"traceutil/trace.go:171","msg":"trace[479058322] transaction","detail":"{read_only:false; response_revision:25936; number_of_response:1; }","duration":"129.17746ms","start":"2026-08-11T13:46:24.134689Z","end":"2026-08-11T13:46:24.263866Z","steps":["trace[479058322] 'process raft request' (duration: 128.989775ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:46:24.263865Z","caller":"traceutil/trace.go:171","msg":"trace[1058458425] transaction","detail":"{read_only:false; response_revision:25935; number_of_response:1; }","duration":"129.88527ms","start":"2026-08-11T13:46:24.133892Z","end":"2026-08-11T13:46:24.263777Z","steps":["trace[1058458425] 'process raft request' (duration: 129.473948ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:50:53.79006Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25706} {"level":"info","ts":"2026-08-11T13:50:53.960123Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25706,"took":"164.770021ms","hash":2085684284,"current-db-size-bytes":60456960,"current-db-size":"60 MB","current-db-size-in-use-bytes":36642816,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-08-11T13:50:53.960231Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2085684284,"revision":25706,"compact-revision":22262} {"level":"info","ts":"2026-08-11T13:51:59.644037Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"2d372260bb4c37a","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-11T13:51:59.64885Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-08-11T13:51:59.648969Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-08-11T13:55:53.797249Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28594} {"level":"info","ts":"2026-08-11T13:55:53.947334Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28594,"took":"144.189273ms","hash":421099849,"current-db-size-bytes":60456960,"current-db-size":"60 MB","current-db-size-in-use-bytes":31444992,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-11T13:55:53.947417Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":421099849,"revision":28594,"compact-revision":25706} {"level":"info","ts":"2026-08-11T14:00:53.804878Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30817} {"level":"info","ts":"2026-08-11T14:00:53.921687Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30817,"took":"111.297687ms","hash":386876971,"current-db-size-bytes":60456960,"current-db-size":"60 MB","current-db-size-in-use-bytes":31723520,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-11T14:00:53.921757Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":386876971,"revision":30817,"compact-revision":28594} {"level":"info","ts":"2026-08-11T14:05:53.812326Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33137} {"level":"info","ts":"2026-08-11T14:05:53.965329Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33137,"took":"147.2655ms","hash":2303040003,"current-db-size-bytes":61501440,"current-db-size":"62 MB","current-db-size-in-use-bytes":30826496,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-11T14:05:53.965403Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2303040003,"revision":33137,"compact-revision":30817} {"level":"info","ts":"2026-08-11T14:10:53.818644Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35425} {"level":"info","ts":"2026-08-11T14:10:53.972924Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35425,"took":"148.893663ms","hash":1080611090,"current-db-size-bytes":61501440,"current-db-size":"62 MB","current-db-size-in-use-bytes":31719424,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-11T14:10:53.972999Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1080611090,"revision":35425,"compact-revision":33137} {"level":"info","ts":"2026-08-11T14:13:18.807507Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"2d372260bb4c37a","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-11T14:13:18.81266Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-08-11T14:13:18.812767Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-08-11T14:15:53.8261Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37692} {"level":"info","ts":"2026-08-11T14:15:53.952379Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37692,"took":"121.877344ms","hash":908014498,"current-db-size-bytes":63193088,"current-db-size":"63 MB","current-db-size-in-use-bytes":32210944,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-11T14:15:53.952453Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":908014498,"revision":37692,"compact-revision":35425} {"level":"info","ts":"2026-08-11T14:20:53.832286Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39972} {"level":"info","ts":"2026-08-11T14:20:53.956329Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39972,"took":"120.075943ms","hash":215553704,"current-db-size-bytes":63193088,"current-db-size":"63 MB","current-db-size-in-use-bytes":30515200,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-11T14:20:53.956414Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":215553704,"revision":39972,"compact-revision":37692}