{"level":"warn","ts":"2026-08-22T10:32:22.882794Z","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-22T10:32:22.882883Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.169: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.169:2380","--initial-cluster=instance=https://199.204.45.169:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.169:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.169: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-22T10:32:22.882954Z","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-22T10:32:22.883003Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.169:2380"]} {"level":"info","ts":"2026-08-22T10:32:22.883023Z","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-22T10:32:22.883848Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"]} {"level":"info","ts":"2026-08-22T10:32:22.883917Z","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.169:2380"],"listen-peer-urls":["https://199.204.45.169:2380"],"advertise-client-urls":["https://199.204.45.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.169: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-22T10:32:22.886477Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.304611ms"} {"level":"info","ts":"2026-08-22T10:32:22.896192Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1c917701ce165bac","cluster-id":"bfb5247022b87cb1"} {"level":"info","ts":"2026-08-22T10:32:22.896276Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=()"} {"level":"info","ts":"2026-08-22T10:32:22.896299Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became follower at term 0"} {"level":"info","ts":"2026-08-22T10:32:22.896309Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1c917701ce165bac [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-22T10:32:22.896322Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became follower at term 1"} {"level":"info","ts":"2026-08-22T10:32:22.896356Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=(2058557354321271724)"} {"level":"warn","ts":"2026-08-22T10:32:22.902666Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-22T10:32:22.90414Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-22T10:32:22.905256Z","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-22T10:32:22.908943Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1c917701ce165bac","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-22T10:32:22.909451Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1c917701ce165bac","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-22T10:32:22.909402Z","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-22T10:32:22.90957Z","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-22T10:32:22.909601Z","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-22T10:32:22.911227Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=(2058557354321271724)"} {"level":"info","ts":"2026-08-22T10:32:22.91153Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"bfb5247022b87cb1","local-member-id":"1c917701ce165bac","added-peer-id":"1c917701ce165bac","added-peer-peer-urls":["https://199.204.45.169:2380"]} {"level":"info","ts":"2026-08-22T10:32:22.913656Z","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-22T10:32:22.913803Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.169:2380"} {"level":"info","ts":"2026-08-22T10:32:22.913847Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.169:2380"} {"level":"info","ts":"2026-08-22T10:32:22.914082Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1c917701ce165bac","initial-advertise-peer-urls":["https://199.204.45.169:2380"],"listen-peer-urls":["https://199.204.45.169:2380"],"advertise-client-urls":["https://199.204.45.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-22T10:32:22.914116Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-22T10:32:23.496802Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac is starting a new election at term 1"} {"level":"info","ts":"2026-08-22T10:32:23.496868Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became pre-candidate at term 1"} {"level":"info","ts":"2026-08-22T10:32:23.496901Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac received MsgPreVoteResp from 1c917701ce165bac at term 1"} {"level":"info","ts":"2026-08-22T10:32:23.496917Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became candidate at term 2"} {"level":"info","ts":"2026-08-22T10:32:23.496925Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac received MsgVoteResp from 1c917701ce165bac at term 2"} {"level":"info","ts":"2026-08-22T10:32:23.496936Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became leader at term 2"} {"level":"info","ts":"2026-08-22T10:32:23.496945Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1c917701ce165bac elected leader 1c917701ce165bac at term 2"} {"level":"info","ts":"2026-08-22T10:32:23.497752Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1c917701ce165bac","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.169:2379]}","request-path":"/0/members/1c917701ce165bac/attributes","cluster-id":"bfb5247022b87cb1","publish-timeout":"7s"} {"level":"info","ts":"2026-08-22T10:32:23.497758Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-22T10:32:23.497859Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-22T10:32:23.497971Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-22T10:32:23.498012Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-22T10:32:23.497891Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T10:32:23.499031Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"bfb5247022b87cb1","local-member-id":"1c917701ce165bac","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T10:32:23.499185Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T10:32:23.499226Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T10:32:23.499929Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-22T10:32:23.504133Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.169:2379"} {"level":"info","ts":"2026-08-22T10:33:00.534802Z","caller":"traceutil/trace.go:171","msg":"trace[22789509] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"181.229801ms","start":"2026-08-22T10:33:00.35354Z","end":"2026-08-22T10:33:00.53477Z","steps":["trace[22789509] 'process raft request' (duration: 176.855832ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:33:00.538418Z","caller":"traceutil/trace.go:171","msg":"trace[326529523] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"183.946011ms","start":"2026-08-22T10:33:00.354404Z","end":"2026-08-22T10:33:00.53835Z","steps":["trace[326529523] 'process raft request' (duration: 183.693787ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:33:00.538588Z","caller":"traceutil/trace.go:171","msg":"trace[2005300932] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"180.967206ms","start":"2026-08-22T10:33:00.357587Z","end":"2026-08-22T10:33:00.538554Z","steps":["trace[2005300932] 'process raft request' (duration: 180.66394ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:33:00.538733Z","caller":"traceutil/trace.go:171","msg":"trace[682770649] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"180.887654ms","start":"2026-08-22T10:33:00.357818Z","end":"2026-08-22T10:33:00.538705Z","steps":["trace[682770649] 'process raft request' (duration: 180.65042ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:33:00.538955Z","caller":"traceutil/trace.go:171","msg":"trace[1854883542] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"109.269189ms","start":"2026-08-22T10:33:00.429617Z","end":"2026-08-22T10:33:00.538886Z","steps":["trace[1854883542] 'process raft request' (duration: 109.001164ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:33:06.383079Z","caller":"traceutil/trace.go:171","msg":"trace[310979008] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"100.438239ms","start":"2026-08-22T10:33:06.282603Z","end":"2026-08-22T10:33:06.383041Z","steps":["trace[310979008] 'process raft request' (duration: 100.121572ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:33:06.435118Z","caller":"traceutil/trace.go:171","msg":"trace[828892320] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"145.366702ms","start":"2026-08-22T10:33:06.289713Z","end":"2026-08-22T10:33:06.43508Z","steps":["trace[828892320] 'process raft request' (duration: 145.098637ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:33:06.687336Z","caller":"traceutil/trace.go:171","msg":"trace[870652370] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"121.887617ms","start":"2026-08-22T10:33:06.565379Z","end":"2026-08-22T10:33:06.687266Z","steps":["trace[870652370] 'process raft request' (duration: 106.452037ms)","trace[870652370] 'compare' (duration: 15.286287ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:33:06.736754Z","caller":"traceutil/trace.go:171","msg":"trace[657768240] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"140.241639ms","start":"2026-08-22T10:33:06.59649Z","end":"2026-08-22T10:33:06.736732Z","steps":["trace[657768240] 'process raft request' (duration: 140.130097ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:33:07.526188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.629151ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-22T10:33:07.526331Z","caller":"traceutil/trace.go:171","msg":"trace[1790990740] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"197.811452ms","start":"2026-08-22T10:33:07.328502Z","end":"2026-08-22T10:33:07.526313Z","steps":["trace[1790990740] 'process raft request' (duration: 80.568619ms)","trace[1790990740] 'compare' (duration: 116.464388ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:33:07.537114Z","caller":"traceutil/trace.go:171","msg":"trace[1672505418] linearizableReadLoop","detail":"{readStateIndex:666; appliedIndex:662; }","duration":"155.678638ms","start":"2026-08-22T10:33:07.381412Z","end":"2026-08-22T10:33:07.53709Z","steps":["trace[1672505418] 'read index received' (duration: 27.741813ms)","trace[1672505418] 'applied index is now lower than readState.Index' (duration: 127.935515ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:33:07.537131Z","caller":"traceutil/trace.go:171","msg":"trace[1429154573] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"205.541381ms","start":"2026-08-22T10:33:07.331563Z","end":"2026-08-22T10:33:07.537104Z","steps":["trace[1429154573] 'process raft request' (duration: 205.44076ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:33:07.537179Z","caller":"traceutil/trace.go:171","msg":"trace[34806370] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"207.044727ms","start":"2026-08-22T10:33:07.330112Z","end":"2026-08-22T10:33:07.537156Z","steps":["trace[34806370] 'process raft request' (duration: 206.776602ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:33:07.537316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.882243ms","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-22T10:33:07.537347Z","caller":"traceutil/trace.go:171","msg":"trace[1164607232] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:641; }","duration":"155.979544ms","start":"2026-08-22T10:33:07.38136Z","end":"2026-08-22T10:33:07.537339Z","steps":["trace[1164607232] 'agreement among raft nodes before linearized reading' (duration: 155.820332ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:33:07.537252Z","caller":"traceutil/trace.go:171","msg":"trace[1767662172] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"202.10571ms","start":"2026-08-22T10:33:07.335125Z","end":"2026-08-22T10:33:07.537231Z","steps":["trace[1767662172] 'process raft request' (duration: 201.910535ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:33:07.754684Z","caller":"traceutil/trace.go:171","msg":"trace[261201258] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"212.663781ms","start":"2026-08-22T10:33:07.541993Z","end":"2026-08-22T10:33:07.754656Z","steps":["trace[261201258] 'process raft request' (duration: 186.889524ms)","trace[261201258] 'compare' (duration: 25.597204ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:34:31.674138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.074456ms","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-22T10:34:31.674232Z","caller":"traceutil/trace.go:171","msg":"trace[2024648596] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1829; }","duration":"115.229848ms","start":"2026-08-22T10:34:31.558981Z","end":"2026-08-22T10:34:31.674211Z","steps":["trace[2024648596] 'range keys from in-memory index tree' (duration: 114.863092ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:34:43.862644Z","caller":"traceutil/trace.go:171","msg":"trace[2074523305] transaction","detail":"{read_only:false; response_revision:1939; number_of_response:1; }","duration":"122.525284ms","start":"2026-08-22T10:34:43.740094Z","end":"2026-08-22T10:34:43.862619Z","steps":["trace[2074523305] 'process raft request' (duration: 122.30484ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:35:16.766803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.183674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.169\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-22T10:35:16.766907Z","caller":"traceutil/trace.go:171","msg":"trace[564344790] range","detail":"{range_begin:/registry/masterleases/199.204.45.169; range_end:; response_count:1; response_revision:2128; }","duration":"166.341518ms","start":"2026-08-22T10:35:16.600542Z","end":"2026-08-22T10:35:16.766884Z","steps":["trace[564344790] 'range keys from in-memory index tree' (duration: 165.96739ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:36:39.671324Z","caller":"traceutil/trace.go:171","msg":"trace[314046914] transaction","detail":"{read_only:false; response_revision:2628; number_of_response:1; }","duration":"123.985118ms","start":"2026-08-22T10:36:39.547314Z","end":"2026-08-22T10:36:39.671299Z","steps":["trace[314046914] 'process raft request' (duration: 58.232514ms)","trace[314046914] 'compare' (duration: 65.628762ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:36:40.099115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.163415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"} {"level":"info","ts":"2026-08-22T10:36:40.09931Z","caller":"traceutil/trace.go:171","msg":"trace[719026240] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2630; }","duration":"109.43793ms","start":"2026-08-22T10:36:39.989842Z","end":"2026-08-22T10:36:40.09928Z","steps":["trace[719026240] 'range keys from in-memory index tree' (duration: 108.091133ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:36:40.240971Z","caller":"traceutil/trace.go:171","msg":"trace[2043507186] transaction","detail":"{read_only:false; response_revision:2631; number_of_response:1; }","duration":"136.978366ms","start":"2026-08-22T10:36:40.103958Z","end":"2026-08-22T10:36:40.240937Z","steps":["trace[2043507186] 'process raft request' (duration: 136.760432ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:40:39.428574Z","caller":"traceutil/trace.go:171","msg":"trace[390877802] linearizableReadLoop","detail":"{readStateIndex:4989; appliedIndex:4988; }","duration":"150.569831ms","start":"2026-08-22T10:40:39.27798Z","end":"2026-08-22T10:40:39.42855Z","steps":["trace[390877802] 'read index received' (duration: 150.405778ms)","trace[390877802] 'applied index is now lower than readState.Index' (duration: 162.833µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:40:39.428718Z","caller":"traceutil/trace.go:171","msg":"trace[674145842] transaction","detail":"{read_only:false; response_revision:4808; number_of_response:1; }","duration":"198.310891ms","start":"2026-08-22T10:40:39.230396Z","end":"2026-08-22T10:40:39.428707Z","steps":["trace[674145842] 'process raft request' (duration: 198.027206ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:40:39.428927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.784331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-tmjqg\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-08-22T10:40:39.429006Z","caller":"traceutil/trace.go:171","msg":"trace[1521491750] range","detail":"{range_begin:/registry/pods/openstack/keepalived-tmjqg; range_end:; response_count:1; response_revision:4808; }","duration":"116.889952ms","start":"2026-08-22T10:40:39.312099Z","end":"2026-08-22T10:40:39.428989Z","steps":["trace[1521491750] 'agreement among raft nodes before linearized reading' (duration: 116.628737ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:40:39.429059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.06535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:40:39.429127Z","caller":"traceutil/trace.go:171","msg":"trace[2138704943] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4808; }","duration":"151.165482ms","start":"2026-08-22T10:40:39.277947Z","end":"2026-08-22T10:40:39.429112Z","steps":["trace[2138704943] 'agreement among raft nodes before linearized reading' (duration: 151.05295ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:40:52.88549Z","caller":"traceutil/trace.go:171","msg":"trace[1172688910] linearizableReadLoop","detail":"{readStateIndex:5265; appliedIndex:5261; }","duration":"110.993577ms","start":"2026-08-22T10:40:52.774474Z","end":"2026-08-22T10:40:52.885467Z","steps":["trace[1172688910] 'read index received' (duration: 39.613013ms)","trace[1172688910] 'applied index is now lower than readState.Index' (duration: 71.377554ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:40:52.885678Z","caller":"traceutil/trace.go:171","msg":"trace[1422985637] transaction","detail":"{read_only:false; response_revision:5075; number_of_response:1; }","duration":"114.581227ms","start":"2026-08-22T10:40:52.771019Z","end":"2026-08-22T10:40:52.8856Z","steps":["trace[1422985637] 'process raft request' (duration: 89.413152ms)","trace[1422985637] 'compare' (duration: 24.93201ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:40:52.885787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.254372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-08-22T10:40:52.885861Z","caller":"traceutil/trace.go:171","msg":"trace[1248422085] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5075; }","duration":"111.409087ms","start":"2026-08-22T10:40:52.774436Z","end":"2026-08-22T10:40:52.885846Z","steps":["trace[1248422085] 'agreement among raft nodes before linearized reading' (duration: 111.12595ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:40:59.977759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.30958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-08-22T10:40:59.978056Z","caller":"traceutil/trace.go:171","msg":"trace[1675826504] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:5227; }","duration":"145.688987ms","start":"2026-08-22T10:40:59.832342Z","end":"2026-08-22T10:40:59.978031Z","steps":["trace[1675826504] 'range keys from in-memory index tree' (duration: 145.130247ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:41:00.295256Z","caller":"traceutil/trace.go:171","msg":"trace[1265682216] transaction","detail":"{read_only:false; response_revision:5229; number_of_response:1; }","duration":"245.160192ms","start":"2026-08-22T10:41:00.050071Z","end":"2026-08-22T10:41:00.295231Z","steps":["trace[1265682216] 'process raft request' (duration: 145.985092ms)","trace[1265682216] 'compare' (duration: 98.959286ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:41:00.295413Z","caller":"traceutil/trace.go:171","msg":"trace[1875124015] transaction","detail":"{read_only:false; response_revision:5230; number_of_response:1; }","duration":"112.973527ms","start":"2026-08-22T10:41:00.182416Z","end":"2026-08-22T10:41:00.295389Z","steps":["trace[1875124015] 'process raft request' (duration: 112.762022ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:41:00.29527Z","caller":"traceutil/trace.go:171","msg":"trace[1055219750] linearizableReadLoop","detail":"{readStateIndex:5423; appliedIndex:5422; }","duration":"175.006221ms","start":"2026-08-22T10:41:00.120236Z","end":"2026-08-22T10:41:00.295242Z","steps":["trace[1055219750] 'read index received' (duration: 75.834601ms)","trace[1055219750] 'applied index is now lower than readState.Index' (duration: 99.1689ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:41:00.295798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.568522ms","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-22T10:41:00.295894Z","caller":"traceutil/trace.go:171","msg":"trace[1759883709] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5230; }","duration":"175.714155ms","start":"2026-08-22T10:41:00.120161Z","end":"2026-08-22T10:41:00.295875Z","steps":["trace[1759883709] 'agreement among raft nodes before linearized reading' (duration: 175.161774ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:42:23.519743Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2858} {"level":"info","ts":"2026-08-22T10:42:23.581656Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2858,"took":"60.533455ms","hash":3479308847,"current-db-size-bytes":58789888,"current-db-size":"59 MB","current-db-size-in-use-bytes":33615872,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-22T10:42:23.581709Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3479308847,"revision":2858,"compact-revision":-1} {"level":"warn","ts":"2026-08-22T10:46:50.659498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.142043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-08-22T10:46:50.659573Z","caller":"traceutil/trace.go:171","msg":"trace[606052668] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:7981; }","duration":"259.253165ms","start":"2026-08-22T10:46:50.400305Z","end":"2026-08-22T10:46:50.659558Z","steps":["trace[606052668] 'range keys from in-memory index tree' (duration: 258.96482ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:46:50.660088Z","caller":"traceutil/trace.go:171","msg":"trace[817837845] transaction","detail":"{read_only:false; response_revision:7982; number_of_response:1; }","duration":"146.456352ms","start":"2026-08-22T10:46:50.51362Z","end":"2026-08-22T10:46:50.660076Z","steps":["trace[817837845] 'process raft request' (duration: 140.713433ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:47:02.118186Z","caller":"traceutil/trace.go:171","msg":"trace[92816894] transaction","detail":"{read_only:false; response_revision:8041; number_of_response:1; }","duration":"113.862013ms","start":"2026-08-22T10:47:02.004285Z","end":"2026-08-22T10:47:02.118147Z","steps":["trace[92816894] 'process raft request' (duration: 113.572398ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:47:03.243972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.199959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:47:03.244075Z","caller":"traceutil/trace.go:171","msg":"trace[452788037] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:8046; }","duration":"114.323852ms","start":"2026-08-22T10:47:03.12973Z","end":"2026-08-22T10:47:03.244053Z","steps":["trace[452788037] 'count revisions from in-memory index tree' (duration: 113.927065ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:47:04.302462Z","caller":"traceutil/trace.go:171","msg":"trace[966698620] linearizableReadLoop","detail":"{readStateIndex:8334; appliedIndex:8333; }","duration":"128.056722ms","start":"2026-08-22T10:47:04.174383Z","end":"2026-08-22T10:47:04.30244Z","steps":["trace[966698620] 'read index received' (duration: 127.94151ms)","trace[966698620] 'applied index is now lower than readState.Index' (duration: 114.342µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:47:04.302585Z","caller":"traceutil/trace.go:171","msg":"trace[761177808] transaction","detail":"{read_only:false; response_revision:8052; number_of_response:1; }","duration":"128.534442ms","start":"2026-08-22T10:47:04.174042Z","end":"2026-08-22T10:47:04.302576Z","steps":["trace[761177808] 'process raft request' (duration: 128.261647ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:47:04.302675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.984451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:47:04.302757Z","caller":"traceutil/trace.go:171","msg":"trace[1958031915] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:8052; }","duration":"128.071373ms","start":"2026-08-22T10:47:04.174667Z","end":"2026-08-22T10:47:04.302738Z","steps":["trace[1958031915] 'agreement among raft nodes before linearized reading' (duration: 127.960731ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:47:04.302758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.44135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:47:04.302816Z","caller":"traceutil/trace.go:171","msg":"trace[1208425362] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:8052; }","duration":"128.495031ms","start":"2026-08-22T10:47:04.174304Z","end":"2026-08-22T10:47:04.302799Z","steps":["trace[1208425362] 'agreement among raft nodes before linearized reading' (duration: 128.42142ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:47:04.303022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.309062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-22T10:47:04.303005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.222461ms","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:5"} {"level":"info","ts":"2026-08-22T10:47:04.303067Z","caller":"traceutil/trace.go:171","msg":"trace[689726157] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:8052; }","duration":"103.388064ms","start":"2026-08-22T10:47:04.199667Z","end":"2026-08-22T10:47:04.303055Z","steps":["trace[689726157] 'agreement among raft nodes before linearized reading' (duration: 103.325323ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:47:04.303092Z","caller":"traceutil/trace.go:171","msg":"trace[630032064] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:8052; }","duration":"103.358963ms","start":"2026-08-22T10:47:04.19971Z","end":"2026-08-22T10:47:04.303069Z","steps":["trace[630032064] 'agreement among raft nodes before linearized reading' (duration: 103.21355ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:47:04.302675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.337128ms","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:5"} {"level":"info","ts":"2026-08-22T10:47:04.303231Z","caller":"traceutil/trace.go:171","msg":"trace[128038700] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:8052; }","duration":"128.944359ms","start":"2026-08-22T10:47:04.174273Z","end":"2026-08-22T10:47:04.303217Z","steps":["trace[128038700] 'agreement among raft nodes before linearized reading' (duration: 128.320537ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:47:04.30354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.275782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:47:04.303601Z","caller":"traceutil/trace.go:171","msg":"trace[52001373] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:8052; }","duration":"103.358944ms","start":"2026-08-22T10:47:04.200226Z","end":"2026-08-22T10:47:04.303585Z","steps":["trace[52001373] 'agreement among raft nodes before linearized reading' (duration: 103.265752ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:47:23.529971Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5992} {"level":"info","ts":"2026-08-22T10:47:23.672837Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5992,"took":"140.931777ms","hash":176287216,"current-db-size-bytes":58789888,"current-db-size":"59 MB","current-db-size-in-use-bytes":22601728,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-08-22T10:47:23.672906Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":176287216,"revision":5992,"compact-revision":2858} {"level":"warn","ts":"2026-08-22T10:47:30.67153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.134671ms","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-22T10:47:30.671653Z","caller":"traceutil/trace.go:171","msg":"trace[1362888729] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8249; }","duration":"221.296624ms","start":"2026-08-22T10:47:30.45033Z","end":"2026-08-22T10:47:30.671626Z","steps":["trace[1362888729] 'range keys from in-memory index tree' (duration: 220.934797ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:47:30.671962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.333594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-22T10:47:30.672058Z","caller":"traceutil/trace.go:171","msg":"trace[1598262410] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8249; }","duration":"241.481986ms","start":"2026-08-22T10:47:30.430556Z","end":"2026-08-22T10:47:30.672038Z","steps":["trace[1598262410] 'range keys from in-memory index tree' (duration: 241.12839ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:47:43.018264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.963177ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T10:47:43.018354Z","caller":"traceutil/trace.go:171","msg":"trace[707080270] transaction","detail":"{read_only:false; response_revision:8353; number_of_response:1; }","duration":"248.855639ms","start":"2026-08-22T10:47:42.769483Z","end":"2026-08-22T10:47:43.018339Z","steps":["trace[707080270] 'process raft request' (duration: 116.638537ms)","trace[707080270] 'compare' (duration: 131.839475ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:47:43.16482Z","caller":"traceutil/trace.go:171","msg":"trace[617330979] linearizableReadLoop","detail":"{readStateIndex:8650; appliedIndex:8649; }","duration":"143.399275ms","start":"2026-08-22T10:47:43.021387Z","end":"2026-08-22T10:47:43.164786Z","steps":["trace[617330979] 'read index received' (duration: 131.53496ms)","trace[617330979] 'applied index is now lower than readState.Index' (duration: 11.862765ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:47:43.164851Z","caller":"traceutil/trace.go:171","msg":"trace[514261170] transaction","detail":"{read_only:false; response_revision:8354; number_of_response:1; }","duration":"143.568617ms","start":"2026-08-22T10:47:43.021248Z","end":"2026-08-22T10:47:43.164817Z","steps":["trace[514261170] 'process raft request' (duration: 131.661891ms)","trace[514261170] 'compare' (duration: 11.659682ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:47:43.165094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.684021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-zjbrs\" ","response":"range_response_count:1 size:4648"} {"level":"info","ts":"2026-08-22T10:47:43.165165Z","caller":"traceutil/trace.go:171","msg":"trace[1994685993] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-zjbrs; range_end:; response_count:1; response_revision:8354; }","duration":"143.811143ms","start":"2026-08-22T10:47:43.021334Z","end":"2026-08-22T10:47:43.165145Z","steps":["trace[1994685993] 'agreement among raft nodes before linearized reading' (duration: 143.580469ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:47:43.833366Z","caller":"traceutil/trace.go:171","msg":"trace[715454075] transaction","detail":"{read_only:false; response_revision:8361; number_of_response:1; }","duration":"197.687525ms","start":"2026-08-22T10:47:43.635653Z","end":"2026-08-22T10:47:43.83334Z","steps":["trace[715454075] 'process raft request' (duration: 197.44122ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:47:43.987075Z","caller":"traceutil/trace.go:171","msg":"trace[301759734] transaction","detail":"{read_only:false; response_revision:8363; number_of_response:1; }","duration":"112.393805ms","start":"2026-08-22T10:47:43.874651Z","end":"2026-08-22T10:47:43.987045Z","steps":["trace[301759734] 'process raft request' (duration: 112.243863ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:48:48.242447Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002455.wal"} {"level":"warn","ts":"2026-08-22T10:48:50.849501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.527867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:48:50.849592Z","caller":"traceutil/trace.go:171","msg":"trace[176107712] range","detail":"{range_begin:/registry/monitoring.coreos.com/scrapeconfigs/; range_end:/registry/monitoring.coreos.com/scrapeconfigs0; response_count:0; response_revision:8998; }","duration":"103.678409ms","start":"2026-08-22T10:48:50.745894Z","end":"2026-08-22T10:48:50.849572Z","steps":["trace[176107712] 'count revisions from in-memory index tree' (duration: 103.359854ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:50:12.512421Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"1c917701ce165bac","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-22T10:50:12.51824Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-22T10:50:12.518348Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-22T10:52:23.540968Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8162} {"level":"info","ts":"2026-08-22T10:52:23.616996Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8162,"took":"73.637249ms","hash":175969219,"current-db-size-bytes":58789888,"current-db-size":"59 MB","current-db-size-in-use-bytes":30928896,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-22T10:52:23.617129Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":175969219,"revision":8162,"compact-revision":5992} {"level":"info","ts":"2026-08-22T10:52:28.272445Z","caller":"traceutil/trace.go:171","msg":"trace[704986135] transaction","detail":"{read_only:false; response_revision:10911; number_of_response:1; }","duration":"118.800837ms","start":"2026-08-22T10:52:28.153599Z","end":"2026-08-22T10:52:28.2724Z","steps":["trace[704986135] 'process raft request' (duration: 118.131884ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:57:23.547092Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10890} {"level":"info","ts":"2026-08-22T10:57:23.626577Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10890,"took":"77.426649ms","hash":252544959,"current-db-size-bytes":58789888,"current-db-size":"59 MB","current-db-size-in-use-bytes":27668480,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-22T10:57:23.626653Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":252544959,"revision":10890,"compact-revision":8162} {"level":"info","ts":"2026-08-22T10:58:48.481944Z","caller":"traceutil/trace.go:171","msg":"trace[817731214] transaction","detail":"{read_only:false; response_revision:13466; number_of_response:1; }","duration":"181.523146ms","start":"2026-08-22T10:58:48.300402Z","end":"2026-08-22T10:58:48.481925Z","steps":["trace[817731214] 'process raft request' (duration: 181.493395ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:58:48.481972Z","caller":"traceutil/trace.go:171","msg":"trace[818647431] transaction","detail":"{read_only:false; response_revision:13465; number_of_response:1; }","duration":"257.995417ms","start":"2026-08-22T10:58:48.22394Z","end":"2026-08-22T10:58:48.481936Z","steps":["trace[818647431] 'process raft request' (duration: 225.559972ms)","trace[818647431] 'compare' (duration: 32.15006ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:59:02.643403Z","caller":"traceutil/trace.go:171","msg":"trace[1936013466] transaction","detail":"{read_only:false; response_revision:13551; number_of_response:1; }","duration":"287.054108ms","start":"2026-08-22T10:59:02.356328Z","end":"2026-08-22T10:59:02.643382Z","steps":["trace[1936013466] 'process raft request' (duration: 286.901045ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:59:02.647227Z","caller":"traceutil/trace.go:171","msg":"trace[2053912689] linearizableReadLoop","detail":"{readStateIndex:14015; appliedIndex:14013; }","duration":"104.246419ms","start":"2026-08-22T10:59:02.542955Z","end":"2026-08-22T10:59:02.647202Z","steps":["trace[2053912689] 'read index received' (duration: 100.351734ms)","trace[2053912689] 'applied index is now lower than readState.Index' (duration: 3.893585ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:59:02.647908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.927421ms","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-22T10:59:02.64822Z","caller":"traceutil/trace.go:171","msg":"trace[1783580204] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:13552; }","duration":"105.289688ms","start":"2026-08-22T10:59:02.542914Z","end":"2026-08-22T10:59:02.648203Z","steps":["trace[1783580204] 'agreement among raft nodes before linearized reading' (duration: 104.636626ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:59:02.648138Z","caller":"traceutil/trace.go:171","msg":"trace[1207187207] transaction","detail":"{read_only:false; response_revision:13552; number_of_response:1; }","duration":"123.595757ms","start":"2026-08-22T10:59:02.524519Z","end":"2026-08-22T10:59:02.648115Z","steps":["trace[1207187207] 'process raft request' (duration: 122.553757ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:00:49.301935Z","caller":"traceutil/trace.go:171","msg":"trace[771148860] transaction","detail":"{read_only:false; response_revision:14658; number_of_response:1; }","duration":"124.934662ms","start":"2026-08-22T11:00:49.176968Z","end":"2026-08-22T11:00:49.301903Z","steps":["trace[771148860] 'process raft request' (duration: 91.426455ms)","trace[771148860] 'compare' (duration: 33.180921ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:00:49.302003Z","caller":"traceutil/trace.go:171","msg":"trace[333011691] linearizableReadLoop","detail":"{readStateIndex:15156; appliedIndex:15155; }","duration":"103.542186ms","start":"2026-08-22T11:00:49.198442Z","end":"2026-08-22T11:00:49.301984Z","steps":["trace[333011691] 'read index received' (duration: 69.969888ms)","trace[333011691] 'applied index is now lower than readState.Index' (duration: 33.570408ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:00:49.302181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.719489ms","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-22T11:00:49.302582Z","caller":"traceutil/trace.go:171","msg":"trace[1948893539] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14659; }","duration":"104.136978ms","start":"2026-08-22T11:00:49.198416Z","end":"2026-08-22T11:00:49.302553Z","steps":["trace[1948893539] 'agreement among raft nodes before linearized reading' (duration: 103.591828ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:00:49.302708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.984186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-22T11:00:49.302773Z","caller":"traceutil/trace.go:171","msg":"trace[765506737] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14659; }","duration":"102.082598ms","start":"2026-08-22T11:00:49.200667Z","end":"2026-08-22T11:00:49.302749Z","steps":["trace[765506737] 'agreement among raft nodes before linearized reading' (duration: 101.822924ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:02:23.568125Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12918} {"level":"info","ts":"2026-08-22T11:02:23.640794Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12918,"took":"70.766423ms","hash":2118992014,"current-db-size-bytes":58789888,"current-db-size":"59 MB","current-db-size-in-use-bytes":30240768,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-22T11:02:23.640862Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2118992014,"revision":12918,"compact-revision":10890} {"level":"info","ts":"2026-08-22T11:06:06.334154Z","caller":"traceutil/trace.go:171","msg":"trace[18635435] transaction","detail":"{read_only:false; response_revision:17115; number_of_response:1; }","duration":"241.744619ms","start":"2026-08-22T11:06:06.092386Z","end":"2026-08-22T11:06:06.33413Z","steps":["trace[18635435] 'process raft request' (duration: 241.602187ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:06:06.551742Z","caller":"traceutil/trace.go:171","msg":"trace[1241533106] transaction","detail":"{read_only:false; response_revision:17117; number_of_response:1; }","duration":"157.158704ms","start":"2026-08-22T11:06:06.394558Z","end":"2026-08-22T11:06:06.551716Z","steps":["trace[1241533106] 'process raft request' (duration: 157.114254ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:06:06.552015Z","caller":"traceutil/trace.go:171","msg":"trace[272276277] linearizableReadLoop","detail":"{readStateIndex:17693; appliedIndex:17691; }","duration":"321.038766ms","start":"2026-08-22T11:06:06.230963Z","end":"2026-08-22T11:06:06.552001Z","steps":["trace[272276277] 'read index received' (duration: 103.027537ms)","trace[272276277] 'applied index is now lower than readState.Index' (duration: 218.009579ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:06:06.55204Z","caller":"traceutil/trace.go:171","msg":"trace[881768386] transaction","detail":"{read_only:false; response_revision:17116; number_of_response:1; }","duration":"350.789831ms","start":"2026-08-22T11:06:06.201203Z","end":"2026-08-22T11:06:06.551993Z","steps":["trace[881768386] 'process raft request' (duration: 285.258366ms)","trace[881768386] 'compare' (duration: 65.093526ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:06:06.552164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.181578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-4p9gn\" ","response":"range_response_count:1 size:20762"} {"level":"info","ts":"2026-08-22T11:06:06.552196Z","caller":"traceutil/trace.go:171","msg":"trace[560120809] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-4p9gn; range_end:; response_count:1; response_revision:17117; }","duration":"321.253369ms","start":"2026-08-22T11:06:06.230934Z","end":"2026-08-22T11:06:06.552187Z","steps":["trace[560120809] 'agreement among raft nodes before linearized reading' (duration: 321.113167ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:06:06.552236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:06:06.230921Z","time spent":"321.29674ms","remote":"127.0.0.1:50496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":20785,"request content":"key:\"/registry/pods/openstack/libvirt-libvirt-default-4p9gn\" "} {"level":"warn","ts":"2026-08-22T11:06:06.552201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:06:06.201177Z","time spent":"350.941853ms","remote":"127.0.0.1:50596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T11:07:23.581558Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15271} {"level":"info","ts":"2026-08-22T11:07:23.683945Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15271,"took":"98.128862ms","hash":523884433,"current-db-size-bytes":58789888,"current-db-size":"59 MB","current-db-size-in-use-bytes":33443840,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-08-22T11:07:23.68403Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":523884433,"revision":15271,"compact-revision":12918} {"level":"info","ts":"2026-08-22T11:10:00.922093Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"1c917701ce165bac","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-22T11:10:00.925875Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-22T11:10:00.925992Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-22T11:12:23.587031Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17948} {"level":"info","ts":"2026-08-22T11:12:23.697755Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17948,"took":"107.826056ms","hash":1132840203,"current-db-size-bytes":58789888,"current-db-size":"59 MB","current-db-size-in-use-bytes":32165888,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-22T11:12:23.697818Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1132840203,"revision":17948,"compact-revision":15271} {"level":"info","ts":"2026-08-22T11:13:07.699115Z","caller":"traceutil/trace.go:171","msg":"trace[52873180] transaction","detail":"{read_only:false; response_revision:20703; number_of_response:1; }","duration":"119.550509ms","start":"2026-08-22T11:13:07.579539Z","end":"2026-08-22T11:13:07.69909Z","steps":["trace[52873180] 'process raft request' (duration: 119.039549ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:13:18.229988Z","caller":"traceutil/trace.go:171","msg":"trace[206051600] transaction","detail":"{read_only:false; response_revision:20757; number_of_response:1; }","duration":"145.854449ms","start":"2026-08-22T11:13:18.084109Z","end":"2026-08-22T11:13:18.229964Z","steps":["trace[206051600] 'process raft request' (duration: 145.663275ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:13:18.273399Z","caller":"traceutil/trace.go:171","msg":"trace[1680228654] transaction","detail":"{read_only:false; response_revision:20758; number_of_response:1; }","duration":"188.08655ms","start":"2026-08-22T11:13:18.085284Z","end":"2026-08-22T11:13:18.273371Z","steps":["trace[1680228654] 'process raft request' (duration: 187.638592ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:13:23.640297Z","caller":"traceutil/trace.go:171","msg":"trace[845086725] transaction","detail":"{read_only:false; response_revision:20782; number_of_response:1; }","duration":"105.36092ms","start":"2026-08-22T11:13:23.534911Z","end":"2026-08-22T11:13:23.640272Z","steps":["trace[845086725] 'process raft request' (duration: 105.215267ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:13:23.665141Z","caller":"traceutil/trace.go:171","msg":"trace[547820757] transaction","detail":"{read_only:false; response_revision:20783; number_of_response:1; }","duration":"120.857205ms","start":"2026-08-22T11:13:23.544254Z","end":"2026-08-22T11:13:23.665111Z","steps":["trace[547820757] 'process raft request' (duration: 120.686242ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:13:23.929395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.922906ms","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-22T11:13:23.929524Z","caller":"traceutil/trace.go:171","msg":"trace[241363192] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20783; }","duration":"160.066209ms","start":"2026-08-22T11:13:23.769412Z","end":"2026-08-22T11:13:23.929478Z","steps":["trace[241363192] 'range keys from in-memory index tree' (duration: 159.726952ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:13:23.929415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.218816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-22T11:13:23.929821Z","caller":"traceutil/trace.go:171","msg":"trace[2092765547] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:20783; }","duration":"195.601763ms","start":"2026-08-22T11:13:23.734091Z","end":"2026-08-22T11:13:23.929693Z","steps":["trace[2092765547] 'count revisions from in-memory index tree' (duration: 195.110124ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:13:24.005656Z","caller":"traceutil/trace.go:171","msg":"trace[1640329030] transaction","detail":"{read_only:false; response_revision:20784; number_of_response:1; }","duration":"107.48842ms","start":"2026-08-22T11:13:23.898132Z","end":"2026-08-22T11:13:24.00562Z","steps":["trace[1640329030] 'process raft request' (duration: 107.358267ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:13:24.171088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.302351ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T11:13:24.171178Z","caller":"traceutil/trace.go:171","msg":"trace[1623083175] linearizableReadLoop","detail":"{readStateIndex:21476; appliedIndex:21474; }","duration":"198.129831ms","start":"2026-08-22T11:13:23.973033Z","end":"2026-08-22T11:13:24.171163Z","steps":["trace[1623083175] 'read index received' (duration: 32.535188ms)","trace[1623083175] 'applied index is now lower than readState.Index' (duration: 165.593493ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:13:24.171243Z","caller":"traceutil/trace.go:171","msg":"trace[1233131706] transaction","detail":"{read_only:false; response_revision:20785; number_of_response:1; }","duration":"237.885736ms","start":"2026-08-22T11:13:23.933324Z","end":"2026-08-22T11:13:24.17121Z","steps":["trace[1233131706] 'process raft request' (duration: 134.386761ms)","trace[1233131706] 'compare' (duration: 103.035856ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:13:24.171342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.315104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:573"} {"level":"info","ts":"2026-08-22T11:13:24.171376Z","caller":"traceutil/trace.go:171","msg":"trace[1048466363] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:20785; }","duration":"198.383406ms","start":"2026-08-22T11:13:23.97298Z","end":"2026-08-22T11:13:24.171364Z","steps":["trace[1048466363] 'agreement among raft nodes before linearized reading' (duration: 198.262964ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:13:24.171369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.876285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:18218"} {"level":"info","ts":"2026-08-22T11:13:24.171433Z","caller":"traceutil/trace.go:171","msg":"trace[1712436034] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:20785; }","duration":"159.981257ms","start":"2026-08-22T11:13:24.011434Z","end":"2026-08-22T11:13:24.171415Z","steps":["trace[1712436034] 'agreement among raft nodes before linearized reading' (duration: 159.834484ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:13:24.338448Z","caller":"traceutil/trace.go:171","msg":"trace[1411575307] transaction","detail":"{read_only:false; response_revision:20786; number_of_response:1; }","duration":"162.180639ms","start":"2026-08-22T11:13:24.17625Z","end":"2026-08-22T11:13:24.338431Z","steps":["trace[1411575307] 'process raft request' (duration: 162.086467ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:13:24.368821Z","caller":"traceutil/trace.go:171","msg":"trace[1500491035] transaction","detail":"{read_only:false; response_revision:20787; number_of_response:1; }","duration":"120.725621ms","start":"2026-08-22T11:13:24.248073Z","end":"2026-08-22T11:13:24.368799Z","steps":["trace[1500491035] 'process raft request' (duration: 120.393625ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:13:24.626085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.316903ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T11:13:24.62644Z","caller":"traceutil/trace.go:171","msg":"trace[1493038398] transaction","detail":"{read_only:false; response_revision:20789; number_of_response:1; }","duration":"216.654663ms","start":"2026-08-22T11:13:24.409768Z","end":"2026-08-22T11:13:24.626422Z","steps":["trace[1493038398] 'process raft request' (duration: 104.935813ms)","trace[1493038398] 'compare' (duration: 111.1664ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:16:50.549261Z","caller":"traceutil/trace.go:171","msg":"trace[954137770] transaction","detail":"{read_only:false; response_revision:22772; number_of_response:1; }","duration":"112.945374ms","start":"2026-08-22T11:16:50.436292Z","end":"2026-08-22T11:16:50.549237Z","steps":["trace[954137770] 'process raft request' (duration: 112.560017ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:16:50.553607Z","caller":"traceutil/trace.go:171","msg":"trace[770219145] transaction","detail":"{read_only:false; response_revision:22773; number_of_response:1; }","duration":"116.779856ms","start":"2026-08-22T11:16:50.436793Z","end":"2026-08-22T11:16:50.553573Z","steps":["trace[770219145] 'process raft request' (duration: 116.549271ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:16:50.553815Z","caller":"traceutil/trace.go:171","msg":"trace[49962126] transaction","detail":"{read_only:false; response_revision:22774; number_of_response:1; }","duration":"108.00357ms","start":"2026-08-22T11:16:50.445786Z","end":"2026-08-22T11:16:50.55379Z","steps":["trace[49962126] 'process raft request' (duration: 107.668484ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:16:50.554169Z","caller":"traceutil/trace.go:171","msg":"trace[1459295119] transaction","detail":"{read_only:false; number_of_response:1; response_revision:22774; }","duration":"107.4659ms","start":"2026-08-22T11:16:50.446683Z","end":"2026-08-22T11:16:50.554148Z","steps":["trace[1459295119] 'process raft request' (duration: 107.075462ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:16:50.554142Z","caller":"traceutil/trace.go:171","msg":"trace[392994277] linearizableReadLoop","detail":"{readStateIndex:23530; appliedIndex:23528; }","duration":"114.762298ms","start":"2026-08-22T11:16:50.439365Z","end":"2026-08-22T11:16:50.554127Z","steps":["trace[392994277] 'read index received' (duration: 109.458487ms)","trace[392994277] 'applied index is now lower than readState.Index' (duration: 5.302141ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:16:50.554533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.126086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1125"} {"level":"info","ts":"2026-08-22T11:16:50.55463Z","caller":"traceutil/trace.go:171","msg":"trace[1228630494] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:22774; }","duration":"115.262608ms","start":"2026-08-22T11:16:50.439346Z","end":"2026-08-22T11:16:50.554609Z","steps":["trace[1228630494] 'agreement among raft nodes before linearized reading' (duration: 114.896221ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:16:50.564057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.697173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:4727"} {"level":"info","ts":"2026-08-22T11:16:50.564109Z","caller":"traceutil/trace.go:171","msg":"trace[135743705] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:22775; }","duration":"119.747954ms","start":"2026-08-22T11:16:50.444346Z","end":"2026-08-22T11:16:50.564093Z","steps":["trace[135743705] 'agreement among raft nodes before linearized reading' (duration: 119.607741ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:17:00.288607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.524361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-08-22T11:17:00.288706Z","caller":"traceutil/trace.go:171","msg":"trace[1925270662] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:23150; }","duration":"116.677615ms","start":"2026-08-22T11:17:00.172006Z","end":"2026-08-22T11:17:00.288684Z","steps":["trace[1925270662] 'count revisions from in-memory index tree' (duration: 116.370939ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:17:00.30213Z","caller":"traceutil/trace.go:171","msg":"trace[1347316944] linearizableReadLoop","detail":"{readStateIndex:23917; appliedIndex:23916; }","duration":"101.533327ms","start":"2026-08-22T11:17:00.200572Z","end":"2026-08-22T11:17:00.302106Z","steps":["trace[1347316944] 'read index received' (duration: 101.348414ms)","trace[1347316944] 'applied index is now lower than readState.Index' (duration: 183.833µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:17:00.30225Z","caller":"traceutil/trace.go:171","msg":"trace[2112602941] transaction","detail":"{read_only:false; response_revision:23151; number_of_response:1; }","duration":"122.169158ms","start":"2026-08-22T11:17:00.179992Z","end":"2026-08-22T11:17:00.302161Z","steps":["trace[2112602941] 'process raft request' (duration: 121.953954ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:17:00.302555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.919524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-08-22T11:17:00.302593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.450566ms","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-22T11:17:00.302647Z","caller":"traceutil/trace.go:171","msg":"trace[1332315309] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23151; }","duration":"102.098648ms","start":"2026-08-22T11:17:00.200524Z","end":"2026-08-22T11:17:00.302623Z","steps":["trace[1332315309] 'agreement among raft nodes before linearized reading' (duration: 101.67341ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:17:00.302695Z","caller":"traceutil/trace.go:171","msg":"trace[1964932738] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:23151; }","duration":"100.531637ms","start":"2026-08-22T11:17:00.202102Z","end":"2026-08-22T11:17:00.302634Z","steps":["trace[1964932738] 'agreement among raft nodes before linearized reading' (duration: 100.349344ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:17:23.593043Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20451} {"level":"info","ts":"2026-08-22T11:17:23.70181Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20451,"took":"105.022223ms","hash":2278119270,"current-db-size-bytes":58789888,"current-db-size":"59 MB","current-db-size-in-use-bytes":43524096,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-08-22T11:17:23.701887Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2278119270,"revision":20451,"compact-revision":17948} {"level":"warn","ts":"2026-08-22T11:20:18.40459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.059278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:20:18.404695Z","caller":"traceutil/trace.go:171","msg":"trace[1294298289] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:25242; }","duration":"123.228242ms","start":"2026-08-22T11:20:18.281446Z","end":"2026-08-22T11:20:18.404674Z","steps":["trace[1294298289] 'count revisions from in-memory index tree' (duration: 122.953457ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:20:18.640413Z","caller":"traceutil/trace.go:171","msg":"trace[1949209661] transaction","detail":"{read_only:false; response_revision:25243; number_of_response:1; }","duration":"119.879349ms","start":"2026-08-22T11:20:18.520512Z","end":"2026-08-22T11:20:18.640391Z","steps":["trace[1949209661] 'process raft request' (duration: 119.737386ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:20:18.909052Z","caller":"traceutil/trace.go:171","msg":"trace[321281346] transaction","detail":"{read_only:false; response_revision:25244; number_of_response:1; }","duration":"253.062628ms","start":"2026-08-22T11:20:18.655963Z","end":"2026-08-22T11:20:18.909025Z","steps":["trace[321281346] 'process raft request' (duration: 245.523875ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:20:18.928438Z","caller":"traceutil/trace.go:171","msg":"trace[1051542701] transaction","detail":"{read_only:false; response_revision:25245; number_of_response:1; }","duration":"193.263043ms","start":"2026-08-22T11:20:18.735149Z","end":"2026-08-22T11:20:18.928412Z","steps":["trace[1051542701] 'process raft request' (duration: 193.12907ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:20:23.482366Z","caller":"traceutil/trace.go:171","msg":"trace[703968944] transaction","detail":"{read_only:false; response_revision:25277; number_of_response:1; }","duration":"180.791055ms","start":"2026-08-22T11:20:23.301545Z","end":"2026-08-22T11:20:23.482336Z","steps":["trace[703968944] 'process raft request' (duration: 155.865031ms)","trace[703968944] 'compare' (duration: 24.747291ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:20:23.569011Z","caller":"traceutil/trace.go:171","msg":"trace[823942207] linearizableReadLoop","detail":"{readStateIndex:26099; appliedIndex:26097; }","duration":"116.518803ms","start":"2026-08-22T11:20:23.452462Z","end":"2026-08-22T11:20:23.56898Z","steps":["trace[823942207] 'read index received' (duration: 4.918943ms)","trace[823942207] 'applied index is now lower than readState.Index' (duration: 111.59885ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:20:23.569195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.711687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:20:23.56923Z","caller":"traceutil/trace.go:171","msg":"trace[12088409] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:25278; }","duration":"116.791029ms","start":"2026-08-22T11:20:23.452428Z","end":"2026-08-22T11:20:23.569219Z","steps":["trace[12088409] 'agreement among raft nodes before linearized reading' (duration: 116.683057ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:20:23.569526Z","caller":"traceutil/trace.go:171","msg":"trace[113470330] transaction","detail":"{read_only:false; response_revision:25278; number_of_response:1; }","duration":"266.83478ms","start":"2026-08-22T11:20:23.302613Z","end":"2026-08-22T11:20:23.569447Z","steps":["trace[113470330] 'process raft request' (duration: 266.238359ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:20:23.941775Z","caller":"traceutil/trace.go:171","msg":"trace[1209182312] transaction","detail":"{read_only:false; response_revision:25279; number_of_response:1; }","duration":"137.412081ms","start":"2026-08-22T11:20:23.804325Z","end":"2026-08-22T11:20:23.941737Z","steps":["trace[1209182312] 'process raft request' (duration: 137.147807ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:20:24.107984Z","caller":"traceutil/trace.go:171","msg":"trace[929547847] transaction","detail":"{read_only:false; response_revision:25280; number_of_response:1; }","duration":"299.031662ms","start":"2026-08-22T11:20:23.8089Z","end":"2026-08-22T11:20:24.107931Z","steps":["trace[929547847] 'process raft request' (duration: 288.491842ms)","trace[929547847] 'compare' (duration: 10.080902ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:20:24.108324Z","caller":"traceutil/trace.go:171","msg":"trace[89198773] transaction","detail":"{read_only:false; response_revision:25281; number_of_response:1; }","duration":"294.963325ms","start":"2026-08-22T11:20:23.813325Z","end":"2026-08-22T11:20:24.108289Z","steps":["trace[89198773] 'process raft request' (duration: 294.505186ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:20:24.108384Z","caller":"traceutil/trace.go:171","msg":"trace[508436103] transaction","detail":"{read_only:false; response_revision:25282; number_of_response:1; }","duration":"292.764243ms","start":"2026-08-22T11:20:23.815598Z","end":"2026-08-22T11:20:24.108362Z","steps":["trace[508436103] 'process raft request' (duration: 292.59898ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:20:24.108511Z","caller":"traceutil/trace.go:171","msg":"trace[1851414275] linearizableReadLoop","detail":"{readStateIndex:26103; appliedIndex:26099; }","duration":"252.062079ms","start":"2026-08-22T11:20:23.856418Z","end":"2026-08-22T11:20:24.10848Z","steps":["trace[1851414275] 'read index received' (duration: 84.995864ms)","trace[1851414275] 'applied index is now lower than readState.Index' (duration: 167.064915ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:20:24.108543Z","caller":"traceutil/trace.go:171","msg":"trace[632583028] transaction","detail":"{read_only:false; response_revision:25283; number_of_response:1; }","duration":"219.604412ms","start":"2026-08-22T11:20:23.888923Z","end":"2026-08-22T11:20:24.108527Z","steps":["trace[632583028] 'process raft request' (duration: 219.380608ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:20:24.10865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.208372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-08-22T11:20:24.108681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.452724ms","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-22T11:20:24.108708Z","caller":"traceutil/trace.go:171","msg":"trace[1824957126] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:25283; }","duration":"252.311144ms","start":"2026-08-22T11:20:23.856379Z","end":"2026-08-22T11:20:24.10869Z","steps":["trace[1824957126] 'agreement among raft nodes before linearized reading' (duration: 252.190072ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:20:24.108723Z","caller":"traceutil/trace.go:171","msg":"trace[1929880346] 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:25283; }","duration":"175.557157ms","start":"2026-08-22T11:20:23.933152Z","end":"2026-08-22T11:20:24.108709Z","steps":["trace[1929880346] 'agreement among raft nodes before linearized reading' (duration: 175.460125ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:20:24.10888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.205263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-22T11:20:24.109022Z","caller":"traceutil/trace.go:171","msg":"trace[2098993491] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:25283; }","duration":"141.403926ms","start":"2026-08-22T11:20:23.967589Z","end":"2026-08-22T11:20:24.108993Z","steps":["trace[2098993491] 'agreement among raft nodes before linearized reading' (duration: 141.125861ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:20:24.309031Z","caller":"traceutil/trace.go:171","msg":"trace[1097516397] transaction","detail":"{read_only:false; response_revision:25284; number_of_response:1; }","duration":"101.06743ms","start":"2026-08-22T11:20:24.207917Z","end":"2026-08-22T11:20:24.308984Z","steps":["trace[1097516397] 'process raft request' (duration: 95.109517ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:20:25.026354Z","caller":"traceutil/trace.go:171","msg":"trace[1783092606] linearizableReadLoop","detail":"{readStateIndex:26107; appliedIndex:26106; }","duration":"125.139199ms","start":"2026-08-22T11:20:24.901192Z","end":"2026-08-22T11:20:25.026332Z","steps":["trace[1783092606] 'read index received' (duration: 124.955144ms)","trace[1783092606] 'applied index is now lower than readState.Index' (duration: 183.205µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:20:25.02668Z","caller":"traceutil/trace.go:171","msg":"trace[191572701] transaction","detail":"{read_only:false; response_revision:25286; number_of_response:1; }","duration":"357.365021ms","start":"2026-08-22T11:20:24.669276Z","end":"2026-08-22T11:20:25.026641Z","steps":["trace[191572701] 'process raft request' (duration: 356.925661ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:20:25.026751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.543406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-08-22T11:20:25.026799Z","caller":"traceutil/trace.go:171","msg":"trace[1919032008] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25286; }","duration":"125.642777ms","start":"2026-08-22T11:20:24.901144Z","end":"2026-08-22T11:20:25.026786Z","steps":["trace[1919032008] 'agreement among raft nodes before linearized reading' (duration: 125.457404ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:20:25.026879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:20:24.669253Z","time spent":"357.517984ms","remote":"127.0.0.1:50596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T11:20:25.041047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.525467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:119"} {"level":"info","ts":"2026-08-22T11:20:25.041102Z","caller":"traceutil/trace.go:171","msg":"trace[1047836346] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:25287; }","duration":"104.625339ms","start":"2026-08-22T11:20:24.936465Z","end":"2026-08-22T11:20:25.04109Z","steps":["trace[1047836346] 'agreement among raft nodes before linearized reading' (duration: 104.476356ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:20:25.041209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.835266ms","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-22T11:20:25.04128Z","caller":"traceutil/trace.go:171","msg":"trace[1567308665] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25287; }","duration":"111.964527ms","start":"2026-08-22T11:20:24.929297Z","end":"2026-08-22T11:20:25.041262Z","steps":["trace[1567308665] 'agreement among raft nodes before linearized reading' (duration: 111.733693ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:20:25.247666Z","caller":"traceutil/trace.go:171","msg":"trace[1962146733] linearizableReadLoop","detail":"{readStateIndex:26109; appliedIndex:26108; }","duration":"200.025072ms","start":"2026-08-22T11:20:25.047604Z","end":"2026-08-22T11:20:25.247629Z","steps":["trace[1962146733] 'read index received' (duration: 136.500584ms)","trace[1962146733] 'applied index is now lower than readState.Index' (duration: 63.522678ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:20:25.247854Z","caller":"traceutil/trace.go:171","msg":"trace[1423282215] transaction","detail":"{read_only:false; response_revision:25288; number_of_response:1; }","duration":"201.837085ms","start":"2026-08-22T11:20:25.045982Z","end":"2026-08-22T11:20:25.247819Z","steps":["trace[1423282215] 'process raft request' (duration: 138.203916ms)","trace[1423282215] 'compare' (duration: 62.972606ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:20:25.248065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.009726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-22T11:20:25.248097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.48105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:119"} {"level":"warn","ts":"2026-08-22T11:20:25.248111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.553053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:129893"} {"level":"info","ts":"2026-08-22T11:20:25.248154Z","caller":"traceutil/trace.go:171","msg":"trace[496128558] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:25288; }","duration":"200.601862ms","start":"2026-08-22T11:20:25.047535Z","end":"2026-08-22T11:20:25.248137Z","steps":["trace[496128558] 'agreement among raft nodes before linearized reading' (duration: 200.283206ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:20:25.248151Z","caller":"traceutil/trace.go:171","msg":"trace[804019767] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25288; }","duration":"154.144759ms","start":"2026-08-22T11:20:25.093983Z","end":"2026-08-22T11:20:25.248128Z","steps":["trace[804019767] 'agreement among raft nodes before linearized reading' (duration: 154.002817ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:20:25.248191Z","caller":"traceutil/trace.go:171","msg":"trace[371289633] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:25288; }","duration":"198.658845ms","start":"2026-08-22T11:20:25.049513Z","end":"2026-08-22T11:20:25.248172Z","steps":["trace[371289633] 'agreement among raft nodes before linearized reading' (duration: 198.428851ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:20:25.248235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.053315ms","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-22T11:20:25.248338Z","caller":"traceutil/trace.go:171","msg":"trace[1972362311] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25288; }","duration":"177.197277ms","start":"2026-08-22T11:20:25.071117Z","end":"2026-08-22T11:20:25.248314Z","steps":["trace[1972362311] 'agreement among raft nodes before linearized reading' (duration: 176.893131ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:20:25.548905Z","caller":"traceutil/trace.go:171","msg":"trace[863910735] linearizableReadLoop","detail":"{readStateIndex:26111; appliedIndex:26110; }","duration":"235.119718ms","start":"2026-08-22T11:20:25.31376Z","end":"2026-08-22T11:20:25.54888Z","steps":["trace[863910735] 'read index received' (duration: 234.105559ms)","trace[863910735] 'applied index is now lower than readState.Index' (duration: 1.013109ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:20:25.549054Z","caller":"traceutil/trace.go:171","msg":"trace[1924586933] transaction","detail":"{read_only:false; response_revision:25290; number_of_response:1; }","duration":"279.133384ms","start":"2026-08-22T11:20:25.269885Z","end":"2026-08-22T11:20:25.549018Z","steps":["trace[1924586933] 'process raft request' (duration: 278.036654ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:20:25.549163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.387952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-08-22T11:20:25.549207Z","caller":"traceutil/trace.go:171","msg":"trace[289699468] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25290; }","duration":"235.444693ms","start":"2026-08-22T11:20:25.313751Z","end":"2026-08-22T11:20:25.549196Z","steps":["trace[289699468] 'agreement among raft nodes before linearized reading' (duration: 235.26437ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:20:25.936588Z","caller":"traceutil/trace.go:171","msg":"trace[299800367] transaction","detail":"{read_only:false; response_revision:25292; number_of_response:1; }","duration":"332.506208ms","start":"2026-08-22T11:20:25.604055Z","end":"2026-08-22T11:20:25.936561Z","steps":["trace[299800367] 'process raft request' (duration: 332.314204ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:20:25.936753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:20:25.604039Z","time spent":"332.62703ms","remote":"127.0.0.1:50596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T11:20:26.13505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.747378ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T11:20:26.135158Z","caller":"traceutil/trace.go:171","msg":"trace[34436784] linearizableReadLoop","detail":"{readStateIndex:26114; appliedIndex:26112; }","duration":"332.444926ms","start":"2026-08-22T11:20:25.802696Z","end":"2026-08-22T11:20:26.135141Z","steps":["trace[34436784] 'read index received' (duration: 133.69829ms)","trace[34436784] 'applied index is now lower than readState.Index' (duration: 198.745176ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:20:26.135231Z","caller":"traceutil/trace.go:171","msg":"trace[1198786962] transaction","detail":"{read_only:false; response_revision:25293; number_of_response:1; }","duration":"521.346606ms","start":"2026-08-22T11:20:25.613873Z","end":"2026-08-22T11:20:26.135219Z","steps":["trace[1198786962] 'process raft request' (duration: 402.736222ms)","trace[1198786962] 'compare' (duration: 117.552954ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:20:26.135299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:20:25.61385Z","time spent":"521.414508ms","remote":"127.0.0.1:50596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T11:20:26.135496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.774182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:20:26.135599Z","caller":"traceutil/trace.go:171","msg":"trace[1870343241] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0; response_count:0; response_revision:25293; }","duration":"332.946816ms","start":"2026-08-22T11:20:25.802625Z","end":"2026-08-22T11:20:26.135572Z","steps":["trace[1870343241] 'agreement among raft nodes before linearized reading' (duration: 332.737762ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:20:26.135672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:20:25.802588Z","time spent":"333.069729ms","remote":"127.0.0.1:52276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":126,"response count":0,"response size":29,"request content":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true "} {"level":"info","ts":"2026-08-22T11:20:26.30323Z","caller":"traceutil/trace.go:171","msg":"trace[1176270570] transaction","detail":"{read_only:false; response_revision:25295; number_of_response:1; }","duration":"188.819228ms","start":"2026-08-22T11:20:26.114378Z","end":"2026-08-22T11:20:26.303197Z","steps":["trace[1176270570] 'process raft request' (duration: 188.491832ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:20:26.303567Z","caller":"traceutil/trace.go:171","msg":"trace[1293557518] transaction","detail":"{read_only:false; response_revision:25297; number_of_response:1; }","duration":"188.982841ms","start":"2026-08-22T11:20:26.114567Z","end":"2026-08-22T11:20:26.30355Z","steps":["trace[1293557518] 'process raft request' (duration: 188.608944ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:20:26.303233Z","caller":"traceutil/trace.go:171","msg":"trace[1320986733] transaction","detail":"{read_only:false; response_revision:25296; number_of_response:1; }","duration":"188.642404ms","start":"2026-08-22T11:20:26.114567Z","end":"2026-08-22T11:20:26.30321Z","steps":["trace[1320986733] 'process raft request' (duration: 188.558353ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:20:26.303379Z","caller":"traceutil/trace.go:171","msg":"trace[2019113373] linearizableReadLoop","detail":"{readStateIndex:26118; appliedIndex:26114; }","duration":"168.136765ms","start":"2026-08-22T11:20:26.135184Z","end":"2026-08-22T11:20:26.303321Z","steps":["trace[2019113373] 'read index received' (duration: 22.711742ms)","trace[2019113373] 'applied index is now lower than readState.Index' (duration: 145.423923ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:20:26.303804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.062335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"warn","ts":"2026-08-22T11:20:26.303479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.409334ms","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-22T11:20:26.303903Z","caller":"traceutil/trace.go:171","msg":"trace[935961018] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25297; }","duration":"357.238179ms","start":"2026-08-22T11:20:25.94664Z","end":"2026-08-22T11:20:26.303878Z","steps":["trace[935961018] 'agreement among raft nodes before linearized reading' (duration: 356.898701ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:20:26.303941Z","caller":"traceutil/trace.go:171","msg":"trace[903107921] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/users/; range_end:/registry/openstack.k-orc.cloud/users0; response_count:0; response_revision:25297; }","duration":"264.894124ms","start":"2026-08-22T11:20:26.039001Z","end":"2026-08-22T11:20:26.303895Z","steps":["trace[903107921] 'agreement among raft nodes before linearized reading' (duration: 264.420604ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:20:26.303993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:20:25.946621Z","time spent":"357.356981ms","remote":"127.0.0.1:50596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":582,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-22T11:20:26.303501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.737692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:20:26.304043Z","caller":"traceutil/trace.go:171","msg":"trace[1668714064] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:25297; }","duration":"433.338305ms","start":"2026-08-22T11:20:25.87069Z","end":"2026-08-22T11:20:26.304028Z","steps":["trace[1668714064] 'agreement among raft nodes before linearized reading' (duration: 432.715372ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:20:26.304097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:20:25.870671Z","time spent":"433.411216ms","remote":"127.0.0.1:56614","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":29,"request content":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true "} {"level":"info","ts":"2026-08-22T11:20:26.468897Z","caller":"traceutil/trace.go:171","msg":"trace[1061230841] transaction","detail":"{read_only:false; response_revision:25299; number_of_response:1; }","duration":"134.332092ms","start":"2026-08-22T11:20:26.334219Z","end":"2026-08-22T11:20:26.468551Z","steps":["trace[1061230841] 'process raft request' (duration: 120.698633ms)","trace[1061230841] 'compare' (duration: 13.437375ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:20:27.136924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.78392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:20:27.137253Z","caller":"traceutil/trace.go:171","msg":"trace[1316229183] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:25300; }","duration":"126.167828ms","start":"2026-08-22T11:20:27.01107Z","end":"2026-08-22T11:20:27.137237Z","steps":["trace[1316229183] 'count revisions from in-memory index tree' (duration: 125.672638ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:20:27.137155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.811571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:573"} {"level":"info","ts":"2026-08-22T11:20:27.13778Z","caller":"traceutil/trace.go:171","msg":"trace[1098745614] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:25300; }","duration":"106.458613ms","start":"2026-08-22T11:20:27.031296Z","end":"2026-08-22T11:20:27.137754Z","steps":["trace[1098745614] 'range keys from in-memory index tree' (duration: 105.657498ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:20:27.269191Z","caller":"traceutil/trace.go:171","msg":"trace[1707846157] transaction","detail":"{read_only:false; response_revision:25302; number_of_response:1; }","duration":"126.945241ms","start":"2026-08-22T11:20:27.142223Z","end":"2026-08-22T11:20:27.269168Z","steps":["trace[1707846157] 'process raft request' (duration: 124.889602ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:20:27.568895Z","caller":"traceutil/trace.go:171","msg":"trace[2068869833] linearizableReadLoop","detail":"{readStateIndex:26125; appliedIndex:26124; }","duration":"251.870996ms","start":"2026-08-22T11:20:27.316998Z","end":"2026-08-22T11:20:27.568869Z","steps":["trace[2068869833] 'read index received' (duration: 239.621644ms)","trace[2068869833] 'applied index is now lower than readState.Index' (duration: 12.247932ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:20:27.568929Z","caller":"traceutil/trace.go:171","msg":"trace[122312224] transaction","detail":"{read_only:false; response_revision:25303; number_of_response:1; }","duration":"296.487944ms","start":"2026-08-22T11:20:27.272406Z","end":"2026-08-22T11:20:27.568894Z","steps":["trace[122312224] 'process raft request' (duration: 284.204911ms)","trace[122312224] 'compare' (duration: 11.925626ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:20:27.56909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.09144ms","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-22T11:20:27.569144Z","caller":"traceutil/trace.go:171","msg":"trace[693480716] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25303; }","duration":"252.183302ms","start":"2026-08-22T11:20:27.31695Z","end":"2026-08-22T11:20:27.569134Z","steps":["trace[693480716] 'agreement among raft nodes before linearized reading' (duration: 252.013289ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:20:27.871303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.893746ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T11:20:27.871445Z","caller":"traceutil/trace.go:171","msg":"trace[164489891] linearizableReadLoop","detail":"{readStateIndex:26127; appliedIndex:26126; }","duration":"267.784558ms","start":"2026-08-22T11:20:27.60364Z","end":"2026-08-22T11:20:27.871425Z","steps":["trace[164489891] 'read index received' (duration: 115.649157ms)","trace[164489891] 'applied index is now lower than readState.Index' (duration: 152.13367ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:20:27.871635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.989781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-08-22T11:20:27.87167Z","caller":"traceutil/trace.go:171","msg":"trace[771615136] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25305; }","duration":"268.062513ms","start":"2026-08-22T11:20:27.603599Z","end":"2026-08-22T11:20:27.871662Z","steps":["trace[771615136] 'agreement among raft nodes before linearized reading' (duration: 267.88277ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:20:27.872013Z","caller":"traceutil/trace.go:171","msg":"trace[891747455] transaction","detail":"{read_only:false; response_revision:25305; number_of_response:1; }","duration":"298.571663ms","start":"2026-08-22T11:20:27.573427Z","end":"2026-08-22T11:20:27.871999Z","steps":["trace[891747455] 'process raft request' (duration: 145.888722ms)","trace[891747455] 'compare' (duration: 151.326356ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:20:28.350492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.276532ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T11:20:28.351028Z","caller":"traceutil/trace.go:171","msg":"trace[213497225] transaction","detail":"{read_only:false; response_revision:25307; number_of_response:1; }","duration":"406.056786ms","start":"2026-08-22T11:20:27.944941Z","end":"2026-08-22T11:20:28.350998Z","steps":["trace[213497225] 'process raft request' (duration: 131.174303ms)","trace[213497225] 'compare' (duration: 274.008847ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:20:28.351102Z","caller":"traceutil/trace.go:171","msg":"trace[672462966] linearizableReadLoop","detail":"{readStateIndex:26129; appliedIndex:26128; }","duration":"364.097829ms","start":"2026-08-22T11:20:27.98698Z","end":"2026-08-22T11:20:28.351078Z","steps":["trace[672462966] 'read index received' (duration: 88.999512ms)","trace[672462966] 'applied index is now lower than readState.Index' (duration: 275.096157ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:20:28.351183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:20:27.944928Z","time spent":"406.166048ms","remote":"127.0.0.1:50596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T11:20:28.351327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.329793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/services/\" range_end:\"/registry/openstack.k-orc.cloud/services0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:20:28.35146Z","caller":"traceutil/trace.go:171","msg":"trace[11769171] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/services/; range_end:/registry/openstack.k-orc.cloud/services0; response_count:0; response_revision:25307; }","duration":"364.488016ms","start":"2026-08-22T11:20:27.986945Z","end":"2026-08-22T11:20:28.351433Z","steps":["trace[11769171] 'agreement among raft nodes before linearized reading' (duration: 364.209101ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:20:28.351577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:20:27.986933Z","time spent":"364.62203ms","remote":"127.0.0.1:52342","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/services/\" range_end:\"/registry/openstack.k-orc.cloud/services0\" count_only:true "} {"level":"info","ts":"2026-08-22T11:20:28.352184Z","caller":"traceutil/trace.go:171","msg":"trace[911330689] transaction","detail":"{read_only:false; response_revision:25308; number_of_response:1; }","duration":"187.253958ms","start":"2026-08-22T11:20:28.164913Z","end":"2026-08-22T11:20:28.352167Z","steps":["trace[911330689] 'process raft request' (duration: 186.127746ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:20:28.363301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.982023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2083"} {"level":"info","ts":"2026-08-22T11:20:28.363498Z","caller":"traceutil/trace.go:171","msg":"trace[1160192143] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:25311; }","duration":"157.222577ms","start":"2026-08-22T11:20:28.206255Z","end":"2026-08-22T11:20:28.363478Z","steps":["trace[1160192143] 'agreement among raft nodes before linearized reading' (duration: 156.922961ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:20:37.782783Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-000000000000666f.wal"} {"level":"info","ts":"2026-08-22T11:20:39.308217Z","caller":"traceutil/trace.go:171","msg":"trace[959649760] transaction","detail":"{read_only:false; response_revision:25446; number_of_response:1; }","duration":"179.048023ms","start":"2026-08-22T11:20:39.129142Z","end":"2026-08-22T11:20:39.30819Z","steps":["trace[959649760] 'process raft request' (duration: 178.872359ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:20:39.331719Z","caller":"traceutil/trace.go:171","msg":"trace[883492077] linearizableReadLoop","detail":"{readStateIndex:26271; appliedIndex:26269; }","duration":"121.409417ms","start":"2026-08-22T11:20:39.210281Z","end":"2026-08-22T11:20:39.331691Z","steps":["trace[883492077] 'read index received' (duration: 97.734487ms)","trace[883492077] 'applied index is now lower than readState.Index' (duration: 23.67374ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:20:39.332037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.731553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1936"} {"level":"info","ts":"2026-08-22T11:20:39.332048Z","caller":"traceutil/trace.go:171","msg":"trace[463443599] transaction","detail":"{read_only:false; response_revision:25447; number_of_response:1; }","duration":"160.051391ms","start":"2026-08-22T11:20:39.171969Z","end":"2026-08-22T11:20:39.33202Z","steps":["trace[463443599] 'process raft request' (duration: 159.569072ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:20:39.332116Z","caller":"traceutil/trace.go:171","msg":"trace[1826348037] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:25447; }","duration":"121.826244ms","start":"2026-08-22T11:20:39.210271Z","end":"2026-08-22T11:20:39.332097Z","steps":["trace[1826348037] 'agreement among raft nodes before linearized reading' (duration: 121.59417ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:20:39.862316Z","caller":"traceutil/trace.go:171","msg":"trace[888314790] linearizableReadLoop","detail":"{readStateIndex:26273; appliedIndex:26272; }","duration":"244.571058ms","start":"2026-08-22T11:20:39.617718Z","end":"2026-08-22T11:20:39.862289Z","steps":["trace[888314790] 'read index received' (duration: 244.425464ms)","trace[888314790] 'applied index is now lower than readState.Index' (duration: 144.334µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:20:39.862423Z","caller":"traceutil/trace.go:171","msg":"trace[2114769173] transaction","detail":"{read_only:false; response_revision:25449; number_of_response:1; }","duration":"256.927382ms","start":"2026-08-22T11:20:39.605483Z","end":"2026-08-22T11:20:39.86241Z","steps":["trace[2114769173] 'process raft request' (duration: 256.610675ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:20:39.862606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.872673ms","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-22T11:20:39.862635Z","caller":"traceutil/trace.go:171","msg":"trace[302158869] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25449; }","duration":"244.947065ms","start":"2026-08-22T11:20:39.617681Z","end":"2026-08-22T11:20:39.862628Z","steps":["trace[302158869] 'agreement among raft nodes before linearized reading' (duration: 244.838823ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:20:40.156594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.306468ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T11:20:40.156741Z","caller":"traceutil/trace.go:171","msg":"trace[97459308] transaction","detail":"{read_only:false; response_revision:25451; number_of_response:1; }","duration":"290.4748ms","start":"2026-08-22T11:20:39.866252Z","end":"2026-08-22T11:20:40.156726Z","steps":["trace[97459308] 'process raft request' (duration: 290.423549ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:20:40.156719Z","caller":"traceutil/trace.go:171","msg":"trace[1113708647] transaction","detail":"{read_only:false; response_revision:25450; number_of_response:1; }","duration":"491.619262ms","start":"2026-08-22T11:20:39.665067Z","end":"2026-08-22T11:20:40.156687Z","steps":["trace[1113708647] 'process raft request' (duration: 212.142101ms)","trace[1113708647] 'compare' (duration: 279.182065ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:20:40.15675Z","caller":"traceutil/trace.go:171","msg":"trace[1290636278] linearizableReadLoop","detail":"{readStateIndex:26274; appliedIndex:26273; }","duration":"294.339903ms","start":"2026-08-22T11:20:39.862379Z","end":"2026-08-22T11:20:40.156719Z","steps":["trace[1290636278] 'read index received' (duration: 14.842822ms)","trace[1290636278] 'applied index is now lower than readState.Index' (duration: 279.494291ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:20:40.156886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:20:39.665047Z","time spent":"491.741615ms","remote":"127.0.0.1:50596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T11:20:40.156933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.114704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinesets/\" range_end:\"/registry/cluster.x-k8s.io/machinesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-22T11:20:40.156964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.513163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:20:40.15697Z","caller":"traceutil/trace.go:171","msg":"trace[288069080] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinesets/; range_end:/registry/cluster.x-k8s.io/machinesets0; response_count:0; response_revision:25451; }","duration":"340.200345ms","start":"2026-08-22T11:20:39.816759Z","end":"2026-08-22T11:20:40.156959Z","steps":["trace[288069080] 'agreement among raft nodes before linearized reading' (duration: 340.107163ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:20:40.156989Z","caller":"traceutil/trace.go:171","msg":"trace[654297033] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:25451; }","duration":"257.569504ms","start":"2026-08-22T11:20:39.899412Z","end":"2026-08-22T11:20:40.156981Z","steps":["trace[654297033] 'agreement among raft nodes before linearized reading' (duration: 257.522603ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:20:40.157006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:20:39.816741Z","time spent":"340.255857ms","remote":"127.0.0.1:52142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":29,"request content":"key:\"/registry/cluster.x-k8s.io/machinesets/\" range_end:\"/registry/cluster.x-k8s.io/machinesets0\" count_only:true "} {"level":"warn","ts":"2026-08-22T11:20:40.157093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.511547ms","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-22T11:20:40.157093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.587828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-41cd399c-3daf-49e6-8ef8-7d9cb512a45f\" ","response":"range_response_count:1 size:3761"} {"level":"warn","ts":"2026-08-22T11:20:40.157114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.693896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-08-22T11:20:40.157162Z","caller":"traceutil/trace.go:171","msg":"trace[1816084491] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25451; }","duration":"234.595798ms","start":"2026-08-22T11:20:39.922542Z","end":"2026-08-22T11:20:40.157138Z","steps":["trace[1816084491] 'agreement among raft nodes before linearized reading' (duration: 234.413785ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:20:40.157159Z","caller":"traceutil/trace.go:171","msg":"trace[474930570] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-41cd399c-3daf-49e6-8ef8-7d9cb512a45f; range_end:; response_count:1; response_revision:25451; }","duration":"244.6946ms","start":"2026-08-22T11:20:39.912451Z","end":"2026-08-22T11:20:40.157145Z","steps":["trace[474930570] 'agreement among raft nodes before linearized reading' (duration: 244.509887ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:20:40.157196Z","caller":"traceutil/trace.go:171","msg":"trace[1725898398] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25451; }","duration":"228.816068ms","start":"2026-08-22T11:20:39.928359Z","end":"2026-08-22T11:20:40.157175Z","steps":["trace[1725898398] 'agreement among raft nodes before linearized reading' (duration: 228.585754ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:20:40.38906Z","caller":"traceutil/trace.go:171","msg":"trace[208087498] linearizableReadLoop","detail":"{readStateIndex:26277; appliedIndex:26275; }","duration":"153.695691ms","start":"2026-08-22T11:20:40.235347Z","end":"2026-08-22T11:20:40.389042Z","steps":["trace[208087498] 'read index received' (duration: 75.546726ms)","trace[208087498] 'applied index is now lower than readState.Index' (duration: 78.148335ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:20:40.389085Z","caller":"traceutil/trace.go:171","msg":"trace[1713349251] transaction","detail":"{read_only:false; response_revision:25452; number_of_response:1; }","duration":"227.576705ms","start":"2026-08-22T11:20:40.16148Z","end":"2026-08-22T11:20:40.389057Z","steps":["trace[1713349251] 'process raft request' (duration: 149.366909ms)","trace[1713349251] 'compare' (duration: 77.980921ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:20:40.389224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.860354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-22T11:20:40.389257Z","caller":"traceutil/trace.go:171","msg":"trace[1431472201] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:25453; }","duration":"153.942555ms","start":"2026-08-22T11:20:40.235306Z","end":"2026-08-22T11:20:40.389248Z","steps":["trace[1431472201] 'agreement among raft nodes before linearized reading' (duration: 153.831353ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:20:40.389205Z","caller":"traceutil/trace.go:171","msg":"trace[1869291106] transaction","detail":"{read_only:false; response_revision:25453; number_of_response:1; }","duration":"227.123475ms","start":"2026-08-22T11:20:40.162034Z","end":"2026-08-22T11:20:40.389157Z","steps":["trace[1869291106] 'process raft request' (duration: 226.942412ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:20:40.389796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.379047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:20:40.38985Z","caller":"traceutil/trace.go:171","msg":"trace[1478165256] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25454; }","duration":"111.453038ms","start":"2026-08-22T11:20:40.278375Z","end":"2026-08-22T11:20:40.389828Z","steps":["trace[1478165256] 'agreement among raft nodes before linearized reading' (duration: 111.368196ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:20:40.583985Z","caller":"traceutil/trace.go:171","msg":"trace[183956747] transaction","detail":"{read_only:false; response_revision:25455; number_of_response:1; }","duration":"178.476212ms","start":"2026-08-22T11:20:40.405469Z","end":"2026-08-22T11:20:40.583946Z","steps":["trace[183956747] 'process raft request' (duration: 154.865254ms)","trace[183956747] 'compare' (duration: 23.455955ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:20:40.837725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.343482ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T11:20:40.837893Z","caller":"traceutil/trace.go:171","msg":"trace[146215147] linearizableReadLoop","detail":"{readStateIndex:26281; appliedIndex:26278; }","duration":"428.717467ms","start":"2026-08-22T11:20:40.409158Z","end":"2026-08-22T11:20:40.837875Z","steps":["trace[146215147] 'read index received' (duration: 151.279595ms)","trace[146215147] 'applied index is now lower than readState.Index' (duration: 277.436612ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:20:40.838009Z","caller":"traceutil/trace.go:171","msg":"trace[639271959] transaction","detail":"{read_only:false; response_revision:25457; number_of_response:1; }","duration":"430.526151ms","start":"2026-08-22T11:20:40.407471Z","end":"2026-08-22T11:20:40.837997Z","steps":["trace[639271959] 'process raft request' (duration: 430.342528ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:20:40.838025Z","caller":"traceutil/trace.go:171","msg":"trace[1921984268] transaction","detail":"{read_only:false; response_revision:25456; number_of_response:1; }","duration":"432.453428ms","start":"2026-08-22T11:20:40.405513Z","end":"2026-08-22T11:20:40.837966Z","steps":["trace[1921984268] 'process raft request' (duration: 285.76219ms)","trace[1921984268] 'compare' (duration: 146.079397ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:20:40.838082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:20:40.407448Z","time spent":"430.595123ms","remote":"127.0.0.1:50596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T11:20:40.838214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:20:40.405487Z","time spent":"432.621252ms","remote":"127.0.0.1:50596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T11:20:40.83833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.159415ms","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-22T11:20:40.838323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.183972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-08-22T11:20:40.838362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.679755ms","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-22T11:20:40.838417Z","caller":"traceutil/trace.go:171","msg":"trace[561464160] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25457; }","duration":"429.240586ms","start":"2026-08-22T11:20:40.409151Z","end":"2026-08-22T11:20:40.838392Z","steps":["trace[561464160] 'agreement among raft nodes before linearized reading' (duration: 429.024382ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:20:40.838424Z","caller":"traceutil/trace.go:171","msg":"trace[1889151063] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:25457; }","duration":"362.342776ms","start":"2026-08-22T11:20:40.476059Z","end":"2026-08-22T11:20:40.838401Z","steps":["trace[1889151063] 'agreement among raft nodes before linearized reading' (duration: 362.131332ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:20:40.838435Z","caller":"traceutil/trace.go:171","msg":"trace[865131292] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25457; }","duration":"308.830519ms","start":"2026-08-22T11:20:40.529583Z","end":"2026-08-22T11:20:40.838413Z","steps":["trace[865131292] 'agreement among raft nodes before linearized reading' (duration: 308.580954ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:20:40.83847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:20:40.476045Z","time spent":"362.414997ms","remote":"127.0.0.1:49116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":36,"response size":31,"request content":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true "} {"level":"warn","ts":"2026-08-22T11:20:40.838477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:20:40.409113Z","time spent":"429.344629ms","remote":"127.0.0.1:50596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-22T11:20:40.838484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:20:40.529545Z","time spent":"308.92546ms","remote":"127.0.0.1:50596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-22T11:20:40.838621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.81359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-08-22T11:20:40.83868Z","caller":"traceutil/trace.go:171","msg":"trace[371317019] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25457; }","duration":"407.980823ms","start":"2026-08-22T11:20:40.430684Z","end":"2026-08-22T11:20:40.838665Z","steps":["trace[371317019] 'agreement among raft nodes before linearized reading' (duration: 407.449494ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:20:40.838756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:20:40.430665Z","time spent":"408.078255ms","remote":"127.0.0.1:50596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":582,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-08-22T11:20:41.024433Z","caller":"traceutil/trace.go:171","msg":"trace[1625072076] transaction","detail":"{read_only:false; response_revision:25458; number_of_response:1; }","duration":"180.271395ms","start":"2026-08-22T11:20:40.844136Z","end":"2026-08-22T11:20:41.024407Z","steps":["trace[1625072076] 'process raft request' (duration: 153.585789ms)","trace[1625072076] 'compare' (duration: 26.586525ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:20:41.27837Z","caller":"traceutil/trace.go:171","msg":"trace[217534159] transaction","detail":"{read_only:false; response_revision:25459; number_of_response:1; }","duration":"434.14781ms","start":"2026-08-22T11:20:40.844193Z","end":"2026-08-22T11:20:41.27834Z","steps":["trace[217534159] 'process raft request' (duration: 340.189094ms)","trace[217534159] 'compare' (duration: 93.619989ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:20:41.278655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:20:40.844186Z","time spent":"434.365694ms","remote":"127.0.0.1:50596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T11:20:41.278487Z","caller":"traceutil/trace.go:171","msg":"trace[1421041333] transaction","detail":"{read_only:false; response_revision:25460; number_of_response:1; }","duration":"434.589598ms","start":"2026-08-22T11:20:40.843874Z","end":"2026-08-22T11:20:41.278464Z","steps":["trace[1421041333] 'process raft request' (duration: 434.232731ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:20:41.278816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.885411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-22T11:20:41.278846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:20:40.843859Z","time spent":"434.933974ms","remote":"127.0.0.1:50596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T11:20:41.27888Z","caller":"traceutil/trace.go:171","msg":"trace[155812320] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25460; }","duration":"356.996253ms","start":"2026-08-22T11:20:40.921869Z","end":"2026-08-22T11:20:41.278866Z","steps":["trace[155812320] 'agreement among raft nodes before linearized reading' (duration: 356.85232ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:20:41.278921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:20:40.921855Z","time spent":"357.059105ms","remote":"127.0.0.1:50776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":29,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-08-22T11:20:41.278162Z","caller":"traceutil/trace.go:171","msg":"trace[778056194] linearizableReadLoop","detail":"{readStateIndex:26284; appliedIndex:26281; }","duration":"356.236309ms","start":"2026-08-22T11:20:40.921906Z","end":"2026-08-22T11:20:41.278142Z","steps":["trace[778056194] 'read index received' (duration: 75.78095ms)","trace[778056194] 'applied index is now lower than readState.Index' (duration: 280.454569ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:20:41.279906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.005893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-41cd399c-3daf-49e6-8ef8-7d9cb512a45f\" ","response":"range_response_count:1 size:3761"} {"level":"info","ts":"2026-08-22T11:20:41.279969Z","caller":"traceutil/trace.go:171","msg":"trace[148349609] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-41cd399c-3daf-49e6-8ef8-7d9cb512a45f; range_end:; response_count:1; response_revision:25460; }","duration":"118.124545ms","start":"2026-08-22T11:20:41.161828Z","end":"2026-08-22T11:20:41.279953Z","steps":["trace[148349609] 'agreement among raft nodes before linearized reading' (duration: 117.617134ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:20:41.577148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.400116ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T11:20:41.57722Z","caller":"traceutil/trace.go:171","msg":"trace[671932714] linearizableReadLoop","detail":"{readStateIndex:26285; appliedIndex:26284; }","duration":"182.259883ms","start":"2026-08-22T11:20:41.39495Z","end":"2026-08-22T11:20:41.57721Z","steps":["trace[671932714] 'read index received' (duration: 59.718994ms)","trace[671932714] 'applied index is now lower than readState.Index' (duration: 122.540129ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:20:41.577276Z","caller":"traceutil/trace.go:171","msg":"trace[152527818] transaction","detail":"{read_only:false; response_revision:25461; number_of_response:1; }","duration":"238.548612ms","start":"2026-08-22T11:20:41.338705Z","end":"2026-08-22T11:20:41.577254Z","steps":["trace[152527818] 'process raft request' (duration: 115.990903ms)","trace[152527818] 'compare' (duration: 122.325345ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:20:41.577313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.379595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:573"} {"level":"info","ts":"2026-08-22T11:20:41.577334Z","caller":"traceutil/trace.go:171","msg":"trace[1064676457] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:25461; }","duration":"182.445697ms","start":"2026-08-22T11:20:41.394881Z","end":"2026-08-22T11:20:41.577326Z","steps":["trace[1064676457] 'agreement among raft nodes before linearized reading' (duration: 182.370965ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:20:41.920244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.396186ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T11:20:41.920335Z","caller":"traceutil/trace.go:171","msg":"trace[734346758] transaction","detail":"{read_only:false; response_revision:25462; number_of_response:1; }","duration":"331.302936ms","start":"2026-08-22T11:20:41.589017Z","end":"2026-08-22T11:20:41.92032Z","steps":["trace[734346758] 'process raft request' (duration: 98.756627ms)","trace[734346758] 'compare' (duration: 232.281754ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:20:41.920379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:20:41.588997Z","time spent":"331.363146ms","remote":"127.0.0.1:50596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T11:20:41.986139Z","caller":"traceutil/trace.go:171","msg":"trace[1647285742] transaction","detail":"{read_only:false; response_revision:25463; number_of_response:1; }","duration":"118.750947ms","start":"2026-08-22T11:20:41.867367Z","end":"2026-08-22T11:20:41.986118Z","steps":["trace[1647285742] 'process raft request' (duration: 118.642195ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:20:41.986585Z","caller":"traceutil/trace.go:171","msg":"trace[1499893852] linearizableReadLoop","detail":"{readStateIndex:26287; appliedIndex:26287; }","duration":"113.08655ms","start":"2026-08-22T11:20:41.87349Z","end":"2026-08-22T11:20:41.986576Z","steps":["trace[1499893852] 'read index received' (duration: 113.08266ms)","trace[1499893852] 'applied index is now lower than readState.Index' (duration: 3.12µs)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:20:41.986677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.170882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:20:41.986705Z","caller":"traceutil/trace.go:171","msg":"trace[1514482704] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:25463; }","duration":"113.257203ms","start":"2026-08-22T11:20:41.873441Z","end":"2026-08-22T11:20:41.986698Z","steps":["trace[1514482704] 'agreement among raft nodes before linearized reading' (duration: 113.170312ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:20:42.250644Z","caller":"traceutil/trace.go:171","msg":"trace[285608203] transaction","detail":"{read_only:false; response_revision:25464; number_of_response:1; }","duration":"316.998154ms","start":"2026-08-22T11:20:41.93362Z","end":"2026-08-22T11:20:42.250618Z","steps":["trace[285608203] 'process raft request' (duration: 302.541399ms)","trace[285608203] 'compare' (duration: 14.302592ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:20:42.250789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:20:41.933596Z","time spent":"317.110536ms","remote":"127.0.0.1:50596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T11:20:42.42277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.170762ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-08-22T11:20:42.42289Z","caller":"traceutil/trace.go:171","msg":"trace[437710193] linearizableReadLoop","detail":"{readStateIndex:26289; appliedIndex:26287; }","duration":"333.444717ms","start":"2026-08-22T11:20:42.089427Z","end":"2026-08-22T11:20:42.422871Z","steps":["trace[437710193] 'read index received' (duration: 146.697768ms)","trace[437710193] 'applied index is now lower than readState.Index' (duration: 186.745529ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:20:42.423012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.581979ms","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-22T11:20:42.423051Z","caller":"traceutil/trace.go:171","msg":"trace[2011179472] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25464; }","duration":"333.669371ms","start":"2026-08-22T11:20:42.08937Z","end":"2026-08-22T11:20:42.423039Z","steps":["trace[2011179472] 'agreement among raft nodes before linearized reading' (duration: 333.585379ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:20:42.423089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:20:42.089346Z","time spent":"333.733622ms","remote":"127.0.0.1:37500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-08-22T11:20:42.560232Z","caller":"traceutil/trace.go:171","msg":"trace[1932863315] linearizableReadLoop","detail":"{readStateIndex:26290; appliedIndex:26289; }","duration":"137.279128ms","start":"2026-08-22T11:20:42.422924Z","end":"2026-08-22T11:20:42.560203Z","steps":["trace[1932863315] 'read index received' (duration: 137.042694ms)","trace[1932863315] 'applied index is now lower than readState.Index' (duration: 234.874µs)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:20:42.560574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.481624ms","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-22T11:20:42.560622Z","caller":"traceutil/trace.go:171","msg":"trace[1669445078] transaction","detail":"{read_only:false; response_revision:25465; number_of_response:1; }","duration":"164.15794ms","start":"2026-08-22T11:20:42.396434Z","end":"2026-08-22T11:20:42.560592Z","steps":["trace[1669445078] 'process raft request' (duration: 163.568709ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:20:42.560654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.175512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:20:42.560683Z","caller":"traceutil/trace.go:171","msg":"trace[1188000511] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25465; }","duration":"166.658798ms","start":"2026-08-22T11:20:42.394002Z","end":"2026-08-22T11:20:42.560661Z","steps":["trace[1188000511] 'agreement among raft nodes before linearized reading' (duration: 166.335192ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:20:42.560712Z","caller":"traceutil/trace.go:171","msg":"trace[1736918766] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25465; }","duration":"282.291645ms","start":"2026-08-22T11:20:42.278405Z","end":"2026-08-22T11:20:42.560697Z","steps":["trace[1736918766] 'agreement among raft nodes before linearized reading' (duration: 282.175082ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:20:42.560745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.155258ms","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-22T11:20:42.560742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.413695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:673"} {"level":"warn","ts":"2026-08-22T11:20:42.560862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.641066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:673"} {"level":"info","ts":"2026-08-22T11:20:42.560894Z","caller":"traceutil/trace.go:171","msg":"trace[1086896834] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25465; }","duration":"157.686776ms","start":"2026-08-22T11:20:42.4032Z","end":"2026-08-22T11:20:42.560887Z","steps":["trace[1086896834] 'agreement among raft nodes before linearized reading' (duration: 157.33784ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:20:42.560879Z","caller":"traceutil/trace.go:171","msg":"trace[1659525699] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25465; }","duration":"164.558317ms","start":"2026-08-22T11:20:42.396262Z","end":"2026-08-22T11:20:42.56082Z","steps":["trace[1659525699] 'agreement among raft nodes before linearized reading' (duration: 164.016487ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:20:42.560745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.707528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:673"} {"level":"info","ts":"2026-08-22T11:20:42.56095Z","caller":"traceutil/trace.go:171","msg":"trace[403418813] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25465; }","duration":"157.931172ms","start":"2026-08-22T11:20:42.402995Z","end":"2026-08-22T11:20:42.560926Z","steps":["trace[403418813] 'agreement among raft nodes before linearized reading' (duration: 157.544575ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:20:42.560972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.927293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-41cd399c-3daf-49e6-8ef8-7d9cb512a45f\" ","response":"range_response_count:1 size:3761"} {"level":"info","ts":"2026-08-22T11:20:42.561012Z","caller":"traceutil/trace.go:171","msg":"trace[1172006373] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-41cd399c-3daf-49e6-8ef8-7d9cb512a45f; range_end:; response_count:1; response_revision:25465; }","duration":"277.005074ms","start":"2026-08-22T11:20:42.283996Z","end":"2026-08-22T11:20:42.561001Z","steps":["trace[1172006373] 'agreement among raft nodes before linearized reading' (duration: 276.401023ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:20:42.561079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.056232ms","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-22T11:20:42.561145Z","caller":"traceutil/trace.go:171","msg":"trace[448946016] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25465; }","duration":"400.151134ms","start":"2026-08-22T11:20:42.160977Z","end":"2026-08-22T11:20:42.561128Z","steps":["trace[448946016] 'agreement among raft nodes before linearized reading' (duration: 399.345159ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:20:42.561202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:20:42.160966Z","time spent":"400.220955ms","remote":"127.0.0.1:50596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-22T11:20:42.561485Z","caller":"traceutil/trace.go:171","msg":"trace[726897840] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25465; }","duration":"166.265151ms","start":"2026-08-22T11:20:42.394538Z","end":"2026-08-22T11:20:42.560803Z","steps":["trace[726897840] 'agreement among raft nodes before linearized reading' (duration: 165.920964ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:20:44.695173Z","caller":"traceutil/trace.go:171","msg":"trace[1858172687] transaction","detail":"{read_only:false; response_revision:25481; number_of_response:1; }","duration":"116.24965ms","start":"2026-08-22T11:20:44.578896Z","end":"2026-08-22T11:20:44.695146Z","steps":["trace[1858172687] 'process raft request' (duration: 94.297413ms)","trace[1858172687] 'compare' (duration: 21.824215ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:20:44.697252Z","caller":"traceutil/trace.go:171","msg":"trace[1196591203] transaction","detail":"{read_only:false; response_revision:25482; number_of_response:1; }","duration":"117.783518ms","start":"2026-08-22T11:20:44.579441Z","end":"2026-08-22T11:20:44.697225Z","steps":["trace[1196591203] 'process raft request' (duration: 117.460722ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:20:44.697609Z","caller":"traceutil/trace.go:171","msg":"trace[1377133613] transaction","detail":"{read_only:false; response_revision:25484; number_of_response:1; }","duration":"100.814166ms","start":"2026-08-22T11:20:44.596721Z","end":"2026-08-22T11:20:44.697535Z","steps":["trace[1377133613] 'process raft request' (duration: 100.677753ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:20:44.697827Z","caller":"traceutil/trace.go:171","msg":"trace[270574165] transaction","detail":"{read_only:false; response_revision:25483; number_of_response:1; }","duration":"117.724537ms","start":"2026-08-22T11:20:44.579831Z","end":"2026-08-22T11:20:44.697556Z","steps":["trace[270574165] 'process raft request' (duration: 117.459351ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:20:47.18102Z","caller":"traceutil/trace.go:171","msg":"trace[1087562153] transaction","detail":"{read_only:false; response_revision:25522; number_of_response:1; }","duration":"129.181055ms","start":"2026-08-22T11:20:47.051807Z","end":"2026-08-22T11:20:47.180988Z","steps":["trace[1087562153] 'process raft request' (duration: 128.94672ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:20:47.48135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.171848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:20:47.481437Z","caller":"traceutil/trace.go:171","msg":"trace[787454154] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25522; }","duration":"205.298961ms","start":"2026-08-22T11:20:47.276121Z","end":"2026-08-22T11:20:47.48142Z","steps":["trace[787454154] 'range keys from in-memory index tree' (duration: 205.079427ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:20:47.481468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.727773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:10359"} {"level":"warn","ts":"2026-08-22T11:20:47.481528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.72936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-08-22T11:20:47.481556Z","caller":"traceutil/trace.go:171","msg":"trace[434088722] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:25522; }","duration":"200.851535ms","start":"2026-08-22T11:20:47.280683Z","end":"2026-08-22T11:20:47.481535Z","steps":["trace[434088722] 'range keys from in-memory index tree' (duration: 200.569251ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:20:47.48157Z","caller":"traceutil/trace.go:171","msg":"trace[896153982] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:25522; }","duration":"134.801222ms","start":"2026-08-22T11:20:47.346757Z","end":"2026-08-22T11:20:47.481558Z","steps":["trace[896153982] 'range keys from in-memory index tree' (duration: 134.594297ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:20:47.481763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.735893ms","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-22T11:20:47.481766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.350098ms","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-22T11:20:47.481899Z","caller":"traceutil/trace.go:171","msg":"trace[1917344741] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25522; }","duration":"182.952857ms","start":"2026-08-22T11:20:47.298919Z","end":"2026-08-22T11:20:47.481871Z","steps":["trace[1917344741] 'range keys from in-memory index tree' (duration: 182.427096ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:20:47.481934Z","caller":"traceutil/trace.go:171","msg":"trace[539237565] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25522; }","duration":"179.956539ms","start":"2026-08-22T11:20:47.30195Z","end":"2026-08-22T11:20:47.481906Z","steps":["trace[539237565] 'range keys from in-memory index tree' (duration: 179.173135ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:20:47.606891Z","caller":"traceutil/trace.go:171","msg":"trace[848401859] transaction","detail":"{read_only:false; response_revision:25524; number_of_response:1; }","duration":"120.062872ms","start":"2026-08-22T11:20:47.486802Z","end":"2026-08-22T11:20:47.606865Z","steps":["trace[848401859] 'process raft request' (duration: 111.51089ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:20:47.608639Z","caller":"traceutil/trace.go:171","msg":"trace[339997366] transaction","detail":"{read_only:false; response_revision:25525; number_of_response:1; }","duration":"116.404763ms","start":"2026-08-22T11:20:47.492212Z","end":"2026-08-22T11:20:47.608617Z","steps":["trace[339997366] 'process raft request' (duration: 116.286331ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:20:52.828212Z","caller":"traceutil/trace.go:171","msg":"trace[113714210] transaction","detail":"{read_only:false; response_revision:25569; number_of_response:1; }","duration":"102.512968ms","start":"2026-08-22T11:20:52.725661Z","end":"2026-08-22T11:20:52.828174Z","steps":["trace[113714210] 'process raft request' (duration: 102.240613ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:21:13.825465Z","caller":"traceutil/trace.go:171","msg":"trace[415356975] transaction","detail":"{read_only:false; response_revision:25740; number_of_response:1; }","duration":"104.677209ms","start":"2026-08-22T11:21:13.720769Z","end":"2026-08-22T11:21:13.825447Z","steps":["trace[415356975] 'process raft request' (duration: 104.21181ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:21:20.000103Z","caller":"traceutil/trace.go:171","msg":"trace[1569625331] linearizableReadLoop","detail":"{readStateIndex:26618; appliedIndex:26617; }","duration":"145.603627ms","start":"2026-08-22T11:21:19.854472Z","end":"2026-08-22T11:21:20.000076Z","steps":["trace[1569625331] 'read index received' (duration: 145.437484ms)","trace[1569625331] 'applied index is now lower than readState.Index' (duration: 165.193µs)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:21:20.000372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.887573ms","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-22T11:21:20.000424Z","caller":"traceutil/trace.go:171","msg":"trace[1578867784] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25784; }","duration":"145.982865ms","start":"2026-08-22T11:21:19.854427Z","end":"2026-08-22T11:21:20.00041Z","steps":["trace[1578867784] 'agreement among raft nodes before linearized reading' (duration: 145.755741ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:21:20.000371Z","caller":"traceutil/trace.go:171","msg":"trace[898028943] transaction","detail":"{read_only:false; response_revision:25784; number_of_response:1; }","duration":"154.513407ms","start":"2026-08-22T11:21:19.845834Z","end":"2026-08-22T11:21:20.000348Z","steps":["trace[898028943] 'process raft request' (duration: 154.063378ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:21:22.223449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.308839ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-08-22T11:21:22.22385Z","caller":"traceutil/trace.go:171","msg":"trace[1309216536] linearizableReadLoop","detail":"{readStateIndex:26633; appliedIndex:26631; }","duration":"214.22723ms","start":"2026-08-22T11:21:22.009594Z","end":"2026-08-22T11:21:22.223822Z","steps":["trace[1309216536] 'read index received' (duration: 66.587115ms)","trace[1309216536] 'applied index is now lower than readState.Index' (duration: 147.637875ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:21:22.2239Z","caller":"traceutil/trace.go:171","msg":"trace[1937685988] transaction","detail":"{read_only:false; response_revision:25798; number_of_response:1; }","duration":"218.298418ms","start":"2026-08-22T11:21:22.005564Z","end":"2026-08-22T11:21:22.223862Z","steps":["trace[1937685988] 'process raft request' (duration: 218.008352ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:21:22.378384Z","caller":"traceutil/trace.go:171","msg":"trace[435896050] linearizableReadLoop","detail":"{readStateIndex:26634; appliedIndex:26633; }","duration":"154.462935ms","start":"2026-08-22T11:21:22.223904Z","end":"2026-08-22T11:21:22.378367Z","steps":["trace[435896050] 'read index received' (duration: 133.12858ms)","trace[435896050] 'applied index is now lower than readState.Index' (duration: 21.333415ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:21:22.378526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.546301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"warn","ts":"2026-08-22T11:21:22.378596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.891075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:21:22.3786Z","caller":"traceutil/trace.go:171","msg":"trace[758673808] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:25799; }","duration":"229.660674ms","start":"2026-08-22T11:21:22.14892Z","end":"2026-08-22T11:21:22.378581Z","steps":["trace[758673808] 'agreement among raft nodes before linearized reading' (duration: 229.459949ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:21:22.378621Z","caller":"traceutil/trace.go:171","msg":"trace[761849938] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25799; }","duration":"102.949146ms","start":"2026-08-22T11:21:22.275665Z","end":"2026-08-22T11:21:22.378614Z","steps":["trace[761849938] 'agreement among raft nodes before linearized reading' (duration: 102.914156ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:21:22.378661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.399719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:21:22.378783Z","caller":"traceutil/trace.go:171","msg":"trace[87217546] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:25799; }","duration":"300.586193ms","start":"2026-08-22T11:21:22.078165Z","end":"2026-08-22T11:21:22.378751Z","steps":["trace[87217546] 'agreement among raft nodes before linearized reading' (duration: 300.361329ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:21:22.378554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.756997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-08-22T11:21:22.378837Z","caller":"traceutil/trace.go:171","msg":"trace[357939780] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25799; }","duration":"269.072493ms","start":"2026-08-22T11:21:22.109757Z","end":"2026-08-22T11:21:22.37883Z","steps":["trace[357939780] 'agreement among raft nodes before linearized reading' (duration: 268.708076ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:21:22.378851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:21:22.078143Z","time spent":"300.688354ms","remote":"127.0.0.1:53890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true "} {"level":"info","ts":"2026-08-22T11:21:22.378949Z","caller":"traceutil/trace.go:171","msg":"trace[1264064507] transaction","detail":"{read_only:false; response_revision:25799; number_of_response:1; }","duration":"236.493974ms","start":"2026-08-22T11:21:22.142445Z","end":"2026-08-22T11:21:22.378939Z","steps":["trace[1264064507] 'process raft request' (duration: 214.647149ms)","trace[1264064507] 'compare' (duration: 20.888787ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:21:22.379136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.514561ms","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-22T11:21:22.379218Z","caller":"traceutil/trace.go:171","msg":"trace[401923908] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25798; }","duration":"369.650934ms","start":"2026-08-22T11:21:22.009544Z","end":"2026-08-22T11:21:22.379195Z","steps":["trace[401923908] 'agreement among raft nodes before linearized reading' (duration: 214.447004ms)","trace[401923908] 'range keys from in-memory index tree' (duration: 154.043568ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:21:22.379224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.361107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-08-22T11:21:22.379259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:21:22.009514Z","time spent":"369.737026ms","remote":"127.0.0.1:50596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-22T11:21:22.379268Z","caller":"traceutil/trace.go:171","msg":"trace[990171596] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:25799; }","duration":"280.428478ms","start":"2026-08-22T11:21:22.098828Z","end":"2026-08-22T11:21:22.379257Z","steps":["trace[990171596] 'agreement among raft nodes before linearized reading' (duration: 280.317626ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:21:22.682306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.659771ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T11:21:22.682433Z","caller":"traceutil/trace.go:171","msg":"trace[1892184497] linearizableReadLoop","detail":"{readStateIndex:26636; appliedIndex:26634; }","duration":"297.88129ms","start":"2026-08-22T11:21:22.38454Z","end":"2026-08-22T11:21:22.682421Z","steps":["trace[1892184497] 'read index received' (duration: 105.047725ms)","trace[1892184497] 'applied index is now lower than readState.Index' (duration: 192.832925ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:21:22.682497Z","caller":"traceutil/trace.go:171","msg":"trace[834012162] transaction","detail":"{read_only:false; response_revision:25801; number_of_response:1; }","duration":"299.080693ms","start":"2026-08-22T11:21:22.38341Z","end":"2026-08-22T11:21:22.682491Z","steps":["trace[834012162] 'process raft request' (duration: 298.973961ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:21:22.682588Z","caller":"traceutil/trace.go:171","msg":"trace[1921941061] transaction","detail":"{read_only:false; response_revision:25800; number_of_response:1; }","duration":"299.671824ms","start":"2026-08-22T11:21:22.382881Z","end":"2026-08-22T11:21:22.682553Z","steps":["trace[1921941061] 'process raft request' (duration: 106.693727ms)","trace[1921941061] 'compare' (duration: 192.563249ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:21:22.682839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.274037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:17940"} {"level":"info","ts":"2026-08-22T11:21:22.682939Z","caller":"traceutil/trace.go:171","msg":"trace[1194894162] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25801; }","duration":"298.42676ms","start":"2026-08-22T11:21:22.384492Z","end":"2026-08-22T11:21:22.682919Z","steps":["trace[1194894162] 'agreement among raft nodes before linearized reading' (duration: 298.106654ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:21:22.900369Z","caller":"traceutil/trace.go:171","msg":"trace[1078613281] transaction","detail":"{read_only:false; response_revision:25802; number_of_response:1; }","duration":"117.35746ms","start":"2026-08-22T11:21:22.782981Z","end":"2026-08-22T11:21:22.900338Z","steps":["trace[1078613281] 'process raft request' (duration: 92.376885ms)","trace[1078613281] 'compare' (duration: 24.811612ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:21:22.971452Z","caller":"traceutil/trace.go:171","msg":"trace[1289562271] transaction","detail":"{read_only:false; response_revision:25803; number_of_response:1; }","duration":"168.833038ms","start":"2026-08-22T11:21:22.802589Z","end":"2026-08-22T11:21:22.971422Z","steps":["trace[1289562271] 'process raft request' (duration: 168.594884ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:21:22.971594Z","caller":"traceutil/trace.go:171","msg":"trace[147529226] transaction","detail":"{read_only:false; response_revision:25804; number_of_response:1; }","duration":"161.715813ms","start":"2026-08-22T11:21:22.809819Z","end":"2026-08-22T11:21:22.971534Z","steps":["trace[147529226] 'process raft request' (duration: 161.52784ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:21:23.051168Z","caller":"traceutil/trace.go:171","msg":"trace[1116701636] transaction","detail":"{read_only:false; response_revision:25805; number_of_response:1; }","duration":"102.594299ms","start":"2026-08-22T11:21:22.948547Z","end":"2026-08-22T11:21:23.051141Z","steps":["trace[1116701636] 'process raft request' (duration: 102.402706ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:21:23.303133Z","caller":"traceutil/trace.go:171","msg":"trace[543680887] transaction","detail":"{read_only:false; response_revision:25806; number_of_response:1; }","duration":"277.669207ms","start":"2026-08-22T11:21:23.025437Z","end":"2026-08-22T11:21:23.303106Z","steps":["trace[543680887] 'process raft request' (duration: 262.827165ms)","trace[543680887] 'compare' (duration: 14.698849ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:21:23.303256Z","caller":"traceutil/trace.go:171","msg":"trace[1504011347] linearizableReadLoop","detail":"{readStateIndex:26642; appliedIndex:26640; }","duration":"221.186853ms","start":"2026-08-22T11:21:23.082046Z","end":"2026-08-22T11:21:23.303233Z","steps":["trace[1504011347] 'read index received' (duration: 206.173927ms)","trace[1504011347] 'applied index is now lower than readState.Index' (duration: 15.011036ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:21:23.303284Z","caller":"traceutil/trace.go:171","msg":"trace[1669517392] transaction","detail":"{read_only:false; response_revision:25807; number_of_response:1; }","duration":"266.132327ms","start":"2026-08-22T11:21:23.037124Z","end":"2026-08-22T11:21:23.303256Z","steps":["trace[1669517392] 'process raft request' (duration: 265.940974ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:21:23.30345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.390726ms","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-22T11:21:23.303498Z","caller":"traceutil/trace.go:171","msg":"trace[1562896702] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25807; }","duration":"221.483059ms","start":"2026-08-22T11:21:23.082003Z","end":"2026-08-22T11:21:23.303486Z","steps":["trace[1562896702] 'agreement among raft nodes before linearized reading' (duration: 221.331766ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:21:23.303571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.976662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-22T11:21:23.303611Z","caller":"traceutil/trace.go:171","msg":"trace[1330419024] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25807; }","duration":"169.018393ms","start":"2026-08-22T11:21:23.134583Z","end":"2026-08-22T11:21:23.303602Z","steps":["trace[1330419024] 'agreement among raft nodes before linearized reading' (duration: 168.923261ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:21:23.30359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.308123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-22T11:21:23.303691Z","caller":"traceutil/trace.go:171","msg":"trace[2064557163] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25807; }","duration":"173.441086ms","start":"2026-08-22T11:21:23.130222Z","end":"2026-08-22T11:21:23.303663Z","steps":["trace[2064557163] 'agreement among raft nodes before linearized reading' (duration: 173.170191ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:21:23.45188Z","caller":"traceutil/trace.go:171","msg":"trace[924990138] transaction","detail":"{read_only:false; response_revision:25808; number_of_response:1; }","duration":"143.575989ms","start":"2026-08-22T11:21:23.308275Z","end":"2026-08-22T11:21:23.451851Z","steps":["trace[924990138] 'process raft request' (duration: 138.969641ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:21:23.76931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.661294ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T11:21:23.769516Z","caller":"traceutil/trace.go:171","msg":"trace[212590477] linearizableReadLoop","detail":"{readStateIndex:26645; appliedIndex:26642; }","duration":"341.047142ms","start":"2026-08-22T11:21:23.428452Z","end":"2026-08-22T11:21:23.769499Z","steps":["trace[212590477] 'read index received' (duration: 18.802578ms)","trace[212590477] 'applied index is now lower than readState.Index' (duration: 322.243034ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:21:23.769585Z","caller":"traceutil/trace.go:171","msg":"trace[2018756140] transaction","detail":"{read_only:false; response_revision:25809; number_of_response:1; }","duration":"461.176594ms","start":"2026-08-22T11:21:23.308384Z","end":"2026-08-22T11:21:23.76956Z","steps":["trace[2018756140] 'process raft request' (duration: 321.185924ms)","trace[2018756140] 'compare' (duration: 139.337178ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:21:23.769616Z","caller":"traceutil/trace.go:171","msg":"trace[928097173] transaction","detail":"{read_only:false; response_revision:25810; number_of_response:1; }","duration":"460.890038ms","start":"2026-08-22T11:21:23.308683Z","end":"2026-08-22T11:21:23.769573Z","steps":["trace[928097173] 'process raft request' (duration: 460.724035ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:21:23.769765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.290396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"warn","ts":"2026-08-22T11:21:23.769814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:21:23.30866Z","time spent":"461.077391ms","remote":"127.0.0.1:50596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T11:21:23.769827Z","caller":"traceutil/trace.go:171","msg":"trace[804165423] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25810; }","duration":"341.403028ms","start":"2026-08-22T11:21:23.428407Z","end":"2026-08-22T11:21:23.76981Z","steps":["trace[804165423] 'agreement among raft nodes before linearized reading' (duration: 341.188124ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:21:23.769857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:21:23.428385Z","time spent":"341.467409ms","remote":"127.0.0.1:50596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":582,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-22T11:21:23.769838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:21:23.308342Z","time spent":"461.367888ms","remote":"127.0.0.1:50596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T11:21:24.060268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.969532ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T11:21:24.060454Z","caller":"traceutil/trace.go:171","msg":"trace[632517975] linearizableReadLoop","detail":"{readStateIndex:26647; appliedIndex:26645; }","duration":"200.410878ms","start":"2026-08-22T11:21:23.860028Z","end":"2026-08-22T11:21:24.060438Z","steps":["trace[632517975] 'read index received' (duration: 63.198991ms)","trace[632517975] 'applied index is now lower than readState.Index' (duration: 137.210887ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:21:24.060548Z","caller":"traceutil/trace.go:171","msg":"trace[18620084] transaction","detail":"{read_only:false; response_revision:25812; number_of_response:1; }","duration":"287.063496ms","start":"2026-08-22T11:21:23.773464Z","end":"2026-08-22T11:21:24.060528Z","steps":["trace[18620084] 'process raft request' (duration: 286.922853ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:21:24.060581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.554652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:21:24.060612Z","caller":"traceutil/trace.go:171","msg":"trace[864060702] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:25812; }","duration":"200.615902ms","start":"2026-08-22T11:21:23.859986Z","end":"2026-08-22T11:21:24.060602Z","steps":["trace[864060702] 'agreement among raft nodes before linearized reading' (duration: 200.536321ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:21:24.060677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.961341ms","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-22T11:21:24.060752Z","caller":"traceutil/trace.go:171","msg":"trace[612953956] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:25812; }","duration":"158.093834ms","start":"2026-08-22T11:21:23.902639Z","end":"2026-08-22T11:21:24.060733Z","steps":["trace[612953956] 'agreement among raft nodes before linearized reading' (duration: 157.953111ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:21:24.060782Z","caller":"traceutil/trace.go:171","msg":"trace[1188901017] transaction","detail":"{read_only:false; response_revision:25811; number_of_response:1; }","duration":"287.598005ms","start":"2026-08-22T11:21:23.773155Z","end":"2026-08-22T11:21:24.060753Z","steps":["trace[1188901017] 'process raft request' (duration: 150.058172ms)","trace[1188901017] 'compare' (duration: 136.871821ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:21:24.360867Z","caller":"traceutil/trace.go:171","msg":"trace[1023477250] transaction","detail":"{read_only:false; response_revision:25813; number_of_response:1; }","duration":"132.200371ms","start":"2026-08-22T11:21:24.228651Z","end":"2026-08-22T11:21:24.360851Z","steps":["trace[1023477250] 'process raft request' (duration: 131.522889ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:21:24.516179Z","caller":"traceutil/trace.go:171","msg":"trace[1381660344] transaction","detail":"{read_only:false; response_revision:25814; number_of_response:1; }","duration":"132.238894ms","start":"2026-08-22T11:21:24.383905Z","end":"2026-08-22T11:21:24.516144Z","steps":["trace[1381660344] 'process raft request' (duration: 131.185413ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:21:24.853108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.292151ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:21:24.853229Z","caller":"traceutil/trace.go:171","msg":"trace[1581363296] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:25814; }","duration":"185.433064ms","start":"2026-08-22T11:21:24.667778Z","end":"2026-08-22T11:21:24.853211Z","steps":["trace[1581363296] 'range keys from in-memory index tree' (duration: 185.271771ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:21:24.853205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.966121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-22T11:21:24.853249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.79486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:673"} {"level":"info","ts":"2026-08-22T11:21:24.85331Z","caller":"traceutil/trace.go:171","msg":"trace[551518096] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25814; }","duration":"165.855941ms","start":"2026-08-22T11:21:24.687441Z","end":"2026-08-22T11:21:24.853297Z","steps":["trace[551518096] 'range keys from in-memory index tree' (duration: 165.697319ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:21:24.853329Z","caller":"traceutil/trace.go:171","msg":"trace[1801009854] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0; response_count:0; response_revision:25814; }","duration":"219.100524ms","start":"2026-08-22T11:21:24.634166Z","end":"2026-08-22T11:21:24.853267Z","steps":["trace[1801009854] 'count revisions from in-memory index tree' (duration: 218.889199ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:21:24.853331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.728779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"warn","ts":"2026-08-22T11:21:24.853349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.227938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:673"} {"level":"info","ts":"2026-08-22T11:21:24.853397Z","caller":"traceutil/trace.go:171","msg":"trace[1383292187] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:25814; }","duration":"166.81158ms","start":"2026-08-22T11:21:24.686566Z","end":"2026-08-22T11:21:24.853377Z","steps":["trace[1383292187] 'range keys from in-memory index tree' (duration: 166.597086ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:21:24.8534Z","caller":"traceutil/trace.go:171","msg":"trace[1446935655] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25814; }","duration":"166.29432ms","start":"2026-08-22T11:21:24.687093Z","end":"2026-08-22T11:21:24.853387Z","steps":["trace[1446935655] 'range keys from in-memory index tree' (duration: 166.084536ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:21:24.853412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.732868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:673"} {"level":"info","ts":"2026-08-22T11:21:24.853439Z","caller":"traceutil/trace.go:171","msg":"trace[544170737] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25814; }","duration":"166.79786ms","start":"2026-08-22T11:21:24.686633Z","end":"2026-08-22T11:21:24.853431Z","steps":["trace[544170737] 'range keys from in-memory index tree' (duration: 166.621377ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:21:24.853315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.28363ms","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-22T11:21:24.853543Z","caller":"traceutil/trace.go:171","msg":"trace[1646035679] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25814; }","duration":"166.551094ms","start":"2026-08-22T11:21:24.686963Z","end":"2026-08-22T11:21:24.853514Z","steps":["trace[1646035679] 'range keys from in-memory index tree' (duration: 166.120996ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:21:25.172873Z","caller":"traceutil/trace.go:171","msg":"trace[366514249] transaction","detail":"{read_only:false; response_revision:25816; number_of_response:1; }","duration":"315.164979ms","start":"2026-08-22T11:21:24.857418Z","end":"2026-08-22T11:21:25.172583Z","steps":["trace[366514249] 'process raft request' (duration: 315.011756ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:21:25.172996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:21:24.857391Z","time spent":"315.543637ms","remote":"127.0.0.1:50596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T11:21:25.558792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.667991ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T11:21:25.559047Z","caller":"traceutil/trace.go:171","msg":"trace[1633326496] linearizableReadLoop","detail":"{readStateIndex:26653; appliedIndex:26650; }","duration":"483.475188ms","start":"2026-08-22T11:21:25.075555Z","end":"2026-08-22T11:21:25.55903Z","steps":["trace[1633326496] 'read index received' (duration: 96.843571ms)","trace[1633326496] 'applied index is now lower than readState.Index' (duration: 386.630677ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:21:25.559036Z","caller":"traceutil/trace.go:171","msg":"trace[232447924] transaction","detail":"{read_only:false; response_revision:25817; number_of_response:1; }","duration":"581.626123ms","start":"2026-08-22T11:21:24.977378Z","end":"2026-08-22T11:21:25.559004Z","steps":["trace[232447924] 'process raft request' (duration: 298.655046ms)","trace[232447924] 'compare' (duration: 282.329145ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:21:25.559129Z","caller":"traceutil/trace.go:171","msg":"trace[408067315] transaction","detail":"{read_only:false; response_revision:25818; number_of_response:1; }","duration":"502.753393ms","start":"2026-08-22T11:21:25.056365Z","end":"2026-08-22T11:21:25.559118Z","steps":["trace[408067315] 'process raft request' (duration: 502.519339ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:21:25.55919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:21:24.977318Z","time spent":"581.789076ms","remote":"127.0.0.1:50596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T11:21:25.559212Z","caller":"traceutil/trace.go:171","msg":"trace[998784391] transaction","detail":"{read_only:false; response_revision:25819; number_of_response:1; }","duration":"327.347991ms","start":"2026-08-22T11:21:25.231838Z","end":"2026-08-22T11:21:25.559186Z","steps":["trace[998784391] 'process raft request' (duration: 327.159387ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:21:25.559301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.742593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:21:25.55934Z","caller":"traceutil/trace.go:171","msg":"trace[1045253401] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:25819; }","duration":"483.817864ms","start":"2026-08-22T11:21:25.075512Z","end":"2026-08-22T11:21:25.55933Z","steps":["trace[1045253401] 'agreement among raft nodes before linearized reading' (duration: 483.714682ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:21:25.559352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:21:25.231815Z","time spent":"327.460743ms","remote":"127.0.0.1:50596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T11:21:25.559198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:21:25.056329Z","time spent":"502.832335ms","remote":"127.0.0.1:50596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T11:21:25.559376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:21:25.075487Z","time spent":"483.880895ms","remote":"127.0.0.1:50464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"info","ts":"2026-08-22T11:21:25.737054Z","caller":"traceutil/trace.go:171","msg":"trace[824324519] transaction","detail":"{read_only:false; response_revision:25820; number_of_response:1; }","duration":"428.916661ms","start":"2026-08-22T11:21:25.308119Z","end":"2026-08-22T11:21:25.737036Z","steps":["trace[824324519] 'process raft request' (duration: 417.646946ms)","trace[824324519] 'compare' (duration: 11.117752ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:21:25.73717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:21:25.308099Z","time spent":"429.005672ms","remote":"127.0.0.1:50596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T11:21:25.737268Z","caller":"traceutil/trace.go:171","msg":"trace[1490880436] linearizableReadLoop","detail":"{readStateIndex:26656; appliedIndex:26654; }","duration":"178.157374ms","start":"2026-08-22T11:21:25.559087Z","end":"2026-08-22T11:21:25.737244Z","steps":["trace[1490880436] 'read index received' (duration: 166.728747ms)","trace[1490880436] 'applied index is now lower than readState.Index' (duration: 11.427427ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:21:25.737418Z","caller":"traceutil/trace.go:171","msg":"trace[494856958] transaction","detail":"{read_only:false; response_revision:25821; number_of_response:1; }","duration":"427.705227ms","start":"2026-08-22T11:21:25.309701Z","end":"2026-08-22T11:21:25.737406Z","steps":["trace[494856958] 'process raft request' (duration: 427.290409ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:21:25.737454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.029781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-22T11:21:25.737504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.903919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-22T11:21:25.737535Z","caller":"traceutil/trace.go:171","msg":"trace[2068505808] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25821; }","duration":"461.151763ms","start":"2026-08-22T11:21:25.276364Z","end":"2026-08-22T11:21:25.737516Z","steps":["trace[2068505808] 'agreement among raft nodes before linearized reading' (duration: 461.009801ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:21:25.737539Z","caller":"traceutil/trace.go:171","msg":"trace[421614090] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25821; }","duration":"280.94009ms","start":"2026-08-22T11:21:25.45659Z","end":"2026-08-22T11:21:25.73753Z","steps":["trace[421614090] 'agreement among raft nodes before linearized reading' (duration: 280.777137ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:21:25.737531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:21:25.309682Z","time spent":"427.773009ms","remote":"127.0.0.1:50596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T11:21:25.737582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:21:25.276349Z","time spent":"461.223775ms","remote":"127.0.0.1:50270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-22T11:21:25.949037Z","caller":"traceutil/trace.go:171","msg":"trace[628321277] transaction","detail":"{read_only:false; response_revision:25823; number_of_response:1; }","duration":"144.243411ms","start":"2026-08-22T11:21:25.804771Z","end":"2026-08-22T11:21:25.949014Z","steps":["trace[628321277] 'process raft request' (duration: 70.682393ms)","trace[628321277] 'compare' (duration: 73.264872ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:21:25.949042Z","caller":"traceutil/trace.go:171","msg":"trace[664451097] linearizableReadLoop","detail":"{readStateIndex:26659; appliedIndex:26657; }","duration":"135.859111ms","start":"2026-08-22T11:21:25.813159Z","end":"2026-08-22T11:21:25.949018Z","steps":["trace[664451097] 'read index received' (duration: 62.283733ms)","trace[664451097] 'applied index is now lower than readState.Index' (duration: 73.574138ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:21:25.949162Z","caller":"traceutil/trace.go:171","msg":"trace[183971461] transaction","detail":"{read_only:false; response_revision:25824; number_of_response:1; }","duration":"142.507379ms","start":"2026-08-22T11:21:25.806648Z","end":"2026-08-22T11:21:25.949156Z","steps":["trace[183971461] 'process raft request' (duration: 142.253164ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:21:25.949257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.062485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:21:25.949328Z","caller":"traceutil/trace.go:171","msg":"trace[1655403380] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachines/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachines0; response_count:0; response_revision:25824; }","duration":"136.190257ms","start":"2026-08-22T11:21:25.813118Z","end":"2026-08-22T11:21:25.949308Z","steps":["trace[1655403380] 'agreement among raft nodes before linearized reading' (duration: 136.064985ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:21:26.230352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.342742ms","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-22T11:21:26.230414Z","caller":"traceutil/trace.go:171","msg":"trace[928802259] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25824; }","duration":"164.425835ms","start":"2026-08-22T11:21:26.065974Z","end":"2026-08-22T11:21:26.2304Z","steps":["trace[928802259] 'range keys from in-memory index tree' (duration: 164.23562ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:21:26.230673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.796202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-08-22T11:21:26.230809Z","caller":"traceutil/trace.go:171","msg":"trace[489872239] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25824; }","duration":"165.971394ms","start":"2026-08-22T11:21:26.064812Z","end":"2026-08-22T11:21:26.230783Z","steps":["trace[489872239] 'range keys from in-memory index tree' (duration: 165.602637ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:21:26.535459Z","caller":"traceutil/trace.go:171","msg":"trace[1227453405] linearizableReadLoop","detail":"{readStateIndex:26661; appliedIndex:26660; }","duration":"256.136627ms","start":"2026-08-22T11:21:26.279297Z","end":"2026-08-22T11:21:26.535433Z","steps":["trace[1227453405] 'read index received' (duration: 255.988664ms)","trace[1227453405] 'applied index is now lower than readState.Index' (duration: 146.623µs)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:21:26.535615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.296689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:21:26.535598Z","caller":"traceutil/trace.go:171","msg":"trace[724061214] transaction","detail":"{read_only:false; response_revision:25826; number_of_response:1; }","duration":"300.072632ms","start":"2026-08-22T11:21:26.235485Z","end":"2026-08-22T11:21:26.535558Z","steps":["trace[724061214] 'process raft request' (duration: 299.732176ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:21:26.535647Z","caller":"traceutil/trace.go:171","msg":"trace[410904684] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25826; }","duration":"256.386491ms","start":"2026-08-22T11:21:26.279251Z","end":"2026-08-22T11:21:26.535637Z","steps":["trace[410904684] 'agreement among raft nodes before linearized reading' (duration: 256.2835ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:21:26.535802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:21:26.235469Z","time spent":"300.219224ms","remote":"127.0.0.1:50596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T11:21:26.578392Z","caller":"traceutil/trace.go:171","msg":"trace[1138910347] transaction","detail":"{read_only:false; response_revision:25827; number_of_response:1; }","duration":"212.393756ms","start":"2026-08-22T11:21:26.365961Z","end":"2026-08-22T11:21:26.578354Z","steps":["trace[1138910347] 'process raft request' (duration: 212.09597ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:21:27.008771Z","caller":"traceutil/trace.go:171","msg":"trace[2015657266] linearizableReadLoop","detail":"{readStateIndex:26666; appliedIndex:26665; }","duration":"119.558721ms","start":"2026-08-22T11:21:26.889185Z","end":"2026-08-22T11:21:27.008744Z","steps":["trace[2015657266] 'read index received' (duration: 118.610144ms)","trace[2015657266] 'applied index is now lower than readState.Index' (duration: 947.447µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:21:27.008803Z","caller":"traceutil/trace.go:171","msg":"trace[410911783] transaction","detail":"{read_only:false; response_revision:25830; number_of_response:1; }","duration":"121.519909ms","start":"2026-08-22T11:21:26.887255Z","end":"2026-08-22T11:21:27.008775Z","steps":["trace[410911783] 'process raft request' (duration: 120.465809ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:21:27.008969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.766975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-08-22T11:21:27.009003Z","caller":"traceutil/trace.go:171","msg":"trace[38853275] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:25830; }","duration":"119.833876ms","start":"2026-08-22T11:21:26.88916Z","end":"2026-08-22T11:21:27.008994Z","steps":["trace[38853275] 'agreement among raft nodes before linearized reading' (duration: 119.680563ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:21:27.293123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.946554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:573"} {"level":"info","ts":"2026-08-22T11:21:27.293213Z","caller":"traceutil/trace.go:171","msg":"trace[737636018] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:25830; }","duration":"116.078266ms","start":"2026-08-22T11:21:27.177109Z","end":"2026-08-22T11:21:27.293187Z","steps":["trace[737636018] 'range keys from in-memory index tree' (duration: 115.76028ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:21:27.96699Z","caller":"traceutil/trace.go:171","msg":"trace[1961715056] linearizableReadLoop","detail":"{readStateIndex:26673; appliedIndex:26672; }","duration":"139.213904ms","start":"2026-08-22T11:21:27.82775Z","end":"2026-08-22T11:21:27.966964Z","steps":["trace[1961715056] 'read index received' (duration: 138.942219ms)","trace[1961715056] 'applied index is now lower than readState.Index' (duration: 270.285µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:21:27.96706Z","caller":"traceutil/trace.go:171","msg":"trace[804188092] transaction","detail":"{read_only:false; response_revision:25837; number_of_response:1; }","duration":"139.945259ms","start":"2026-08-22T11:21:27.827091Z","end":"2026-08-22T11:21:27.967036Z","steps":["trace[804188092] 'process raft request' (duration: 139.658843ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:21:27.967242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.466919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-08-22T11:21:27.967529Z","caller":"traceutil/trace.go:171","msg":"trace[447682577] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25837; }","duration":"139.790806ms","start":"2026-08-22T11:21:27.827714Z","end":"2026-08-22T11:21:27.967504Z","steps":["trace[447682577] 'agreement among raft nodes before linearized reading' (duration: 139.352197ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:21:28.174182Z","caller":"traceutil/trace.go:171","msg":"trace[1050396954] linearizableReadLoop","detail":"{readStateIndex:26675; appliedIndex:26673; }","duration":"122.526068ms","start":"2026-08-22T11:21:28.051632Z","end":"2026-08-22T11:21:28.174158Z","steps":["trace[1050396954] 'read index received' (duration: 40.01471ms)","trace[1050396954] 'applied index is now lower than readState.Index' (duration: 82.510288ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:21:28.174198Z","caller":"traceutil/trace.go:171","msg":"trace[360806082] transaction","detail":"{read_only:false; response_revision:25838; number_of_response:1; }","duration":"202.61151ms","start":"2026-08-22T11:21:27.971562Z","end":"2026-08-22T11:21:28.174173Z","steps":["trace[360806082] 'process raft request' (duration: 119.994721ms)","trace[360806082] 'compare' (duration: 82.396835ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:21:28.174351Z","caller":"traceutil/trace.go:171","msg":"trace[1808501703] transaction","detail":"{read_only:false; response_revision:25839; number_of_response:1; }","duration":"190.910858ms","start":"2026-08-22T11:21:27.983407Z","end":"2026-08-22T11:21:28.174318Z","steps":["trace[1808501703] 'process raft request' (duration: 190.704914ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:21:28.174417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.766572ms","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-22T11:21:28.174526Z","caller":"traceutil/trace.go:171","msg":"trace[1797817289] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25839; }","duration":"122.936215ms","start":"2026-08-22T11:21:28.051564Z","end":"2026-08-22T11:21:28.1745Z","steps":["trace[1797817289] 'agreement among raft nodes before linearized reading' (duration: 122.747232ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:21:28.457882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.605509ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T11:21:28.457977Z","caller":"traceutil/trace.go:171","msg":"trace[1593504519] linearizableReadLoop","detail":"{readStateIndex:26676; appliedIndex:26675; }","duration":"180.664873ms","start":"2026-08-22T11:21:28.277299Z","end":"2026-08-22T11:21:28.457963Z","steps":["trace[1593504519] 'read index received' (duration: 46.84373ms)","trace[1593504519] 'applied index is now lower than readState.Index' (duration: 133.820213ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:21:28.458054Z","caller":"traceutil/trace.go:171","msg":"trace[765932543] transaction","detail":"{read_only:false; response_revision:25840; number_of_response:1; }","duration":"203.663781ms","start":"2026-08-22T11:21:28.254381Z","end":"2026-08-22T11:21:28.458045Z","steps":["trace[765932543] 'process raft request' (duration: 69.842348ms)","trace[765932543] 'compare' (duration: 133.473966ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:21:28.458263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.946529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:21:28.458345Z","caller":"traceutil/trace.go:171","msg":"trace[304813057] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25840; }","duration":"181.079671ms","start":"2026-08-22T11:21:28.277247Z","end":"2026-08-22T11:21:28.458327Z","steps":["trace[304813057] 'agreement among raft nodes before linearized reading' (duration: 180.929818ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:21:28.733868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.858205ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T11:21:28.733977Z","caller":"traceutil/trace.go:171","msg":"trace[1774581879] transaction","detail":"{read_only:false; response_revision:25842; number_of_response:1; }","duration":"191.06856ms","start":"2026-08-22T11:21:28.542887Z","end":"2026-08-22T11:21:28.733955Z","steps":["trace[1774581879] 'process raft request' (duration: 58.041863ms)","trace[1774581879] 'compare' (duration: 132.543528ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:21:28.757836Z","caller":"traceutil/trace.go:171","msg":"trace[1318029371] transaction","detail":"{read_only:false; response_revision:25843; number_of_response:1; }","duration":"174.894743ms","start":"2026-08-22T11:21:28.582905Z","end":"2026-08-22T11:21:28.7578Z","steps":["trace[1318029371] 'process raft request' (duration: 174.534607ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:21:28.75808Z","caller":"traceutil/trace.go:171","msg":"trace[1920750700] transaction","detail":"{read_only:false; response_revision:25844; number_of_response:1; }","duration":"174.080878ms","start":"2026-08-22T11:21:28.583956Z","end":"2026-08-22T11:21:28.758037Z","steps":["trace[1920750700] 'process raft request' (duration: 173.770102ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:21:29.847457Z","caller":"traceutil/trace.go:171","msg":"trace[2029962893] transaction","detail":"{read_only:false; response_revision:25848; number_of_response:1; }","duration":"209.192465ms","start":"2026-08-22T11:21:29.638228Z","end":"2026-08-22T11:21:29.847421Z","steps":["trace[2029962893] 'process raft request' (duration: 147.178107ms)","trace[2029962893] 'compare' (duration: 61.692062ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:22:14.609093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.69428ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T11:22:14.609211Z","caller":"traceutil/trace.go:171","msg":"trace[2140989352] linearizableReadLoop","detail":"{readStateIndex:27035; appliedIndex:27034; }","duration":"117.629325ms","start":"2026-08-22T11:22:14.491565Z","end":"2026-08-22T11:22:14.609194Z","steps":["trace[2140989352] 'read index received' (duration: 13.807082ms)","trace[2140989352] 'applied index is now lower than readState.Index' (duration: 103.821103ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:22:14.609309Z","caller":"traceutil/trace.go:171","msg":"trace[1853733187] transaction","detail":"{read_only:false; response_revision:26189; number_of_response:1; }","duration":"142.266713ms","start":"2026-08-22T11:22:14.466997Z","end":"2026-08-22T11:22:14.609264Z","steps":["trace[1853733187] 'process raft request' (duration: 38.314018ms)","trace[1853733187] 'compare' (duration: 103.475286ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:22:14.609548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.956442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:22:14.609715Z","caller":"traceutil/trace.go:171","msg":"trace[184518060] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0; response_count:0; response_revision:26189; }","duration":"118.038713ms","start":"2026-08-22T11:22:14.491539Z","end":"2026-08-22T11:22:14.609578Z","steps":["trace[184518060] 'agreement among raft nodes before linearized reading' (duration: 117.745067ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:22:23.601301Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23399} {"level":"info","ts":"2026-08-22T11:22:23.741724Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23399,"took":"137.487793ms","hash":177945817,"current-db-size-bytes":58789888,"current-db-size":"59 MB","current-db-size-in-use-bytes":39698432,"current-db-size-in-use":"40 MB"} {"level":"info","ts":"2026-08-22T11:22:23.741781Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":177945817,"revision":23399,"compact-revision":20451}