{"level":"warn","ts":"2026-08-12T11:04:42.17665Z","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-12T11:04:42.176734Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.223: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.19.213.223:2380","--initial-cluster=instance=https://199.19.213.223:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.223:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.223: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-12T11:04:42.176795Z","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-12T11:04:42.176809Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.223:2380"]} {"level":"info","ts":"2026-08-12T11:04:42.176826Z","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-12T11:04:42.178637Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.223:2379"]} {"level":"info","ts":"2026-08-12T11:04:42.178808Z","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.19.213.223:2380"],"listen-peer-urls":["https://199.19.213.223:2380"],"advertise-client-urls":["https://199.19.213.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.223: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-12T11:04:42.181279Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.064741ms"} {"level":"info","ts":"2026-08-12T11:04:42.186874Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"12dd93b2fab6fcb","cluster-id":"8ac3bdb9c2c26b53"} {"level":"info","ts":"2026-08-12T11:04:42.186946Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"12dd93b2fab6fcb switched to configuration voters=()"} {"level":"info","ts":"2026-08-12T11:04:42.186993Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"12dd93b2fab6fcb became follower at term 0"} {"level":"info","ts":"2026-08-12T11:04:42.187012Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 12dd93b2fab6fcb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-12T11:04:42.187032Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"12dd93b2fab6fcb became follower at term 1"} {"level":"info","ts":"2026-08-12T11:04:42.187084Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"12dd93b2fab6fcb switched to configuration voters=(84962816215969739)"} {"level":"warn","ts":"2026-08-12T11:04:42.192357Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-12T11:04:42.196111Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-12T11:04:42.197665Z","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-12T11:04:42.200541Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"12dd93b2fab6fcb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-12T11:04:42.200714Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"12dd93b2fab6fcb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-12T11:04:42.200814Z","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-12T11:04:42.200883Z","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-12T11:04:42.200896Z","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-12T11:04:42.201624Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"12dd93b2fab6fcb switched to configuration voters=(84962816215969739)"} {"level":"info","ts":"2026-08-12T11:04:42.20176Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8ac3bdb9c2c26b53","local-member-id":"12dd93b2fab6fcb","added-peer-id":"12dd93b2fab6fcb","added-peer-peer-urls":["https://199.19.213.223:2380"]} {"level":"info","ts":"2026-08-12T11:04:42.203879Z","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-12T11:04:42.203992Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.223:2380"} {"level":"info","ts":"2026-08-12T11:04:42.20404Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.223:2380"} {"level":"info","ts":"2026-08-12T11:04:42.204117Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"12dd93b2fab6fcb","initial-advertise-peer-urls":["https://199.19.213.223:2380"],"listen-peer-urls":["https://199.19.213.223:2380"],"advertise-client-urls":["https://199.19.213.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-12T11:04:42.204168Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-12T11:04:42.988164Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"12dd93b2fab6fcb is starting a new election at term 1"} {"level":"info","ts":"2026-08-12T11:04:42.98826Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"12dd93b2fab6fcb became pre-candidate at term 1"} {"level":"info","ts":"2026-08-12T11:04:42.98829Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"12dd93b2fab6fcb received MsgPreVoteResp from 12dd93b2fab6fcb at term 1"} {"level":"info","ts":"2026-08-12T11:04:42.988312Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"12dd93b2fab6fcb became candidate at term 2"} {"level":"info","ts":"2026-08-12T11:04:42.988333Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"12dd93b2fab6fcb received MsgVoteResp from 12dd93b2fab6fcb at term 2"} {"level":"info","ts":"2026-08-12T11:04:42.988353Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"12dd93b2fab6fcb became leader at term 2"} {"level":"info","ts":"2026-08-12T11:04:42.988371Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 12dd93b2fab6fcb elected leader 12dd93b2fab6fcb at term 2"} {"level":"info","ts":"2026-08-12T11:04:42.989655Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-12T11:04:42.990597Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-12T11:04:42.990597Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"12dd93b2fab6fcb","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.223:2379]}","request-path":"/0/members/12dd93b2fab6fcb/attributes","cluster-id":"8ac3bdb9c2c26b53","publish-timeout":"7s"} {"level":"info","ts":"2026-08-12T11:04:42.990627Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-12T11:04:42.99092Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-12T11:04:42.991003Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-12T11:04:42.99202Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8ac3bdb9c2c26b53","local-member-id":"12dd93b2fab6fcb","cluster-version":"3.5"} {"level":"info","ts":"2026-08-12T11:04:42.992119Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-12T11:04:42.992151Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-12T11:04:42.996499Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.223:2379"} {"level":"info","ts":"2026-08-12T11:04:43.000203Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-12T11:04:58.065176Z","caller":"traceutil/trace.go:171","msg":"trace[1062171815] linearizableReadLoop","detail":"{readStateIndex:382; appliedIndex:381; }","duration":"104.427736ms","start":"2026-08-12T11:04:57.960719Z","end":"2026-08-12T11:04:58.065146Z","steps":["trace[1062171815] 'read index received' (duration: 104.253972ms)","trace[1062171815] 'applied index is now lower than readState.Index' (duration: 172.454µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:04:58.065298Z","caller":"traceutil/trace.go:171","msg":"trace[1781945124] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"165.79896ms","start":"2026-08-12T11:04:57.899458Z","end":"2026-08-12T11:04:58.065257Z","steps":["trace[1781945124] 'process raft request' (duration: 165.491684ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:04:58.065424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.636929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T11:04:58.065559Z","caller":"traceutil/trace.go:171","msg":"trace[351125958] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:368; }","duration":"104.895025ms","start":"2026-08-12T11:04:57.960639Z","end":"2026-08-12T11:04:58.065534Z","steps":["trace[351125958] 'agreement among raft nodes before linearized reading' (duration: 104.636429ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:04:58.2078Z","caller":"traceutil/trace.go:171","msg":"trace[1444373428] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"132.010458ms","start":"2026-08-12T11:04:58.075756Z","end":"2026-08-12T11:04:58.207766Z","steps":["trace[1444373428] 'process raft request' (duration: 61.856724ms)","trace[1444373428] 'compare' (duration: 70.013531ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:05:23.013039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.754432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-12T11:05:23.013085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.254552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T11:05:23.013129Z","caller":"traceutil/trace.go:171","msg":"trace[1022119672] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:580; }","duration":"104.889864ms","start":"2026-08-12T11:05:22.908217Z","end":"2026-08-12T11:05:23.013107Z","steps":["trace[1022119672] 'count revisions from in-memory index tree' (duration: 104.683811ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:05:23.01316Z","caller":"traceutil/trace.go:171","msg":"trace[2120596056] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:580; }","duration":"105.377354ms","start":"2026-08-12T11:05:22.907765Z","end":"2026-08-12T11:05:23.013142Z","steps":["trace[2120596056] 'range keys from in-memory index tree' (duration: 105.1755ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:05:23.028473Z","caller":"traceutil/trace.go:171","msg":"trace[670041690] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"117.631911ms","start":"2026-08-12T11:05:22.910825Z","end":"2026-08-12T11:05:23.028457Z","steps":["trace[670041690] 'process raft request' (duration: 116.877085ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:05:23.199653Z","caller":"traceutil/trace.go:171","msg":"trace[238586583] linearizableReadLoop","detail":"{readStateIndex:606; appliedIndex:605; }","duration":"100.762774ms","start":"2026-08-12T11:05:23.098875Z","end":"2026-08-12T11:05:23.199638Z","steps":["trace[238586583] 'read index received' (duration: 42.909857ms)","trace[238586583] 'applied index is now lower than readState.Index' (duration: 57.851867ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:05:23.199776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.211772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T11:05:23.199797Z","caller":"traceutil/trace.go:171","msg":"trace[1847286980] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:583; }","duration":"147.263843ms","start":"2026-08-12T11:05:23.052527Z","end":"2026-08-12T11:05:23.199791Z","steps":["trace[1847286980] 'agreement among raft nodes before linearized reading' (duration: 147.206682ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:05:23.199789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.642586ms","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:5"} {"level":"warn","ts":"2026-08-12T11:05:23.199801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.657306ms","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\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T11:05:23.199865Z","caller":"traceutil/trace.go:171","msg":"trace[452904539] 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:583; }","duration":"124.778428ms","start":"2026-08-12T11:05:23.07507Z","end":"2026-08-12T11:05:23.199849Z","steps":["trace[452904539] 'agreement among raft nodes before linearized reading' (duration: 124.602665ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:05:23.199888Z","caller":"traceutil/trace.go:171","msg":"trace[885829252] 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:583; }","duration":"124.789339ms","start":"2026-08-12T11:05:23.075076Z","end":"2026-08-12T11:05:23.199866Z","steps":["trace[885829252] 'agreement among raft nodes before linearized reading' (duration: 124.613835ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:05:23.200034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.487793ms","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\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T11:05:23.200716Z","caller":"traceutil/trace.go:171","msg":"trace[2102184724] 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:583; }","duration":"125.166566ms","start":"2026-08-12T11:05:23.075508Z","end":"2026-08-12T11:05:23.200674Z","steps":["trace[2102184724] 'agreement among raft nodes before linearized reading' (duration: 124.445812ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:05:23.200206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.990266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T11:05:23.20097Z","caller":"traceutil/trace.go:171","msg":"trace[1444847585] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:583; }","duration":"149.77362ms","start":"2026-08-12T11:05:23.05118Z","end":"2026-08-12T11:05:23.200953Z","steps":["trace[1444847585] 'agreement among raft nodes before linearized reading' (duration: 148.970145ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:05:23.199733Z","caller":"traceutil/trace.go:171","msg":"trace[312238978] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"106.73048ms","start":"2026-08-12T11:05:23.092982Z","end":"2026-08-12T11:05:23.199712Z","steps":["trace[312238978] 'process raft request' (duration: 48.855892ms)","trace[312238978] 'compare' (duration: 57.715285ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:05:23.761652Z","caller":"traceutil/trace.go:171","msg":"trace[44345697] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"264.795251ms","start":"2026-08-12T11:05:23.496823Z","end":"2026-08-12T11:05:23.761619Z","steps":["trace[44345697] 'process raft request' (duration: 264.561936ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:05:23.936524Z","caller":"traceutil/trace.go:171","msg":"trace[122148440] linearizableReadLoop","detail":"{readStateIndex:608; appliedIndex:606; }","duration":"239.706647ms","start":"2026-08-12T11:05:23.69679Z","end":"2026-08-12T11:05:23.936497Z","steps":["trace[122148440] 'read index received' (duration: 64.76926ms)","trace[122148440] 'applied index is now lower than readState.Index' (duration: 174.936107ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:05:23.93663Z","caller":"traceutil/trace.go:171","msg":"trace[1814445929] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"321.146399ms","start":"2026-08-12T11:05:23.615456Z","end":"2026-08-12T11:05:23.936603Z","steps":["trace[1814445929] 'process raft request' (duration: 320.495275ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:05:23.93676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.977902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-12T11:05:23.937239Z","caller":"traceutil/trace.go:171","msg":"trace[400785132] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:585; }","duration":"240.501252ms","start":"2026-08-12T11:05:23.696724Z","end":"2026-08-12T11:05:23.937226Z","steps":["trace[400785132] 'agreement among raft nodes before linearized reading' (duration: 239.918571ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:05:23.938522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:05:23.615401Z","time spent":"321.540166ms","remote":"127.0.0.1:37432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:05:38.050271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.395621ms","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-12T11:05:38.050436Z","caller":"traceutil/trace.go:171","msg":"trace[1493103420] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:741; }","duration":"225.623615ms","start":"2026-08-12T11:05:37.824784Z","end":"2026-08-12T11:05:38.050408Z","steps":["trace[1493103420] 'range keys from in-memory index tree' (duration: 225.086655ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:06:10.377962Z","caller":"traceutil/trace.go:171","msg":"trace[1252671036] transaction","detail":"{read_only:false; response_revision:1152; number_of_response:1; }","duration":"101.915631ms","start":"2026-08-12T11:06:10.276019Z","end":"2026-08-12T11:06:10.377934Z","steps":["trace[1252671036] 'process raft request' (duration: 101.747257ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:06:10.417121Z","caller":"traceutil/trace.go:171","msg":"trace[272880028] linearizableReadLoop","detail":"{readStateIndex:1188; appliedIndex:1186; }","duration":"134.595952ms","start":"2026-08-12T11:06:10.282499Z","end":"2026-08-12T11:06:10.417095Z","steps":["trace[272880028] 'read index received' (duration: 95.223841ms)","trace[272880028] 'applied index is now lower than readState.Index' (duration: 39.370891ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:06:10.417229Z","caller":"traceutil/trace.go:171","msg":"trace[1197300924] transaction","detail":"{read_only:false; response_revision:1153; number_of_response:1; }","duration":"139.14633ms","start":"2026-08-12T11:06:10.278056Z","end":"2026-08-12T11:06:10.417202Z","steps":["trace[1197300924] 'process raft request' (duration: 138.881535ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:06:10.417322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.796456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-12T11:06:10.417357Z","caller":"traceutil/trace.go:171","msg":"trace[2066654615] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1153; }","duration":"134.873678ms","start":"2026-08-12T11:06:10.282473Z","end":"2026-08-12T11:06:10.417347Z","steps":["trace[2066654615] 'agreement among raft nodes before linearized reading' (duration: 134.721955ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:06:31.462883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.870223ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-12T11:06:31.481706Z","caller":"traceutil/trace.go:171","msg":"trace[426219126] linearizableReadLoop","detail":"{readStateIndex:1453; appliedIndex:1452; }","duration":"127.007634ms","start":"2026-08-12T11:06:31.354669Z","end":"2026-08-12T11:06:31.481676Z","steps":["trace[426219126] 'read index received' (duration: 126.79014ms)","trace[426219126] 'applied index is now lower than readState.Index' (duration: 216.014µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:06:31.481752Z","caller":"traceutil/trace.go:171","msg":"trace[399007223] transaction","detail":"{read_only:false; response_revision:1408; number_of_response:1; }","duration":"129.3373ms","start":"2026-08-12T11:06:31.352375Z","end":"2026-08-12T11:06:31.481712Z","steps":["trace[399007223] 'process raft request' (duration: 129.099556ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:06:31.48192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.230419ms","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-12T11:06:31.481961Z","caller":"traceutil/trace.go:171","msg":"trace[1870068785] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1408; }","duration":"127.323371ms","start":"2026-08-12T11:06:31.354627Z","end":"2026-08-12T11:06:31.48195Z","steps":["trace[1870068785] 'agreement among raft nodes before linearized reading' (duration: 127.143907ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:06:55.606523Z","caller":"traceutil/trace.go:171","msg":"trace[582365760] transaction","detail":"{read_only:false; response_revision:1814; number_of_response:1; }","duration":"173.470657ms","start":"2026-08-12T11:06:55.433024Z","end":"2026-08-12T11:06:55.606494Z","steps":["trace[582365760] 'process raft request' (duration: 173.273833ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:06:55.619428Z","caller":"traceutil/trace.go:171","msg":"trace[1924383211] transaction","detail":"{read_only:false; response_revision:1815; number_of_response:1; }","duration":"135.370375ms","start":"2026-08-12T11:06:55.484033Z","end":"2026-08-12T11:06:55.619403Z","steps":["trace[1924383211] 'process raft request' (duration: 135.088929ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:07:08.986859Z","caller":"traceutil/trace.go:171","msg":"trace[891794051] transaction","detail":"{read_only:false; response_revision:1929; number_of_response:1; }","duration":"301.358167ms","start":"2026-08-12T11:07:08.685422Z","end":"2026-08-12T11:07:08.986781Z","steps":["trace[891794051] 'process raft request' (duration: 283.243039ms)","trace[891794051] 'compare' (duration: 17.871533ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:07:08.987165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:07:08.6854Z","time spent":"301.606592ms","remote":"127.0.0.1:37592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:07:08.987162Z","caller":"traceutil/trace.go:171","msg":"trace[1552078527] linearizableReadLoop","detail":"{readStateIndex:2004; appliedIndex:2001; }","duration":"275.290046ms","start":"2026-08-12T11:07:08.711845Z","end":"2026-08-12T11:07:08.987135Z","steps":["trace[1552078527] 'read index received' (duration: 44.272742ms)","trace[1552078527] 'applied index is now lower than readState.Index' (duration: 231.016214ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:07:08.987188Z","caller":"traceutil/trace.go:171","msg":"trace[2126278909] transaction","detail":"{read_only:false; response_revision:1930; number_of_response:1; }","duration":"300.493141ms","start":"2026-08-12T11:07:08.68666Z","end":"2026-08-12T11:07:08.987153Z","steps":["trace[2126278909] 'process raft request' (duration: 300.062973ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:07:08.987384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:07:08.686636Z","time spent":"300.636924ms","remote":"127.0.0.1:37592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:07:08.98743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.561142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11989"} {"level":"info","ts":"2026-08-12T11:07:08.987387Z","caller":"traceutil/trace.go:171","msg":"trace[14953468] transaction","detail":"{read_only:false; response_revision:1931; number_of_response:1; }","duration":"132.894318ms","start":"2026-08-12T11:07:08.854416Z","end":"2026-08-12T11:07:08.98731Z","steps":["trace[14953468] 'process raft request' (duration: 132.633412ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:07:08.987473Z","caller":"traceutil/trace.go:171","msg":"trace[21281493] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1931; }","duration":"275.653924ms","start":"2026-08-12T11:07:08.711808Z","end":"2026-08-12T11:07:08.987462Z","steps":["trace[21281493] 'agreement among raft nodes before linearized reading' (duration: 275.44688ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:07:39.373399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.515079ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-12T11:07:39.373533Z","caller":"traceutil/trace.go:171","msg":"trace[2052964158] linearizableReadLoop","detail":"{readStateIndex:2191; appliedIndex:2190; }","duration":"204.80775ms","start":"2026-08-12T11:07:39.168707Z","end":"2026-08-12T11:07:39.373515Z","steps":["trace[2052964158] 'read index received' (duration: 59.087836ms)","trace[2052964158] 'applied index is now lower than readState.Index' (duration: 145.718574ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:07:39.373545Z","caller":"traceutil/trace.go:171","msg":"trace[2044039139] transaction","detail":"{read_only:false; response_revision:2111; number_of_response:1; }","duration":"207.40714ms","start":"2026-08-12T11:07:39.166112Z","end":"2026-08-12T11:07:39.373519Z","steps":["trace[2044039139] 'process raft request' (duration: 61.663156ms)","trace[2044039139] 'compare' (duration: 145.359677ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:07:39.373645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.934393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T11:07:39.373681Z","caller":"traceutil/trace.go:171","msg":"trace[2137991618] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2111; }","duration":"204.969764ms","start":"2026-08-12T11:07:39.168702Z","end":"2026-08-12T11:07:39.373672Z","steps":["trace[2137991618] 'agreement among raft nodes before linearized reading' (duration: 204.904312ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:07:39.944465Z","caller":"traceutil/trace.go:171","msg":"trace[910669890] transaction","detail":"{read_only:false; response_revision:2114; number_of_response:1; }","duration":"117.746005ms","start":"2026-08-12T11:07:39.826677Z","end":"2026-08-12T11:07:39.944423Z","steps":["trace[910669890] 'process raft request' (duration: 117.46928ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:07:45.521874Z","caller":"traceutil/trace.go:171","msg":"trace[970465703] linearizableReadLoop","detail":"{readStateIndex:2224; appliedIndex:2223; }","duration":"108.826023ms","start":"2026-08-12T11:07:45.413026Z","end":"2026-08-12T11:07:45.521852Z","steps":["trace[970465703] 'read index received' (duration: 108.617339ms)","trace[970465703] 'applied index is now lower than readState.Index' (duration: 207.764µs)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:07:45.522059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.021538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-08-12T11:07:45.522098Z","caller":"traceutil/trace.go:171","msg":"trace[1912376613] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2143; }","duration":"109.094599ms","start":"2026-08-12T11:07:45.412991Z","end":"2026-08-12T11:07:45.522086Z","steps":["trace[1912376613] 'agreement among raft nodes before linearized reading' (duration: 108.949126ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:07:45.522308Z","caller":"traceutil/trace.go:171","msg":"trace[811056711] transaction","detail":"{read_only:false; response_revision:2143; number_of_response:1; }","duration":"121.179441ms","start":"2026-08-12T11:07:45.401088Z","end":"2026-08-12T11:07:45.522267Z","steps":["trace[811056711] 'process raft request' (duration: 120.61739ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:07:46.106603Z","caller":"traceutil/trace.go:171","msg":"trace[933887563] transaction","detail":"{read_only:false; response_revision:2145; number_of_response:1; }","duration":"166.040094ms","start":"2026-08-12T11:07:45.940533Z","end":"2026-08-12T11:07:46.106573Z","steps":["trace[933887563] 'process raft request' (duration: 165.848131ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:07:46.125492Z","caller":"traceutil/trace.go:171","msg":"trace[1919254176] transaction","detail":"{read_only:false; response_revision:2146; number_of_response:1; }","duration":"164.401343ms","start":"2026-08-12T11:07:45.961063Z","end":"2026-08-12T11:07:46.125465Z","steps":["trace[1919254176] 'process raft request' (duration: 164.202509ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:07:46.125671Z","caller":"traceutil/trace.go:171","msg":"trace[1467736650] transaction","detail":"{read_only:false; response_revision:2147; number_of_response:1; }","duration":"117.44831ms","start":"2026-08-12T11:07:46.008203Z","end":"2026-08-12T11:07:46.125651Z","steps":["trace[1467736650] 'process raft request' (duration: 117.211795ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:07:46.125673Z","caller":"traceutil/trace.go:171","msg":"trace[1926245507] linearizableReadLoop","detail":"{readStateIndex:2228; appliedIndex:2225; }","duration":"114.116605ms","start":"2026-08-12T11:07:46.011512Z","end":"2026-08-12T11:07:46.125629Z","steps":["trace[1926245507] 'read index received' (duration: 94.854555ms)","trace[1926245507] 'applied index is now lower than readState.Index' (duration: 19.25747ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:07:46.126145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.600334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:489"} {"level":"info","ts":"2026-08-12T11:07:46.126465Z","caller":"traceutil/trace.go:171","msg":"trace[643069307] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2147; }","duration":"114.717776ms","start":"2026-08-12T11:07:46.011503Z","end":"2026-08-12T11:07:46.12622Z","steps":["trace[643069307] 'agreement among raft nodes before linearized reading' (duration: 114.282818ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:07:51.375195Z","caller":"traceutil/trace.go:171","msg":"trace[1689568740] linearizableReadLoop","detail":"{readStateIndex:2255; appliedIndex:2254; }","duration":"130.692465ms","start":"2026-08-12T11:07:51.244478Z","end":"2026-08-12T11:07:51.375171Z","steps":["trace[1689568740] 'read index received' (duration: 130.363808ms)","trace[1689568740] 'applied index is now lower than readState.Index' (duration: 327.696µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:07:51.375267Z","caller":"traceutil/trace.go:171","msg":"trace[1900535234] transaction","detail":"{read_only:false; response_revision:2173; number_of_response:1; }","duration":"188.043807ms","start":"2026-08-12T11:07:51.187176Z","end":"2026-08-12T11:07:51.37522Z","steps":["trace[1900535234] 'process raft request' (duration: 187.842374ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:07:51.375521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.032221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-12T11:07:51.37563Z","caller":"traceutil/trace.go:171","msg":"trace[930472878] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2173; }","duration":"131.189984ms","start":"2026-08-12T11:07:51.244422Z","end":"2026-08-12T11:07:51.375612Z","steps":["trace[930472878] 'agreement among raft nodes before linearized reading' (duration: 130.948109ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:08:34.401602Z","caller":"traceutil/trace.go:171","msg":"trace[581077939] transaction","detail":"{read_only:false; response_revision:2580; number_of_response:1; }","duration":"133.69525ms","start":"2026-08-12T11:08:34.267882Z","end":"2026-08-12T11:08:34.401577Z","steps":["trace[581077939] 'process raft request' (duration: 133.556357ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:08:34.47695Z","caller":"traceutil/trace.go:171","msg":"trace[121943996] linearizableReadLoop","detail":"{readStateIndex:2681; appliedIndex:2678; }","duration":"104.009859ms","start":"2026-08-12T11:08:34.372915Z","end":"2026-08-12T11:08:34.476924Z","steps":["trace[121943996] 'read index received' (duration: 28.475848ms)","trace[121943996] 'applied index is now lower than readState.Index' (duration: 75.532981ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:08:34.476976Z","caller":"traceutil/trace.go:171","msg":"trace[289411874] transaction","detail":"{read_only:false; response_revision:2581; number_of_response:1; }","duration":"192.228365ms","start":"2026-08-12T11:08:34.284718Z","end":"2026-08-12T11:08:34.476947Z","steps":["trace[289411874] 'process raft request' (duration: 192.00392ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:08:34.477055Z","caller":"traceutil/trace.go:171","msg":"trace[1477044771] transaction","detail":"{read_only:false; response_revision:2582; number_of_response:1; }","duration":"181.677742ms","start":"2026-08-12T11:08:34.295354Z","end":"2026-08-12T11:08:34.477032Z","steps":["trace[1477044771] 'process raft request' (duration: 181.524309ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:08:34.47786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.908666ms","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-12T11:08:34.477969Z","caller":"traceutil/trace.go:171","msg":"trace[1355436171] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2582; }","duration":"105.070449ms","start":"2026-08-12T11:08:34.372876Z","end":"2026-08-12T11:08:34.477947Z","steps":["trace[1355436171] 'agreement among raft nodes before linearized reading' (duration: 104.146971ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:09:03.029637Z","caller":"traceutil/trace.go:171","msg":"trace[242553127] transaction","detail":"{read_only:false; response_revision:2759; number_of_response:1; }","duration":"137.198357ms","start":"2026-08-12T11:09:02.892399Z","end":"2026-08-12T11:09:03.029598Z","steps":["trace[242553127] 'process raft request' (duration: 136.895782ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:09:03.551421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.696694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-08-12T11:09:03.551506Z","caller":"traceutil/trace.go:171","msg":"trace[2117561982] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2760; }","duration":"114.826747ms","start":"2026-08-12T11:09:03.43666Z","end":"2026-08-12T11:09:03.551486Z","steps":["trace[2117561982] 'range keys from in-memory index tree' (duration: 114.521851ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:09:03.973258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.598412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-12T11:09:03.97334Z","caller":"traceutil/trace.go:171","msg":"trace[1326865781] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2761; }","duration":"205.688563ms","start":"2026-08-12T11:09:03.767633Z","end":"2026-08-12T11:09:03.973322Z","steps":["trace[1326865781] 'range keys from in-memory index tree' (duration: 205.49894ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:09:09.846535Z","caller":"traceutil/trace.go:171","msg":"trace[2128558161] transaction","detail":"{read_only:false; response_revision:2792; number_of_response:1; }","duration":"121.781632ms","start":"2026-08-12T11:09:09.724722Z","end":"2026-08-12T11:09:09.846503Z","steps":["trace[2128558161] 'process raft request' (duration: 121.531746ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:09:10.059543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.513786ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-12T11:09:10.059675Z","caller":"traceutil/trace.go:171","msg":"trace[1301552119] linearizableReadLoop","detail":"{readStateIndex:2900; appliedIndex:2899; }","duration":"104.344665ms","start":"2026-08-12T11:09:09.95531Z","end":"2026-08-12T11:09:10.059654Z","steps":["trace[1301552119] 'read index received' (duration: 55.401µs)","trace[1301552119] 'applied index is now lower than readState.Index' (duration: 104.287734ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:09:10.059731Z","caller":"traceutil/trace.go:171","msg":"trace[2130303593] transaction","detail":"{read_only:false; response_revision:2793; number_of_response:1; }","duration":"210.603547ms","start":"2026-08-12T11:09:09.849107Z","end":"2026-08-12T11:09:10.059711Z","steps":["trace[2130303593] 'process raft request' (duration: 72.789895ms)","trace[2130303593] 'compare' (duration: 137.370373ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:09:10.059798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.488568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T11:09:10.05984Z","caller":"traceutil/trace.go:171","msg":"trace[716858924] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2793; }","duration":"104.556239ms","start":"2026-08-12T11:09:09.955271Z","end":"2026-08-12T11:09:10.059827Z","steps":["trace[716858924] 'agreement among raft nodes before linearized reading' (duration: 104.485638ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:12:58.482273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.840945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"info","ts":"2026-08-12T11:12:58.482367Z","caller":"traceutil/trace.go:171","msg":"trace[1099650352] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4782; }","duration":"108.997507ms","start":"2026-08-12T11:12:58.37335Z","end":"2026-08-12T11:12:58.482347Z","steps":["trace[1099650352] 'range keys from in-memory index tree' (duration: 108.690832ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:13:01.490873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.38728ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-12T11:13:01.491242Z","caller":"traceutil/trace.go:171","msg":"trace[1204569778] transaction","detail":"{read_only:false; response_revision:4932; number_of_response:1; }","duration":"200.540948ms","start":"2026-08-12T11:13:01.290649Z","end":"2026-08-12T11:13:01.49119Z","steps":["trace[1204569778] 'process raft request' (duration: 46.747569ms)","trace[1204569778] 'compare' (duration: 153.291359ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:13:01.520591Z","caller":"traceutil/trace.go:171","msg":"trace[430442304] linearizableReadLoop","detail":"{readStateIndex:5119; appliedIndex:5117; }","duration":"189.087095ms","start":"2026-08-12T11:13:01.33148Z","end":"2026-08-12T11:13:01.520567Z","steps":["trace[430442304] 'read index received' (duration: 5.989536ms)","trace[430442304] 'applied index is now lower than readState.Index' (duration: 183.096599ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:13:01.520775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.287399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c92186b5-a16f-43a2-a73f-e709fd9bb080\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-08-12T11:13:01.520833Z","caller":"traceutil/trace.go:171","msg":"trace[53649513] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c92186b5-a16f-43a2-a73f-e709fd9bb080; range_end:; response_count:1; response_revision:4932; }","duration":"189.393992ms","start":"2026-08-12T11:13:01.331426Z","end":"2026-08-12T11:13:01.52082Z","steps":["trace[53649513] 'agreement among raft nodes before linearized reading' (duration: 189.232189ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:13:01.626296Z","caller":"traceutil/trace.go:171","msg":"trace[1864016002] transaction","detail":"{read_only:false; response_revision:4933; number_of_response:1; }","duration":"194.370229ms","start":"2026-08-12T11:13:01.431897Z","end":"2026-08-12T11:13:01.626267Z","steps":["trace[1864016002] 'process raft request' (duration: 194.150114ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:13:01.720877Z","caller":"traceutil/trace.go:171","msg":"trace[2096294063] transaction","detail":"{read_only:false; response_revision:4934; number_of_response:1; }","duration":"279.296369ms","start":"2026-08-12T11:13:01.441551Z","end":"2026-08-12T11:13:01.720847Z","steps":["trace[2096294063] 'process raft request' (duration: 276.178608ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:13:01.721119Z","caller":"traceutil/trace.go:171","msg":"trace[1176241535] transaction","detail":"{read_only:false; response_revision:4935; number_of_response:1; }","duration":"258.941344ms","start":"2026-08-12T11:13:01.462151Z","end":"2026-08-12T11:13:01.721092Z","steps":["trace[1176241535] 'process raft request' (duration: 258.642787ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:13:01.721249Z","caller":"traceutil/trace.go:171","msg":"trace[774550868] linearizableReadLoop","detail":"{readStateIndex:5123; appliedIndex:5119; }","duration":"200.590249ms","start":"2026-08-12T11:13:01.520647Z","end":"2026-08-12T11:13:01.721238Z","steps":["trace[774550868] 'read index received' (duration: 105.444289ms)","trace[774550868] 'applied index is now lower than readState.Index' (duration: 95.14513ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:13:01.721296Z","caller":"traceutil/trace.go:171","msg":"trace[460156935] transaction","detail":"{read_only:false; response_revision:4937; number_of_response:1; }","duration":"191.667136ms","start":"2026-08-12T11:13:01.529619Z","end":"2026-08-12T11:13:01.721286Z","steps":["trace[460156935] 'process raft request' (duration: 191.552554ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:13:01.721291Z","caller":"traceutil/trace.go:171","msg":"trace[2017474783] transaction","detail":"{read_only:false; response_revision:4936; number_of_response:1; }","duration":"249.818527ms","start":"2026-08-12T11:13:01.471452Z","end":"2026-08-12T11:13:01.72127Z","steps":["trace[2017474783] 'process raft request' (duration: 249.551041ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:13:01.721394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.272827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-c9htt.18cb0a760b9584dd\" ","response":"range_response_count:1 size:962"} {"level":"info","ts":"2026-08-12T11:13:01.72143Z","caller":"traceutil/trace.go:171","msg":"trace[561240154] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-c9htt.18cb0a760b9584dd; range_end:; response_count:1; response_revision:4937; }","duration":"388.365529ms","start":"2026-08-12T11:13:01.333055Z","end":"2026-08-12T11:13:01.721421Z","steps":["trace[561240154] 'agreement among raft nodes before linearized reading' (duration: 388.223117ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:13:01.721458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:13:01.333033Z","time spent":"388.41809ms","remote":"127.0.0.1:37414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":1,"response size":985,"request content":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-c9htt.18cb0a760b9584dd\" "} {"level":"warn","ts":"2026-08-12T11:13:03.652595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.511982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-12T11:13:03.652688Z","caller":"traceutil/trace.go:171","msg":"trace[784349465] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4976; }","duration":"156.672696ms","start":"2026-08-12T11:13:03.495994Z","end":"2026-08-12T11:13:03.652667Z","steps":["trace[784349465] 'range keys from in-memory index tree' (duration: 156.315539ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:13:09.512658Z","caller":"traceutil/trace.go:171","msg":"trace[631258116] transaction","detail":"{read_only:false; response_revision:5088; number_of_response:1; }","duration":"126.05339ms","start":"2026-08-12T11:13:09.38656Z","end":"2026-08-12T11:13:09.512613Z","steps":["trace[631258116] 'process raft request' (duration: 86.054043ms)","trace[631258116] 'compare' (duration: 39.784673ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:13:15.636762Z","caller":"traceutil/trace.go:171","msg":"trace[1177381479] transaction","detail":"{read_only:false; response_revision:5223; number_of_response:1; }","duration":"125.469599ms","start":"2026-08-12T11:13:15.511258Z","end":"2026-08-12T11:13:15.636727Z","steps":["trace[1177381479] 'process raft request' (duration: 125.214744ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:13:15.637261Z","caller":"traceutil/trace.go:171","msg":"trace[228308016] linearizableReadLoop","detail":"{readStateIndex:5418; appliedIndex:5418; }","duration":"122.039041ms","start":"2026-08-12T11:13:15.515183Z","end":"2026-08-12T11:13:15.637222Z","steps":["trace[228308016] 'read index received' (duration: 122.019241ms)","trace[228308016] 'applied index is now lower than readState.Index' (duration: 12.01µs)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:13:15.637654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.4529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-7tx9b\" ","response":"range_response_count:1 size:7818"} {"level":"info","ts":"2026-08-12T11:13:15.637731Z","caller":"traceutil/trace.go:171","msg":"trace[173912129] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-7tx9b; range_end:; response_count:1; response_revision:5223; }","duration":"122.562912ms","start":"2026-08-12T11:13:15.515146Z","end":"2026-08-12T11:13:15.637709Z","steps":["trace[173912129] 'agreement among raft nodes before linearized reading' (duration: 122.241565ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:13:15.887656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.617034ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-12T11:13:15.888127Z","caller":"traceutil/trace.go:171","msg":"trace[388064280] transaction","detail":"{read_only:false; response_revision:5226; number_of_response:1; }","duration":"249.120442ms","start":"2026-08-12T11:13:15.638966Z","end":"2026-08-12T11:13:15.888086Z","steps":["trace[388064280] 'process raft request' (duration: 248.906708ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:13:15.888349Z","caller":"traceutil/trace.go:171","msg":"trace[1346597169] transaction","detail":"{read_only:false; response_revision:5228; number_of_response:1; }","duration":"111.727442ms","start":"2026-08-12T11:13:15.776601Z","end":"2026-08-12T11:13:15.888328Z","steps":["trace[1346597169] 'process raft request' (duration: 111.377365ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:13:15.888411Z","caller":"traceutil/trace.go:171","msg":"trace[621085065] transaction","detail":"{read_only:false; response_revision:5224; number_of_response:1; }","duration":"370.803917ms","start":"2026-08-12T11:13:15.517593Z","end":"2026-08-12T11:13:15.888397Z","steps":["trace[621085065] 'process raft request' (duration: 211.324297ms)","trace[621085065] 'compare' (duration: 158.465441ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:13:15.888538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:13:15.517563Z","time spent":"370.88725ms","remote":"127.0.0.1:37596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1777,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:13:15.888648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.251005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-12T11:13:15.888723Z","caller":"traceutil/trace.go:171","msg":"trace[1705906551] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5230; }","duration":"161.391218ms","start":"2026-08-12T11:13:15.727313Z","end":"2026-08-12T11:13:15.888705Z","steps":["trace[1705906551] 'agreement among raft nodes before linearized reading' (duration: 161.084712ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:13:15.88831Z","caller":"traceutil/trace.go:171","msg":"trace[1434918670] linearizableReadLoop","detail":"{readStateIndex:5422; appliedIndex:5418; }","duration":"160.891278ms","start":"2026-08-12T11:13:15.727375Z","end":"2026-08-12T11:13:15.888266Z","steps":["trace[1434918670] 'read index received' (duration: 1.52166ms)","trace[1434918670] 'applied index is now lower than readState.Index' (duration: 159.365308ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:13:15.890266Z","caller":"traceutil/trace.go:171","msg":"trace[736161393] transaction","detail":"{read_only:false; response_revision:5227; number_of_response:1; }","duration":"243.106206ms","start":"2026-08-12T11:13:15.647109Z","end":"2026-08-12T11:13:15.890215Z","steps":["trace[736161393] 'process raft request' (duration: 240.815061ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:13:15.89421Z","caller":"traceutil/trace.go:171","msg":"trace[1537327833] transaction","detail":"{read_only:false; response_revision:5225; number_of_response:1; }","duration":"295.047515ms","start":"2026-08-12T11:13:15.599037Z","end":"2026-08-12T11:13:15.894085Z","steps":["trace[1537327833] 'process raft request' (duration: 288.742593ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:13:15.894261Z","caller":"traceutil/trace.go:171","msg":"trace[1121162462] transaction","detail":"{read_only:false; response_revision:5229; number_of_response:1; }","duration":"102.248818ms","start":"2026-08-12T11:13:15.79198Z","end":"2026-08-12T11:13:15.894229Z","steps":["trace[1121162462] 'process raft request' (duration: 96.034228ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:13:24.257746Z","caller":"traceutil/trace.go:171","msg":"trace[2055926351] transaction","detail":"{read_only:false; response_revision:5419; number_of_response:1; }","duration":"273.404464ms","start":"2026-08-12T11:13:23.984302Z","end":"2026-08-12T11:13:24.257706Z","steps":["trace[2055926351] 'process raft request' (duration: 273.349433ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:13:24.258117Z","caller":"traceutil/trace.go:171","msg":"trace[326556893] transaction","detail":"{read_only:false; response_revision:5418; number_of_response:1; }","duration":"275.231161ms","start":"2026-08-12T11:13:23.982845Z","end":"2026-08-12T11:13:24.258077Z","steps":["trace[326556893] 'process raft request' (duration: 194.848378ms)","trace[326556893] 'compare' (duration: 79.73955ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:13:24.414417Z","caller":"traceutil/trace.go:171","msg":"trace[1189680365] transaction","detail":"{read_only:false; response_revision:5420; number_of_response:1; }","duration":"118.635337ms","start":"2026-08-12T11:13:24.295745Z","end":"2026-08-12T11:13:24.41438Z","steps":["trace[1189680365] 'process raft request' (duration: 116.739271ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:14:43.020023Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2978} {"level":"info","ts":"2026-08-12T11:14:43.097716Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2978,"took":"75.726175ms","hash":174744533,"current-db-size-bytes":59273216,"current-db-size":"59 MB","current-db-size-in-use-bytes":34426880,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-12T11:14:43.0978Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":174744533,"revision":2978,"compact-revision":-1} {"level":"info","ts":"2026-08-12T11:19:32.10413Z","caller":"traceutil/trace.go:171","msg":"trace[1659087423] transaction","detail":"{read_only:false; response_revision:8212; number_of_response:1; }","duration":"113.32453ms","start":"2026-08-12T11:19:31.990778Z","end":"2026-08-12T11:19:32.104103Z","steps":["trace[1659087423] 'process raft request' (duration: 113.163377ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:19:43.026453Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6100} {"level":"info","ts":"2026-08-12T11:19:43.12785Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6100,"took":"99.537114ms","hash":1169648872,"current-db-size-bytes":59273216,"current-db-size":"59 MB","current-db-size-in-use-bytes":23142400,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-08-12T11:19:43.127899Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1169648872,"revision":6100,"compact-revision":2978} {"level":"info","ts":"2026-08-12T11:19:43.788976Z","caller":"traceutil/trace.go:171","msg":"trace[1838989484] transaction","detail":"{read_only:false; response_revision:8276; number_of_response:1; }","duration":"168.63015ms","start":"2026-08-12T11:19:43.620304Z","end":"2026-08-12T11:19:43.788934Z","steps":["trace[1838989484] 'process raft request' (duration: 140.136249ms)","trace[1838989484] 'compare' (duration: 28.293907ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:20:12.572483Z","caller":"traceutil/trace.go:171","msg":"trace[371967203] linearizableReadLoop","detail":"{readStateIndex:8781; appliedIndex:8780; }","duration":"248.092106ms","start":"2026-08-12T11:20:12.324367Z","end":"2026-08-12T11:20:12.572459Z","steps":["trace[371967203] 'read index received' (duration: 247.878242ms)","trace[371967203] 'applied index is now lower than readState.Index' (duration: 213.004µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:20:12.57261Z","caller":"traceutil/trace.go:171","msg":"trace[2036208443] transaction","detail":"{read_only:false; response_revision:8480; number_of_response:1; }","duration":"326.994082ms","start":"2026-08-12T11:20:12.245592Z","end":"2026-08-12T11:20:12.572586Z","steps":["trace[2036208443] 'process raft request' (duration: 326.709977ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:20:12.572752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:20:12.245566Z","time spent":"327.101245ms","remote":"127.0.0.1:37592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:20:12.572737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.3529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-08-12T11:20:12.572861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.296175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-12T11:20:12.572923Z","caller":"traceutil/trace.go:171","msg":"trace[1908820591] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8480; }","duration":"248.580475ms","start":"2026-08-12T11:20:12.324324Z","end":"2026-08-12T11:20:12.572904Z","steps":["trace[1908820591] 'agreement among raft nodes before linearized reading' (duration: 248.281849ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:20:12.572948Z","caller":"traceutil/trace.go:171","msg":"trace[2137794370] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8480; }","duration":"240.418707ms","start":"2026-08-12T11:20:12.332509Z","end":"2026-08-12T11:20:12.572927Z","steps":["trace[2137794370] 'agreement among raft nodes before linearized reading' (duration: 240.167743ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:20:15.693462Z","caller":"traceutil/trace.go:171","msg":"trace[2040828297] linearizableReadLoop","detail":"{readStateIndex:8798; appliedIndex:8797; }","duration":"150.98978ms","start":"2026-08-12T11:20:15.542446Z","end":"2026-08-12T11:20:15.693436Z","steps":["trace[2040828297] 'read index received' (duration: 150.881988ms)","trace[2040828297] 'applied index is now lower than readState.Index' (duration: 106.772µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:20:15.693521Z","caller":"traceutil/trace.go:171","msg":"trace[1036954693] transaction","detail":"{read_only:false; response_revision:8497; number_of_response:1; }","duration":"204.000234ms","start":"2026-08-12T11:20:15.489491Z","end":"2026-08-12T11:20:15.693491Z","steps":["trace[1036954693] 'process raft request' (duration: 203.771799ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:20:15.693745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.273185ms","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-12T11:20:15.693829Z","caller":"traceutil/trace.go:171","msg":"trace[2009246760] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8497; }","duration":"151.400737ms","start":"2026-08-12T11:20:15.542411Z","end":"2026-08-12T11:20:15.693812Z","steps":["trace[2009246760] 'agreement among raft nodes before linearized reading' (duration: 151.167992ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:20:15.700203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.906599ms","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-12T11:20:15.700315Z","caller":"traceutil/trace.go:171","msg":"trace[1032727264] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8498; }","duration":"119.065163ms","start":"2026-08-12T11:20:15.581226Z","end":"2026-08-12T11:20:15.700291Z","steps":["trace[1032727264] 'agreement among raft nodes before linearized reading' (duration: 118.752937ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:20:15.854238Z","caller":"traceutil/trace.go:171","msg":"trace[1226123040] transaction","detail":"{read_only:false; response_revision:8501; number_of_response:1; }","duration":"140.466275ms","start":"2026-08-12T11:20:15.713745Z","end":"2026-08-12T11:20:15.854211Z","steps":["trace[1226123040] 'process raft request' (duration: 140.404934ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:20:15.854531Z","caller":"traceutil/trace.go:171","msg":"trace[401696936] transaction","detail":"{read_only:false; response_revision:8500; number_of_response:1; }","duration":"150.782495ms","start":"2026-08-12T11:20:15.703715Z","end":"2026-08-12T11:20:15.854497Z","steps":["trace[401696936] 'process raft request' (duration: 91.11589ms)","trace[401696936] 'compare' (duration: 59.068683ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:20:25.404044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.882304ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-12T11:20:25.404182Z","caller":"traceutil/trace.go:171","msg":"trace[1144840027] linearizableReadLoop","detail":"{readStateIndex:8894; appliedIndex:8893; }","duration":"206.067454ms","start":"2026-08-12T11:20:25.198097Z","end":"2026-08-12T11:20:25.404164Z","steps":["trace[1144840027] 'read index received' (duration: 62.902157ms)","trace[1144840027] 'applied index is now lower than readState.Index' (duration: 143.164187ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:20:25.404335Z","caller":"traceutil/trace.go:171","msg":"trace[199757329] transaction","detail":"{read_only:false; response_revision:8588; number_of_response:1; }","duration":"236.050144ms","start":"2026-08-12T11:20:25.168238Z","end":"2026-08-12T11:20:25.404288Z","steps":["trace[199757329] 'process raft request' (duration: 93.825175ms)","trace[199757329] 'compare' (duration: 141.75566ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:20:25.404896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.797137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228819"} {"level":"info","ts":"2026-08-12T11:20:25.404956Z","caller":"traceutil/trace.go:171","msg":"trace[1619218749] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8588; }","duration":"206.90549ms","start":"2026-08-12T11:20:25.198036Z","end":"2026-08-12T11:20:25.404941Z","steps":["trace[1619218749] 'agreement among raft nodes before linearized reading' (duration: 206.183816ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:20:26.084009Z","caller":"traceutil/trace.go:171","msg":"trace[136217921] linearizableReadLoop","detail":"{readStateIndex:8900; appliedIndex:8899; }","duration":"128.395081ms","start":"2026-08-12T11:20:25.955591Z","end":"2026-08-12T11:20:26.083986Z","steps":["trace[136217921] 'read index received' (duration: 79.645369ms)","trace[136217921] 'applied index is now lower than readState.Index' (duration: 48.748572ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:20:26.084148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.563485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T11:20:26.084182Z","caller":"traceutil/trace.go:171","msg":"trace[849842327] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8594; }","duration":"128.665367ms","start":"2026-08-12T11:20:25.955506Z","end":"2026-08-12T11:20:26.084172Z","steps":["trace[849842327] 'agreement among raft nodes before linearized reading' (duration: 128.573275ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:20:26.084408Z","caller":"traceutil/trace.go:171","msg":"trace[147212371] transaction","detail":"{read_only:false; response_revision:8594; number_of_response:1; }","duration":"150.966749ms","start":"2026-08-12T11:20:25.933429Z","end":"2026-08-12T11:20:26.084395Z","steps":["trace[147212371] 'process raft request' (duration: 101.878519ms)","trace[147212371] 'compare' (duration: 48.572719ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:20:26.388632Z","caller":"traceutil/trace.go:171","msg":"trace[1323320933] transaction","detail":"{read_only:false; response_revision:8597; number_of_response:1; }","duration":"112.559876ms","start":"2026-08-12T11:20:26.276039Z","end":"2026-08-12T11:20:26.388599Z","steps":["trace[1323320933] 'process raft request' (duration: 112.346872ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:20:44.690832Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023ac.wal"} {"level":"info","ts":"2026-08-12T11:22:26.369736Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"12dd93b2fab6fcb","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-12T11:22:26.374905Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-12T11:22:26.375003Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-12T11:24:43.033708Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8269} {"level":"info","ts":"2026-08-12T11:24:43.11457Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8269,"took":"78.401688ms","hash":3034247624,"current-db-size-bytes":59273216,"current-db-size":"59 MB","current-db-size-in-use-bytes":25407488,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-08-12T11:24:43.114638Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3034247624,"revision":8269,"compact-revision":6100} {"level":"info","ts":"2026-08-12T11:25:26.526279Z","caller":"traceutil/trace.go:171","msg":"trace[2016354839] transaction","detail":"{read_only:false; response_revision:11198; number_of_response:1; }","duration":"105.387465ms","start":"2026-08-12T11:25:26.42086Z","end":"2026-08-12T11:25:26.526248Z","steps":["trace[2016354839] 'process raft request' (duration: 40.163556ms)","trace[2016354839] 'compare' (duration: 65.067096ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:25:29.231983Z","caller":"traceutil/trace.go:171","msg":"trace[1948684794] transaction","detail":"{read_only:false; response_revision:11215; number_of_response:1; }","duration":"106.999455ms","start":"2026-08-12T11:25:29.124964Z","end":"2026-08-12T11:25:29.231963Z","steps":["trace[1948684794] 'process raft request' (duration: 106.856763ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:29:43.039559Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10760} {"level":"info","ts":"2026-08-12T11:29:43.121118Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10760,"took":"78.996796ms","hash":1788228524,"current-db-size-bytes":59273216,"current-db-size":"59 MB","current-db-size-in-use-bytes":27844608,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-12T11:29:43.121175Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1788228524,"revision":10760,"compact-revision":8269} {"level":"info","ts":"2026-08-12T11:31:45.268108Z","caller":"traceutil/trace.go:171","msg":"trace[961895775] transaction","detail":"{read_only:false; response_revision:13748; number_of_response:1; }","duration":"112.529509ms","start":"2026-08-12T11:31:45.155549Z","end":"2026-08-12T11:31:45.268079Z","steps":["trace[961895775] 'process raft request' (duration: 62.047356ms)","trace[961895775] 'compare' (duration: 50.30895ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:32:00.775071Z","caller":"traceutil/trace.go:171","msg":"trace[2008950176] transaction","detail":"{read_only:false; response_revision:13842; number_of_response:1; }","duration":"104.478735ms","start":"2026-08-12T11:32:00.670553Z","end":"2026-08-12T11:32:00.775031Z","steps":["trace[2008950176] 'process raft request' (duration: 104.170119ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:32:01.381905Z","caller":"traceutil/trace.go:171","msg":"trace[1506146649] linearizableReadLoop","detail":"{readStateIndex:14316; appliedIndex:14315; }","duration":"342.113385ms","start":"2026-08-12T11:32:01.039776Z","end":"2026-08-12T11:32:01.38189Z","steps":["trace[1506146649] 'read index received' (duration: 341.994303ms)","trace[1506146649] 'applied index is now lower than readState.Index' (duration: 118.262µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:32:01.381993Z","caller":"traceutil/trace.go:171","msg":"trace[1112479001] transaction","detail":"{read_only:false; response_revision:13843; number_of_response:1; }","duration":"428.566612ms","start":"2026-08-12T11:32:00.953417Z","end":"2026-08-12T11:32:01.381983Z","steps":["trace[1112479001] 'process raft request' (duration: 428.363458ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:32:01.382075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:32:00.953399Z","time spent":"428.610173ms","remote":"127.0.0.1:37514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12483,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:32:01.382179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.405231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T11:32:01.382204Z","caller":"traceutil/trace.go:171","msg":"trace[1741559924] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13843; }","duration":"342.473602ms","start":"2026-08-12T11:32:01.039723Z","end":"2026-08-12T11:32:01.382197Z","steps":["trace[1741559924] 'agreement among raft nodes before linearized reading' (duration: 342.432901ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:32:01.382222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:32:01.039698Z","time spent":"342.520423ms","remote":"127.0.0.1:37352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-12T11:32:01.385832Z","caller":"traceutil/trace.go:171","msg":"trace[1503153392] transaction","detail":"{read_only:false; response_revision:13844; number_of_response:1; }","duration":"151.584902ms","start":"2026-08-12T11:32:01.234209Z","end":"2026-08-12T11:32:01.385794Z","steps":["trace[1503153392] 'process raft request' (duration: 151.354998ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:32:23.376379Z","caller":"traceutil/trace.go:171","msg":"trace[2041428116] linearizableReadLoop","detail":"{readStateIndex:14639; appliedIndex:14638; }","duration":"145.470534ms","start":"2026-08-12T11:32:23.230884Z","end":"2026-08-12T11:32:23.376355Z","steps":["trace[2041428116] 'read index received' (duration: 145.228309ms)","trace[2041428116] 'applied index is now lower than readState.Index' (duration: 241.165µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:32:23.376439Z","caller":"traceutil/trace.go:171","msg":"trace[1955706945] transaction","detail":"{read_only:false; response_revision:14152; number_of_response:1; }","duration":"161.382842ms","start":"2026-08-12T11:32:23.215015Z","end":"2026-08-12T11:32:23.376398Z","steps":["trace[1955706945] 'process raft request' (duration: 161.163848ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:32:23.376677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.76032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1a7173bd-0941-4910-a928-539dcf40c775\" ","response":"range_response_count:1 size:3637"} {"level":"info","ts":"2026-08-12T11:32:23.376796Z","caller":"traceutil/trace.go:171","msg":"trace[1196740379] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1a7173bd-0941-4910-a928-539dcf40c775; range_end:; response_count:1; response_revision:14152; }","duration":"145.942813ms","start":"2026-08-12T11:32:23.230828Z","end":"2026-08-12T11:32:23.376771Z","steps":["trace[1196740379] 'agreement among raft nodes before linearized reading' (duration: 145.651527ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:33:51.686468Z","caller":"traceutil/trace.go:171","msg":"trace[1620767905] linearizableReadLoop","detail":"{readStateIndex:15486; appliedIndex:15485; }","duration":"129.803083ms","start":"2026-08-12T11:33:51.55664Z","end":"2026-08-12T11:33:51.686443Z","steps":["trace[1620767905] 'read index received' (duration: 129.671509ms)","trace[1620767905] 'applied index is now lower than readState.Index' (duration: 130.144µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:33:51.686639Z","caller":"traceutil/trace.go:171","msg":"trace[1666082249] transaction","detail":"{read_only:false; response_revision:14975; number_of_response:1; }","duration":"205.799008ms","start":"2026-08-12T11:33:51.480773Z","end":"2026-08-12T11:33:51.686572Z","steps":["trace[1666082249] 'process raft request' (duration: 205.502442ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:33:51.686733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.079828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-kzchh\" ","response":"range_response_count:1 size:22132"} {"level":"info","ts":"2026-08-12T11:33:51.686788Z","caller":"traceutil/trace.go:171","msg":"trace[1037541685] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-kzchh; range_end:; response_count:1; response_revision:14975; }","duration":"130.18281ms","start":"2026-08-12T11:33:51.556594Z","end":"2026-08-12T11:33:51.686777Z","steps":["trace[1037541685] 'agreement among raft nodes before linearized reading' (duration: 130.009817ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:33:51.68696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.563893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-kzchh\" ","response":"range_response_count:1 size:22132"} {"level":"info","ts":"2026-08-12T11:33:51.687111Z","caller":"traceutil/trace.go:171","msg":"trace[2051227566] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-kzchh; range_end:; response_count:1; response_revision:14975; }","duration":"122.763127ms","start":"2026-08-12T11:33:51.564316Z","end":"2026-08-12T11:33:51.68708Z","steps":["trace[2051227566] 'agreement among raft nodes before linearized reading' (duration: 122.356089ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:34:43.046547Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12876} {"level":"info","ts":"2026-08-12T11:34:43.127049Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12876,"took":"77.591755ms","hash":643941606,"current-db-size-bytes":59273216,"current-db-size":"59 MB","current-db-size-in-use-bytes":30597120,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-12T11:34:43.127122Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":643941606,"revision":12876,"compact-revision":10760} {"level":"info","ts":"2026-08-12T11:39:43.053251Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15301} {"level":"info","ts":"2026-08-12T11:39:43.147789Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15301,"took":"92.064035ms","hash":138383696,"current-db-size-bytes":59273216,"current-db-size":"59 MB","current-db-size-in-use-bytes":31678464,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-12T11:39:43.14787Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":138383696,"revision":15301,"compact-revision":12876} {"level":"info","ts":"2026-08-12T11:39:45.279183Z","caller":"traceutil/trace.go:171","msg":"trace[1051788697] transaction","detail":"{read_only:false; response_revision:17588; number_of_response:1; }","duration":"166.275395ms","start":"2026-08-12T11:39:45.112888Z","end":"2026-08-12T11:39:45.279163Z","steps":["trace[1051788697] 'process raft request' (duration: 166.169253ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:39:52.083774Z","caller":"traceutil/trace.go:171","msg":"trace[48272985] transaction","detail":"{read_only:false; response_revision:17622; number_of_response:1; }","duration":"165.068832ms","start":"2026-08-12T11:39:51.918665Z","end":"2026-08-12T11:39:52.083733Z","steps":["trace[48272985] 'process raft request' (duration: 164.648904ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:39:52.567393Z","caller":"traceutil/trace.go:171","msg":"trace[1472809955] linearizableReadLoop","detail":"{readStateIndex:18224; appliedIndex:18223; }","duration":"471.764074ms","start":"2026-08-12T11:39:52.095598Z","end":"2026-08-12T11:39:52.567362Z","steps":["trace[1472809955] 'read index received' (duration: 471.675872ms)","trace[1472809955] 'applied index is now lower than readState.Index' (duration: 86.772µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:39:52.567586Z","caller":"traceutil/trace.go:171","msg":"trace[1139338576] transaction","detail":"{read_only:false; response_revision:17623; number_of_response:1; }","duration":"541.723571ms","start":"2026-08-12T11:39:52.025803Z","end":"2026-08-12T11:39:52.567527Z","steps":["trace[1139338576] 'process raft request' (duration: 541.364964ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:39:52.567927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:39:52.025775Z","time spent":"542.007427ms","remote":"127.0.0.1:37592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:39:52.568385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.766823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:94 size:946897"} {"level":"info","ts":"2026-08-12T11:39:52.576873Z","caller":"traceutil/trace.go:171","msg":"trace[507990596] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:94; response_revision:17623; }","duration":"472.867555ms","start":"2026-08-12T11:39:52.095572Z","end":"2026-08-12T11:39:52.568439Z","steps":["trace[507990596] 'agreement among raft nodes before linearized reading' (duration: 471.982738ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:39:52.576959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:39:52.095555Z","time spent":"481.373699ms","remote":"127.0.0.1:37514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":94,"response size":946920,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-08-12T11:39:52.678491Z","caller":"traceutil/trace.go:171","msg":"trace[830401588] transaction","detail":"{read_only:false; response_revision:17624; number_of_response:1; }","duration":"549.047003ms","start":"2026-08-12T11:39:52.129415Z","end":"2026-08-12T11:39:52.678462Z","steps":["trace[830401588] 'process raft request' (duration: 548.737006ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:39:52.678659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.916602ms","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-12T11:39:52.678708Z","caller":"traceutil/trace.go:171","msg":"trace[654359670] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:17627; }","duration":"466.006924ms","start":"2026-08-12T11:39:52.212691Z","end":"2026-08-12T11:39:52.678697Z","steps":["trace[654359670] 'agreement among raft nodes before linearized reading' (duration: 465.851891ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:39:52.678749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:39:52.212674Z","time spent":"466.065914ms","remote":"127.0.0.1:37592","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-12T11:39:52.678768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.918183ms","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-12T11:39:52.678879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.771309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T11:39:52.678872Z","caller":"traceutil/trace.go:171","msg":"trace[1850450284] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:17627; }","duration":"229.075986ms","start":"2026-08-12T11:39:52.449776Z","end":"2026-08-12T11:39:52.678852Z","steps":["trace[1850450284] 'agreement among raft nodes before linearized reading' (duration: 228.77188ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:39:52.678917Z","caller":"traceutil/trace.go:171","msg":"trace[832288515] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:17627; }","duration":"243.857381ms","start":"2026-08-12T11:39:52.435048Z","end":"2026-08-12T11:39:52.678905Z","steps":["trace[832288515] 'agreement among raft nodes before linearized reading' (duration: 243.800399ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:39:52.678889Z","caller":"traceutil/trace.go:171","msg":"trace[420223980] transaction","detail":"{read_only:false; response_revision:17625; number_of_response:1; }","duration":"527.466948ms","start":"2026-08-12T11:39:52.151378Z","end":"2026-08-12T11:39:52.678845Z","steps":["trace[420223980] 'process raft request' (duration: 526.937257ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:39:52.679188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.528448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-qrkt5\" ","response":"range_response_count:1 size:9797"} {"level":"info","ts":"2026-08-12T11:39:52.679237Z","caller":"traceutil/trace.go:171","msg":"trace[449650162] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-qrkt5; range_end:; response_count:1; response_revision:17627; }","duration":"184.63719ms","start":"2026-08-12T11:39:52.494589Z","end":"2026-08-12T11:39:52.679226Z","steps":["trace[449650162] 'agreement among raft nodes before linearized reading' (duration: 184.519077ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:39:52.67935Z","caller":"traceutil/trace.go:171","msg":"trace[631776059] transaction","detail":"{read_only:false; response_revision:17627; number_of_response:1; }","duration":"165.614903ms","start":"2026-08-12T11:39:52.513725Z","end":"2026-08-12T11:39:52.67934Z","steps":["trace[631776059] 'process raft request' (duration: 164.699395ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:39:52.679452Z","caller":"traceutil/trace.go:171","msg":"trace[905304911] transaction","detail":"{read_only:false; response_revision:17626; number_of_response:1; }","duration":"301.833749ms","start":"2026-08-12T11:39:52.377609Z","end":"2026-08-12T11:39:52.679443Z","steps":["trace[905304911] 'process raft request' (duration: 300.755397ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:39:52.678665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:39:52.129387Z","time spent":"549.191115ms","remote":"127.0.0.1:37592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:39:52.679516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:39:52.37758Z","time spent":"301.897379ms","remote":"127.0.0.1:52274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7680,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:39:52.679195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.154548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-08-12T11:39:52.679629Z","caller":"traceutil/trace.go:171","msg":"trace[999322403] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:17627; }","duration":"270.607807ms","start":"2026-08-12T11:39:52.40899Z","end":"2026-08-12T11:39:52.679598Z","steps":["trace[999322403] 'agreement among raft nodes before linearized reading' (duration: 270.122617ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:39:52.67924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.046255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T11:39:52.679704Z","caller":"traceutil/trace.go:171","msg":"trace[28219063] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:17627; }","duration":"451.505673ms","start":"2026-08-12T11:39:52.228171Z","end":"2026-08-12T11:39:52.679677Z","steps":["trace[28219063] 'agreement among raft nodes before linearized reading' (duration: 451.046554ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:39:52.67977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:39:52.228164Z","time spent":"451.582944ms","remote":"127.0.0.1:35160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"info","ts":"2026-08-12T11:39:52.678494Z","caller":"traceutil/trace.go:171","msg":"trace[1655319219] linearizableReadLoop","detail":"{readStateIndex:18228; appliedIndex:18224; }","duration":"110.99722ms","start":"2026-08-12T11:39:52.567477Z","end":"2026-08-12T11:39:52.678474Z","steps":["trace[1655319219] 'read index received' (duration: 110.648123ms)","trace[1655319219] 'applied index is now lower than readState.Index' (duration: 347.557µs)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:39:52.679177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:39:52.151356Z","time spent":"527.651571ms","remote":"127.0.0.1:37592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:40:00.473358Z","caller":"traceutil/trace.go:171","msg":"trace[30860774] transaction","detail":"{read_only:false; response_revision:17750; number_of_response:1; }","duration":"179.403418ms","start":"2026-08-12T11:40:00.293925Z","end":"2026-08-12T11:40:00.473328Z","steps":["trace[30860774] 'process raft request' (duration: 155.527428ms)","trace[30860774] 'compare' (duration: 23.415211ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:40:00.473437Z","caller":"traceutil/trace.go:171","msg":"trace[1294093196] linearizableReadLoop","detail":"{readStateIndex:18354; appliedIndex:18350; }","duration":"119.772529ms","start":"2026-08-12T11:40:00.353617Z","end":"2026-08-12T11:40:00.47339Z","steps":["trace[1294093196] 'read index received' (duration: 7.309771ms)","trace[1294093196] 'applied index is now lower than readState.Index' (duration: 112.460488ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:40:00.474034Z","caller":"traceutil/trace.go:171","msg":"trace[1261514192] transaction","detail":"{read_only:false; number_of_response:1; response_revision:17750; }","duration":"178.028231ms","start":"2026-08-12T11:40:00.295985Z","end":"2026-08-12T11:40:00.474013Z","steps":["trace[1261514192] 'process raft request' (duration: 177.193785ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:40:00.474074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.408021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-12T11:40:00.474122Z","caller":"traceutil/trace.go:171","msg":"trace[413808404] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:17750; }","duration":"120.537394ms","start":"2026-08-12T11:40:00.353569Z","end":"2026-08-12T11:40:00.474107Z","steps":["trace[413808404] 'agreement among raft nodes before linearized reading' (duration: 119.991073ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:40:00.474345Z","caller":"traceutil/trace.go:171","msg":"trace[2031935504] transaction","detail":"{read_only:false; number_of_response:1; response_revision:17750; }","duration":"176.149585ms","start":"2026-08-12T11:40:00.298182Z","end":"2026-08-12T11:40:00.474332Z","steps":["trace[2031935504] 'process raft request' (duration: 175.142656ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:43:23.626407Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"12dd93b2fab6fcb","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-12T11:43:23.63267Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-12T11:43:23.632782Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-12T11:44:43.060731Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17555} {"level":"info","ts":"2026-08-12T11:44:43.163386Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17555,"took":"99.245772ms","hash":1315447071,"current-db-size-bytes":59273216,"current-db-size":"59 MB","current-db-size-in-use-bytes":34054144,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-12T11:44:43.163478Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1315447071,"revision":17555,"compact-revision":15301} {"level":"info","ts":"2026-08-12T11:45:41.885223Z","caller":"traceutil/trace.go:171","msg":"trace[1547325398] transaction","detail":"{read_only:false; response_revision:20325; number_of_response:1; }","duration":"291.275364ms","start":"2026-08-12T11:45:41.593926Z","end":"2026-08-12T11:45:41.885202Z","steps":["trace[1547325398] 'process raft request' (duration: 291.141371ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:45:42.10779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.016645ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-08-12T11:45:42.107985Z","caller":"traceutil/trace.go:171","msg":"trace[476254762] linearizableReadLoop","detail":"{readStateIndex:21015; appliedIndex:21013; }","duration":"240.641597ms","start":"2026-08-12T11:45:41.867316Z","end":"2026-08-12T11:45:42.107957Z","steps":["trace[476254762] 'read index received' (duration: 17.790242ms)","trace[476254762] 'applied index is now lower than readState.Index' (duration: 222.849135ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:45:42.108221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.881372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/heat-cfn-5f45c6fb64-fx4jt.18cb0c20b8faf6d0\" ","response":"range_response_count:1 size:906"} {"level":"info","ts":"2026-08-12T11:45:42.108328Z","caller":"traceutil/trace.go:171","msg":"trace[665332883] range","detail":"{range_begin:/registry/events/openstack/heat-cfn-5f45c6fb64-fx4jt.18cb0c20b8faf6d0; range_end:; response_count:1; response_revision:20325; }","duration":"241.018635ms","start":"2026-08-12T11:45:41.867282Z","end":"2026-08-12T11:45:42.108301Z","steps":["trace[665332883] 'agreement among raft nodes before linearized reading' (duration: 240.739539ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:45:42.136945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.81423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-08-12T11:45:42.137011Z","caller":"traceutil/trace.go:171","msg":"trace[1446475507] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:20328; }","duration":"104.926721ms","start":"2026-08-12T11:45:42.032071Z","end":"2026-08-12T11:45:42.136998Z","steps":["trace[1446475507] 'agreement among raft nodes before linearized reading' (duration: 103.81043ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:45:42.552569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.894858ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-12T11:45:42.552715Z","caller":"traceutil/trace.go:171","msg":"trace[1240327586] linearizableReadLoop","detail":"{readStateIndex:21020; appliedIndex:21019; }","duration":"280.121928ms","start":"2026-08-12T11:45:42.272575Z","end":"2026-08-12T11:45:42.552697Z","steps":["trace[1240327586] 'read index received' (duration: 128.837162ms)","trace[1240327586] 'applied index is now lower than readState.Index' (duration: 151.283176ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:45:42.552885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.434829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-12T11:45:42.553107Z","caller":"traceutil/trace.go:171","msg":"trace[208252679] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; response_count:0; response_revision:20329; }","duration":"109.4765ms","start":"2026-08-12T11:45:42.443436Z","end":"2026-08-12T11:45:42.552913Z","steps":["trace[208252679] 'agreement among raft nodes before linearized reading' (duration: 109.373258ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:45:42.552886Z","caller":"traceutil/trace.go:171","msg":"trace[1898566346] transaction","detail":"{read_only:false; response_revision:20329; number_of_response:1; }","duration":"285.122475ms","start":"2026-08-12T11:45:42.267686Z","end":"2026-08-12T11:45:42.552809Z","steps":["trace[1898566346] 'process raft request' (duration: 133.8532ms)","trace[1898566346] 'compare' (duration: 150.562082ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:45:42.552882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.279491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-12T11:45:42.553794Z","caller":"traceutil/trace.go:171","msg":"trace[2092056961] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:20329; }","duration":"281.200019ms","start":"2026-08-12T11:45:42.272543Z","end":"2026-08-12T11:45:42.553743Z","steps":["trace[2092056961] 'agreement among raft nodes before linearized reading' (duration: 280.245011ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:49:37.404185Z","caller":"traceutil/trace.go:171","msg":"trace[191567233] transaction","detail":"{read_only:false; response_revision:22754; number_of_response:1; }","duration":"115.872664ms","start":"2026-08-12T11:49:37.288286Z","end":"2026-08-12T11:49:37.404159Z","steps":["trace[191567233] 'process raft request' (duration: 115.6936ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:49:37.755418Z","caller":"traceutil/trace.go:171","msg":"trace[652489790] transaction","detail":"{read_only:false; response_revision:22755; number_of_response:1; }","duration":"213.532825ms","start":"2026-08-12T11:49:37.541848Z","end":"2026-08-12T11:49:37.755381Z","steps":["trace[652489790] 'process raft request' (duration: 209.900635ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:49:43.066881Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19988} {"level":"info","ts":"2026-08-12T11:49:43.187845Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19988,"took":"116.919053ms","hash":3873613090,"current-db-size-bytes":59273216,"current-db-size":"59 MB","current-db-size-in-use-bytes":42598400,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-08-12T11:49:43.187917Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3873613090,"revision":19988,"compact-revision":17555} {"level":"warn","ts":"2026-08-12T11:53:16.550596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.713713ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-08-12T11:53:21.267761Z","caller":"traceutil/trace.go:171","msg":"trace[1867261559] transaction","detail":"{read_only:false; response_revision:25001; number_of_response:1; }","duration":"248.251359ms","start":"2026-08-12T11:53:21.019489Z","end":"2026-08-12T11:53:21.267741Z","steps":["trace[1867261559] 'process raft request' (duration: 248.122676ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:21.360969Z","caller":"traceutil/trace.go:171","msg":"trace[716105251] linearizableReadLoop","detail":"{readStateIndex:25824; appliedIndex:25820; }","duration":"318.632816ms","start":"2026-08-12T11:53:21.042297Z","end":"2026-08-12T11:53:21.36093Z","steps":["trace[716105251] 'read index received' (duration: 225.388989ms)","trace[716105251] 'applied index is now lower than readState.Index' (duration: 93.242367ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:53:21.361022Z","caller":"traceutil/trace.go:171","msg":"trace[1268371202] transaction","detail":"{read_only:false; response_revision:25003; number_of_response:1; }","duration":"340.956907ms","start":"2026-08-12T11:53:21.020032Z","end":"2026-08-12T11:53:21.360989Z","steps":["trace[1268371202] 'process raft request' (duration: 340.789643ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:21.361054Z","caller":"traceutil/trace.go:171","msg":"trace[364774881] transaction","detail":"{read_only:false; response_revision:25004; number_of_response:1; }","duration":"339.265453ms","start":"2026-08-12T11:53:21.021761Z","end":"2026-08-12T11:53:21.361027Z","steps":["trace[364774881] 'process raft request' (duration: 339.11448ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:53:21.36119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:53:21.02002Z","time spent":"341.080399ms","remote":"127.0.0.1:37592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:53:21.361227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:53:21.021752Z","time spent":"339.368756ms","remote":"127.0.0.1:37592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:53:21.361692Z","caller":"traceutil/trace.go:171","msg":"trace[1285542395] transaction","detail":"{read_only:false; response_revision:25002; number_of_response:1; }","duration":"341.197411ms","start":"2026-08-12T11:53:21.019714Z","end":"2026-08-12T11:53:21.360911Z","steps":["trace[1285542395] 'process raft request' (duration: 340.898495ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:53:21.361774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:53:21.019688Z","time spent":"342.046488ms","remote":"127.0.0.1:37592","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-12T11:53:21.556207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.843202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T11:53:21.55632Z","caller":"traceutil/trace.go:171","msg":"trace[1540879243] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25004; }","duration":"514.021025ms","start":"2026-08-12T11:53:21.04227Z","end":"2026-08-12T11:53:21.556291Z","steps":["trace[1540879243] 'agreement among raft nodes before linearized reading' (duration: 318.84741ms)","trace[1540879243] 'range keys from in-memory index tree' (duration: 194.988232ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:53:21.556383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:53:21.042246Z","time spent":"514.123738ms","remote":"127.0.0.1:37352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-12T11:53:21.639752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.670645ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-08-12T11:53:21.64014Z","caller":"traceutil/trace.go:171","msg":"trace[1617026166] linearizableReadLoop","detail":"{readStateIndex:25825; appliedIndex:25824; }","duration":"279.043013ms","start":"2026-08-12T11:53:21.361076Z","end":"2026-08-12T11:53:21.640119Z","steps":["trace[1617026166] 'read index received' (duration: 57.013471ms)","trace[1617026166] 'applied index is now lower than readState.Index' (duration: 222.028232ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:53:21.640296Z","caller":"traceutil/trace.go:171","msg":"trace[1022244539] transaction","detail":"{read_only:false; response_revision:25005; number_of_response:1; }","duration":"237.353709ms","start":"2026-08-12T11:53:21.402918Z","end":"2026-08-12T11:53:21.640272Z","steps":["trace[1022244539] 'process raft request' (duration: 236.953641ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:53:21.640752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.188871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T11:53:21.640812Z","caller":"traceutil/trace.go:171","msg":"trace[798001617] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:25005; }","duration":"313.298253ms","start":"2026-08-12T11:53:21.327496Z","end":"2026-08-12T11:53:21.640795Z","steps":["trace[798001617] 'agreement among raft nodes before linearized reading' (duration: 312.728172ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:53:21.640857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:53:21.327471Z","time spent":"313.375385ms","remote":"127.0.0.1:35842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true "} {"level":"info","ts":"2026-08-12T11:53:21.92576Z","caller":"traceutil/trace.go:171","msg":"trace[848720044] linearizableReadLoop","detail":"{readStateIndex:25827; appliedIndex:25826; }","duration":"285.552148ms","start":"2026-08-12T11:53:21.640185Z","end":"2026-08-12T11:53:21.925738Z","steps":["trace[848720044] 'read index received' (duration: 285.372064ms)","trace[848720044] 'applied index is now lower than readState.Index' (duration: 179.004µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:53:21.925838Z","caller":"traceutil/trace.go:171","msg":"trace[1892764137] transaction","detail":"{read_only:false; response_revision:25006; number_of_response:1; }","duration":"320.425931ms","start":"2026-08-12T11:53:21.605362Z","end":"2026-08-12T11:53:21.925788Z","steps":["trace[1892764137] 'process raft request' (duration: 320.177836ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:53:21.926007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:53:21.605328Z","time spent":"320.578784ms","remote":"127.0.0.1:37592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:53:21.925978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.283427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T11:53:21.92611Z","caller":"traceutil/trace.go:171","msg":"trace[1527566501] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:25006; }","duration":"474.41852ms","start":"2026-08-12T11:53:21.45164Z","end":"2026-08-12T11:53:21.926059Z","steps":["trace[1527566501] 'agreement among raft nodes before linearized reading' (duration: 474.185156ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:53:21.926192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:53:21.451557Z","time spent":"474.604035ms","remote":"127.0.0.1:52374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" count_only:true "} {"level":"info","ts":"2026-08-12T11:53:22.023615Z","caller":"traceutil/trace.go:171","msg":"trace[1183704725] transaction","detail":"{read_only:false; response_revision:25007; number_of_response:1; }","duration":"315.241081ms","start":"2026-08-12T11:53:21.708333Z","end":"2026-08-12T11:53:22.023574Z","steps":["trace[1183704725] 'process raft request' (duration: 295.292896ms)","trace[1183704725] 'compare' (duration: 19.70803ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:53:22.023833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:53:21.708305Z","time spent":"315.412485ms","remote":"127.0.0.1:37592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:53:22.023835Z","caller":"traceutil/trace.go:171","msg":"trace[2112982857] transaction","detail":"{read_only:false; response_revision:25008; number_of_response:1; }","duration":"107.79252ms","start":"2026-08-12T11:53:21.916003Z","end":"2026-08-12T11:53:22.023796Z","steps":["trace[2112982857] 'process raft request' (duration: 107.512735ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:22.079068Z","caller":"traceutil/trace.go:171","msg":"trace[515065872] linearizableReadLoop","detail":"{readStateIndex:25831; appliedIndex:25827; }","duration":"122.329299ms","start":"2026-08-12T11:53:21.956709Z","end":"2026-08-12T11:53:22.079038Z","steps":["trace[515065872] 'read index received' (duration: 46.937376ms)","trace[515065872] 'applied index is now lower than readState.Index' (duration: 75.390263ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:53:22.079111Z","caller":"traceutil/trace.go:171","msg":"trace[217313920] transaction","detail":"{read_only:false; response_revision:25009; number_of_response:1; }","duration":"146.298993ms","start":"2026-08-12T11:53:21.932782Z","end":"2026-08-12T11:53:22.079081Z","steps":["trace[217313920] 'process raft request' (duration: 146.050267ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:53:22.079245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.519213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T11:53:22.079281Z","caller":"traceutil/trace.go:171","msg":"trace[975644825] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25010; }","duration":"122.576104ms","start":"2026-08-12T11:53:21.956693Z","end":"2026-08-12T11:53:22.079269Z","steps":["trace[975644825] 'agreement among raft nodes before linearized reading' (duration: 122.453672ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:22.079185Z","caller":"traceutil/trace.go:171","msg":"trace[2059828401] transaction","detail":"{read_only:false; response_revision:25010; number_of_response:1; }","duration":"146.011587ms","start":"2026-08-12T11:53:21.933125Z","end":"2026-08-12T11:53:22.079136Z","steps":["trace[2059828401] 'process raft request' (duration: 145.868893ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:22.491507Z","caller":"traceutil/trace.go:171","msg":"trace[1661775329] transaction","detail":"{read_only:false; response_revision:25011; number_of_response:1; }","duration":"164.504543ms","start":"2026-08-12T11:53:22.326649Z","end":"2026-08-12T11:53:22.491153Z","steps":["trace[1661775329] 'process raft request' (duration: 164.254989ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:23.003008Z","caller":"traceutil/trace.go:171","msg":"trace[524526027] transaction","detail":"{read_only:false; response_revision:25012; number_of_response:1; }","duration":"273.27954ms","start":"2026-08-12T11:53:22.729654Z","end":"2026-08-12T11:53:23.002933Z","steps":["trace[524526027] 'process raft request' (duration: 273.035075ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:23.063043Z","caller":"traceutil/trace.go:171","msg":"trace[452450073] linearizableReadLoop","detail":"{readStateIndex:25834; appliedIndex:25832; }","duration":"197.322397ms","start":"2026-08-12T11:53:22.865696Z","end":"2026-08-12T11:53:23.063018Z","steps":["trace[452450073] 'read index received' (duration: 137.049373ms)","trace[452450073] 'applied index is now lower than readState.Index' (duration: 60.271834ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:53:23.0634Z","caller":"traceutil/trace.go:171","msg":"trace[599257391] transaction","detail":"{read_only:false; response_revision:25013; number_of_response:1; }","duration":"333.044793ms","start":"2026-08-12T11:53:22.730339Z","end":"2026-08-12T11:53:23.063384Z","steps":["trace[599257391] 'process raft request' (duration: 332.463863ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:53:23.063543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:53:22.730327Z","time spent":"333.119216ms","remote":"127.0.0.1:37592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:53:23.063617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.920218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusterclasses/\" range_end:\"/registry/cluster.x-k8s.io/clusterclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T11:53:23.06369Z","caller":"traceutil/trace.go:171","msg":"trace[892975893] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusterclasses/; range_end:/registry/cluster.x-k8s.io/clusterclasses0; response_count:0; response_revision:25013; }","duration":"198.01809ms","start":"2026-08-12T11:53:22.865644Z","end":"2026-08-12T11:53:23.063662Z","steps":["trace[892975893] 'agreement among raft nodes before linearized reading' (duration: 197.939558ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:53:23.06353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.36202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T11:53:23.063812Z","caller":"traceutil/trace.go:171","msg":"trace[1447285573] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25013; }","duration":"109.706156ms","start":"2026-08-12T11:53:22.95409Z","end":"2026-08-12T11:53:23.063796Z","steps":["trace[1447285573] 'agreement among raft nodes before linearized reading' (duration: 109.35165ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:53:23.596289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.517467ms","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-12T11:53:23.596374Z","caller":"traceutil/trace.go:171","msg":"trace[2121209542] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25014; }","duration":"228.64928ms","start":"2026-08-12T11:53:23.367704Z","end":"2026-08-12T11:53:23.596354Z","steps":["trace[2121209542] 'range keys from in-memory index tree' (duration: 228.355074ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:53:23.596403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.705591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-08-12T11:53:23.596415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.710452ms","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-12T11:53:23.596452Z","caller":"traceutil/trace.go:171","msg":"trace[1921838097] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25014; }","duration":"228.806713ms","start":"2026-08-12T11:53:23.367634Z","end":"2026-08-12T11:53:23.59644Z","steps":["trace[1921838097] 'range keys from in-memory index tree' (duration: 228.536537ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:23.596487Z","caller":"traceutil/trace.go:171","msg":"trace[1961749041] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25014; }","duration":"228.820454ms","start":"2026-08-12T11:53:23.367649Z","end":"2026-08-12T11:53:23.59647Z","steps":["trace[1961749041] 'range keys from in-memory index tree' (duration: 228.539407ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:53:23.596578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.364006ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-12T11:53:23.596662Z","caller":"traceutil/trace.go:171","msg":"trace[1448600647] transaction","detail":"{read_only:false; response_revision:25015; number_of_response:1; }","duration":"209.844047ms","start":"2026-08-12T11:53:23.386804Z","end":"2026-08-12T11:53:23.596648Z","steps":["trace[1448600647] 'process raft request' (duration: 96.327838ms)","trace[1448600647] 'compare' (duration: 113.265495ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:53:23.59687Z","caller":"traceutil/trace.go:171","msg":"trace[951319387] transaction","detail":"{read_only:false; response_revision:25016; number_of_response:1; }","duration":"125.914118ms","start":"2026-08-12T11:53:23.470945Z","end":"2026-08-12T11:53:23.596859Z","steps":["trace[951319387] 'process raft request' (duration: 125.796836ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:24.086869Z","caller":"traceutil/trace.go:171","msg":"trace[1901005089] transaction","detail":"{read_only:false; response_revision:25017; number_of_response:1; }","duration":"486.127666ms","start":"2026-08-12T11:53:23.600721Z","end":"2026-08-12T11:53:24.086848Z","steps":["trace[1901005089] 'process raft request' (duration: 485.946063ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:53:24.08727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:53:23.600689Z","time spent":"486.481252ms","remote":"127.0.0.1:37592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:53:24.386171Z","caller":"traceutil/trace.go:171","msg":"trace[1368769876] linearizableReadLoop","detail":"{readStateIndex:25841; appliedIndex:25837; }","duration":"554.642758ms","start":"2026-08-12T11:53:23.83151Z","end":"2026-08-12T11:53:24.386153Z","steps":["trace[1368769876] 'read index received' (duration: 255.272304ms)","trace[1368769876] 'applied index is now lower than readState.Index' (duration: 299.369854ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:53:24.386293Z","caller":"traceutil/trace.go:171","msg":"trace[483800326] transaction","detail":"{read_only:false; response_revision:25023; number_of_response:1; }","duration":"356.186199ms","start":"2026-08-12T11:53:24.030067Z","end":"2026-08-12T11:53:24.386254Z","steps":["trace[483800326] 'process raft request' (duration: 356.016736ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:53:24.386379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.361459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/trunks/\" range_end:\"/registry/openstack.k-orc.cloud/trunks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T11:53:24.38658Z","caller":"traceutil/trace.go:171","msg":"trace[1053856462] transaction","detail":"{read_only:false; response_revision:25024; number_of_response:1; }","duration":"300.885032ms","start":"2026-08-12T11:53:24.08549Z","end":"2026-08-12T11:53:24.386375Z","steps":["trace[1053856462] 'process raft request' (duration: 300.618147ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:24.386606Z","caller":"traceutil/trace.go:171","msg":"trace[66567284] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/trunks/; range_end:/registry/openstack.k-orc.cloud/trunks0; response_count:0; response_revision:25025; }","duration":"347.637755ms","start":"2026-08-12T11:53:24.038954Z","end":"2026-08-12T11:53:24.386592Z","steps":["trace[66567284] 'agreement among raft nodes before linearized reading' (duration: 347.40729ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:53:24.386658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:53:24.038941Z","time spent":"347.705057ms","remote":"127.0.0.1:33766","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/trunks/\" range_end:\"/registry/openstack.k-orc.cloud/trunks0\" count_only:true "} {"level":"warn","ts":"2026-08-12T11:53:24.386696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:53:24.030049Z","time spent":"356.531976ms","remote":"127.0.0.1:37592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:53:24.386739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:53:24.085458Z","time spent":"301.173818ms","remote":"127.0.0.1:37592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:53:24.38691Z","caller":"traceutil/trace.go:171","msg":"trace[941482676] transaction","detail":"{read_only:false; response_revision:25022; number_of_response:1; }","duration":"357.222709ms","start":"2026-08-12T11:53:24.02967Z","end":"2026-08-12T11:53:24.386893Z","steps":["trace[941482676] 'process raft request' (duration: 356.388203ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:24.386996Z","caller":"traceutil/trace.go:171","msg":"trace[849571705] transaction","detail":"{read_only:false; response_revision:25018; number_of_response:1; }","duration":"785.804056ms","start":"2026-08-12T11:53:23.601183Z","end":"2026-08-12T11:53:24.386987Z","steps":["trace[849571705] 'process raft request' (duration: 784.639244ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:53:24.387021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:53:24.02965Z","time spent":"357.29904ms","remote":"127.0.0.1:37592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:53:24.38707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:53:23.601168Z","time spent":"785.855117ms","remote":"127.0.0.1:37592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:53:24.386337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.814991ms","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-12T11:53:24.387138Z","caller":"traceutil/trace.go:171","msg":"trace[94375028] 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:25025; }","duration":"555.683108ms","start":"2026-08-12T11:53:23.83144Z","end":"2026-08-12T11:53:24.387123Z","steps":["trace[94375028] 'agreement among raft nodes before linearized reading' (duration: 554.817731ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:24.387179Z","caller":"traceutil/trace.go:171","msg":"trace[1477171764] transaction","detail":"{read_only:false; response_revision:25019; number_of_response:1; }","duration":"785.803826ms","start":"2026-08-12T11:53:23.601368Z","end":"2026-08-12T11:53:24.387172Z","steps":["trace[1477171764] 'process raft request' (duration: 784.589663ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:53:24.387183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:53:23.831414Z","time spent":"555.756358ms","remote":"127.0.0.1:41592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":152,"response count":0,"response size":29,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" "} {"level":"warn","ts":"2026-08-12T11:53:24.387218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:53:23.601362Z","time spent":"785.836237ms","remote":"127.0.0.1:37592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:53:24.387272Z","caller":"traceutil/trace.go:171","msg":"trace[1039524742] transaction","detail":"{read_only:false; response_revision:25020; number_of_response:1; }","duration":"741.185055ms","start":"2026-08-12T11:53:23.646082Z","end":"2026-08-12T11:53:24.387267Z","steps":["trace[1039524742] 'process raft request' (duration: 739.922601ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:53:24.387305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:53:23.646059Z","time spent":"741.228276ms","remote":"127.0.0.1:37592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:53:24.386305Z","caller":"traceutil/trace.go:171","msg":"trace[567530377] transaction","detail":"{read_only:false; response_revision:25025; number_of_response:1; }","duration":"300.455674ms","start":"2026-08-12T11:53:24.085814Z","end":"2026-08-12T11:53:24.386269Z","steps":["trace[567530377] 'process raft request' (duration: 300.314382ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:53:24.387519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:53:24.085803Z","time spent":"301.607917ms","remote":"127.0.0.1:37592","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-12T11:53:24.387945Z","caller":"traceutil/trace.go:171","msg":"trace[1845734356] transaction","detail":"{read_only:false; response_revision:25021; number_of_response:1; }","duration":"454.121918ms","start":"2026-08-12T11:53:23.932097Z","end":"2026-08-12T11:53:24.386218Z","steps":["trace[1845734356] 'process raft request' (duration: 453.936715ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:53:24.388083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:53:23.932073Z","time spent":"455.922484ms","remote":"127.0.0.1:37592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:53:25.469745Z","caller":"traceutil/trace.go:171","msg":"trace[298833957] transaction","detail":"{read_only:false; response_revision:25028; number_of_response:1; }","duration":"400.506545ms","start":"2026-08-12T11:53:25.069212Z","end":"2026-08-12T11:53:25.469719Z","steps":["trace[298833957] 'process raft request' (duration: 400.346362ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:53:25.469919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:53:25.06919Z","time spent":"400.638687ms","remote":"127.0.0.1:37592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:53:25.470019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.715325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-12T11:53:25.470083Z","caller":"traceutil/trace.go:171","msg":"trace[1991676758] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:25028; }","duration":"223.844238ms","start":"2026-08-12T11:53:25.246223Z","end":"2026-08-12T11:53:25.470067Z","steps":["trace[1991676758] 'agreement among raft nodes before linearized reading' (duration: 223.589903ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:25.469757Z","caller":"traceutil/trace.go:171","msg":"trace[917619657] linearizableReadLoop","detail":"{readStateIndex:25849; appliedIndex:25849; }","duration":"223.45893ms","start":"2026-08-12T11:53:25.246268Z","end":"2026-08-12T11:53:25.469727Z","steps":["trace[917619657] 'read index received' (duration: 223.44779ms)","trace[917619657] 'applied index is now lower than readState.Index' (duration: 8.84µs)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:53:25.470347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.905442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T11:53:25.470391Z","caller":"traceutil/trace.go:171","msg":"trace[1685738636] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:25028; }","duration":"176.987414ms","start":"2026-08-12T11:53:25.293392Z","end":"2026-08-12T11:53:25.470379Z","steps":["trace[1685738636] 'agreement among raft nodes before linearized reading' (duration: 176.907642ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:53:25.470706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.757731ms","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-12T11:53:25.470743Z","caller":"traceutil/trace.go:171","msg":"trace[590085691] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25028; }","duration":"161.828131ms","start":"2026-08-12T11:53:25.308906Z","end":"2026-08-12T11:53:25.470734Z","steps":["trace[590085691] 'agreement among raft nodes before linearized reading' (duration: 161.694079ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:53:25.470793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.860031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T11:53:25.470876Z","caller":"traceutil/trace.go:171","msg":"trace[1343770708] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:25028; }","duration":"162.979854ms","start":"2026-08-12T11:53:25.307876Z","end":"2026-08-12T11:53:25.470856Z","steps":["trace[1343770708] 'agreement among raft nodes before linearized reading' (duration: 162.861532ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:53:25.760932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.598204ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-12T11:53:25.761119Z","caller":"traceutil/trace.go:171","msg":"trace[1208887186] transaction","detail":"{read_only:false; response_revision:25029; number_of_response:1; }","duration":"284.037479ms","start":"2026-08-12T11:53:25.477041Z","end":"2026-08-12T11:53:25.761078Z","steps":["trace[1208887186] 'process raft request' (duration: 132.18983ms)","trace[1208887186] 'compare' (duration: 150.010933ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:53:25.761164Z","caller":"traceutil/trace.go:171","msg":"trace[1108870384] transaction","detail":"{read_only:false; response_revision:25030; number_of_response:1; }","duration":"221.746877ms","start":"2026-08-12T11:53:25.5394Z","end":"2026-08-12T11:53:25.761147Z","steps":["trace[1108870384] 'process raft request' (duration: 221.638005ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:25.761192Z","caller":"traceutil/trace.go:171","msg":"trace[1276128779] linearizableReadLoop","detail":"{readStateIndex:25850; appliedIndex:25849; }","duration":"276.369511ms","start":"2026-08-12T11:53:25.484789Z","end":"2026-08-12T11:53:25.761159Z","steps":["trace[1276128779] 'read index received' (duration: 124.455271ms)","trace[1276128779] 'applied index is now lower than readState.Index' (duration: 151.91121ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:53:25.761351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.536924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-12T11:53:25.761389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.650781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-12T11:53:25.761399Z","caller":"traceutil/trace.go:171","msg":"trace[1494045825] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:25030; }","duration":"276.638045ms","start":"2026-08-12T11:53:25.484747Z","end":"2026-08-12T11:53:25.761385Z","steps":["trace[1494045825] 'agreement among raft nodes before linearized reading' (duration: 276.539654ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:25.761429Z","caller":"traceutil/trace.go:171","msg":"trace[143770780] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:25030; }","duration":"185.742642ms","start":"2026-08-12T11:53:25.575673Z","end":"2026-08-12T11:53:25.761416Z","steps":["trace[143770780] 'agreement among raft nodes before linearized reading' (duration: 185.63815ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:53:26.084389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.905887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T11:53:26.084472Z","caller":"traceutil/trace.go:171","msg":"trace[197034871] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25030; }","duration":"128.039769ms","start":"2026-08-12T11:53:25.956414Z","end":"2026-08-12T11:53:26.084453Z","steps":["trace[197034871] 'range keys from in-memory index tree' (duration: 127.821914ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:53:26.39983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.088067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.223\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-08-12T11:53:26.399886Z","caller":"traceutil/trace.go:171","msg":"trace[1545281056] range","detail":"{range_begin:/registry/masterleases/199.19.213.223; range_end:; response_count:1; response_revision:25031; }","duration":"119.178599ms","start":"2026-08-12T11:53:26.280695Z","end":"2026-08-12T11:53:26.399874Z","steps":["trace[1545281056] 'range keys from in-memory index tree' (duration: 118.974046ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:26.52483Z","caller":"traceutil/trace.go:171","msg":"trace[1771856687] transaction","detail":"{read_only:false; response_revision:25032; number_of_response:1; }","duration":"132.572166ms","start":"2026-08-12T11:53:26.392223Z","end":"2026-08-12T11:53:26.524795Z","steps":["trace[1771856687] 'process raft request' (duration: 132.371562ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:26.587941Z","caller":"traceutil/trace.go:171","msg":"trace[307538991] transaction","detail":"{read_only:false; response_revision:25034; number_of_response:1; }","duration":"195.581162ms","start":"2026-08-12T11:53:26.392347Z","end":"2026-08-12T11:53:26.587928Z","steps":["trace[307538991] 'process raft request' (duration: 195.43416ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:26.588017Z","caller":"traceutil/trace.go:171","msg":"trace[196326436] transaction","detail":"{read_only:false; response_revision:25035; number_of_response:1; }","duration":"195.509161ms","start":"2026-08-12T11:53:26.392477Z","end":"2026-08-12T11:53:26.587986Z","steps":["trace[196326436] 'process raft request' (duration: 195.368888ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:26.588112Z","caller":"traceutil/trace.go:171","msg":"trace[998918297] transaction","detail":"{read_only:false; response_revision:25036; number_of_response:1; }","duration":"194.631293ms","start":"2026-08-12T11:53:26.393474Z","end":"2026-08-12T11:53:26.588105Z","steps":["trace[998918297] 'process raft request' (duration: 194.425149ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:26.588165Z","caller":"traceutil/trace.go:171","msg":"trace[132258765] transaction","detail":"{read_only:false; response_revision:25037; number_of_response:1; }","duration":"194.623463ms","start":"2026-08-12T11:53:26.393531Z","end":"2026-08-12T11:53:26.588155Z","steps":["trace[132258765] 'process raft request' (duration: 194.415279ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:26.588227Z","caller":"traceutil/trace.go:171","msg":"trace[472310504] transaction","detail":"{read_only:false; response_revision:25033; number_of_response:1; }","duration":"195.562671ms","start":"2026-08-12T11:53:26.392313Z","end":"2026-08-12T11:53:26.587876Z","steps":["trace[472310504] 'process raft request' (duration: 195.256446ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:26.588418Z","caller":"traceutil/trace.go:171","msg":"trace[1087508086] transaction","detail":"{read_only:false; response_revision:25038; number_of_response:1; }","duration":"184.756294ms","start":"2026-08-12T11:53:26.403655Z","end":"2026-08-12T11:53:26.588412Z","steps":["trace[1087508086] 'process raft request' (duration: 184.489929ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:26.588452Z","caller":"traceutil/trace.go:171","msg":"trace[1288616861] transaction","detail":"{read_only:false; response_revision:25039; number_of_response:1; }","duration":"175.472714ms","start":"2026-08-12T11:53:26.41296Z","end":"2026-08-12T11:53:26.588432Z","steps":["trace[1288616861] 'process raft request' (duration: 175.392112ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:27.592526Z","caller":"traceutil/trace.go:171","msg":"trace[2131396529] transaction","detail":"{read_only:false; response_revision:25043; number_of_response:1; }","duration":"117.050178ms","start":"2026-08-12T11:53:27.475454Z","end":"2026-08-12T11:53:27.592504Z","steps":["trace[2131396529] 'process raft request' (duration: 116.872785ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:53:28.080233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.311641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T11:53:28.080349Z","caller":"traceutil/trace.go:171","msg":"trace[928115681] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25044; }","duration":"119.472545ms","start":"2026-08-12T11:53:27.96085Z","end":"2026-08-12T11:53:28.080322Z","steps":["trace[928115681] 'range keys from in-memory index tree' (duration: 119.2206ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:28.384638Z","caller":"traceutil/trace.go:171","msg":"trace[2014496191] linearizableReadLoop","detail":"{readStateIndex:25867; appliedIndex:25866; }","duration":"131.70142ms","start":"2026-08-12T11:53:28.252911Z","end":"2026-08-12T11:53:28.384612Z","steps":["trace[2014496191] 'read index received' (duration: 131.457236ms)","trace[2014496191] 'applied index is now lower than readState.Index' (duration: 242.664µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:53:28.384727Z","caller":"traceutil/trace.go:171","msg":"trace[1045944443] transaction","detail":"{read_only:false; response_revision:25045; number_of_response:1; }","duration":"244.288902ms","start":"2026-08-12T11:53:28.140412Z","end":"2026-08-12T11:53:28.384701Z","steps":["trace[1045944443] 'process raft request' (duration: 244.021237ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:53:28.385155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.227351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:32 size:274834"} {"level":"info","ts":"2026-08-12T11:53:28.385215Z","caller":"traceutil/trace.go:171","msg":"trace[895608792] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:32; response_revision:25045; }","duration":"132.322503ms","start":"2026-08-12T11:53:28.252874Z","end":"2026-08-12T11:53:28.385196Z","steps":["trace[895608792] 'agreement among raft nodes before linearized reading' (duration: 131.878594ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:28.855246Z","caller":"traceutil/trace.go:171","msg":"trace[181398740] transaction","detail":"{read_only:false; response_revision:25047; number_of_response:1; }","duration":"261.205838ms","start":"2026-08-12T11:53:28.594016Z","end":"2026-08-12T11:53:28.855222Z","steps":["trace[181398740] 'process raft request' (duration: 255.228623ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:28.855501Z","caller":"traceutil/trace.go:171","msg":"trace[1630823636] transaction","detail":"{read_only:false; response_revision:25049; number_of_response:1; }","duration":"261.088405ms","start":"2026-08-12T11:53:28.594392Z","end":"2026-08-12T11:53:28.85548Z","steps":["trace[1630823636] 'process raft request' (duration: 260.965593ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:28.855584Z","caller":"traceutil/trace.go:171","msg":"trace[463046899] transaction","detail":"{read_only:false; response_revision:25048; number_of_response:1; }","duration":"261.375671ms","start":"2026-08-12T11:53:28.594178Z","end":"2026-08-12T11:53:28.855554Z","steps":["trace[463046899] 'process raft request' (duration: 260.980964ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:28.855666Z","caller":"traceutil/trace.go:171","msg":"trace[1745539596] transaction","detail":"{read_only:false; response_revision:25051; number_of_response:1; }","duration":"260.930483ms","start":"2026-08-12T11:53:28.594721Z","end":"2026-08-12T11:53:28.855652Z","steps":["trace[1745539596] 'process raft request' (duration: 260.80096ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:28.855662Z","caller":"traceutil/trace.go:171","msg":"trace[975712559] linearizableReadLoop","detail":"{readStateIndex:25874; appliedIndex:25868; }","duration":"253.536981ms","start":"2026-08-12T11:53:28.602105Z","end":"2026-08-12T11:53:28.855642Z","steps":["trace[975712559] 'read index received' (duration: 247.078036ms)","trace[975712559] 'applied index is now lower than readState.Index' (duration: 6.458035ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:53:28.85577Z","caller":"traceutil/trace.go:171","msg":"trace[481534830] transaction","detail":"{read_only:false; response_revision:25050; number_of_response:1; }","duration":"261.167688ms","start":"2026-08-12T11:53:28.594591Z","end":"2026-08-12T11:53:28.855758Z","steps":["trace[481534830] 'process raft request' (duration: 260.844241ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:53:28.855842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.350469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-12T11:53:28.855868Z","caller":"traceutil/trace.go:171","msg":"trace[1497513399] transaction","detail":"{read_only:false; response_revision:25052; number_of_response:1; }","duration":"254.766674ms","start":"2026-08-12T11:53:28.601092Z","end":"2026-08-12T11:53:28.855859Z","steps":["trace[1497513399] 'process raft request' (duration: 254.499979ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:28.855906Z","caller":"traceutil/trace.go:171","msg":"trace[83297455] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25052; }","duration":"263.451261ms","start":"2026-08-12T11:53:28.592443Z","end":"2026-08-12T11:53:28.855894Z","steps":["trace[83297455] 'agreement among raft nodes before linearized reading' (duration: 263.254727ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:53:28.855964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.844029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-12T11:53:28.856021Z","caller":"traceutil/trace.go:171","msg":"trace[1731239732] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:25052; }","duration":"194.948651ms","start":"2026-08-12T11:53:28.661059Z","end":"2026-08-12T11:53:28.856007Z","steps":["trace[1731239732] 'agreement among raft nodes before linearized reading' (duration: 194.773308ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:53:28.856096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.785058ms","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-12T11:53:28.856155Z","caller":"traceutil/trace.go:171","msg":"trace[1680392135] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25052; }","duration":"262.909581ms","start":"2026-08-12T11:53:28.593229Z","end":"2026-08-12T11:53:28.856138Z","steps":["trace[1680392135] 'agreement among raft nodes before linearized reading' (duration: 262.782179ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:53:32.388239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.390477ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-08-12T11:53:32.38835Z","caller":"traceutil/trace.go:171","msg":"trace[1251772646] linearizableReadLoop","detail":"{readStateIndex:25897; appliedIndex:25896; }","duration":"520.996078ms","start":"2026-08-12T11:53:31.867332Z","end":"2026-08-12T11:53:32.388328Z","steps":["trace[1251772646] 'read index received' (duration: 238.599791ms)","trace[1251772646] 'applied index is now lower than readState.Index' (duration: 282.394817ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:53:32.388804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.477198ms","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-12T11:53:32.388848Z","caller":"traceutil/trace.go:171","msg":"trace[834465717] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25074; }","duration":"521.59785ms","start":"2026-08-12T11:53:31.867239Z","end":"2026-08-12T11:53:32.388837Z","steps":["trace[834465717] 'agreement among raft nodes before linearized reading' (duration: 521.431327ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:53:32.388882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:53:31.867187Z","time spent":"521.686502ms","remote":"127.0.0.1:37592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-12T11:53:32.389039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.938665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1782"} {"level":"warn","ts":"2026-08-12T11:53:32.389093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.136719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-12T11:53:32.389185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.97046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T11:53:32.389209Z","caller":"traceutil/trace.go:171","msg":"trace[957423908] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:25074; }","duration":"131.307152ms","start":"2026-08-12T11:53:32.257875Z","end":"2026-08-12T11:53:32.389182Z","steps":["trace[957423908] 'agreement among raft nodes before linearized reading' (duration: 131.084078ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:32.389297Z","caller":"traceutil/trace.go:171","msg":"trace[479892442] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25074; }","duration":"434.116843ms","start":"2026-08-12T11:53:31.955166Z","end":"2026-08-12T11:53:32.389283Z","steps":["trace[479892442] 'agreement among raft nodes before linearized reading' (duration: 433.987531ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:53:32.389885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:53:31.955139Z","time spent":"434.727584ms","remote":"127.0.0.1:37356","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-12T11:53:32.389126Z","caller":"traceutil/trace.go:171","msg":"trace[604385595] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:25074; }","duration":"454.076388ms","start":"2026-08-12T11:53:31.935029Z","end":"2026-08-12T11:53:32.389106Z","steps":["trace[604385595] 'agreement among raft nodes before linearized reading' (duration: 453.840914ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:53:32.389995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:53:31.93501Z","time spent":"454.964965ms","remote":"127.0.0.1:37496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":1805,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" "} {"level":"info","ts":"2026-08-12T11:53:32.649083Z","caller":"traceutil/trace.go:171","msg":"trace[460558830] linearizableReadLoop","detail":"{readStateIndex:25898; appliedIndex:25897; }","duration":"248.959392ms","start":"2026-08-12T11:53:32.400098Z","end":"2026-08-12T11:53:32.649057Z","steps":["trace[460558830] 'read index received' (duration: 248.8547ms)","trace[460558830] 'applied index is now lower than readState.Index' (duration: 103.592µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:53:32.649127Z","caller":"traceutil/trace.go:171","msg":"trace[1570924476] transaction","detail":"{read_only:false; response_revision:25075; number_of_response:1; }","duration":"255.872226ms","start":"2026-08-12T11:53:32.393221Z","end":"2026-08-12T11:53:32.649094Z","steps":["trace[1570924476] 'process raft request' (duration: 255.653022ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:53:32.649266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.154166ms","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-12T11:53:32.649308Z","caller":"traceutil/trace.go:171","msg":"trace[329871689] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25075; }","duration":"249.242587ms","start":"2026-08-12T11:53:32.400053Z","end":"2026-08-12T11:53:32.649295Z","steps":["trace[329871689] 'agreement among raft nodes before linearized reading' (duration: 249.100824ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:32.95507Z","caller":"traceutil/trace.go:171","msg":"trace[1123248082] transaction","detail":"{read_only:false; response_revision:25077; number_of_response:1; }","duration":"300.925065ms","start":"2026-08-12T11:53:32.654129Z","end":"2026-08-12T11:53:32.955054Z","steps":["trace[1123248082] 'process raft request' (duration: 300.794793ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:53:32.955185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:53:32.654109Z","time spent":"301.014596ms","remote":"127.0.0.1:37592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:53:41.337123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.965224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-aac4b7b4-bb1a-4bd8-9121-e8bead8bd5fa\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-08-12T11:53:41.337191Z","caller":"traceutil/trace.go:171","msg":"trace[1235249731] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-aac4b7b4-bb1a-4bd8-9121-e8bead8bd5fa; range_end:; response_count:1; response_revision:25205; }","duration":"175.093627ms","start":"2026-08-12T11:53:41.162082Z","end":"2026-08-12T11:53:41.337176Z","steps":["trace[1235249731] 'range keys from in-memory index tree' (duration: 174.802441ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:53:41.33737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.969624ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-12T11:53:41.337521Z","caller":"traceutil/trace.go:171","msg":"trace[81430781] transaction","detail":"{read_only:false; response_revision:25206; number_of_response:1; }","duration":"147.705879ms","start":"2026-08-12T11:53:41.189782Z","end":"2026-08-12T11:53:41.337488Z","steps":["trace[81430781] 'process raft request' (duration: 27.51417ms)","trace[81430781] 'compare' (duration: 119.74869ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:53:41.772689Z","caller":"traceutil/trace.go:171","msg":"trace[190520342] transaction","detail":"{read_only:false; response_revision:25207; number_of_response:1; }","duration":"128.061ms","start":"2026-08-12T11:53:41.644602Z","end":"2026-08-12T11:53:41.772663Z","steps":["trace[190520342] 'process raft request' (duration: 127.906616ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:42.277725Z","caller":"traceutil/trace.go:171","msg":"trace[113532186] linearizableReadLoop","detail":"{readStateIndex:26034; appliedIndex:26033; }","duration":"230.333683ms","start":"2026-08-12T11:53:42.047369Z","end":"2026-08-12T11:53:42.277702Z","steps":["trace[113532186] 'read index received' (duration: 230.071198ms)","trace[113532186] 'applied index is now lower than readState.Index' (duration: 261.585µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:53:42.277809Z","caller":"traceutil/trace.go:171","msg":"trace[80224640] transaction","detail":"{read_only:false; response_revision:25208; number_of_response:1; }","duration":"270.770593ms","start":"2026-08-12T11:53:42.006995Z","end":"2026-08-12T11:53:42.277766Z","steps":["trace[80224640] 'process raft request' (duration: 270.510508ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:53:42.277934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.546567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:1 size:521"} {"level":"info","ts":"2026-08-12T11:53:42.277984Z","caller":"traceutil/trace.go:171","msg":"trace[1095184665] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:25208; }","duration":"230.626668ms","start":"2026-08-12T11:53:42.047333Z","end":"2026-08-12T11:53:42.27796Z","steps":["trace[1095184665] 'agreement among raft nodes before linearized reading' (duration: 230.484945ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:42.815448Z","caller":"traceutil/trace.go:171","msg":"trace[78938865] transaction","detail":"{read_only:false; response_revision:25209; number_of_response:1; }","duration":"115.073289ms","start":"2026-08-12T11:53:42.700347Z","end":"2026-08-12T11:53:42.81542Z","steps":["trace[78938865] 'process raft request' (duration: 114.795134ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:45.43637Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000065d8.wal"} {"level":"info","ts":"2026-08-12T11:53:46.328543Z","caller":"traceutil/trace.go:171","msg":"trace[1793434075] transaction","detail":"{read_only:false; response_revision:25249; number_of_response:1; }","duration":"198.178743ms","start":"2026-08-12T11:53:46.130343Z","end":"2026-08-12T11:53:46.328522Z","steps":["trace[1793434075] 'process raft request' (duration: 198.003939ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:53:46.607264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.333618ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-12T11:53:46.607458Z","caller":"traceutil/trace.go:171","msg":"trace[701621984] transaction","detail":"{read_only:false; response_revision:25251; number_of_response:1; }","duration":"274.128466ms","start":"2026-08-12T11:53:46.333312Z","end":"2026-08-12T11:53:46.607441Z","steps":["trace[701621984] 'process raft request' (duration: 274.052134ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:46.607503Z","caller":"traceutil/trace.go:171","msg":"trace[426053133] transaction","detail":"{read_only:false; response_revision:25250; number_of_response:1; }","duration":"476.838556ms","start":"2026-08-12T11:53:46.13063Z","end":"2026-08-12T11:53:46.607468Z","steps":["trace[426053133] 'process raft request' (duration: 282.218403ms)","trace[426053133] 'compare' (duration: 194.143344ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:53:46.607661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.926057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-12T11:53:46.607704Z","caller":"traceutil/trace.go:171","msg":"trace[708104572] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:25251; }","duration":"380.013018ms","start":"2026-08-12T11:53:46.227681Z","end":"2026-08-12T11:53:46.607694Z","steps":["trace[708104572] 'agreement among raft nodes before linearized reading' (duration: 379.923047ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:46.607516Z","caller":"traceutil/trace.go:171","msg":"trace[1721354626] linearizableReadLoop","detail":"{readStateIndex:26077; appliedIndex:26075; }","duration":"379.752693ms","start":"2026-08-12T11:53:46.227722Z","end":"2026-08-12T11:53:46.607475Z","steps":["trace[1721354626] 'read index received' (duration: 100.61846ms)","trace[1721354626] 'applied index is now lower than readState.Index' (duration: 279.131393ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:53:46.607738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:53:46.227662Z","time spent":"380.06736ms","remote":"127.0.0.1:37576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":7,"response size":31,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-08-12T11:53:46.607787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:53:46.130602Z","time spent":"477.087131ms","remote":"127.0.0.1:37592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:53:46.607903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.842608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T11:53:46.60797Z","caller":"traceutil/trace.go:171","msg":"trace[274227595] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:25251; }","duration":"377.916279ms","start":"2026-08-12T11:53:46.230036Z","end":"2026-08-12T11:53:46.607952Z","steps":["trace[274227595] 'agreement among raft nodes before linearized reading' (duration: 377.798797ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:53:46.608015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:53:46.229974Z","time spent":"378.030741ms","remote":"127.0.0.1:52398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true "} {"level":"warn","ts":"2026-08-12T11:53:46.608035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.902905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:9192"} {"level":"info","ts":"2026-08-12T11:53:46.608071Z","caller":"traceutil/trace.go:171","msg":"trace[1561244010] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:25251; }","duration":"256.973906ms","start":"2026-08-12T11:53:46.351087Z","end":"2026-08-12T11:53:46.608061Z","steps":["trace[1561244010] 'agreement among raft nodes before linearized reading' (duration: 256.853414ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:53:46.608095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.768471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T11:53:46.608134Z","caller":"traceutil/trace.go:171","msg":"trace[283163833] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools0; response_count:0; response_revision:25251; }","duration":"315.828672ms","start":"2026-08-12T11:53:46.292294Z","end":"2026-08-12T11:53:46.608123Z","steps":["trace[283163833] 'agreement among raft nodes before linearized reading' (duration: 315.76437ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:53:46.60818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:53:46.292285Z","time spent":"315.886793ms","remote":"127.0.0.1:41638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":140,"response count":0,"response size":29,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools0\" count_only:true "} {"level":"warn","ts":"2026-08-12T11:53:46.608372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.174561ms","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-12T11:53:46.608418Z","caller":"traceutil/trace.go:171","msg":"trace[1572879095] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:25251; }","duration":"186.258833ms","start":"2026-08-12T11:53:46.422147Z","end":"2026-08-12T11:53:46.608406Z","steps":["trace[1572879095] 'agreement among raft nodes before linearized reading' (duration: 186.067689ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:53:46.60841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.022558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.223\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-08-12T11:53:46.60847Z","caller":"traceutil/trace.go:171","msg":"trace[545869378] range","detail":"{range_begin:/registry/masterleases/199.19.213.223; range_end:; response_count:1; response_revision:25251; }","duration":"327.12483ms","start":"2026-08-12T11:53:46.281329Z","end":"2026-08-12T11:53:46.608453Z","steps":["trace[545869378] 'agreement among raft nodes before linearized reading' (duration: 326.838774ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:53:46.608519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:53:46.281307Z","time spent":"327.202181ms","remote":"127.0.0.1:37382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":163,"request content":"key:\"/registry/masterleases/199.19.213.223\" "} {"level":"info","ts":"2026-08-12T11:53:48.948243Z","caller":"traceutil/trace.go:171","msg":"trace[1142753195] transaction","detail":"{read_only:false; response_revision:25277; number_of_response:1; }","duration":"107.442542ms","start":"2026-08-12T11:53:48.840773Z","end":"2026-08-12T11:53:48.948216Z","steps":["trace[1142753195] 'process raft request' (duration: 107.313089ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:49.013879Z","caller":"traceutil/trace.go:171","msg":"trace[1261813836] transaction","detail":"{read_only:false; response_revision:25278; number_of_response:1; }","duration":"131.25207ms","start":"2026-08-12T11:53:48.882606Z","end":"2026-08-12T11:53:49.013858Z","steps":["trace[1261813836] 'process raft request' (duration: 131.125188ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:49.155382Z","caller":"traceutil/trace.go:171","msg":"trace[307378827] linearizableReadLoop","detail":"{readStateIndex:26107; appliedIndex:26105; }","duration":"199.196282ms","start":"2026-08-12T11:53:48.956161Z","end":"2026-08-12T11:53:49.155357Z","steps":["trace[307378827] 'read index received' (duration: 57.643791ms)","trace[307378827] 'applied index is now lower than readState.Index' (duration: 141.551511ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:53:49.155482Z","caller":"traceutil/trace.go:171","msg":"trace[924335221] transaction","detail":"{read_only:false; response_revision:25279; number_of_response:1; }","duration":"201.859613ms","start":"2026-08-12T11:53:48.95357Z","end":"2026-08-12T11:53:49.155429Z","steps":["trace[924335221] 'process raft request' (duration: 124.660264ms)","trace[924335221] 'compare' (duration: 77.012166ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:53:49.15554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.349915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T11:53:49.155578Z","caller":"traceutil/trace.go:171","msg":"trace[387801184] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25279; }","duration":"199.425187ms","start":"2026-08-12T11:53:48.956141Z","end":"2026-08-12T11:53:49.155566Z","steps":["trace[387801184] 'agreement among raft nodes before linearized reading' (duration: 199.337325ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:49.170102Z","caller":"traceutil/trace.go:171","msg":"trace[1880445751] transaction","detail":"{read_only:false; response_revision:25280; number_of_response:1; }","duration":"144.226392ms","start":"2026-08-12T11:53:49.025857Z","end":"2026-08-12T11:53:49.170084Z","steps":["trace[1880445751] 'process raft request' (duration: 144.046538ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:49.17044Z","caller":"traceutil/trace.go:171","msg":"trace[1033037284] transaction","detail":"{read_only:false; response_revision:25281; number_of_response:1; }","duration":"141.605111ms","start":"2026-08-12T11:53:49.028803Z","end":"2026-08-12T11:53:49.170408Z","steps":["trace[1033037284] 'process raft request' (duration: 141.225014ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:49.170572Z","caller":"traceutil/trace.go:171","msg":"trace[1202683646] transaction","detail":"{read_only:false; response_revision:25282; number_of_response:1; }","duration":"137.237216ms","start":"2026-08-12T11:53:49.033309Z","end":"2026-08-12T11:53:49.170547Z","steps":["trace[1202683646] 'process raft request' (duration: 136.955501ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:53:49.1707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.411905ms","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-12T11:53:49.170777Z","caller":"traceutil/trace.go:171","msg":"trace[770623753] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25284; }","duration":"130.505566ms","start":"2026-08-12T11:53:49.040251Z","end":"2026-08-12T11:53:49.170756Z","steps":["trace[770623753] 'agreement among raft nodes before linearized reading' (duration: 130.304493ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:53:49.170801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.786302ms","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-12T11:53:49.170846Z","caller":"traceutil/trace.go:171","msg":"trace[917286353] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25284; }","duration":"130.857454ms","start":"2026-08-12T11:53:49.039974Z","end":"2026-08-12T11:53:49.170832Z","steps":["trace[917286353] 'agreement among raft nodes before linearized reading' (duration: 130.736632ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:49.170913Z","caller":"traceutil/trace.go:171","msg":"trace[2111618625] transaction","detail":"{read_only:false; response_revision:25283; number_of_response:1; }","duration":"137.469711ms","start":"2026-08-12T11:53:49.033429Z","end":"2026-08-12T11:53:49.170899Z","steps":["trace[2111618625] 'process raft request' (duration: 136.923411ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:53:49.170937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.749201ms","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-12T11:53:49.171054Z","caller":"traceutil/trace.go:171","msg":"trace[612110432] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25284; }","duration":"131.900545ms","start":"2026-08-12T11:53:49.039131Z","end":"2026-08-12T11:53:49.171031Z","steps":["trace[612110432] 'agreement among raft nodes before linearized reading' (duration: 131.63668ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:49.171069Z","caller":"traceutil/trace.go:171","msg":"trace[2012238013] transaction","detail":"{read_only:false; response_revision:25284; number_of_response:1; }","duration":"135.733287ms","start":"2026-08-12T11:53:49.035319Z","end":"2026-08-12T11:53:49.171052Z","steps":["trace[2012238013] 'process raft request' (duration: 135.105965ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:53:49.171213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.460719ms","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-12T11:53:49.171257Z","caller":"traceutil/trace.go:171","msg":"trace[1044675861] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25284; }","duration":"127.530381ms","start":"2026-08-12T11:53:49.043718Z","end":"2026-08-12T11:53:49.171248Z","steps":["trace[1044675861] 'agreement among raft nodes before linearized reading' (duration: 127.354087ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:49.38042Z","caller":"traceutil/trace.go:171","msg":"trace[816483010] transaction","detail":"{read_only:false; response_revision:25285; number_of_response:1; }","duration":"205.699287ms","start":"2026-08-12T11:53:49.174691Z","end":"2026-08-12T11:53:49.380391Z","steps":["trace[816483010] 'process raft request' (duration: 134.370202ms)","trace[816483010] 'compare' (duration: 71.165682ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:53:49.430923Z","caller":"traceutil/trace.go:171","msg":"trace[413277711] transaction","detail":"{read_only:false; response_revision:25288; number_of_response:1; }","duration":"254.959447ms","start":"2026-08-12T11:53:49.175933Z","end":"2026-08-12T11:53:49.430893Z","steps":["trace[413277711] 'process raft request' (duration: 254.905676ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:49.431246Z","caller":"traceutil/trace.go:171","msg":"trace[723485052] transaction","detail":"{read_only:false; response_revision:25286; number_of_response:1; }","duration":"255.695401ms","start":"2026-08-12T11:53:49.175531Z","end":"2026-08-12T11:53:49.431227Z","steps":["trace[723485052] 'process raft request' (duration: 255.058189ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:49.431367Z","caller":"traceutil/trace.go:171","msg":"trace[1633484992] transaction","detail":"{read_only:false; response_revision:25287; number_of_response:1; }","duration":"255.702662ms","start":"2026-08-12T11:53:49.175652Z","end":"2026-08-12T11:53:49.431355Z","steps":["trace[1633484992] 'process raft request' (duration: 255.135821ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:54:13.398051Z","caller":"traceutil/trace.go:171","msg":"trace[1228837879] transaction","detail":"{read_only:false; response_revision:25478; number_of_response:1; }","duration":"138.698106ms","start":"2026-08-12T11:54:13.259313Z","end":"2026-08-12T11:54:13.398011Z","steps":["trace[1228837879] 'process raft request' (duration: 132.923464ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:54:13.423083Z","caller":"traceutil/trace.go:171","msg":"trace[2045997483] transaction","detail":"{read_only:false; response_revision:25479; number_of_response:1; }","duration":"161.991855ms","start":"2026-08-12T11:54:13.261064Z","end":"2026-08-12T11:54:13.423056Z","steps":["trace[2045997483] 'process raft request' (duration: 161.76011ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:54:13.423497Z","caller":"traceutil/trace.go:171","msg":"trace[450079556] linearizableReadLoop","detail":"{readStateIndex:26314; appliedIndex:26310; }","duration":"156.264454ms","start":"2026-08-12T11:54:13.267202Z","end":"2026-08-12T11:54:13.423466Z","steps":["trace[450079556] 'read index received' (duration: 125.050173ms)","trace[450079556] 'applied index is now lower than readState.Index' (duration: 31.212701ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:54:13.42353Z","caller":"traceutil/trace.go:171","msg":"trace[739985822] transaction","detail":"{read_only:false; response_revision:25480; number_of_response:1; }","duration":"160.910824ms","start":"2026-08-12T11:54:13.26257Z","end":"2026-08-12T11:54:13.423481Z","steps":["trace[739985822] 'process raft request' (duration: 160.437055ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:54:13.42358Z","caller":"traceutil/trace.go:171","msg":"trace[1666890462] transaction","detail":"{read_only:false; response_revision:25481; number_of_response:1; }","duration":"158.084029ms","start":"2026-08-12T11:54:13.265473Z","end":"2026-08-12T11:54:13.423557Z","steps":["trace[1666890462] 'process raft request' (duration: 157.859925ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:13.423725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.499209ms","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-12T11:54:13.423776Z","caller":"traceutil/trace.go:171","msg":"trace[768509975] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25481; }","duration":"156.610051ms","start":"2026-08-12T11:54:13.267152Z","end":"2026-08-12T11:54:13.423762Z","steps":["trace[768509975] 'agreement among raft nodes before linearized reading' (duration: 156.439988ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:54:13.704014Z","caller":"traceutil/trace.go:171","msg":"trace[483639486] transaction","detail":"{read_only:false; response_revision:25483; number_of_response:1; }","duration":"204.119927ms","start":"2026-08-12T11:54:13.499873Z","end":"2026-08-12T11:54:13.703993Z","steps":["trace[483639486] 'process raft request' (duration: 194.108013ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:54:13.900699Z","caller":"traceutil/trace.go:171","msg":"trace[13831245] linearizableReadLoop","detail":"{readStateIndex:26317; appliedIndex:26315; }","duration":"362.395249ms","start":"2026-08-12T11:54:13.538269Z","end":"2026-08-12T11:54:13.900665Z","steps":["trace[13831245] 'read index received' (duration: 155.725803ms)","trace[13831245] 'applied index is now lower than readState.Index' (duration: 206.667666ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:54:13.900772Z","caller":"traceutil/trace.go:171","msg":"trace[55127581] transaction","detail":"{read_only:false; response_revision:25484; number_of_response:1; }","duration":"385.664518ms","start":"2026-08-12T11:54:13.515084Z","end":"2026-08-12T11:54:13.900749Z","steps":["trace[55127581] 'process raft request' (duration: 354.259352ms)","trace[55127581] 'compare' (duration: 31.149041ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:54:13.900884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:54:13.515071Z","time spent":"385.736309ms","remote":"127.0.0.1:37592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:54:13.900995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.708685ms","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-12T11:54:13.901052Z","caller":"traceutil/trace.go:171","msg":"trace[165122698] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25484; }","duration":"362.789037ms","start":"2026-08-12T11:54:13.538243Z","end":"2026-08-12T11:54:13.901032Z","steps":["trace[165122698] 'agreement among raft nodes before linearized reading' (duration: 362.572343ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:13.901096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:54:13.53823Z","time spent":"362.854418ms","remote":"127.0.0.1:37592","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-12T11:54:13.952327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.620798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-12T11:54:13.952347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.907055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-08-12T11:54:13.952364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.848293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-12T11:54:13.952416Z","caller":"traceutil/trace.go:171","msg":"trace[1457365034] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddresses/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddresses0; response_count:0; response_revision:25485; }","duration":"259.761281ms","start":"2026-08-12T11:54:13.692632Z","end":"2026-08-12T11:54:13.952393Z","steps":["trace[1457365034] 'agreement among raft nodes before linearized reading' (duration: 259.609398ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:54:13.952435Z","caller":"traceutil/trace.go:171","msg":"trace[1785671487] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25485; }","duration":"412.051237ms","start":"2026-08-12T11:54:13.540363Z","end":"2026-08-12T11:54:13.952414Z","steps":["trace[1785671487] 'agreement among raft nodes before linearized reading' (duration: 411.811602ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:54:13.952443Z","caller":"traceutil/trace.go:171","msg":"trace[772572566] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/; range_end:/registry/ceph.rook.io/cephclusters0; response_count:0; response_revision:25485; }","duration":"342.019227ms","start":"2026-08-12T11:54:13.610405Z","end":"2026-08-12T11:54:13.952424Z","steps":["trace[772572566] 'agreement among raft nodes before linearized reading' (duration: 341.863924ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:13.952482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:54:13.540338Z","time spent":"412.135499ms","remote":"127.0.0.1:37592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-12T11:54:13.9525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:54:13.610383Z","time spent":"342.105858ms","remote":"127.0.0.1:52274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":31,"request content":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" count_only:true "} {"level":"warn","ts":"2026-08-12T11:54:13.952588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.805265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-12T11:54:13.952684Z","caller":"traceutil/trace.go:171","msg":"trace[983524212] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25485; }","duration":"408.944098ms","start":"2026-08-12T11:54:13.543722Z","end":"2026-08-12T11:54:13.952666Z","steps":["trace[983524212] 'agreement among raft nodes before linearized reading' (duration: 408.657422ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:13.952731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:54:13.543713Z","time spent":"409.009319ms","remote":"127.0.0.1:37592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-12T11:54:15.539894Z","caller":"traceutil/trace.go:171","msg":"trace[1941082791] transaction","detail":"{read_only:false; response_revision:25495; number_of_response:1; }","duration":"111.069162ms","start":"2026-08-12T11:54:15.428797Z","end":"2026-08-12T11:54:15.539866Z","steps":["trace[1941082791] 'process raft request' (duration: 110.97834ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:54:15.539938Z","caller":"traceutil/trace.go:171","msg":"trace[1929030479] transaction","detail":"{read_only:false; response_revision:25494; number_of_response:1; }","duration":"112.366617ms","start":"2026-08-12T11:54:15.427528Z","end":"2026-08-12T11:54:15.539895Z","steps":["trace[1929030479] 'process raft request' (duration: 112.052521ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:54:15.540113Z","caller":"traceutil/trace.go:171","msg":"trace[1919592901] linearizableReadLoop","detail":"{readStateIndex:26329; appliedIndex:26326; }","duration":"100.840965ms","start":"2026-08-12T11:54:15.439261Z","end":"2026-08-12T11:54:15.540102Z","steps":["trace[1919592901] 'read index received' (duration: 20.519047ms)","trace[1919592901] 'applied index is now lower than readState.Index' (duration: 80.320888ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:54:15.540336Z","caller":"traceutil/trace.go:171","msg":"trace[1756050029] transaction","detail":"{read_only:false; response_revision:25496; number_of_response:1; }","duration":"110.270487ms","start":"2026-08-12T11:54:15.430048Z","end":"2026-08-12T11:54:15.540318Z","steps":["trace[1756050029] 'process raft request' (duration: 109.778477ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:15.540443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.006058ms","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-12T11:54:15.540494Z","caller":"traceutil/trace.go:171","msg":"trace[309323143] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25496; }","duration":"101.253324ms","start":"2026-08-12T11:54:15.439229Z","end":"2026-08-12T11:54:15.540482Z","steps":["trace[309323143] 'agreement among raft nodes before linearized reading' (duration: 100.921007ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:54:15.991398Z","caller":"traceutil/trace.go:171","msg":"trace[261727134] transaction","detail":"{read_only:false; response_revision:25499; number_of_response:1; }","duration":"144.216272ms","start":"2026-08-12T11:54:15.847141Z","end":"2026-08-12T11:54:15.991357Z","steps":["trace[261727134] 'process raft request' (duration: 73.299323ms)","trace[261727134] 'compare' (duration: 70.773095ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:54:16.014515Z","caller":"traceutil/trace.go:171","msg":"trace[655548146] transaction","detail":"{read_only:false; response_revision:25500; number_of_response:1; }","duration":"108.91524ms","start":"2026-08-12T11:54:15.90558Z","end":"2026-08-12T11:54:16.014495Z","steps":["trace[655548146] 'process raft request' (duration: 108.827609ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:54:16.072801Z","caller":"traceutil/trace.go:171","msg":"trace[223257361] transaction","detail":"{read_only:false; response_revision:25501; number_of_response:1; }","duration":"115.230094ms","start":"2026-08-12T11:54:15.957535Z","end":"2026-08-12T11:54:16.072765Z","steps":["trace[223257361] 'process raft request' (duration: 115.05ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:54:16.217693Z","caller":"traceutil/trace.go:171","msg":"trace[379151947] transaction","detail":"{read_only:false; response_revision:25502; number_of_response:1; }","duration":"138.222686ms","start":"2026-08-12T11:54:16.079435Z","end":"2026-08-12T11:54:16.217657Z","steps":["trace[379151947] 'process raft request' (duration: 72.490458ms)","trace[379151947] 'compare' (duration: 65.479963ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:54:16.218074Z","caller":"traceutil/trace.go:171","msg":"trace[1020554370] transaction","detail":"{read_only:false; response_revision:25503; number_of_response:1; }","duration":"138.43715ms","start":"2026-08-12T11:54:16.079602Z","end":"2026-08-12T11:54:16.218039Z","steps":["trace[1020554370] 'process raft request' (duration: 137.990022ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:54:16.218125Z","caller":"traceutil/trace.go:171","msg":"trace[600429158] linearizableReadLoop","detail":"{readStateIndex:26337; appliedIndex:26334; }","duration":"129.891315ms","start":"2026-08-12T11:54:16.088192Z","end":"2026-08-12T11:54:16.218083Z","steps":["trace[600429158] 'read index received' (duration: 63.829641ms)","trace[600429158] 'applied index is now lower than readState.Index' (duration: 66.060624ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:54:16.218152Z","caller":"traceutil/trace.go:171","msg":"trace[1993178552] transaction","detail":"{read_only:false; response_revision:25504; number_of_response:1; }","duration":"137.038922ms","start":"2026-08-12T11:54:16.081069Z","end":"2026-08-12T11:54:16.218108Z","steps":["trace[1993178552] 'process raft request' (duration: 136.880229ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:16.218454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.226892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"info","ts":"2026-08-12T11:54:16.218564Z","caller":"traceutil/trace.go:171","msg":"trace[1919035960] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:25504; }","duration":"130.363755ms","start":"2026-08-12T11:54:16.088172Z","end":"2026-08-12T11:54:16.218536Z","steps":["trace[1919035960] 'agreement among raft nodes before linearized reading' (duration: 130.034808ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:54:16.43147Z","caller":"traceutil/trace.go:171","msg":"trace[1410188709] linearizableReadLoop","detail":"{readStateIndex:26338; appliedIndex:26337; }","duration":"116.995206ms","start":"2026-08-12T11:54:16.314432Z","end":"2026-08-12T11:54:16.431427Z","steps":["trace[1410188709] 'read index received' (duration: 36.058295ms)","trace[1410188709] 'applied index is now lower than readState.Index' (duration: 80.934521ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:54:16.431705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.224891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T11:54:16.431779Z","caller":"traceutil/trace.go:171","msg":"trace[1373000474] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:25504; }","duration":"117.330773ms","start":"2026-08-12T11:54:16.314424Z","end":"2026-08-12T11:54:16.431755Z","steps":["trace[1373000474] 'agreement among raft nodes before linearized reading' (duration: 117.161649ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:54:17.205822Z","caller":"traceutil/trace.go:171","msg":"trace[393237968] transaction","detail":"{read_only:false; response_revision:25508; number_of_response:1; }","duration":"123.199126ms","start":"2026-08-12T11:54:17.082594Z","end":"2026-08-12T11:54:17.205793Z","steps":["trace[393237968] 'process raft request' (duration: 122.935911ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:54:17.792819Z","caller":"traceutil/trace.go:171","msg":"trace[433414543] transaction","detail":"{read_only:false; response_revision:25511; number_of_response:1; }","duration":"246.108897ms","start":"2026-08-12T11:54:17.546683Z","end":"2026-08-12T11:54:17.792792Z","steps":["trace[433414543] 'process raft request' (duration: 184.167601ms)","trace[433414543] 'compare' (duration: 61.555898ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:54:17.793009Z","caller":"traceutil/trace.go:171","msg":"trace[1033131099] transaction","detail":"{read_only:false; response_revision:25512; number_of_response:1; }","duration":"246.316371ms","start":"2026-08-12T11:54:17.546683Z","end":"2026-08-12T11:54:17.793Z","steps":["trace[1033131099] 'process raft request' (duration: 246.008015ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:54:17.8028Z","caller":"traceutil/trace.go:171","msg":"trace[1711234615] linearizableReadLoop","detail":"{readStateIndex:26347; appliedIndex:26344; }","duration":"251.42775ms","start":"2026-08-12T11:54:17.551341Z","end":"2026-08-12T11:54:17.802769Z","steps":["trace[1711234615] 'read index received' (duration: 179.608654ms)","trace[1711234615] 'applied index is now lower than readState.Index' (duration: 71.817506ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:54:17.80291Z","caller":"traceutil/trace.go:171","msg":"trace[2123769572] transaction","detail":"{read_only:false; response_revision:25513; number_of_response:1; }","duration":"254.271725ms","start":"2026-08-12T11:54:17.548604Z","end":"2026-08-12T11:54:17.802876Z","steps":["trace[2123769572] 'process raft request' (duration: 253.618963ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:17.803014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.633784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-12T11:54:17.803078Z","caller":"traceutil/trace.go:171","msg":"trace[2026535714] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:25513; }","duration":"251.771497ms","start":"2026-08-12T11:54:17.551289Z","end":"2026-08-12T11:54:17.803061Z","steps":["trace[2026535714] 'agreement among raft nodes before linearized reading' (duration: 251.614064ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:17.803134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.22639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-12T11:54:17.803169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.824384ms","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-12T11:54:17.803202Z","caller":"traceutil/trace.go:171","msg":"trace[1013881683] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:25513; }","duration":"132.317122ms","start":"2026-08-12T11:54:17.670871Z","end":"2026-08-12T11:54:17.803188Z","steps":["trace[1013881683] 'agreement among raft nodes before linearized reading' (duration: 132.18207ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:54:17.803217Z","caller":"traceutil/trace.go:171","msg":"trace[910006431] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25513; }","duration":"185.943076ms","start":"2026-08-12T11:54:17.617261Z","end":"2026-08-12T11:54:17.803204Z","steps":["trace[910006431] 'agreement among raft nodes before linearized reading' (duration: 185.688692ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:18.044563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.773594ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-12T11:54:18.045158Z","caller":"traceutil/trace.go:171","msg":"trace[536357305] transaction","detail":"{read_only:false; response_revision:25514; number_of_response:1; }","duration":"237.44214ms","start":"2026-08-12T11:54:17.807686Z","end":"2026-08-12T11:54:18.045129Z","steps":["trace[536357305] 'process raft request' (duration: 93.991692ms)","trace[536357305] 'compare' (duration: 142.599131ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:54:18.044713Z","caller":"traceutil/trace.go:171","msg":"trace[562471216] linearizableReadLoop","detail":"{readStateIndex:26348; appliedIndex:26347; }","duration":"197.447008ms","start":"2026-08-12T11:54:17.847237Z","end":"2026-08-12T11:54:18.044684Z","steps":["trace[562471216] 'read index received' (duration: 54.331497ms)","trace[562471216] 'applied index is now lower than readState.Index' (duration: 143.112921ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:54:18.045523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.239715ms","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-12T11:54:18.045617Z","caller":"traceutil/trace.go:171","msg":"trace[1541572456] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25514; }","duration":"198.380237ms","start":"2026-08-12T11:54:17.847193Z","end":"2026-08-12T11:54:18.045573Z","steps":["trace[1541572456] 'agreement among raft nodes before linearized reading' (duration: 198.04104ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:18.103499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.864621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T11:54:18.10358Z","caller":"traceutil/trace.go:171","msg":"trace[811075472] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25515; }","duration":"147.998155ms","start":"2026-08-12T11:54:17.955564Z","end":"2026-08-12T11:54:18.103562Z","steps":["trace[811075472] 'agreement among raft nodes before linearized reading' (duration: 147.856952ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:18.10371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.296417ms","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-12T11:54:18.103786Z","caller":"traceutil/trace.go:171","msg":"trace[1913881339] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:25515; }","duration":"223.403169ms","start":"2026-08-12T11:54:17.880363Z","end":"2026-08-12T11:54:18.103766Z","steps":["trace[1913881339] 'agreement among raft nodes before linearized reading' (duration: 223.279307ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:18.104155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.769659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0\" ","response":"range_response_count:1 size:1864"} {"level":"info","ts":"2026-08-12T11:54:18.104189Z","caller":"traceutil/trace.go:171","msg":"trace[1503692905] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0; range_end:; response_count:1; response_revision:25515; }","duration":"232.823901ms","start":"2026-08-12T11:54:17.871356Z","end":"2026-08-12T11:54:18.10418Z","steps":["trace[1503692905] 'agreement among raft nodes before linearized reading' (duration: 232.006194ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:54:18.262894Z","caller":"traceutil/trace.go:171","msg":"trace[2117746773] linearizableReadLoop","detail":"{readStateIndex:26351; appliedIndex:26349; }","duration":"158.123299ms","start":"2026-08-12T11:54:18.104744Z","end":"2026-08-12T11:54:18.262867Z","steps":["trace[2117746773] 'read index received' (duration: 154.989468ms)","trace[2117746773] 'applied index is now lower than readState.Index' (duration: 3.133121ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:54:18.262904Z","caller":"traceutil/trace.go:171","msg":"trace[1494787863] transaction","detail":"{read_only:false; response_revision:25516; number_of_response:1; }","duration":"212.12726ms","start":"2026-08-12T11:54:18.050744Z","end":"2026-08-12T11:54:18.262871Z","steps":["trace[1494787863] 'process raft request' (duration: 208.99903ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:54:18.262932Z","caller":"traceutil/trace.go:171","msg":"trace[1627457062] transaction","detail":"{read_only:false; response_revision:25517; number_of_response:1; }","duration":"184.304824ms","start":"2026-08-12T11:54:18.078616Z","end":"2026-08-12T11:54:18.262921Z","steps":["trace[1627457062] 'process raft request' (duration: 184.185472ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:18.263065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.291233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T11:54:18.263103Z","caller":"traceutil/trace.go:171","msg":"trace[703234017] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25517; }","duration":"158.384504ms","start":"2026-08-12T11:54:18.104709Z","end":"2026-08-12T11:54:18.263093Z","steps":["trace[703234017] 'agreement among raft nodes before linearized reading' (duration: 158.273902ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:18.263303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.439038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-172551cd-750d-4e5b-8b74-a47f19aa9da9\" ","response":"range_response_count:1 size:1324"} {"level":"info","ts":"2026-08-12T11:54:18.263368Z","caller":"traceutil/trace.go:171","msg":"trace[1902616149] range","detail":"{range_begin:/registry/persistentvolumes/pvc-172551cd-750d-4e5b-8b74-a47f19aa9da9; range_end:; response_count:1; response_revision:25517; }","duration":"155.53738ms","start":"2026-08-12T11:54:18.107811Z","end":"2026-08-12T11:54:18.263348Z","steps":["trace[1902616149] 'agreement among raft nodes before linearized reading' (duration: 155.319605ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:54:18.524292Z","caller":"traceutil/trace.go:171","msg":"trace[196772450] transaction","detail":"{read_only:false; response_revision:25519; number_of_response:1; }","duration":"256.698491ms","start":"2026-08-12T11:54:18.267566Z","end":"2026-08-12T11:54:18.524264Z","steps":["trace[196772450] 'process raft request' (duration: 205.574115ms)","trace[196772450] 'compare' (duration: 50.950023ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:54:18.524516Z","caller":"traceutil/trace.go:171","msg":"trace[1912085403] transaction","detail":"{read_only:false; response_revision:25520; number_of_response:1; }","duration":"256.810624ms","start":"2026-08-12T11:54:18.267672Z","end":"2026-08-12T11:54:18.524482Z","steps":["trace[1912085403] 'process raft request' (duration: 256.551129ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:54:19.156858Z","caller":"traceutil/trace.go:171","msg":"trace[2094587474] transaction","detail":"{read_only:false; response_revision:25521; number_of_response:1; }","duration":"101.315404ms","start":"2026-08-12T11:54:19.055514Z","end":"2026-08-12T11:54:19.15683Z","steps":["trace[2094587474] 'process raft request' (duration: 101.006329ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:54:19.409194Z","caller":"traceutil/trace.go:171","msg":"trace[1133328467] transaction","detail":"{read_only:false; response_revision:25522; number_of_response:1; }","duration":"197.554241ms","start":"2026-08-12T11:54:19.211609Z","end":"2026-08-12T11:54:19.409163Z","steps":["trace[1133328467] 'process raft request' (duration: 197.379778ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:54:19.456301Z","caller":"traceutil/trace.go:171","msg":"trace[242607817] transaction","detail":"{read_only:false; response_revision:25523; number_of_response:1; }","duration":"160.526476ms","start":"2026-08-12T11:54:19.295744Z","end":"2026-08-12T11:54:19.45627Z","steps":["trace[242607817] 'process raft request' (duration: 160.305662ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:54:20.023459Z","caller":"traceutil/trace.go:171","msg":"trace[1445934033] transaction","detail":"{read_only:false; response_revision:25526; number_of_response:1; }","duration":"225.829446ms","start":"2026-08-12T11:54:19.797605Z","end":"2026-08-12T11:54:20.023435Z","steps":["trace[1445934033] 'process raft request' (duration: 184.301315ms)","trace[1445934033] 'compare' (duration: 41.367948ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:54:20.023669Z","caller":"traceutil/trace.go:171","msg":"trace[1942726583] linearizableReadLoop","detail":"{readStateIndex:26360; appliedIndex:26358; }","duration":"223.658495ms","start":"2026-08-12T11:54:19.799966Z","end":"2026-08-12T11:54:20.023624Z","steps":["trace[1942726583] 'read index received' (duration: 50.26922ms)","trace[1942726583] 'applied index is now lower than readState.Index' (duration: 173.386124ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:54:20.023704Z","caller":"traceutil/trace.go:171","msg":"trace[1779655431] transaction","detail":"{read_only:false; response_revision:25527; number_of_response:1; }","duration":"217.320832ms","start":"2026-08-12T11:54:19.806336Z","end":"2026-08-12T11:54:20.023657Z","steps":["trace[1779655431] 'process raft request' (duration: 217.060957ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:20.023823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.839118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/ports/\" range_end:\"/registry/openstack.k-orc.cloud/ports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T11:54:20.023869Z","caller":"traceutil/trace.go:171","msg":"trace[291361606] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/ports/; range_end:/registry/openstack.k-orc.cloud/ports0; response_count:0; response_revision:25527; }","duration":"223.897659ms","start":"2026-08-12T11:54:19.799958Z","end":"2026-08-12T11:54:20.023856Z","steps":["trace[291361606] 'agreement among raft nodes before linearized reading' (duration: 223.803247ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:54:20.224611Z","caller":"traceutil/trace.go:171","msg":"trace[811982766] transaction","detail":"{read_only:false; response_revision:25529; number_of_response:1; }","duration":"104.367724ms","start":"2026-08-12T11:54:20.120214Z","end":"2026-08-12T11:54:20.224582Z","steps":["trace[811982766] 'process raft request' (duration: 104.293232ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:54:20.225116Z","caller":"traceutil/trace.go:171","msg":"trace[1754791069] transaction","detail":"{read_only:false; response_revision:25528; number_of_response:1; }","duration":"169.55535ms","start":"2026-08-12T11:54:20.055541Z","end":"2026-08-12T11:54:20.225097Z","steps":["trace[1754791069] 'process raft request' (duration: 72.232053ms)","trace[1754791069] 'compare' (duration: 96.009232ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:54:20.225459Z","caller":"traceutil/trace.go:171","msg":"trace[850262306] linearizableReadLoop","detail":"{readStateIndex:26362; appliedIndex:26361; }","duration":"151.724936ms","start":"2026-08-12T11:54:20.073716Z","end":"2026-08-12T11:54:20.22544Z","steps":["trace[850262306] 'read index received' (duration: 54.065472ms)","trace[850262306] 'applied index is now lower than readState.Index' (duration: 97.657424ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:54:20.225581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.841689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-12T11:54:20.225624Z","caller":"traceutil/trace.go:171","msg":"trace[2126098805] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:25529; }","duration":"151.948031ms","start":"2026-08-12T11:54:20.07366Z","end":"2026-08-12T11:54:20.225608Z","steps":["trace[2126098805] 'agreement among raft nodes before linearized reading' (duration: 151.848199ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:20.654018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.234496ms","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-12T11:54:20.654118Z","caller":"traceutil/trace.go:171","msg":"trace[1758710965] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25532; }","duration":"124.371459ms","start":"2026-08-12T11:54:20.529723Z","end":"2026-08-12T11:54:20.654095Z","steps":["trace[1758710965] 'range keys from in-memory index tree' (duration: 124.075403ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:20.654536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.825517ms","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-12T11:54:20.654678Z","caller":"traceutil/trace.go:171","msg":"trace[1642631145] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25532; }","duration":"126.034811ms","start":"2026-08-12T11:54:20.528614Z","end":"2026-08-12T11:54:20.654648Z","steps":["trace[1642631145] 'range keys from in-memory index tree' (duration: 125.579632ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:21.730057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.298875ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-08-12T11:54:21.730599Z","caller":"traceutil/trace.go:171","msg":"trace[2001915398] linearizableReadLoop","detail":"{readStateIndex:26372; appliedIndex:26370; }","duration":"236.97496ms","start":"2026-08-12T11:54:21.493603Z","end":"2026-08-12T11:54:21.730578Z","steps":["trace[2001915398] 'read index received' (duration: 80.047334ms)","trace[2001915398] 'applied index is now lower than readState.Index' (duration: 156.926606ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:54:21.730615Z","caller":"traceutil/trace.go:171","msg":"trace[1217940511] transaction","detail":"{read_only:false; response_revision:25537; number_of_response:1; }","duration":"269.004349ms","start":"2026-08-12T11:54:21.461581Z","end":"2026-08-12T11:54:21.730585Z","steps":["trace[1217940511] 'process raft request' (duration: 268.57124ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:21.861124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.492958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T11:54:21.86122Z","caller":"traceutil/trace.go:171","msg":"trace[730934653] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:25537; }","duration":"367.641591ms","start":"2026-08-12T11:54:21.493552Z","end":"2026-08-12T11:54:21.861193Z","steps":["trace[730934653] 'agreement among raft nodes before linearized reading' (duration: 237.098963ms)","trace[730934653] 'count revisions from in-memory index tree' (duration: 130.405466ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:54:21.861268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:54:21.493523Z","time spent":"367.732802ms","remote":"127.0.0.1:36216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":0,"response size":29,"request content":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true "} {"level":"warn","ts":"2026-08-12T11:54:21.8616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.947066ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-12T11:54:21.861687Z","caller":"traceutil/trace.go:171","msg":"trace[1917270642] transaction","detail":"{read_only:false; response_revision:25538; number_of_response:1; }","duration":"284.169931ms","start":"2026-08-12T11:54:21.577502Z","end":"2026-08-12T11:54:21.861672Z","steps":["trace[1917270642] 'process raft request' (duration: 153.067972ms)","trace[1917270642] 'compare' (duration: 130.824314ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:54:42.799189Z","caller":"traceutil/trace.go:171","msg":"trace[2122295013] transaction","detail":"{read_only:false; response_revision:25698; number_of_response:1; }","duration":"407.196603ms","start":"2026-08-12T11:54:42.391966Z","end":"2026-08-12T11:54:42.799163Z","steps":["trace[2122295013] 'process raft request' (duration: 407.0164ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:42.799344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:54:42.391942Z","time spent":"407.327696ms","remote":"127.0.0.1:37592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:54:42.800843Z","caller":"traceutil/trace.go:171","msg":"trace[1364018077] linearizableReadLoop","detail":"{readStateIndex:26538; appliedIndex:26536; }","duration":"391.92946ms","start":"2026-08-12T11:54:42.408902Z","end":"2026-08-12T11:54:42.800831Z","steps":["trace[1364018077] 'read index received' (duration: 390.125255ms)","trace[1364018077] 'applied index is now lower than readState.Index' (duration: 1.803135ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:54:42.800916Z","caller":"traceutil/trace.go:171","msg":"trace[161481900] transaction","detail":"{read_only:false; response_revision:25699; number_of_response:1; }","duration":"403.430871ms","start":"2026-08-12T11:54:42.397472Z","end":"2026-08-12T11:54:42.800903Z","steps":["trace[161481900] 'process raft request' (duration: 403.299239ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:42.801012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.118173ms","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-12T11:54:42.801051Z","caller":"traceutil/trace.go:171","msg":"trace[573573321] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25699; }","duration":"392.197915ms","start":"2026-08-12T11:54:42.408844Z","end":"2026-08-12T11:54:42.801041Z","steps":["trace[573573321] 'agreement among raft nodes before linearized reading' (duration: 392.054162ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:42.801045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.053913ms","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-12T11:54:42.801082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:54:42.408829Z","time spent":"392.245366ms","remote":"127.0.0.1:37592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-12T11:54:42.801102Z","caller":"traceutil/trace.go:171","msg":"trace[492636039] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25699; }","duration":"279.146805ms","start":"2026-08-12T11:54:42.521945Z","end":"2026-08-12T11:54:42.801092Z","steps":["trace[492636039] 'agreement among raft nodes before linearized reading' (duration: 279.011062ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:42.801092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:54:42.39745Z","time spent":"403.495642ms","remote":"127.0.0.1:37592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:54:42.80123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.656962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-12T11:54:42.801294Z","caller":"traceutil/trace.go:171","msg":"trace[1422167738] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25699; }","duration":"226.801854ms","start":"2026-08-12T11:54:42.574478Z","end":"2026-08-12T11:54:42.80128Z","steps":["trace[1422167738] 'agreement among raft nodes before linearized reading' (duration: 226.533589ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:54:43.099284Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22814} {"level":"info","ts":"2026-08-12T11:54:43.311605Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22814,"took":"207.89963ms","hash":4205539550,"current-db-size-bytes":60542976,"current-db-size":"60 MB","current-db-size-in-use-bytes":35700736,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-08-12T11:54:43.311654Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4205539550,"revision":22814,"compact-revision":19988} {"level":"info","ts":"2026-08-12T11:54:48.159953Z","caller":"traceutil/trace.go:171","msg":"trace[1072821187] transaction","detail":"{read_only:false; response_revision:25740; number_of_response:1; }","duration":"133.378173ms","start":"2026-08-12T11:54:48.02655Z","end":"2026-08-12T11:54:48.159928Z","steps":["trace[1072821187] 'process raft request' (duration: 133.21144ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:48.437883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.578909ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-12T11:54:48.437973Z","caller":"traceutil/trace.go:171","msg":"trace[491633637] transaction","detail":"{read_only:false; response_revision:25741; number_of_response:1; }","duration":"369.66681ms","start":"2026-08-12T11:54:48.068288Z","end":"2026-08-12T11:54:48.437955Z","steps":["trace[491633637] 'process raft request' (duration: 266.935178ms)","trace[491633637] 'compare' (duration: 102.350435ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:54:48.438018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:54:48.068269Z","time spent":"369.727941ms","remote":"127.0.0.1:37592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:54:48.515364Z","caller":"traceutil/trace.go:171","msg":"trace[1422899782] linearizableReadLoop","detail":"{readStateIndex:26584; appliedIndex:26582; }","duration":"144.744842ms","start":"2026-08-12T11:54:48.370596Z","end":"2026-08-12T11:54:48.515341Z","steps":["trace[1422899782] 'read index received' (duration: 144.559878ms)","trace[1422899782] 'applied index is now lower than readState.Index' (duration: 184.514µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:54:48.5155Z","caller":"traceutil/trace.go:171","msg":"trace[642404323] transaction","detail":"{read_only:false; response_revision:25743; number_of_response:1; }","duration":"340.809983ms","start":"2026-08-12T11:54:48.174664Z","end":"2026-08-12T11:54:48.515474Z","steps":["trace[642404323] 'process raft request' (duration: 340.63569ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:54:48.515491Z","caller":"traceutil/trace.go:171","msg":"trace[947451165] transaction","detail":"{read_only:false; response_revision:25742; number_of_response:1; }","duration":"341.409365ms","start":"2026-08-12T11:54:48.174023Z","end":"2026-08-12T11:54:48.515432Z","steps":["trace[947451165] 'process raft request' (duration: 341.143069ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:48.515617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:54:48.174638Z","time spent":"340.910886ms","remote":"127.0.0.1:37592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:54:48.515546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.921036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-12T11:54:48.51574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:54:48.174001Z","time spent":"341.601279ms","remote":"127.0.0.1:37592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:54:48.515787Z","caller":"traceutil/trace.go:171","msg":"trace[1935052986] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:25743; }","duration":"145.201161ms","start":"2026-08-12T11:54:48.370559Z","end":"2026-08-12T11:54:48.51576Z","steps":["trace[1935052986] 'agreement among raft nodes before linearized reading' (duration: 144.864834ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:59:43.105353Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25705} {"level":"info","ts":"2026-08-12T11:59:43.241524Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25705,"took":"133.168098ms","hash":1688287102,"current-db-size-bytes":60542976,"current-db-size":"60 MB","current-db-size-in-use-bytes":38031360,"current-db-size-in-use":"38 MB"} {"level":"info","ts":"2026-08-12T11:59:43.2416Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1688287102,"revision":25705,"compact-revision":22814} {"level":"info","ts":"2026-08-12T12:00:32.137122Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"12dd93b2fab6fcb","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-12T12:00:32.143642Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-08-12T12:00:32.143766Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-08-12T12:04:43.112555Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28581} {"level":"info","ts":"2026-08-12T12:04:43.256987Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28581,"took":"140.335196ms","hash":2760466813,"current-db-size-bytes":60542976,"current-db-size":"60 MB","current-db-size-in-use-bytes":31838208,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-12T12:04:43.257055Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2760466813,"revision":28581,"compact-revision":25705} {"level":"info","ts":"2026-08-12T12:09:43.118912Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30906} {"level":"info","ts":"2026-08-12T12:09:43.253892Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30906,"took":"130.793463ms","hash":1170494403,"current-db-size-bytes":60542976,"current-db-size":"60 MB","current-db-size-in-use-bytes":31633408,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-12T12:09:43.254039Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1170494403,"revision":30906,"compact-revision":28581} {"level":"info","ts":"2026-08-12T12:14:43.125992Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33205} {"level":"info","ts":"2026-08-12T12:14:43.285066Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33205,"took":"154.439576ms","hash":278799967,"current-db-size-bytes":61583360,"current-db-size":"62 MB","current-db-size-in-use-bytes":32595968,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-08-12T12:14:43.285127Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":278799967,"revision":33205,"compact-revision":30906} {"level":"info","ts":"2026-08-12T12:19:43.134521Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35507} {"level":"info","ts":"2026-08-12T12:19:43.277558Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35507,"took":"140.120941ms","hash":1407811916,"current-db-size-bytes":64172032,"current-db-size":"64 MB","current-db-size-in-use-bytes":31866880,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-12T12:19:43.277616Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1407811916,"revision":35507,"compact-revision":33205} {"level":"info","ts":"2026-08-12T12:21:57.580623Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"12dd93b2fab6fcb","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-12T12:21:57.586377Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-08-12T12:21:57.586501Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-08-12T12:24:43.140185Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37736} {"level":"info","ts":"2026-08-12T12:24:43.263164Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37736,"took":"119.724908ms","hash":623867933,"current-db-size-bytes":64172032,"current-db-size":"64 MB","current-db-size-in-use-bytes":31809536,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-12T12:24:43.263224Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":623867933,"revision":37736,"compact-revision":35507} {"level":"info","ts":"2026-08-12T12:26:47.197878Z","caller":"traceutil/trace.go:171","msg":"trace[2034546546] transaction","detail":"{read_only:false; response_revision:40967; number_of_response:1; }","duration":"172.514106ms","start":"2026-08-12T12:26:47.025324Z","end":"2026-08-12T12:26:47.197838Z","steps":["trace[2034546546] 'process raft request' (duration: 172.294622ms)"],"step_count":1}