{"level":"warn","ts":"2026-08-22T10:40:26.132946Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-22T10:40:26.13304Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.179: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.179:2380","--initial-cluster=instance=https://199.19.213.179:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.179:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.179:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-08-22T10:40:26.133114Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-22T10:40:26.133126Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.179:2380"]} {"level":"info","ts":"2026-08-22T10:40:26.133145Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-22T10:40:26.133741Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.179:2379"]} {"level":"info","ts":"2026-08-22T10:40:26.133812Z","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.179:2380"],"listen-peer-urls":["https://199.19.213.179:2380"],"advertise-client-urls":["https://199.19.213.179:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.179:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.179:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-08-22T10:40:26.136339Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.281155ms"} {"level":"info","ts":"2026-08-22T10:40:26.141702Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"69895f7c3b831156","cluster-id":"caac76e5ff14ceff"} {"level":"info","ts":"2026-08-22T10:40:26.1418Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"69895f7c3b831156 switched to configuration voters=()"} {"level":"info","ts":"2026-08-22T10:40:26.141826Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"69895f7c3b831156 became follower at term 0"} {"level":"info","ts":"2026-08-22T10:40:26.141845Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 69895f7c3b831156 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-22T10:40:26.141859Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"69895f7c3b831156 became follower at term 1"} {"level":"info","ts":"2026-08-22T10:40:26.141904Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"69895f7c3b831156 switched to configuration voters=(7604714432970821974)"} {"level":"warn","ts":"2026-08-22T10:40:26.146555Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-22T10:40:26.147999Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-22T10:40:26.149782Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-08-22T10:40:26.153233Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"69895f7c3b831156","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-22T10:40:26.15501Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-22T10:40:26.155131Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-22T10:40:26.155146Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-22T10:40:26.154966Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"69895f7c3b831156","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-22T10:40:26.156924Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"69895f7c3b831156 switched to configuration voters=(7604714432970821974)"} {"level":"info","ts":"2026-08-22T10:40:26.15713Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"caac76e5ff14ceff","local-member-id":"69895f7c3b831156","added-peer-id":"69895f7c3b831156","added-peer-peer-urls":["https://199.19.213.179:2380"]} {"level":"info","ts":"2026-08-22T10:40:26.160853Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-22T10:40:26.16104Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"69895f7c3b831156","initial-advertise-peer-urls":["https://199.19.213.179:2380"],"listen-peer-urls":["https://199.19.213.179:2380"],"advertise-client-urls":["https://199.19.213.179:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.179:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-22T10:40:26.161067Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-22T10:40:26.161169Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.179:2380"} {"level":"info","ts":"2026-08-22T10:40:26.161188Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.179:2380"} {"level":"info","ts":"2026-08-22T10:40:26.442386Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"69895f7c3b831156 is starting a new election at term 1"} {"level":"info","ts":"2026-08-22T10:40:26.442455Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"69895f7c3b831156 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-22T10:40:26.442523Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"69895f7c3b831156 received MsgPreVoteResp from 69895f7c3b831156 at term 1"} {"level":"info","ts":"2026-08-22T10:40:26.442552Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"69895f7c3b831156 became candidate at term 2"} {"level":"info","ts":"2026-08-22T10:40:26.442567Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"69895f7c3b831156 received MsgVoteResp from 69895f7c3b831156 at term 2"} {"level":"info","ts":"2026-08-22T10:40:26.442594Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"69895f7c3b831156 became leader at term 2"} {"level":"info","ts":"2026-08-22T10:40:26.442616Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 69895f7c3b831156 elected leader 69895f7c3b831156 at term 2"} {"level":"info","ts":"2026-08-22T10:40:26.443487Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"69895f7c3b831156","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.179:2379]}","request-path":"/0/members/69895f7c3b831156/attributes","cluster-id":"caac76e5ff14ceff","publish-timeout":"7s"} {"level":"info","ts":"2026-08-22T10:40:26.443512Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-22T10:40:26.443641Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T10:40:26.443641Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-22T10:40:26.443905Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-22T10:40:26.443983Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-22T10:40:26.445314Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"caac76e5ff14ceff","local-member-id":"69895f7c3b831156","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T10:40:26.445441Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T10:40:26.445494Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T10:40:26.446421Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-22T10:40:26.447902Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.179:2379"} {"level":"info","ts":"2026-08-22T10:41:04.109351Z","caller":"traceutil/trace.go:171","msg":"trace[1629048897] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"101.773953ms","start":"2026-08-22T10:41:04.007562Z","end":"2026-08-22T10:41:04.109336Z","steps":["trace[1629048897] 'process raft request' (duration: 77.907778ms)","trace[1629048897] 'compare' (duration: 23.795274ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:41:04.110465Z","caller":"traceutil/trace.go:171","msg":"trace[396480087] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"101.439195ms","start":"2026-08-22T10:41:04.008995Z","end":"2026-08-22T10:41:04.110434Z","steps":["trace[396480087] 'process raft request' (duration: 101.342233ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:41:04.110587Z","caller":"traceutil/trace.go:171","msg":"trace[630895519] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"102.002969ms","start":"2026-08-22T10:41:04.008565Z","end":"2026-08-22T10:41:04.110568Z","steps":["trace[630895519] 'process raft request' (duration: 101.674611ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:41:04.110755Z","caller":"traceutil/trace.go:171","msg":"trace[1168898721] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"101.243991ms","start":"2026-08-22T10:41:04.00948Z","end":"2026-08-22T10:41:04.110724Z","steps":["trace[1168898721] 'process raft request' (duration: 100.881302ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:41:10.419349Z","caller":"traceutil/trace.go:171","msg":"trace[1227103532] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"148.110922ms","start":"2026-08-22T10:41:10.27122Z","end":"2026-08-22T10:41:10.419331Z","steps":["trace[1227103532] 'process raft request' (duration: 147.982229ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:41:10.490623Z","caller":"traceutil/trace.go:171","msg":"trace[1045499152] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"217.710302ms","start":"2026-08-22T10:41:10.272842Z","end":"2026-08-22T10:41:10.490552Z","steps":["trace[1045499152] 'process raft request' (duration: 189.050472ms)","trace[1045499152] 'compare' (duration: 28.372733ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:41:10.79721Z","caller":"traceutil/trace.go:171","msg":"trace[276048669] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"286.011183ms","start":"2026-08-22T10:41:10.511173Z","end":"2026-08-22T10:41:10.797185Z","steps":["trace[276048669] 'process raft request' (duration: 245.105362ms)","trace[276048669] 'compare' (duration: 40.555592ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:41:10.97399Z","caller":"traceutil/trace.go:171","msg":"trace[899190089] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"108.253137ms","start":"2026-08-22T10:41:10.865709Z","end":"2026-08-22T10:41:10.973962Z","steps":["trace[899190089] 'process raft request' (duration: 56.376877ms)","trace[899190089] 'compare' (duration: 51.755268ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:41:11.460831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.83258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:41:11.460978Z","caller":"traceutil/trace.go:171","msg":"trace[176705756] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:631; }","duration":"351.093686ms","start":"2026-08-22T10:41:11.109856Z","end":"2026-08-22T10:41:11.46095Z","steps":["trace[176705756] 'range keys from in-memory index tree' (duration: 350.728118ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:41:11.46103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:41:11.109832Z","time spent":"351.184388ms","remote":"127.0.0.1:38542","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-22T10:41:27.273092Z","caller":"traceutil/trace.go:171","msg":"trace[317319556] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"175.634025ms","start":"2026-08-22T10:41:27.097432Z","end":"2026-08-22T10:41:27.273066Z","steps":["trace[317319556] 'process raft request' (duration: 175.498962ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:42:42.987986Z","caller":"traceutil/trace.go:171","msg":"trace[1496661104] linearizableReadLoop","detail":"{readStateIndex:1938; appliedIndex:1937; }","duration":"117.342991ms","start":"2026-08-22T10:42:42.870599Z","end":"2026-08-22T10:42:42.987942Z","steps":["trace[1496661104] 'read index received' (duration: 117.128826ms)","trace[1496661104] 'applied index is now lower than readState.Index' (duration: 212.925µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:42:42.988057Z","caller":"traceutil/trace.go:171","msg":"trace[647018356] transaction","detail":"{read_only:false; response_revision:1870; number_of_response:1; }","duration":"117.999847ms","start":"2026-08-22T10:42:42.870023Z","end":"2026-08-22T10:42:42.988023Z","steps":["trace[647018356] 'process raft request' (duration: 117.71553ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:42:42.988217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.608177ms","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-22T10:42:42.988261Z","caller":"traceutil/trace.go:171","msg":"trace[235990122] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1870; }","duration":"117.777442ms","start":"2026-08-22T10:42:42.870473Z","end":"2026-08-22T10:42:42.988251Z","steps":["trace[235990122] 'agreement among raft nodes before linearized reading' (duration: 117.595187ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:42:43.35369Z","caller":"traceutil/trace.go:171","msg":"trace[576593535] linearizableReadLoop","detail":"{readStateIndex:1949; appliedIndex:1947; }","duration":"245.993176ms","start":"2026-08-22T10:42:43.107659Z","end":"2026-08-22T10:42:43.353652Z","steps":["trace[576593535] 'read index received' (duration: 244.803478ms)","trace[576593535] 'applied index is now lower than readState.Index' (duration: 1.188218ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:42:43.353875Z","caller":"traceutil/trace.go:171","msg":"trace[846922648] transaction","detail":"{read_only:false; response_revision:1880; number_of_response:1; }","duration":"298.676016ms","start":"2026-08-22T10:42:43.055176Z","end":"2026-08-22T10:42:43.353852Z","steps":["trace[846922648] 'process raft request' (duration: 297.201881ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:42:43.353839Z","caller":"traceutil/trace.go:171","msg":"trace[1876878049] transaction","detail":"{read_only:false; response_revision:1881; number_of_response:1; }","duration":"263.555013ms","start":"2026-08-22T10:42:43.090241Z","end":"2026-08-22T10:42:43.353796Z","steps":["trace[1876878049] 'process raft request' (duration: 263.304697ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:42:43.353999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.594639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-22T10:42:43.354059Z","caller":"traceutil/trace.go:171","msg":"trace[1810195595] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1881; }","duration":"185.693462ms","start":"2026-08-22T10:42:43.168349Z","end":"2026-08-22T10:42:43.354042Z","steps":["trace[1810195595] 'agreement among raft nodes before linearized reading' (duration: 185.516407ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:42:43.354087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.387595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:42:43.354868Z","caller":"traceutil/trace.go:171","msg":"trace[1109442866] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1881; }","duration":"247.223407ms","start":"2026-08-22T10:42:43.107605Z","end":"2026-08-22T10:42:43.354828Z","steps":["trace[1109442866] 'agreement among raft nodes before linearized reading' (duration: 246.259353ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:42:55.29491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.098603ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T10:42:55.295042Z","caller":"traceutil/trace.go:171","msg":"trace[922773910] linearizableReadLoop","detail":"{readStateIndex:2052; appliedIndex:2051; }","duration":"173.163228ms","start":"2026-08-22T10:42:55.121865Z","end":"2026-08-22T10:42:55.295029Z","steps":["trace[922773910] 'read index received' (duration: 72.40691ms)","trace[922773910] 'applied index is now lower than readState.Index' (duration: 100.754708ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:42:55.295151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.287441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-22T10:42:55.295128Z","caller":"traceutil/trace.go:171","msg":"trace[1545520546] transaction","detail":"{read_only:false; response_revision:1980; number_of_response:1; }","duration":"185.559939ms","start":"2026-08-22T10:42:55.109531Z","end":"2026-08-22T10:42:55.295091Z","steps":["trace[1545520546] 'process raft request' (duration: 84.757359ms)","trace[1545520546] 'compare' (duration: 99.836536ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:42:55.295177Z","caller":"traceutil/trace.go:171","msg":"trace[967004239] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1980; }","duration":"173.332382ms","start":"2026-08-22T10:42:55.121838Z","end":"2026-08-22T10:42:55.295171Z","steps":["trace[967004239] 'agreement among raft nodes before linearized reading' (duration: 173.223679ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:42:55.606624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.673189ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T10:42:55.607005Z","caller":"traceutil/trace.go:171","msg":"trace[305263363] linearizableReadLoop","detail":"{readStateIndex:2054; appliedIndex:2053; }","duration":"200.743186ms","start":"2026-08-22T10:42:55.406211Z","end":"2026-08-22T10:42:55.606955Z","steps":["trace[305263363] 'read index received' (duration: 98.582285ms)","trace[305263363] 'applied index is now lower than readState.Index' (duration: 102.155841ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:42:55.607243Z","caller":"traceutil/trace.go:171","msg":"trace[1578096481] transaction","detail":"{read_only:false; response_revision:1982; number_of_response:1; }","duration":"214.708696ms","start":"2026-08-22T10:42:55.392509Z","end":"2026-08-22T10:42:55.607217Z","steps":["trace[1578096481] 'process raft request' (duration: 112.3605ms)","trace[1578096481] 'compare' (duration: 101.555657ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:42:55.607646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.417283ms","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-22T10:42:55.607655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.115992ms","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-22T10:42:55.607721Z","caller":"traceutil/trace.go:171","msg":"trace[1993967090] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1982; }","duration":"201.534866ms","start":"2026-08-22T10:42:55.40617Z","end":"2026-08-22T10:42:55.607705Z","steps":["trace[1993967090] 'agreement among raft nodes before linearized reading' (duration: 201.321951ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:42:55.607742Z","caller":"traceutil/trace.go:171","msg":"trace[1237438687] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1982; }","duration":"174.221204ms","start":"2026-08-22T10:42:55.433499Z","end":"2026-08-22T10:42:55.607721Z","steps":["trace[1237438687] 'agreement among raft nodes before linearized reading' (duration: 174.021059ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:42:58.249291Z","caller":"traceutil/trace.go:171","msg":"trace[1818551052] transaction","detail":"{read_only:false; response_revision:2006; number_of_response:1; }","duration":"190.449228ms","start":"2026-08-22T10:42:58.058806Z","end":"2026-08-22T10:42:58.249255Z","steps":["trace[1818551052] 'process raft request' (duration: 190.235712ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:42:58.270568Z","caller":"traceutil/trace.go:171","msg":"trace[1585199720] transaction","detail":"{read_only:false; response_revision:2007; number_of_response:1; }","duration":"124.75111ms","start":"2026-08-22T10:42:58.145789Z","end":"2026-08-22T10:42:58.27054Z","steps":["trace[1585199720] 'process raft request' (duration: 124.564546ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:43:31.910175Z","caller":"traceutil/trace.go:171","msg":"trace[798522820] transaction","detail":"{read_only:false; response_revision:2192; number_of_response:1; }","duration":"116.651024ms","start":"2026-08-22T10:43:31.793494Z","end":"2026-08-22T10:43:31.910145Z","steps":["trace[798522820] 'process raft request' (duration: 96.72846ms)","trace[798522820] 'compare' (duration: 19.452723ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:43:32.518427Z","caller":"traceutil/trace.go:171","msg":"trace[1400470935] transaction","detail":"{read_only:false; response_revision:2194; number_of_response:1; }","duration":"126.69051ms","start":"2026-08-22T10:43:32.391705Z","end":"2026-08-22T10:43:32.518396Z","steps":["trace[1400470935] 'process raft request' (duration: 126.414494ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:43:38.130288Z","caller":"traceutil/trace.go:171","msg":"trace[1075001391] transaction","detail":"{read_only:false; response_revision:2226; number_of_response:1; }","duration":"102.91258ms","start":"2026-08-22T10:43:38.027344Z","end":"2026-08-22T10:43:38.130257Z","steps":["trace[1075001391] 'process raft request' (duration: 102.490622ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:44:13.761328Z","caller":"traceutil/trace.go:171","msg":"trace[120952845] transaction","detail":"{read_only:false; response_revision:2595; number_of_response:1; }","duration":"121.010279ms","start":"2026-08-22T10:44:13.64027Z","end":"2026-08-22T10:44:13.76128Z","steps":["trace[120952845] 'process raft request' (duration: 120.648533ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:44:48.119684Z","caller":"traceutil/trace.go:171","msg":"trace[1844905201] transaction","detail":"{read_only:false; response_revision:2801; number_of_response:1; }","duration":"191.593604ms","start":"2026-08-22T10:44:47.928064Z","end":"2026-08-22T10:44:48.119658Z","steps":["trace[1844905201] 'process raft request' (duration: 191.438161ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:44:48.199794Z","caller":"traceutil/trace.go:171","msg":"trace[930045701] transaction","detail":"{read_only:false; response_revision:2802; number_of_response:1; }","duration":"271.530019ms","start":"2026-08-22T10:44:47.928228Z","end":"2026-08-22T10:44:48.199758Z","steps":["trace[930045701] 'process raft request' (duration: 269.70033ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:44:48.199957Z","caller":"traceutil/trace.go:171","msg":"trace[1260735632] transaction","detail":"{read_only:false; response_revision:2803; number_of_response:1; }","duration":"193.799561ms","start":"2026-08-22T10:44:48.006146Z","end":"2026-08-22T10:44:48.199946Z","steps":["trace[1260735632] 'process raft request' (duration: 193.539996ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:44:48.622389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.397372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-22T10:44:48.622516Z","caller":"traceutil/trace.go:171","msg":"trace[573098620] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2803; }","duration":"182.541006ms","start":"2026-08-22T10:44:48.439933Z","end":"2026-08-22T10:44:48.622474Z","steps":["trace[573098620] 'range keys from in-memory index tree' (duration: 182.251029ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:44:48.622441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.347837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-22T10:44:48.622954Z","caller":"traceutil/trace.go:171","msg":"trace[245092491] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2803; }","duration":"278.92991ms","start":"2026-08-22T10:44:48.343994Z","end":"2026-08-22T10:44:48.622924Z","steps":["trace[245092491] 'range keys from in-memory index tree' (duration: 278.127683ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:47:13.555396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.930981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-08-22T10:47:13.555412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.029604ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T10:47:13.555499Z","caller":"traceutil/trace.go:171","msg":"trace[644718583] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3766; }","duration":"222.074784ms","start":"2026-08-22T10:47:13.333404Z","end":"2026-08-22T10:47:13.555479Z","steps":["trace[644718583] 'range keys from in-memory index tree' (duration: 221.791918ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:47:13.555598Z","caller":"traceutil/trace.go:171","msg":"trace[1246574498] transaction","detail":"{read_only:false; response_revision:3767; number_of_response:1; }","duration":"218.52638ms","start":"2026-08-22T10:47:13.337045Z","end":"2026-08-22T10:47:13.555571Z","steps":["trace[1246574498] 'process raft request' (duration: 103.25666ms)","trace[1246574498] 'compare' (duration: 114.917901ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:48:36.269587Z","caller":"traceutil/trace.go:171","msg":"trace[961245554] transaction","detail":"{read_only:false; response_revision:4944; number_of_response:1; }","duration":"128.657603ms","start":"2026-08-22T10:48:36.140897Z","end":"2026-08-22T10:48:36.269555Z","steps":["trace[961245554] 'process raft request' (duration: 128.50652ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:48:36.371127Z","caller":"traceutil/trace.go:171","msg":"trace[1173986951] linearizableReadLoop","detail":"{readStateIndex:5131; appliedIndex:5128; }","duration":"146.598646ms","start":"2026-08-22T10:48:36.224494Z","end":"2026-08-22T10:48:36.371092Z","steps":["trace[1173986951] 'read index received' (duration: 45.005816ms)","trace[1173986951] 'applied index is now lower than readState.Index' (duration: 101.59176ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:48:36.37115Z","caller":"traceutil/trace.go:171","msg":"trace[1401634903] transaction","detail":"{read_only:false; response_revision:4945; number_of_response:1; }","duration":"218.797178ms","start":"2026-08-22T10:48:36.152321Z","end":"2026-08-22T10:48:36.371118Z","steps":["trace[1401634903] 'process raft request' (duration: 209.102674ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:48:36.371368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.855983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-28ad19a8-6e16-48c5-9776-4044c6d2585b\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-08-22T10:48:36.371419Z","caller":"traceutil/trace.go:171","msg":"trace[1224117055] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-28ad19a8-6e16-48c5-9776-4044c6d2585b; range_end:; response_count:1; response_revision:4947; }","duration":"146.955556ms","start":"2026-08-22T10:48:36.224446Z","end":"2026-08-22T10:48:36.371402Z","steps":["trace[1224117055] 'agreement among raft nodes before linearized reading' (duration: 146.75981ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:48:36.371659Z","caller":"traceutil/trace.go:171","msg":"trace[660794704] transaction","detail":"{read_only:false; response_revision:4946; number_of_response:1; }","duration":"190.479484ms","start":"2026-08-22T10:48:36.181164Z","end":"2026-08-22T10:48:36.371643Z","steps":["trace[660794704] 'process raft request' (duration: 189.805137ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:48:36.371774Z","caller":"traceutil/trace.go:171","msg":"trace[849167111] transaction","detail":"{read_only:false; response_revision:4947; number_of_response:1; }","duration":"126.709617ms","start":"2026-08-22T10:48:36.245051Z","end":"2026-08-22T10:48:36.37176Z","steps":["trace[849167111] 'process raft request' (duration: 125.978039ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:48:47.92059Z","caller":"traceutil/trace.go:171","msg":"trace[480843106] transaction","detail":"{read_only:false; response_revision:5181; number_of_response:1; }","duration":"151.385201ms","start":"2026-08-22T10:48:47.769169Z","end":"2026-08-22T10:48:47.920554Z","steps":["trace[480843106] 'process raft request' (duration: 90.696388ms)","trace[480843106] 'compare' (duration: 60.427767ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:48:47.971691Z","caller":"traceutil/trace.go:171","msg":"trace[881958964] transaction","detail":"{read_only:false; response_revision:5182; number_of_response:1; }","duration":"201.031878ms","start":"2026-08-22T10:48:47.770639Z","end":"2026-08-22T10:48:47.971671Z","steps":["trace[881958964] 'process raft request' (duration: 200.894965ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:48:48.124528Z","caller":"traceutil/trace.go:171","msg":"trace[1559429231] transaction","detail":"{read_only:false; response_revision:5185; number_of_response:1; }","duration":"146.489513ms","start":"2026-08-22T10:48:47.978018Z","end":"2026-08-22T10:48:48.124507Z","steps":["trace[1559429231] 'process raft request' (duration: 146.38339ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:48:48.124518Z","caller":"traceutil/trace.go:171","msg":"trace[1243553484] transaction","detail":"{read_only:false; response_revision:5184; number_of_response:1; }","duration":"147.324154ms","start":"2026-08-22T10:48:47.97715Z","end":"2026-08-22T10:48:48.124474Z","steps":["trace[1243553484] 'process raft request' (duration: 99.615323ms)","trace[1243553484] 'compare' (duration: 47.503536ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:48:48.124742Z","caller":"traceutil/trace.go:171","msg":"trace[412848326] linearizableReadLoop","detail":"{readStateIndex:5378; appliedIndex:5375; }","duration":"112.655777ms","start":"2026-08-22T10:48:48.012053Z","end":"2026-08-22T10:48:48.124709Z","steps":["trace[412848326] 'read index received' (duration: 64.813684ms)","trace[412848326] 'applied index is now lower than readState.Index' (duration: 47.840763ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:48:48.124769Z","caller":"traceutil/trace.go:171","msg":"trace[702597067] transaction","detail":"{read_only:false; response_revision:5186; number_of_response:1; }","duration":"143.177893ms","start":"2026-08-22T10:48:47.981564Z","end":"2026-08-22T10:48:48.124742Z","steps":["trace[702597067] 'process raft request' (duration: 142.892216ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:48:48.124933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.144698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-22T10:48:48.12496Z","caller":"traceutil/trace.go:171","msg":"trace[813472218] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5186; }","duration":"130.21633ms","start":"2026-08-22T10:48:47.994737Z","end":"2026-08-22T10:48:48.124953Z","steps":["trace[813472218] 'agreement among raft nodes before linearized reading' (duration: 130.096027ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:48:48.132076Z","caller":"traceutil/trace.go:171","msg":"trace[1723003087] transaction","detail":"{read_only:false; response_revision:5189; number_of_response:1; }","duration":"111.007298ms","start":"2026-08-22T10:48:48.021056Z","end":"2026-08-22T10:48:48.132063Z","steps":["trace[1723003087] 'process raft request' (duration: 110.951297ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:48:48.132286Z","caller":"traceutil/trace.go:171","msg":"trace[383099450] transaction","detail":"{read_only:false; response_revision:5187; number_of_response:1; }","duration":"115.904056ms","start":"2026-08-22T10:48:48.016373Z","end":"2026-08-22T10:48:48.132277Z","steps":["trace[383099450] 'process raft request' (duration: 115.526397ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:48:48.132427Z","caller":"traceutil/trace.go:171","msg":"trace[1656076612] transaction","detail":"{read_only:false; response_revision:5188; number_of_response:1; }","duration":"115.952338ms","start":"2026-08-22T10:48:48.016468Z","end":"2026-08-22T10:48:48.13242Z","steps":["trace[1656076612] 'process raft request' (duration: 115.510507ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:48:48.132528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.489463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-22T10:48:48.132587Z","caller":"traceutil/trace.go:171","msg":"trace[750036581] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5189; }","duration":"107.599976ms","start":"2026-08-22T10:48:48.024974Z","end":"2026-08-22T10:48:48.132574Z","steps":["trace[750036581] 'agreement among raft nodes before linearized reading' (duration: 107.358091ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:48:48.495988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.019615ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T10:48:48.496292Z","caller":"traceutil/trace.go:171","msg":"trace[2130670942] linearizableReadLoop","detail":"{readStateIndex:5382; appliedIndex:5381; }","duration":"359.975723ms","start":"2026-08-22T10:48:48.136282Z","end":"2026-08-22T10:48:48.496258Z","steps":["trace[2130670942] 'read index received' (duration: 103.547308ms)","trace[2130670942] 'applied index is now lower than readState.Index' (duration: 256.423965ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:48:48.496518Z","caller":"traceutil/trace.go:171","msg":"trace[1971800926] transaction","detail":"{read_only:false; response_revision:5191; number_of_response:1; }","duration":"360.179578ms","start":"2026-08-22T10:48:48.13631Z","end":"2026-08-22T10:48:48.496489Z","steps":["trace[1971800926] 'process raft request' (duration: 359.808569ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:48:48.49697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:48:48.13628Z","time spent":"360.585758ms","remote":"127.0.0.1:38810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T10:48:48.496893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.583248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-lvz8f\" ","response":"range_response_count:1 size:7818"} {"level":"info","ts":"2026-08-22T10:48:48.496731Z","caller":"traceutil/trace.go:171","msg":"trace[1179121074] transaction","detail":"{read_only:false; response_revision:5190; number_of_response:1; }","duration":"367.711778ms","start":"2026-08-22T10:48:48.128976Z","end":"2026-08-22T10:48:48.496688Z","steps":["trace[1179121074] 'process raft request' (duration: 110.852893ms)","trace[1179121074] 'compare' (duration: 255.765358ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:48:48.497106Z","caller":"traceutil/trace.go:171","msg":"trace[1296460629] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-lvz8f; range_end:; response_count:1; response_revision:5193; }","duration":"360.787632ms","start":"2026-08-22T10:48:48.13626Z","end":"2026-08-22T10:48:48.497047Z","steps":["trace[1296460629] 'agreement among raft nodes before linearized reading' (duration: 360.425583ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:48:48.497169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:48:48.136244Z","time spent":"360.910086ms","remote":"127.0.0.1:38736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":7841,"request content":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-lvz8f\" "} {"level":"warn","ts":"2026-08-22T10:48:48.497308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:48:48.128937Z","time spent":"368.17345ms","remote":"127.0.0.1:38810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:50:26.486661Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3012} {"level":"info","ts":"2026-08-22T10:50:26.579862Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3012,"took":"91.005948ms","hash":1846128420,"current-db-size-bytes":58941440,"current-db-size":"59 MB","current-db-size-in-use-bytes":32247808,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-22T10:50:26.579949Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1846128420,"revision":3012,"compact-revision":-1} {"level":"info","ts":"2026-08-22T10:55:15.33908Z","caller":"traceutil/trace.go:171","msg":"trace[31834603] transaction","detail":"{read_only:false; response_revision:8247; number_of_response:1; }","duration":"221.259985ms","start":"2026-08-22T10:55:15.117789Z","end":"2026-08-22T10:55:15.339049Z","steps":["trace[31834603] 'process raft request' (duration: 221.035ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:55:26.495163Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6141} {"level":"info","ts":"2026-08-22T10:55:26.850979Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6141,"took":"353.171632ms","hash":1519663294,"current-db-size-bytes":58941440,"current-db-size":"59 MB","current-db-size-in-use-bytes":23273472,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-08-22T10:55:26.851068Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1519663294,"revision":6141,"compact-revision":3012} {"level":"info","ts":"2026-08-22T10:55:28.281772Z","caller":"traceutil/trace.go:171","msg":"trace[21971003] transaction","detail":"{read_only:false; response_revision:8315; number_of_response:1; }","duration":"180.075049ms","start":"2026-08-22T10:55:28.101666Z","end":"2026-08-22T10:55:28.281741Z","steps":["trace[21971003] 'process raft request' (duration: 179.901625ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:55:28.300777Z","caller":"traceutil/trace.go:171","msg":"trace[810287806] transaction","detail":"{read_only:false; response_revision:8316; number_of_response:1; }","duration":"196.376303ms","start":"2026-08-22T10:55:28.104368Z","end":"2026-08-22T10:55:28.300744Z","steps":["trace[810287806] 'process raft request' (duration: 196.202479ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:55:28.809923Z","caller":"traceutil/trace.go:171","msg":"trace[1744706703] transaction","detail":"{read_only:false; response_revision:8318; number_of_response:1; }","duration":"135.949945ms","start":"2026-08-22T10:55:28.673927Z","end":"2026-08-22T10:55:28.809877Z","steps":["trace[1744706703] 'process raft request' (duration: 135.727689ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:55:57.228053Z","caller":"traceutil/trace.go:171","msg":"trace[1607914045] linearizableReadLoop","detail":"{readStateIndex:8820; appliedIndex:8818; }","duration":"116.563977ms","start":"2026-08-22T10:55:57.111451Z","end":"2026-08-22T10:55:57.228015Z","steps":["trace[1607914045] 'read index received' (duration: 90.866516ms)","trace[1607914045] 'applied index is now lower than readState.Index' (duration: 25.69636ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:55:57.228071Z","caller":"traceutil/trace.go:171","msg":"trace[22887835] transaction","detail":"{read_only:false; response_revision:8523; number_of_response:1; }","duration":"169.392703ms","start":"2026-08-22T10:55:57.058637Z","end":"2026-08-22T10:55:57.22803Z","steps":["trace[22887835] 'process raft request' (duration: 143.672932ms)","trace[22887835] 'compare' (duration: 25.091686ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:55:57.22822Z","caller":"traceutil/trace.go:171","msg":"trace[1235365864] transaction","detail":"{read_only:false; response_revision:8524; number_of_response:1; }","duration":"168.101241ms","start":"2026-08-22T10:55:57.060047Z","end":"2026-08-22T10:55:57.228148Z","steps":["trace[1235365864] 'process raft request' (duration: 167.819054ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:55:57.228279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.811182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:55:57.228416Z","caller":"traceutil/trace.go:171","msg":"trace[478981257] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8524; }","duration":"117.012547ms","start":"2026-08-22T10:55:57.111369Z","end":"2026-08-22T10:55:57.228381Z","steps":["trace[478981257] 'agreement among raft nodes before linearized reading' (duration: 116.795932ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:55:59.762146Z","caller":"traceutil/trace.go:171","msg":"trace[730221103] linearizableReadLoop","detail":"{readStateIndex:8832; appliedIndex:8831; }","duration":"194.55467ms","start":"2026-08-22T10:55:59.567562Z","end":"2026-08-22T10:55:59.762117Z","steps":["trace[730221103] 'read index received' (duration: 194.442527ms)","trace[730221103] 'applied index is now lower than readState.Index' (duration: 110.973µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:55:59.762207Z","caller":"traceutil/trace.go:171","msg":"trace[740512231] transaction","detail":"{read_only:false; response_revision:8536; number_of_response:1; }","duration":"198.200058ms","start":"2026-08-22T10:55:59.563963Z","end":"2026-08-22T10:55:59.762163Z","steps":["trace[740512231] 'process raft request' (duration: 197.960441ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:55:59.763142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.552464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.179\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-22T10:55:59.763203Z","caller":"traceutil/trace.go:171","msg":"trace[1198652742] range","detail":"{range_begin:/registry/masterleases/199.19.213.179; range_end:; response_count:1; response_revision:8536; }","duration":"195.660947ms","start":"2026-08-22T10:55:59.567528Z","end":"2026-08-22T10:55:59.763189Z","steps":["trace[1198652742] 'agreement among raft nodes before linearized reading' (duration: 194.677444ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:56:07.442746Z","caller":"traceutil/trace.go:171","msg":"trace[106372527] linearizableReadLoop","detail":"{readStateIndex:8877; appliedIndex:8876; }","duration":"105.697152ms","start":"2026-08-22T10:56:07.337024Z","end":"2026-08-22T10:56:07.442721Z","steps":["trace[106372527] 'read index received' (duration: 105.59054ms)","trace[106372527] 'applied index is now lower than readState.Index' (duration: 104.942µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:56:07.442919Z","caller":"traceutil/trace.go:171","msg":"trace[1626430667] transaction","detail":"{read_only:false; response_revision:8578; number_of_response:1; }","duration":"107.567079ms","start":"2026-08-22T10:56:07.335299Z","end":"2026-08-22T10:56:07.442866Z","steps":["trace[1626430667] 'process raft request' (duration: 107.265451ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:56:07.442981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.943379ms","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-22T10:56:07.443218Z","caller":"traceutil/trace.go:171","msg":"trace[788687399] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8578; }","duration":"106.229826ms","start":"2026-08-22T10:56:07.33696Z","end":"2026-08-22T10:56:07.443189Z","steps":["trace[788687399] 'agreement among raft nodes before linearized reading' (duration: 105.859848ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:56:09.689937Z","caller":"traceutil/trace.go:171","msg":"trace[2030613248] linearizableReadLoop","detail":"{readStateIndex:8895; appliedIndex:8894; }","duration":"155.69885ms","start":"2026-08-22T10:56:09.534204Z","end":"2026-08-22T10:56:09.689903Z","steps":["trace[2030613248] 'read index received' (duration: 149.45553ms)","trace[2030613248] 'applied index is now lower than readState.Index' (duration: 6.24193ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:56:09.689994Z","caller":"traceutil/trace.go:171","msg":"trace[1302742795] transaction","detail":"{read_only:false; response_revision:8596; number_of_response:1; }","duration":"157.564926ms","start":"2026-08-22T10:56:09.532391Z","end":"2026-08-22T10:56:09.689956Z","steps":["trace[1302742795] 'process raft request' (duration: 151.319445ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:56:09.690126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.900465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-mkz4z\" ","response":"range_response_count:1 size:4636"} {"level":"info","ts":"2026-08-22T10:56:09.690171Z","caller":"traceutil/trace.go:171","msg":"trace[1067276593] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-mkz4z; range_end:; response_count:1; response_revision:8596; }","duration":"156.009029ms","start":"2026-08-22T10:56:09.53415Z","end":"2026-08-22T10:56:09.690159Z","steps":["trace[1067276593] 'agreement among raft nodes before linearized reading' (duration: 155.900285ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:56:09.690353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.026959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rook-ceph-mon\" ","response":"range_response_count:1 size:713"} {"level":"warn","ts":"2026-08-22T10:56:09.690426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.111088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-mkz4z\" ","response":"range_response_count:1 size:4636"} {"level":"info","ts":"2026-08-22T10:56:09.690425Z","caller":"traceutil/trace.go:171","msg":"trace[1324038183] range","detail":"{range_begin:/registry/secrets/openstack/rook-ceph-mon; range_end:; response_count:1; response_revision:8596; }","duration":"156.104471ms","start":"2026-08-22T10:56:09.534303Z","end":"2026-08-22T10:56:09.690408Z","steps":["trace[1324038183] 'agreement among raft nodes before linearized reading' (duration: 155.885306ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:56:09.690461Z","caller":"traceutil/trace.go:171","msg":"trace[768520913] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-mkz4z; range_end:; response_count:1; response_revision:8596; }","duration":"143.211091ms","start":"2026-08-22T10:56:09.547242Z","end":"2026-08-22T10:56:09.690453Z","steps":["trace[768520913] 'agreement among raft nodes before linearized reading' (duration: 143.098698ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:56:09.690721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.676594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.179\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-22T10:56:09.690745Z","caller":"traceutil/trace.go:171","msg":"trace[775735802] range","detail":"{range_begin:/registry/masterleases/199.19.213.179; range_end:; response_count:1; response_revision:8596; }","duration":"122.742445ms","start":"2026-08-22T10:56:09.567998Z","end":"2026-08-22T10:56:09.69074Z","steps":["trace[775735802] 'agreement among raft nodes before linearized reading' (duration: 122.620012ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:56:09.691043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.277685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-detect-version\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-08-22T10:56:09.69109Z","caller":"traceutil/trace.go:171","msg":"trace[1214097170] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-detect-version; range_end:; response_count:1; response_revision:8596; }","duration":"156.314616ms","start":"2026-08-22T10:56:09.534748Z","end":"2026-08-22T10:56:09.691063Z","steps":["trace[1214097170] 'agreement among raft nodes before linearized reading' (duration: 156.236864ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:56:09.691131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.691995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/rook-ceph-detect-version\" ","response":"range_response_count:1 size:3066"} {"level":"info","ts":"2026-08-22T10:56:09.691243Z","caller":"traceutil/trace.go:171","msg":"trace[120799593] range","detail":"{range_begin:/registry/jobs/openstack/rook-ceph-detect-version; range_end:; response_count:1; response_revision:8596; }","duration":"156.939161ms","start":"2026-08-22T10:56:09.53428Z","end":"2026-08-22T10:56:09.691219Z","steps":["trace[120799593] 'agreement among raft nodes before linearized reading' (duration: 156.348767ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:56:09.924436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.861962ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T10:56:09.924729Z","caller":"traceutil/trace.go:171","msg":"trace[904925597] transaction","detail":"{read_only:false; response_revision:8598; number_of_response:1; }","duration":"213.037287ms","start":"2026-08-22T10:56:09.711668Z","end":"2026-08-22T10:56:09.924706Z","steps":["trace[904925597] 'process raft request' (duration: 212.913654ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:56:09.92475Z","caller":"traceutil/trace.go:171","msg":"trace[374333885] transaction","detail":"{read_only:false; response_revision:8597; number_of_response:1; }","duration":"221.144852ms","start":"2026-08-22T10:56:09.703564Z","end":"2026-08-22T10:56:09.924709Z","steps":["trace[374333885] 'process raft request' (duration: 89.900031ms)","trace[374333885] 'compare' (duration: 130.601736ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:56:09.924821Z","caller":"traceutil/trace.go:171","msg":"trace[762423479] linearizableReadLoop","detail":"{readStateIndex:8897; appliedIndex:8896; }","duration":"214.02265ms","start":"2026-08-22T10:56:09.710732Z","end":"2026-08-22T10:56:09.924755Z","steps":["trace[762423479] 'read index received' (duration: 82.750169ms)","trace[762423479] 'applied index is now lower than readState.Index' (duration: 131.267021ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:56:09.925458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.237572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/rook-ceph-detect-version\" ","response":"range_response_count:1 size:3066"} {"level":"info","ts":"2026-08-22T10:56:09.925512Z","caller":"traceutil/trace.go:171","msg":"trace[1189074405] range","detail":"{range_begin:/registry/jobs/openstack/rook-ceph-detect-version; range_end:; response_count:1; response_revision:8598; }","duration":"223.332586ms","start":"2026-08-22T10:56:09.702166Z","end":"2026-08-22T10:56:09.925499Z","steps":["trace[1189074405] 'agreement among raft nodes before linearized reading' (duration: 222.856593ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:56:09.929835Z","caller":"traceutil/trace.go:171","msg":"trace[1854687864] transaction","detail":"{read_only:false; response_revision:8599; number_of_response:1; }","duration":"125.103222ms","start":"2026-08-22T10:56:09.804701Z","end":"2026-08-22T10:56:09.929804Z","steps":["trace[1854687864] 'process raft request' (duration: 124.623781ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:56:09.930341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.491499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-detect-version\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-08-22T10:56:09.930384Z","caller":"traceutil/trace.go:171","msg":"trace[1788543490] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-detect-version; range_end:; response_count:1; response_revision:8599; }","duration":"216.562761ms","start":"2026-08-22T10:56:09.713808Z","end":"2026-08-22T10:56:09.930371Z","steps":["trace[1788543490] 'agreement among raft nodes before linearized reading' (duration: 216.405678ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:56:45.331345Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002446.wal"} {"level":"info","ts":"2026-08-22T10:58:10.618447Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"69895f7c3b831156","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-22T10:58:10.62597Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-22T10:58:10.626115Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-22T11:00:26.502334Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8306} {"level":"info","ts":"2026-08-22T11:00:26.581027Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8306,"took":"75.926452ms","hash":1485410548,"current-db-size-bytes":58941440,"current-db-size":"59 MB","current-db-size-in-use-bytes":23625728,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-08-22T11:00:26.581097Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1485410548,"revision":8306,"compact-revision":6141} {"level":"info","ts":"2026-08-22T11:05:26.510727Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10525} {"level":"info","ts":"2026-08-22T11:05:26.597851Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10525,"took":"84.194683ms","hash":1403779589,"current-db-size-bytes":58941440,"current-db-size":"59 MB","current-db-size-in-use-bytes":26177536,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-08-22T11:05:26.597947Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1403779589,"revision":10525,"compact-revision":8306} {"level":"info","ts":"2026-08-22T11:09:34.414043Z","caller":"traceutil/trace.go:171","msg":"trace[1876317528] linearizableReadLoop","detail":"{readStateIndex:14937; appliedIndex:14936; }","duration":"109.276692ms","start":"2026-08-22T11:09:34.304736Z","end":"2026-08-22T11:09:34.414013Z","steps":["trace[1876317528] 'read index received' (duration: 109.072327ms)","trace[1876317528] 'applied index is now lower than readState.Index' (duration: 203.325µs)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:09:34.414286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.524219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-22T11:09:34.414302Z","caller":"traceutil/trace.go:171","msg":"trace[1979408678] transaction","detail":"{read_only:false; response_revision:14446; number_of_response:1; }","duration":"111.408654ms","start":"2026-08-22T11:09:34.302868Z","end":"2026-08-22T11:09:34.414277Z","steps":["trace[1979408678] 'process raft request' (duration: 110.967884ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:09:34.414363Z","caller":"traceutil/trace.go:171","msg":"trace[966484378] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14446; }","duration":"109.668172ms","start":"2026-08-22T11:09:34.304681Z","end":"2026-08-22T11:09:34.414349Z","steps":["trace[966484378] 'agreement among raft nodes before linearized reading' (duration: 109.458307ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:09:52.299901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.794195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T11:09:52.300054Z","caller":"traceutil/trace.go:171","msg":"trace[2144251763] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14551; }","duration":"189.985841ms","start":"2026-08-22T11:09:52.110053Z","end":"2026-08-22T11:09:52.300039Z","steps":["trace[2144251763] 'range keys from in-memory index tree' (duration: 189.692373ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:09:52.300367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.50685ms","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-22T11:09:52.300471Z","caller":"traceutil/trace.go:171","msg":"trace[2029454901] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14551; }","duration":"126.650444ms","start":"2026-08-22T11:09:52.173794Z","end":"2026-08-22T11:09:52.300444Z","steps":["trace[2029454901] 'range keys from in-memory index tree' (duration: 126.301525ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:09:52.300367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.985202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-tglbk\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-08-22T11:09:52.300565Z","caller":"traceutil/trace.go:171","msg":"trace[626333456] range","detail":"{range_begin:/registry/pods/openstack/keepalived-tglbk; range_end:; response_count:1; response_revision:14551; }","duration":"216.232919ms","start":"2026-08-22T11:09:52.084311Z","end":"2026-08-22T11:09:52.300544Z","steps":["trace[626333456] 'range keys from in-memory index tree' (duration: 215.767327ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:09:52.300671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.779039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-22T11:09:52.30081Z","caller":"traceutil/trace.go:171","msg":"trace[742978582] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14551; }","duration":"145.977284ms","start":"2026-08-22T11:09:52.1548Z","end":"2026-08-22T11:09:52.300777Z","steps":["trace[742978582] 'range keys from in-memory index tree' (duration: 145.418698ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:09:52.417877Z","caller":"traceutil/trace.go:171","msg":"trace[1370052631] transaction","detail":"{read_only:false; response_revision:14553; number_of_response:1; }","duration":"109.761633ms","start":"2026-08-22T11:09:52.308084Z","end":"2026-08-22T11:09:52.417845Z","steps":["trace[1370052631] 'process raft request' (duration: 109.510387ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:09:52.418188Z","caller":"traceutil/trace.go:171","msg":"trace[797547693] transaction","detail":"{read_only:false; response_revision:14554; number_of_response:1; }","duration":"103.512581ms","start":"2026-08-22T11:09:52.314653Z","end":"2026-08-22T11:09:52.418165Z","steps":["trace[797547693] 'process raft request' (duration: 103.139092ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:10:15.40737Z","caller":"traceutil/trace.go:171","msg":"trace[1146589357] transaction","detail":"{read_only:false; response_revision:14864; number_of_response:1; }","duration":"187.210203ms","start":"2026-08-22T11:10:15.220116Z","end":"2026-08-22T11:10:15.407327Z","steps":["trace[1146589357] 'process raft request' (duration: 171.036551ms)","trace[1146589357] 'compare' (duration: 15.519407ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:10:26.517445Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12765} {"level":"info","ts":"2026-08-22T11:10:26.601162Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12765,"took":"80.73303ms","hash":3445139441,"current-db-size-bytes":58941440,"current-db-size":"59 MB","current-db-size-in-use-bytes":29106176,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-08-22T11:10:26.601607Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3445139441,"revision":12765,"compact-revision":10525} {"level":"warn","ts":"2026-08-22T11:11:32.62319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.285084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-08-22T11:11:32.623289Z","caller":"traceutil/trace.go:171","msg":"trace[438264453] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15628; }","duration":"189.394156ms","start":"2026-08-22T11:11:32.433872Z","end":"2026-08-22T11:11:32.623266Z","steps":["trace[438264453] 'range keys from in-memory index tree' (duration: 189.13761ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:11:32.623755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.814729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-lf6q4\" ","response":"range_response_count:1 size:9799"} {"level":"info","ts":"2026-08-22T11:11:32.62414Z","caller":"traceutil/trace.go:171","msg":"trace[1805100853] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-lf6q4; range_end:; response_count:1; response_revision:15628; }","duration":"193.215759ms","start":"2026-08-22T11:11:32.430904Z","end":"2026-08-22T11:11:32.62412Z","steps":["trace[1805100853] 'range keys from in-memory index tree' (duration: 192.535594ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:15:26.525331Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14948} {"level":"info","ts":"2026-08-22T11:15:26.619141Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14948,"took":"90.67302ms","hash":1750867876,"current-db-size-bytes":58941440,"current-db-size":"59 MB","current-db-size-in-use-bytes":30937088,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-22T11:15:26.619237Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1750867876,"revision":14948,"compact-revision":12765} {"level":"info","ts":"2026-08-22T11:17:31.351082Z","caller":"traceutil/trace.go:171","msg":"trace[999431210] transaction","detail":"{read_only:false; response_revision:18268; number_of_response:1; }","duration":"118.555532ms","start":"2026-08-22T11:17:31.232477Z","end":"2026-08-22T11:17:31.351033Z","steps":["trace[999431210] 'process raft request' (duration: 118.128171ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:17:31.825954Z","caller":"traceutil/trace.go:171","msg":"trace[58022159] linearizableReadLoop","detail":"{readStateIndex:18891; appliedIndex:18890; }","duration":"202.823212ms","start":"2026-08-22T11:17:31.623103Z","end":"2026-08-22T11:17:31.825926Z","steps":["trace[58022159] 'read index received' (duration: 202.668728ms)","trace[58022159] 'applied index is now lower than readState.Index' (duration: 141.234µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:17:31.826031Z","caller":"traceutil/trace.go:171","msg":"trace[749749475] transaction","detail":"{read_only:false; response_revision:18270; number_of_response:1; }","duration":"235.564215ms","start":"2026-08-22T11:17:31.59041Z","end":"2026-08-22T11:17:31.825975Z","steps":["trace[749749475] 'process raft request' (duration: 235.34112ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:17:31.826194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.052907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:17:31.82629Z","caller":"traceutil/trace.go:171","msg":"trace[431503833] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:18270; }","duration":"203.206161ms","start":"2026-08-22T11:17:31.623058Z","end":"2026-08-22T11:17:31.826264Z","steps":["trace[431503833] 'agreement among raft nodes before linearized reading' (duration: 202.999176ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:17:32.249772Z","caller":"traceutil/trace.go:171","msg":"trace[279525284] linearizableReadLoop","detail":"{readStateIndex:18893; appliedIndex:18892; }","duration":"139.645402ms","start":"2026-08-22T11:17:32.110094Z","end":"2026-08-22T11:17:32.249739Z","steps":["trace[279525284] 'read index received' (duration: 139.385826ms)","trace[279525284] 'applied index is now lower than readState.Index' (duration: 258.226µs)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:17:32.249932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.809387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:17:32.249977Z","caller":"traceutil/trace.go:171","msg":"trace[1882680003] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18272; }","duration":"139.874598ms","start":"2026-08-22T11:17:32.110084Z","end":"2026-08-22T11:17:32.249959Z","steps":["trace[1882680003] 'agreement among raft nodes before linearized reading' (duration: 139.779626ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:17:32.249941Z","caller":"traceutil/trace.go:171","msg":"trace[1620179736] transaction","detail":"{read_only:false; response_revision:18272; number_of_response:1; }","duration":"169.043204ms","start":"2026-08-22T11:17:32.080837Z","end":"2026-08-22T11:17:32.24988Z","steps":["trace[1620179736] 'process raft request' (duration: 168.702985ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:19:23.404269Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"69895f7c3b831156","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-22T11:19:23.410208Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-22T11:19:23.410313Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-22T11:20:26.531159Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17421} {"level":"info","ts":"2026-08-22T11:20:26.628962Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17421,"took":"93.917035ms","hash":3073854050,"current-db-size-bytes":58941440,"current-db-size":"59 MB","current-db-size-in-use-bytes":34824192,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-08-22T11:20:26.629058Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3073854050,"revision":17421,"compact-revision":14948} {"level":"info","ts":"2026-08-22T11:23:24.345361Z","caller":"traceutil/trace.go:171","msg":"trace[1242303001] transaction","detail":"{read_only:false; response_revision:20993; number_of_response:1; }","duration":"108.297213ms","start":"2026-08-22T11:23:24.23702Z","end":"2026-08-22T11:23:24.345318Z","steps":["trace[1242303001] 'process raft request' (duration: 104.372599ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:23:29.872608Z","caller":"traceutil/trace.go:171","msg":"trace[2054107004] transaction","detail":"{read_only:false; response_revision:21020; number_of_response:1; }","duration":"165.240802ms","start":"2026-08-22T11:23:29.707332Z","end":"2026-08-22T11:23:29.872573Z","steps":["trace[2054107004] 'process raft request' (duration: 133.847672ms)","trace[2054107004] 'compare' (duration: 31.225796ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:23:30.36668Z","caller":"traceutil/trace.go:171","msg":"trace[109689330] transaction","detail":"{read_only:false; response_revision:21021; number_of_response:1; }","duration":"181.215998ms","start":"2026-08-22T11:23:30.185423Z","end":"2026-08-22T11:23:30.366639Z","steps":["trace[109689330] 'process raft request' (duration: 180.933301ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:23:30.497575Z","caller":"traceutil/trace.go:171","msg":"trace[1165722549] transaction","detail":"{read_only:false; response_revision:21022; number_of_response:1; }","duration":"133.012991ms","start":"2026-08-22T11:23:30.364514Z","end":"2026-08-22T11:23:30.497527Z","steps":["trace[1165722549] 'process raft request' (duration: 130.417299ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:25:26.549412Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19792} {"level":"info","ts":"2026-08-22T11:25:26.685821Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19792,"took":"124.452914ms","hash":2960388159,"current-db-size-bytes":58941440,"current-db-size":"59 MB","current-db-size-in-use-bytes":29810688,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-22T11:25:26.685906Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2960388159,"revision":19792,"compact-revision":17421} {"level":"info","ts":"2026-08-22T11:27:32.16283Z","caller":"traceutil/trace.go:171","msg":"trace[782914427] linearizableReadLoop","detail":"{readStateIndex:24323; appliedIndex:24322; }","duration":"159.724648ms","start":"2026-08-22T11:27:32.003078Z","end":"2026-08-22T11:27:32.162803Z","steps":["trace[782914427] 'read index received' (duration: 159.535764ms)","trace[782914427] 'applied index is now lower than readState.Index' (duration: 187.974µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:27:32.16297Z","caller":"traceutil/trace.go:171","msg":"trace[1858791873] transaction","detail":"{read_only:false; response_revision:23532; number_of_response:1; }","duration":"280.171675ms","start":"2026-08-22T11:27:31.882753Z","end":"2026-08-22T11:27:32.162924Z","steps":["trace[1858791873] 'process raft request' (duration: 279.798016ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:27:32.16309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.944274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:27:32.163202Z","caller":"traceutil/trace.go:171","msg":"trace[2055396159] range","detail":"{range_begin:/registry/ceph.rook.io/cephbucketnotifications/; range_end:/registry/ceph.rook.io/cephbucketnotifications0; response_count:0; response_revision:23532; }","duration":"160.182719ms","start":"2026-08-22T11:27:32.002996Z","end":"2026-08-22T11:27:32.163179Z","steps":["trace[2055396159] 'agreement among raft nodes before linearized reading' (duration: 159.937093ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:31.206615Z","caller":"traceutil/trace.go:171","msg":"trace[1215423353] transaction","detail":"{read_only:false; response_revision:24744; number_of_response:1; }","duration":"132.287043ms","start":"2026-08-22T11:29:31.074292Z","end":"2026-08-22T11:29:31.206579Z","steps":["trace[1215423353] 'process raft request' (duration: 35.617013ms)","trace[1215423353] 'compare' (duration: 96.409714ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:29:31.207009Z","caller":"traceutil/trace.go:171","msg":"trace[1366227920] transaction","detail":"{read_only:false; response_revision:24745; number_of_response:1; }","duration":"130.958882ms","start":"2026-08-22T11:29:31.076018Z","end":"2026-08-22T11:29:31.206976Z","steps":["trace[1366227920] 'process raft request' (duration: 130.49679ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:30:26.565471Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22011} {"level":"info","ts":"2026-08-22T11:30:26.73716Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22011,"took":"167.561608ms","hash":2058336543,"current-db-size-bytes":58941440,"current-db-size":"59 MB","current-db-size-in-use-bytes":44642304,"current-db-size-in-use":"45 MB"} {"level":"info","ts":"2026-08-22T11:30:26.737248Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2058336543,"revision":22011,"compact-revision":19792} {"level":"warn","ts":"2026-08-22T11:31:19.835789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.33474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.179\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-08-22T11:31:19.8359Z","caller":"traceutil/trace.go:171","msg":"trace[860785883] range","detail":"{range_begin:/registry/masterleases/199.19.213.179; range_end:; response_count:1; response_revision:25784; }","duration":"138.497274ms","start":"2026-08-22T11:31:19.697377Z","end":"2026-08-22T11:31:19.835875Z","steps":["trace[860785883] 'range keys from in-memory index tree' (duration: 138.027603ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:31:19.835887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.316068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-08-22T11:31:19.83597Z","caller":"traceutil/trace.go:171","msg":"trace[1824095318] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:25784; }","duration":"133.446012ms","start":"2026-08-22T11:31:19.702509Z","end":"2026-08-22T11:31:19.835955Z","steps":["trace[1824095318] 'range keys from in-memory index tree' (duration: 133.104163ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:31:19.836916Z","caller":"traceutil/trace.go:171","msg":"trace[986449631] transaction","detail":"{read_only:false; response_revision:25785; number_of_response:1; }","duration":"102.81538ms","start":"2026-08-22T11:31:19.734062Z","end":"2026-08-22T11:31:19.836878Z","steps":["trace[986449631] 'process raft request' (duration: 102.630386ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:31:20.131336Z","caller":"traceutil/trace.go:171","msg":"trace[9913318] transaction","detail":"{read_only:false; response_revision:25786; number_of_response:1; }","duration":"235.80123ms","start":"2026-08-22T11:31:19.895481Z","end":"2026-08-22T11:31:20.131282Z","steps":["trace[9913318] 'process raft request' (duration: 213.961481ms)","trace[9913318] 'compare' (duration: 21.075741ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:31:20.13737Z","caller":"traceutil/trace.go:171","msg":"trace[1428254161] linearizableReadLoop","detail":"{readStateIndex:26638; appliedIndex:26636; }","duration":"235.606506ms","start":"2026-08-22T11:31:19.901725Z","end":"2026-08-22T11:31:20.137332Z","steps":["trace[1428254161] 'read index received' (duration: 207.766531ms)","trace[1428254161] 'applied index is now lower than readState.Index' (duration: 27.838545ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:31:20.137443Z","caller":"traceutil/trace.go:171","msg":"trace[90239332] transaction","detail":"{read_only:false; response_revision:25787; number_of_response:1; }","duration":"238.830483ms","start":"2026-08-22T11:31:19.898575Z","end":"2026-08-22T11:31:20.137405Z","steps":["trace[90239332] 'process raft request' (duration: 238.546977ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:31:20.137706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.963825ms","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-22T11:31:20.137796Z","caller":"traceutil/trace.go:171","msg":"trace[1122779893] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25787; }","duration":"236.099957ms","start":"2026-08-22T11:31:19.901677Z","end":"2026-08-22T11:31:20.137777Z","steps":["trace[1122779893] 'agreement among raft nodes before linearized reading' (duration: 235.79182ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:31:20.137857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.723241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-08-22T11:31:20.137843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.169378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-08-22T11:31:20.137918Z","caller":"traceutil/trace.go:171","msg":"trace[396878530] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25787; }","duration":"226.846454ms","start":"2026-08-22T11:31:19.911052Z","end":"2026-08-22T11:31:20.137898Z","steps":["trace[396878530] 'agreement among raft nodes before linearized reading' (duration: 226.625059ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:31:20.137937Z","caller":"traceutil/trace.go:171","msg":"trace[98141172] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25787; }","duration":"231.309452ms","start":"2026-08-22T11:31:19.906608Z","end":"2026-08-22T11:31:20.137918Z","steps":["trace[98141172] 'agreement among raft nodes before linearized reading' (duration: 230.987034ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:31:20.312757Z","caller":"traceutil/trace.go:171","msg":"trace[213251544] transaction","detail":"{read_only:false; response_revision:25790; number_of_response:1; }","duration":"168.642884ms","start":"2026-08-22T11:31:20.144089Z","end":"2026-08-22T11:31:20.312731Z","steps":["trace[213251544] 'process raft request' (duration: 168.595493ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:31:20.312859Z","caller":"traceutil/trace.go:171","msg":"trace[1006115066] transaction","detail":"{read_only:false; response_revision:25789; number_of_response:1; }","duration":"169.708541ms","start":"2026-08-22T11:31:20.143098Z","end":"2026-08-22T11:31:20.312807Z","steps":["trace[1006115066] 'process raft request' (duration: 157.189237ms)","trace[1006115066] 'compare' (duration: 12.285658ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:31:25.216908Z","caller":"traceutil/trace.go:171","msg":"trace[487415063] transaction","detail":"{read_only:false; response_revision:25828; number_of_response:1; }","duration":"336.057739ms","start":"2026-08-22T11:31:24.88082Z","end":"2026-08-22T11:31:25.216878Z","steps":["trace[487415063] 'process raft request' (duration: 335.801173ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:31:25.217137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:31:24.880803Z","time spent":"336.208203ms","remote":"127.0.0.1:38810","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-22T11:31:25.388227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.512515ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-08-22T11:31:25.388941Z","caller":"traceutil/trace.go:171","msg":"trace[1323890982] transaction","detail":"{read_only:false; response_revision:25830; number_of_response:1; }","duration":"213.8532ms","start":"2026-08-22T11:31:25.17507Z","end":"2026-08-22T11:31:25.388923Z","steps":["trace[1323890982] 'process raft request' (duration: 213.472251ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:31:25.388825Z","caller":"traceutil/trace.go:171","msg":"trace[1816178493] transaction","detail":"{read_only:false; response_revision:25829; number_of_response:1; }","duration":"275.541094ms","start":"2026-08-22T11:31:25.113226Z","end":"2026-08-22T11:31:25.388767Z","steps":["trace[1816178493] 'process raft request' (duration: 275.148065ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:31:25.781559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.744301ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T11:31:25.781784Z","caller":"traceutil/trace.go:171","msg":"trace[306520014] transaction","detail":"{read_only:false; response_revision:25833; number_of_response:1; }","duration":"239.598994ms","start":"2026-08-22T11:31:25.542161Z","end":"2026-08-22T11:31:25.78176Z","steps":["trace[306520014] 'process raft request' (duration: 239.532702ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:31:25.781794Z","caller":"traceutil/trace.go:171","msg":"trace[491742026] transaction","detail":"{read_only:false; response_revision:25832; number_of_response:1; }","duration":"313.188176ms","start":"2026-08-22T11:31:25.468562Z","end":"2026-08-22T11:31:25.78175Z","steps":["trace[491742026] 'process raft request' (duration: 72.146278ms)","trace[491742026] 'compare' (duration: 240.622688ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:31:25.782045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:31:25.468531Z","time spent":"313.395231ms","remote":"127.0.0.1:38810","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-22T11:31:25.782051Z","caller":"traceutil/trace.go:171","msg":"trace[2025965009] linearizableReadLoop","detail":"{readStateIndex:26684; appliedIndex:26683; }","duration":"276.63943ms","start":"2026-08-22T11:31:25.505386Z","end":"2026-08-22T11:31:25.782025Z","steps":["trace[2025965009] 'read index received' (duration: 35.324626ms)","trace[2025965009] 'applied index is now lower than readState.Index' (duration: 241.313474ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:31:25.782271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.888397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/pxc-operator\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-08-22T11:31:25.782308Z","caller":"traceutil/trace.go:171","msg":"trace[766412652] range","detail":"{range_begin:/registry/serviceaccounts/openstack/pxc-operator; range_end:; response_count:1; response_revision:25833; }","duration":"276.958588ms","start":"2026-08-22T11:31:25.50534Z","end":"2026-08-22T11:31:25.782298Z","steps":["trace[766412652] 'agreement among raft nodes before linearized reading' (duration: 276.741432ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:31:25.986999Z","caller":"traceutil/trace.go:171","msg":"trace[1844671869] transaction","detail":"{read_only:false; response_revision:25834; number_of_response:1; }","duration":"122.906357ms","start":"2026-08-22T11:31:25.864052Z","end":"2026-08-22T11:31:25.986958Z","steps":["trace[1844671869] 'process raft request' (duration: 121.182656ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:31:26.360889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.637286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-08-22T11:31:26.360933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.891627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:31:26.360983Z","caller":"traceutil/trace.go:171","msg":"trace[2001616760] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25834; }","duration":"199.814771ms","start":"2026-08-22T11:31:26.161148Z","end":"2026-08-22T11:31:26.360962Z","steps":["trace[2001616760] 'range keys from in-memory index tree' (duration: 199.345379ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:31:26.361006Z","caller":"traceutil/trace.go:171","msg":"trace[52235315] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25834; }","duration":"251.01905ms","start":"2026-08-22T11:31:26.109969Z","end":"2026-08-22T11:31:26.360988Z","steps":["trace[52235315] 'range keys from in-memory index tree' (duration: 250.693842ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:31:26.361294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.139827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/volumes/\" range_end:\"/registry/openstack.k-orc.cloud/volumes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:31:26.361377Z","caller":"traceutil/trace.go:171","msg":"trace[319614449] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/volumes/; range_end:/registry/openstack.k-orc.cloud/volumes0; response_count:0; response_revision:25834; }","duration":"143.27378ms","start":"2026-08-22T11:31:26.218084Z","end":"2026-08-22T11:31:26.361358Z","steps":["trace[319614449] 'count revisions from in-memory index tree' (duration: 143.066715ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:31:26.361476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.934303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-08-22T11:31:26.361592Z","caller":"traceutil/trace.go:171","msg":"trace[1247206315] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25834; }","duration":"174.093696ms","start":"2026-08-22T11:31:26.187465Z","end":"2026-08-22T11:31:26.361559Z","steps":["trace[1247206315] 'range keys from in-memory index tree' (duration: 173.518732ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:31:26.472721Z","caller":"traceutil/trace.go:171","msg":"trace[468236546] transaction","detail":"{read_only:false; response_revision:25835; number_of_response:1; }","duration":"106.807348ms","start":"2026-08-22T11:31:26.365882Z","end":"2026-08-22T11:31:26.472689Z","steps":["trace[468236546] 'process raft request' (duration: 106.599873ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:31:26.811823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.595115ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T11:31:26.812047Z","caller":"traceutil/trace.go:171","msg":"trace[1190839534] linearizableReadLoop","detail":"{readStateIndex:26690; appliedIndex:26687; }","duration":"224.546128ms","start":"2026-08-22T11:31:26.587485Z","end":"2026-08-22T11:31:26.812031Z","steps":["trace[1190839534] 'read index received' (duration: 12.566394ms)","trace[1190839534] 'applied index is now lower than readState.Index' (duration: 211.978834ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:31:26.81215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.657531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:31:26.81218Z","caller":"traceutil/trace.go:171","msg":"trace[1422743233] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:25838; }","duration":"224.721763ms","start":"2026-08-22T11:31:26.58745Z","end":"2026-08-22T11:31:26.812171Z","steps":["trace[1422743233] 'agreement among raft nodes before linearized reading' (duration: 224.636941ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:31:26.812423Z","caller":"traceutil/trace.go:171","msg":"trace[1135939870] transaction","detail":"{read_only:false; response_revision:25836; number_of_response:1; }","duration":"446.469553ms","start":"2026-08-22T11:31:26.365942Z","end":"2026-08-22T11:31:26.812412Z","steps":["trace[1135939870] 'process raft request' (duration: 234.189482ms)","trace[1135939870] 'compare' (duration: 210.447857ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:31:26.812483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:31:26.365927Z","time spent":"446.529334ms","remote":"127.0.0.1:38810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T11:31:26.812877Z","caller":"traceutil/trace.go:171","msg":"trace[1819332189] transaction","detail":"{read_only:false; response_revision:25839; number_of_response:1; }","duration":"151.574581ms","start":"2026-08-22T11:31:26.661288Z","end":"2026-08-22T11:31:26.812863Z","steps":["trace[1819332189] 'process raft request' (duration: 151.52535ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:31:26.813209Z","caller":"traceutil/trace.go:171","msg":"trace[1288853487] transaction","detail":"{read_only:false; response_revision:25837; number_of_response:1; }","duration":"446.646467ms","start":"2026-08-22T11:31:26.36598Z","end":"2026-08-22T11:31:26.812627Z","steps":["trace[1288853487] 'process raft request' (duration: 445.950721ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:31:26.813357Z","caller":"traceutil/trace.go:171","msg":"trace[1453759318] transaction","detail":"{read_only:false; response_revision:25838; number_of_response:1; }","duration":"446.542855ms","start":"2026-08-22T11:31:26.366776Z","end":"2026-08-22T11:31:26.813318Z","steps":["trace[1453759318] 'process raft request' (duration: 445.216783ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:31:26.813405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:31:26.365813Z","time spent":"447.487828ms","remote":"127.0.0.1:38810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T11:31:26.813561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:31:26.366767Z","time spent":"446.693598ms","remote":"127.0.0.1:38810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T11:31:27.128961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.416265ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T11:31:27.129076Z","caller":"traceutil/trace.go:171","msg":"trace[2049276659] linearizableReadLoop","detail":"{readStateIndex:26692; appliedIndex:26691; }","duration":"148.957557ms","start":"2026-08-22T11:31:26.980102Z","end":"2026-08-22T11:31:27.129059Z","steps":["trace[2049276659] 'read index received' (duration: 62.651µs)","trace[2049276659] 'applied index is now lower than readState.Index' (duration: 148.893506ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:31:27.129197Z","caller":"traceutil/trace.go:171","msg":"trace[1378426971] transaction","detail":"{read_only:false; response_revision:25840; number_of_response:1; }","duration":"266.623267ms","start":"2026-08-22T11:31:26.862561Z","end":"2026-08-22T11:31:27.129185Z","steps":["trace[1378426971] 'process raft request' (duration: 105.872144ms)","trace[1378426971] 'compare' (duration: 160.20921ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:31:27.129378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.268345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:31:27.12942Z","caller":"traceutil/trace.go:171","msg":"trace[404502084] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:25840; }","duration":"149.349377ms","start":"2026-08-22T11:31:26.98006Z","end":"2026-08-22T11:31:27.129409Z","steps":["trace[404502084] 'agreement among raft nodes before linearized reading' (duration: 149.263935ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:31:27.436002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.872651ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T11:31:27.436129Z","caller":"traceutil/trace.go:171","msg":"trace[1818782003] transaction","detail":"{read_only:false; response_revision:25841; number_of_response:1; }","duration":"211.227516ms","start":"2026-08-22T11:31:27.224879Z","end":"2026-08-22T11:31:27.436106Z","steps":["trace[1818782003] 'process raft request' (duration: 76.915343ms)","trace[1818782003] 'compare' (duration: 133.731878ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:31:28.028407Z","caller":"traceutil/trace.go:171","msg":"trace[1870915486] transaction","detail":"{read_only:false; response_revision:25845; number_of_response:1; }","duration":"240.082135ms","start":"2026-08-22T11:31:27.788292Z","end":"2026-08-22T11:31:28.028374Z","steps":["trace[1870915486] 'process raft request' (duration: 239.858559ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:31:28.151672Z","caller":"traceutil/trace.go:171","msg":"trace[433980296] linearizableReadLoop","detail":"{readStateIndex:26699; appliedIndex:26696; }","duration":"251.805319ms","start":"2026-08-22T11:31:27.899841Z","end":"2026-08-22T11:31:28.151646Z","steps":["trace[433980296] 'read index received' (duration: 128.339458ms)","trace[433980296] 'applied index is now lower than readState.Index' (duration: 123.46485ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:31:28.151731Z","caller":"traceutil/trace.go:171","msg":"trace[156896175] transaction","detail":"{read_only:false; response_revision:25847; number_of_response:1; }","duration":"313.431332ms","start":"2026-08-22T11:31:27.838184Z","end":"2026-08-22T11:31:28.151616Z","steps":["trace[156896175] 'process raft request' (duration: 313.305759ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:31:28.151755Z","caller":"traceutil/trace.go:171","msg":"trace[1988900215] transaction","detail":"{read_only:false; response_revision:25848; number_of_response:1; }","duration":"159.068803ms","start":"2026-08-22T11:31:27.992642Z","end":"2026-08-22T11:31:28.151711Z","steps":["trace[1988900215] 'process raft request' (duration: 158.92635ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:31:28.151877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:31:27.838157Z","time spent":"313.627676ms","remote":"127.0.0.1:38810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T11:31:28.151974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.123576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:119"} {"level":"info","ts":"2026-08-22T11:31:28.152016Z","caller":"traceutil/trace.go:171","msg":"trace[1072715288] transaction","detail":"{read_only:false; response_revision:25846; number_of_response:1; }","duration":"363.023173ms","start":"2026-08-22T11:31:27.788983Z","end":"2026-08-22T11:31:28.152006Z","steps":["trace[1072715288] 'process raft request' (duration: 360.320338ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:31:28.152018Z","caller":"traceutil/trace.go:171","msg":"trace[1993220552] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:25848; }","duration":"252.208288ms","start":"2026-08-22T11:31:27.899794Z","end":"2026-08-22T11:31:28.152003Z","steps":["trace[1993220552] 'agreement among raft nodes before linearized reading' (duration: 252.023764ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:31:28.152069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:31:27.788968Z","time spent":"363.070914ms","remote":"127.0.0.1:38810","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-22T11:31:28.152495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.448743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:128540"} {"level":"info","ts":"2026-08-22T11:31:28.152556Z","caller":"traceutil/trace.go:171","msg":"trace[406023500] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:25848; }","duration":"247.613457ms","start":"2026-08-22T11:31:27.904927Z","end":"2026-08-22T11:31:28.15254Z","steps":["trace[406023500] 'agreement among raft nodes before linearized reading' (duration: 247.296959ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:31:28.632389Z","caller":"traceutil/trace.go:171","msg":"trace[157870921] transaction","detail":"{read_only:false; response_revision:25849; number_of_response:1; }","duration":"150.893074ms","start":"2026-08-22T11:31:28.481468Z","end":"2026-08-22T11:31:28.632361Z","steps":["trace[157870921] 'process raft request' (duration: 150.698159ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:31:28.980465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.859622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:31:28.980553Z","caller":"traceutil/trace.go:171","msg":"trace[1400996919] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagerconfigs/; range_end:/registry/monitoring.coreos.com/alertmanagerconfigs0; response_count:0; response_revision:25849; }","duration":"224.025355ms","start":"2026-08-22T11:31:28.756507Z","end":"2026-08-22T11:31:28.980532Z","steps":["trace[1400996919] 'count revisions from in-memory index tree' (duration: 223.75664ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:31:28.98052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.744871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-22T11:31:28.980623Z","caller":"traceutil/trace.go:171","msg":"trace[873274404] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:25849; }","duration":"304.881554ms","start":"2026-08-22T11:31:28.675715Z","end":"2026-08-22T11:31:28.980596Z","steps":["trace[873274404] 'count revisions from in-memory index tree' (duration: 304.623618ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:31:28.980675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:31:28.67564Z","time spent":"305.025908ms","remote":"127.0.0.1:43994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":31,"request content":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" count_only:true "} {"level":"info","ts":"2026-08-22T11:31:29.018142Z","caller":"traceutil/trace.go:171","msg":"trace[1075089803] linearizableReadLoop","detail":"{readStateIndex:26702; appliedIndex:26701; }","duration":"197.929833ms","start":"2026-08-22T11:31:28.820187Z","end":"2026-08-22T11:31:29.018116Z","steps":["trace[1075089803] 'read index received' (duration: 197.690968ms)","trace[1075089803] 'applied index is now lower than readState.Index' (duration: 237.835µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:31:29.018273Z","caller":"traceutil/trace.go:171","msg":"trace[511317825] transaction","detail":"{read_only:false; response_revision:25850; number_of_response:1; }","duration":"198.253361ms","start":"2026-08-22T11:31:28.819979Z","end":"2026-08-22T11:31:29.018232Z","steps":["trace[511317825] 'process raft request' (duration: 197.963724ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:31:29.018459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.192239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-08-22T11:31:29.018552Z","caller":"traceutil/trace.go:171","msg":"trace[610114582] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25850; }","duration":"198.414615ms","start":"2026-08-22T11:31:28.820127Z","end":"2026-08-22T11:31:29.018541Z","steps":["trace[610114582] 'agreement among raft nodes before linearized reading' (duration: 198.123648ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:31:29.018731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.620937ms","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-22T11:31:29.018746Z","caller":"traceutil/trace.go:171","msg":"trace[2669198] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25850; }","duration":"195.693029ms","start":"2026-08-22T11:31:28.823049Z","end":"2026-08-22T11:31:29.018742Z","steps":["trace[2669198] 'agreement among raft nodes before linearized reading' (duration: 195.625058ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:31:29.018887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.364385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-08-22T11:31:29.018969Z","caller":"traceutil/trace.go:171","msg":"trace[1563814093] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25850; }","duration":"198.488837ms","start":"2026-08-22T11:31:28.820459Z","end":"2026-08-22T11:31:29.018948Z","steps":["trace[1563814093] 'agreement among raft nodes before linearized reading' (duration: 197.879452ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:31:29.318284Z","caller":"traceutil/trace.go:171","msg":"trace[1275485316] transaction","detail":"{read_only:false; response_revision:25852; number_of_response:1; }","duration":"293.948709ms","start":"2026-08-22T11:31:29.024291Z","end":"2026-08-22T11:31:29.318239Z","steps":["trace[1275485316] 'process raft request' (duration: 272.571272ms)","trace[1275485316] 'compare' (duration: 20.725091ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:31:29.318337Z","caller":"traceutil/trace.go:171","msg":"trace[1031871465] transaction","detail":"{read_only:false; response_revision:25853; number_of_response:1; }","duration":"292.77773ms","start":"2026-08-22T11:31:29.025516Z","end":"2026-08-22T11:31:29.318294Z","steps":["trace[1031871465] 'process raft request' (duration: 292.596496ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:31:29.318507Z","caller":"traceutil/trace.go:171","msg":"trace[762057877] linearizableReadLoop","detail":"{readStateIndex:26705; appliedIndex:26702; }","duration":"292.060364ms","start":"2026-08-22T11:31:29.026414Z","end":"2026-08-22T11:31:29.318474Z","steps":["trace[762057877] 'read index received' (duration: 30.384616ms)","trace[762057877] 'applied index is now lower than readState.Index' (duration: 261.674128ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:31:29.318794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.354232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-08-22T11:31:29.318841Z","caller":"traceutil/trace.go:171","msg":"trace[1601590088] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25853; }","duration":"292.437003ms","start":"2026-08-22T11:31:29.026393Z","end":"2026-08-22T11:31:29.31883Z","steps":["trace[1601590088] 'agreement among raft nodes before linearized reading' (duration: 292.215778ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:31:29.327877Z","caller":"traceutil/trace.go:171","msg":"trace[1382298804] transaction","detail":"{read_only:false; response_revision:25854; number_of_response:1; }","duration":"192.185105ms","start":"2026-08-22T11:31:29.135661Z","end":"2026-08-22T11:31:29.327846Z","steps":["trace[1382298804] 'process raft request' (duration: 192.007251ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:31:29.327958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.407989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:31:29.328014Z","caller":"traceutil/trace.go:171","msg":"trace[1253184278] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25854; }","duration":"213.533722ms","start":"2026-08-22T11:31:29.114463Z","end":"2026-08-22T11:31:29.327997Z","steps":["trace[1253184278] 'agreement among raft nodes before linearized reading' (duration: 213.388478ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:31:29.674591Z","caller":"traceutil/trace.go:171","msg":"trace[1484632189] transaction","detail":"{read_only:false; response_revision:25856; number_of_response:1; }","duration":"208.690395ms","start":"2026-08-22T11:31:29.465844Z","end":"2026-08-22T11:31:29.674534Z","steps":["trace[1484632189] 'process raft request' (duration: 167.266451ms)","trace[1484632189] 'compare' (duration: 40.988212ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:31:29.674663Z","caller":"traceutil/trace.go:171","msg":"trace[1958242742] transaction","detail":"{read_only:false; response_revision:25857; number_of_response:1; }","duration":"208.601813ms","start":"2026-08-22T11:31:29.466029Z","end":"2026-08-22T11:31:29.674631Z","steps":["trace[1958242742] 'process raft request' (duration: 208.404038ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:31:29.67488Z","caller":"traceutil/trace.go:171","msg":"trace[1990328163] linearizableReadLoop","detail":"{readStateIndex:26709; appliedIndex:26706; }","duration":"205.540489ms","start":"2026-08-22T11:31:29.469305Z","end":"2026-08-22T11:31:29.674846Z","steps":["trace[1990328163] 'read index received' (duration: 89.618761ms)","trace[1990328163] 'applied index is now lower than readState.Index' (duration: 115.919048ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:31:29.674979Z","caller":"traceutil/trace.go:171","msg":"trace[1191542021] transaction","detail":"{read_only:false; response_revision:25858; number_of_response:1; }","duration":"202.253198ms","start":"2026-08-22T11:31:29.472693Z","end":"2026-08-22T11:31:29.674946Z","steps":["trace[1191542021] 'process raft request' (duration: 201.884129ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:31:29.67506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.732143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:31:29.675111Z","caller":"traceutil/trace.go:171","msg":"trace[284034753] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:25858; }","duration":"205.845776ms","start":"2026-08-22T11:31:29.469247Z","end":"2026-08-22T11:31:29.675093Z","steps":["trace[284034753] 'agreement among raft nodes before linearized reading' (duration: 205.722403ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:31:29.981286Z","caller":"traceutil/trace.go:171","msg":"trace[75817186] linearizableReadLoop","detail":"{readStateIndex:26711; appliedIndex:26710; }","duration":"202.102215ms","start":"2026-08-22T11:31:29.779152Z","end":"2026-08-22T11:31:29.981255Z","steps":["trace[75817186] 'read index received' (duration: 190.75123ms)","trace[75817186] 'applied index is now lower than readState.Index' (duration: 11.349265ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:31:29.981436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.256658ms","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-22T11:31:29.981471Z","caller":"traceutil/trace.go:171","msg":"trace[900729951] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:25858; }","duration":"202.371161ms","start":"2026-08-22T11:31:29.77909Z","end":"2026-08-22T11:31:29.981461Z","steps":["trace[900729951] 'agreement among raft nodes before linearized reading' (duration: 202.263748ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:31:30.311565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.964215ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T11:31:30.31172Z","caller":"traceutil/trace.go:171","msg":"trace[887387466] linearizableReadLoop","detail":"{readStateIndex:26713; appliedIndex:26712; }","duration":"241.426047ms","start":"2026-08-22T11:31:30.070274Z","end":"2026-08-22T11:31:30.3117Z","steps":["trace[887387466] 'read index received' (duration: 126.313289ms)","trace[887387466] 'applied index is now lower than readState.Index' (duration: 115.110968ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:31:30.311811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.544279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/networks/\" range_end:\"/registry/openstack.k-orc.cloud/networks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:31:30.311854Z","caller":"traceutil/trace.go:171","msg":"trace[1285794020] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/networks/; range_end:/registry/openstack.k-orc.cloud/networks0; response_count:0; response_revision:25860; }","duration":"241.642912ms","start":"2026-08-22T11:31:30.070195Z","end":"2026-08-22T11:31:30.311838Z","steps":["trace[1285794020] 'agreement among raft nodes before linearized reading' (duration: 241.55711ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:31:30.311864Z","caller":"traceutil/trace.go:171","msg":"trace[258555793] transaction","detail":"{read_only:false; response_revision:25860; number_of_response:1; }","duration":"278.233468ms","start":"2026-08-22T11:31:30.03359Z","end":"2026-08-22T11:31:30.311823Z","steps":["trace[258555793] 'process raft request' (duration: 162.906575ms)","trace[258555793] 'compare' (duration: 114.323319ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:31:30.548704Z","caller":"traceutil/trace.go:171","msg":"trace[1226384031] transaction","detail":"{read_only:false; response_revision:25862; number_of_response:1; }","duration":"390.811016ms","start":"2026-08-22T11:31:30.157864Z","end":"2026-08-22T11:31:30.548675Z","steps":["trace[1226384031] 'process raft request' (duration: 390.749584ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:31:30.549115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:31:30.157851Z","time spent":"390.949279ms","remote":"127.0.0.1:38810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T11:31:30.549053Z","caller":"traceutil/trace.go:171","msg":"trace[1393617832] transaction","detail":"{read_only:false; response_revision:25861; number_of_response:1; }","duration":"391.936732ms","start":"2026-08-22T11:31:30.156871Z","end":"2026-08-22T11:31:30.548808Z","steps":["trace[1393617832] 'process raft request' (duration: 295.650969ms)","trace[1393617832] 'compare' (duration: 95.939705ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:31:30.549588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:31:30.1568Z","time spent":"392.65472ms","remote":"127.0.0.1:38810","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-22T11:31:40.570229Z","caller":"traceutil/trace.go:171","msg":"trace[1555175780] linearizableReadLoop","detail":"{readStateIndex:26789; appliedIndex:26788; }","duration":"168.504201ms","start":"2026-08-22T11:31:40.401688Z","end":"2026-08-22T11:31:40.570192Z","steps":["trace[1555175780] 'read index received' (duration: 168.262065ms)","trace[1555175780] 'applied index is now lower than readState.Index' (duration: 240.426µs)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:31:40.57041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.696115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1854"} {"level":"info","ts":"2026-08-22T11:31:40.57042Z","caller":"traceutil/trace.go:171","msg":"trace[700251882] transaction","detail":"{read_only:false; response_revision:25934; number_of_response:1; }","duration":"205.953367ms","start":"2026-08-22T11:31:40.36441Z","end":"2026-08-22T11:31:40.570363Z","steps":["trace[700251882] 'process raft request' (duration: 205.528777ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:31:40.57046Z","caller":"traceutil/trace.go:171","msg":"trace[853331027] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25934; }","duration":"168.818618ms","start":"2026-08-22T11:31:40.401627Z","end":"2026-08-22T11:31:40.570446Z","steps":["trace[853331027] 'agreement among raft nodes before linearized reading' (duration: 168.692745ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:31:40.846743Z","caller":"traceutil/trace.go:171","msg":"trace[667281757] transaction","detail":"{read_only:false; response_revision:25935; number_of_response:1; }","duration":"246.48164ms","start":"2026-08-22T11:31:40.600218Z","end":"2026-08-22T11:31:40.8467Z","steps":["trace[667281757] 'process raft request' (duration: 243.461337ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:31:41.159496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.195546ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T11:31:41.159758Z","caller":"traceutil/trace.go:171","msg":"trace[1365181461] transaction","detail":"{read_only:false; response_revision:25936; number_of_response:1; }","duration":"559.317325ms","start":"2026-08-22T11:31:40.600394Z","end":"2026-08-22T11:31:41.159711Z","steps":["trace[1365181461] 'process raft request' (duration: 349.814382ms)","trace[1365181461] 'compare' (duration: 208.778136ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:31:41.15997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:31:40.600384Z","time spent":"559.47305ms","remote":"127.0.0.1:38810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T11:31:41.16011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.633174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedrainrules/\" range_end:\"/registry/cluster.x-k8s.io/machinedrainrules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:31:41.160157Z","caller":"traceutil/trace.go:171","msg":"trace[690786547] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedrainrules/; range_end:/registry/cluster.x-k8s.io/machinedrainrules0; response_count:0; response_revision:25937; }","duration":"426.725606ms","start":"2026-08-22T11:31:40.733422Z","end":"2026-08-22T11:31:41.160147Z","steps":["trace[690786547] 'agreement among raft nodes before linearized reading' (duration: 426.590732ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:31:41.160202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:31:40.733397Z","time spent":"426.795758ms","remote":"127.0.0.1:56744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/cluster.x-k8s.io/machinedrainrules/\" range_end:\"/registry/cluster.x-k8s.io/machinedrainrules0\" count_only:true "} {"level":"info","ts":"2026-08-22T11:31:41.159992Z","caller":"traceutil/trace.go:171","msg":"trace[2043818358] linearizableReadLoop","detail":"{readStateIndex:26791; appliedIndex:26789; }","duration":"426.49711ms","start":"2026-08-22T11:31:40.733474Z","end":"2026-08-22T11:31:41.159971Z","steps":["trace[2043818358] 'read index received' (duration: 110.182309ms)","trace[2043818358] 'applied index is now lower than readState.Index' (duration: 316.31368ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:31:41.160523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.092717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-08-22T11:31:41.160583Z","caller":"traceutil/trace.go:171","msg":"trace[1111461268] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25937; }","duration":"423.163279ms","start":"2026-08-22T11:31:40.737403Z","end":"2026-08-22T11:31:41.160567Z","steps":["trace[1111461268] 'agreement among raft nodes before linearized reading' (duration: 422.978184ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:31:41.160626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:31:40.737398Z","time spent":"423.2157ms","remote":"127.0.0.1:38810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-22T11:31:41.480605Z","caller":"traceutil/trace.go:171","msg":"trace[494331212] transaction","detail":"{read_only:false; response_revision:25938; number_of_response:1; }","duration":"303.413988ms","start":"2026-08-22T11:31:41.177155Z","end":"2026-08-22T11:31:41.480569Z","steps":["trace[494331212] 'process raft request' (duration: 261.505313ms)","trace[494331212] 'compare' (duration: 41.73143ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:31:41.48112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:31:41.177128Z","time spent":"303.564572ms","remote":"127.0.0.1:38810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T11:31:41.808048Z","caller":"traceutil/trace.go:171","msg":"trace[872863136] transaction","detail":"{read_only:false; response_revision:25939; number_of_response:1; }","duration":"630.723035ms","start":"2026-08-22T11:31:41.177297Z","end":"2026-08-22T11:31:41.80802Z","steps":["trace[872863136] 'process raft request' (duration: 574.895853ms)","trace[872863136] 'compare' (duration: 55.457193ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:31:41.808242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:31:41.177262Z","time spent":"630.862649ms","remote":"127.0.0.1:38810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T11:31:41.808221Z","caller":"traceutil/trace.go:171","msg":"trace[1440598944] transaction","detail":"{read_only:false; response_revision:25940; number_of_response:1; }","duration":"434.122504ms","start":"2026-08-22T11:31:41.374046Z","end":"2026-08-22T11:31:41.808169Z","steps":["trace[1440598944] 'process raft request' (duration: 433.821727ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:31:41.808279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.322817ms","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-22T11:31:41.808351Z","caller":"traceutil/trace.go:171","msg":"trace[2082168598] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25940; }","duration":"424.391279ms","start":"2026-08-22T11:31:41.383939Z","end":"2026-08-22T11:31:41.80833Z","steps":["trace[2082168598] 'agreement among raft nodes before linearized reading' (duration: 424.157733ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:31:41.808399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:31:41.383913Z","time spent":"424.470401ms","remote":"127.0.0.1:38810","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":"warn","ts":"2026-08-22T11:31:41.808419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:31:41.374022Z","time spent":"434.289978ms","remote":"127.0.0.1:38810","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-22T11:31:41.807996Z","caller":"traceutil/trace.go:171","msg":"trace[262905785] linearizableReadLoop","detail":"{readStateIndex:26795; appliedIndex:26792; }","duration":"424.00148ms","start":"2026-08-22T11:31:41.383957Z","end":"2026-08-22T11:31:41.807958Z","steps":["trace[262905785] 'read index received' (duration: 54.716366ms)","trace[262905785] 'applied index is now lower than readState.Index' (duration: 369.283464ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:31:42.30897Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1249362044843144763,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-22T11:31:42.376073Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000068b0.wal"} {"level":"info","ts":"2026-08-22T11:31:42.377089Z","caller":"traceutil/trace.go:171","msg":"trace[716365269] transaction","detail":"{read_only:false; response_revision:25941; number_of_response:1; }","duration":"853.504171ms","start":"2026-08-22T11:31:41.523544Z","end":"2026-08-22T11:31:42.377048Z","steps":["trace[716365269] 'process raft request' (duration: 852.926318ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:31:42.377254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:31:41.523514Z","time spent":"853.654835ms","remote":"127.0.0.1:46298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418382,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T11:31:42.377872Z","caller":"traceutil/trace.go:171","msg":"trace[1162636548] transaction","detail":"{read_only:false; response_revision:25942; number_of_response:1; }","duration":"773.837422ms","start":"2026-08-22T11:31:41.603986Z","end":"2026-08-22T11:31:42.377823Z","steps":["trace[1162636548] 'process raft request' (duration: 772.90882ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:31:42.377959Z","caller":"traceutil/trace.go:171","msg":"trace[2085447115] transaction","detail":"{read_only:false; response_revision:25944; number_of_response:1; }","duration":"650.474444ms","start":"2026-08-22T11:31:41.727472Z","end":"2026-08-22T11:31:42.377946Z","steps":["trace[2085447115] 'process raft request' (duration: 650.425233ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:31:42.378011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:31:41.727439Z","time spent":"650.543295ms","remote":"127.0.0.1:38810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T11:31:42.378034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:31:41.603954Z","time spent":"773.995775ms","remote":"127.0.0.1:38810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T11:31:42.378296Z","caller":"traceutil/trace.go:171","msg":"trace[812754604] transaction","detail":"{read_only:false; response_revision:25943; number_of_response:1; }","duration":"650.831712ms","start":"2026-08-22T11:31:41.727365Z","end":"2026-08-22T11:31:42.378197Z","steps":["trace[812754604] 'process raft request' (duration: 650.494304ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:31:42.378644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:31:41.727346Z","time spent":"651.053278ms","remote":"127.0.0.1:38810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T11:31:42.540733Z","caller":"traceutil/trace.go:171","msg":"trace[1586452685] transaction","detail":"{read_only:false; response_revision:25945; number_of_response:1; }","duration":"727.730595ms","start":"2026-08-22T11:31:41.812968Z","end":"2026-08-22T11:31:42.540699Z","steps":["trace[1586452685] 'process raft request' (duration: 654.46223ms)","trace[1586452685] 'compare' (duration: 72.940517ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:31:42.540961Z","caller":"traceutil/trace.go:171","msg":"trace[1403558292] linearizableReadLoop","detail":"{readStateIndex:26801; appliedIndex:26795; }","duration":"732.403048ms","start":"2026-08-22T11:31:41.808514Z","end":"2026-08-22T11:31:42.540917Z","steps":["trace[1403558292] 'read index received' (duration: 567.775361ms)","trace[1403558292] 'applied index is now lower than readState.Index' (duration: 164.625487ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:31:42.541522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.141493627s","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-22T11:31:42.541195Z","caller":"traceutil/trace.go:171","msg":"trace[903264524] transaction","detail":"{read_only:false; response_revision:25946; number_of_response:1; }","duration":"586.918105ms","start":"2026-08-22T11:31:41.954245Z","end":"2026-08-22T11:31:42.541163Z","steps":["trace[903264524] 'process raft request' (duration: 586.33943ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:31:42.541593Z","caller":"traceutil/trace.go:171","msg":"trace[817019693] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0; range_end:; response_count:1; response_revision:25946; }","duration":"1.141579828s","start":"2026-08-22T11:31:41.399988Z","end":"2026-08-22T11:31:42.541568Z","steps":["trace[817019693] 'agreement among raft nodes before linearized reading' (duration: 1.141383714s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:31:42.54166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:31:41.399977Z","time spent":"1.141666441s","remote":"127.0.0.1:38710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":1,"response size":1887,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0\" "} {"level":"warn","ts":"2026-08-22T11:31:42.541724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:31:41.954218Z","time spent":"587.399327ms","remote":"127.0.0.1:38810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T11:31:42.542106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.155721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-22T11:31:42.542155Z","caller":"traceutil/trace.go:171","msg":"trace[870150701] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:25946; }","duration":"362.251153ms","start":"2026-08-22T11:31:42.179893Z","end":"2026-08-22T11:31:42.542144Z","steps":["trace[870150701] 'agreement among raft nodes before linearized reading' (duration: 362.174461ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:31:42.542199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:31:42.17987Z","time spent":"362.318944ms","remote":"127.0.0.1:38856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":31,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-08-22T11:31:42.542246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"731.787413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/neutron-l3-agent-default-d5g8s.18ce1c9a37e03f45\" ","response":"range_response_count:1 size:949"} {"level":"info","ts":"2026-08-22T11:31:42.542313Z","caller":"traceutil/trace.go:171","msg":"trace[105032143] range","detail":"{range_begin:/registry/events/openstack/neutron-l3-agent-default-d5g8s.18ce1c9a37e03f45; range_end:; response_count:1; response_revision:25946; }","duration":"731.872296ms","start":"2026-08-22T11:31:41.810425Z","end":"2026-08-22T11:31:42.542297Z","steps":["trace[105032143] 'agreement among raft nodes before linearized reading' (duration: 731.555918ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:31:42.541247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.156275934s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-08-22T11:31:42.542404Z","caller":"traceutil/trace.go:171","msg":"trace[808274050] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25946; }","duration":"1.157493913s","start":"2026-08-22T11:31:41.384898Z","end":"2026-08-22T11:31:42.542392Z","steps":["trace[808274050] 'agreement among raft nodes before linearized reading' (duration: 1.15613723s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:31:42.542419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"716.32844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:31:42.542477Z","caller":"traceutil/trace.go:171","msg":"trace[301576167] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0; response_count:0; response_revision:25946; }","duration":"716.414122ms","start":"2026-08-22T11:31:41.82605Z","end":"2026-08-22T11:31:42.542464Z","steps":["trace[301576167] 'agreement among raft nodes before linearized reading' (duration: 716.33574ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:31:42.542549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:31:41.826039Z","time spent":"716.499034ms","remote":"127.0.0.1:56886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":142,"response count":0,"response size":29,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0\" count_only:true "} {"level":"warn","ts":"2026-08-22T11:31:42.542641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.406862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-22T11:31:42.542444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:31:41.384887Z","time spent":"1.157546354s","remote":"127.0.0.1:38810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-22T11:31:42.542752Z","caller":"traceutil/trace.go:171","msg":"trace[543037627] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25946; }","duration":"432.515764ms","start":"2026-08-22T11:31:42.110177Z","end":"2026-08-22T11:31:42.542693Z","steps":["trace[543037627] 'agreement among raft nodes before linearized reading' (duration: 432.131405ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:31:42.542862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:31:42.110157Z","time spent":"432.637838ms","remote":"127.0.0.1:38542","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-22T11:31:42.541313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"743.704183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-22T11:31:42.542972Z","caller":"traceutil/trace.go:171","msg":"trace[141618699] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:25946; }","duration":"745.368022ms","start":"2026-08-22T11:31:41.797537Z","end":"2026-08-22T11:31:42.542905Z","steps":["trace[141618699] 'agreement among raft nodes before linearized reading' (duration: 743.702293ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:31:42.543045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:31:41.797522Z","time spent":"745.507905ms","remote":"127.0.0.1:38722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":31,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-08-22T11:31:42.543142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.908489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:31:42.543201Z","caller":"traceutil/trace.go:171","msg":"trace[490740766] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:25946; }","duration":"426.994371ms","start":"2026-08-22T11:31:42.116194Z","end":"2026-08-22T11:31:42.543188Z","steps":["trace[490740766] 'agreement among raft nodes before linearized reading' (duration: 426.902009ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:31:42.543243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:31:42.116183Z","time spent":"427.051322ms","remote":"127.0.0.1:38762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-08-22T11:31:42.541352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"823.471854ms","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-22T11:31:42.543427Z","caller":"traceutil/trace.go:171","msg":"trace[478531278] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:25946; }","duration":"825.549584ms","start":"2026-08-22T11:31:41.717865Z","end":"2026-08-22T11:31:42.543414Z","steps":["trace[478531278] 'agreement among raft nodes before linearized reading' (duration: 823.422843ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:31:42.543522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:31:41.717794Z","time spent":"825.663077ms","remote":"127.0.0.1:38666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":19,"response size":31,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-08-22T11:31:42.541357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.123130211s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:31:42.543631Z","caller":"traceutil/trace.go:171","msg":"trace[423342937] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:0; response_revision:25946; }","duration":"1.125439046s","start":"2026-08-22T11:31:41.418172Z","end":"2026-08-22T11:31:42.543611Z","steps":["trace[423342937] 'agreement among raft nodes before linearized reading' (duration: 1.123157052s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:31:42.543708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:31:41.418148Z","time spent":"1.125537239s","remote":"127.0.0.1:34522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"warn","ts":"2026-08-22T11:31:42.542368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:31:41.810412Z","time spent":"731.926807ms","remote":"127.0.0.1:38614","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":972,"request content":"key:\"/registry/events/openstack/neutron-l3-agent-default-d5g8s.18ce1c9a37e03f45\" "} {"level":"warn","ts":"2026-08-22T11:31:42.541247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"809.618588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-22T11:31:42.547238Z","caller":"traceutil/trace.go:171","msg":"trace[1875708520] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25946; }","duration":"812.376575ms","start":"2026-08-22T11:31:41.731591Z","end":"2026-08-22T11:31:42.543968Z","steps":["trace[1875708520] 'agreement among raft nodes before linearized reading' (duration: 809.441893ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:31:42.547367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:31:41.731549Z","time spent":"815.795767ms","remote":"127.0.0.1:38810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-22T11:31:42.555265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:31:41.812946Z","time spent":"728.461473ms","remote":"127.0.0.1:38810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T11:31:45.047617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.113883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1eaa423b-85c5-4fea-b792-3b97676047bb\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-08-22T11:31:45.047706Z","caller":"traceutil/trace.go:171","msg":"trace[1281311144] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1eaa423b-85c5-4fea-b792-3b97676047bb; range_end:; response_count:1; response_revision:26022; }","duration":"107.255257ms","start":"2026-08-22T11:31:44.94043Z","end":"2026-08-22T11:31:45.047685Z","steps":["trace[1281311144] 'range keys from in-memory index tree' (duration: 106.920599ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:31:45.412422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.294713ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-08-22T11:31:45.412788Z","caller":"traceutil/trace.go:171","msg":"trace[1328308791] linearizableReadLoop","detail":"{readStateIndex:26878; appliedIndex:26877; }","duration":"302.858386ms","start":"2026-08-22T11:31:45.109904Z","end":"2026-08-22T11:31:45.412762Z","steps":["trace[1328308791] 'read index received' (duration: 71.383µs)","trace[1328308791] 'applied index is now lower than readState.Index' (duration: 302.785653ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:31:45.412952Z","caller":"traceutil/trace.go:171","msg":"trace[1612724634] transaction","detail":"{read_only:false; response_revision:26023; number_of_response:1; }","duration":"233.169518ms","start":"2026-08-22T11:31:45.179762Z","end":"2026-08-22T11:31:45.412931Z","steps":["trace[1612724634] 'process raft request' (duration: 232.83689ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:31:45.545223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.300923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:31:45.545321Z","caller":"traceutil/trace.go:171","msg":"trace[1382432590] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26023; }","duration":"435.409386ms","start":"2026-08-22T11:31:45.109885Z","end":"2026-08-22T11:31:45.545295Z","steps":["trace[1382432590] 'agreement among raft nodes before linearized reading' (duration: 302.985399ms)","trace[1382432590] 'range keys from in-memory index tree' (duration: 132.275794ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:31:45.545364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:31:45.109804Z","time spent":"435.550899ms","remote":"127.0.0.1:38542","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-22T11:31:45.545857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.00813ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T11:31:45.545922Z","caller":"traceutil/trace.go:171","msg":"trace[1483558373] linearizableReadLoop","detail":"{readStateIndex:26880; appliedIndex:26879; }","duration":"133.056242ms","start":"2026-08-22T11:31:45.412855Z","end":"2026-08-22T11:31:45.545911Z","steps":["trace[1483558373] 'read index received' (duration: 4.739085ms)","trace[1483558373] 'applied index is now lower than readState.Index' (duration: 128.315157ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:31:45.546003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.312437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-22T11:31:45.546018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.987236ms","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-22T11:31:45.546033Z","caller":"traceutil/trace.go:171","msg":"trace[2146114] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0; response_count:0; response_revision:26024; }","duration":"384.347448ms","start":"2026-08-22T11:31:45.161677Z","end":"2026-08-22T11:31:45.546024Z","steps":["trace[2146114] 'agreement among raft nodes before linearized reading' (duration: 384.284437ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:31:45.546056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:31:45.161647Z","time spent":"384.40427ms","remote":"127.0.0.1:56924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":144,"response count":0,"response size":29,"request content":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true "} {"level":"info","ts":"2026-08-22T11:31:45.545992Z","caller":"traceutil/trace.go:171","msg":"trace[1586538136] transaction","detail":"{read_only:false; response_revision:26024; number_of_response:1; }","duration":"363.76652ms","start":"2026-08-22T11:31:45.182166Z","end":"2026-08-22T11:31:45.545932Z","steps":["trace[1586538136] 'process raft request' (duration: 235.530284ms)","trace[1586538136] 'compare' (duration: 127.403406ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:31:45.546134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.594568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:31:45.546169Z","caller":"traceutil/trace.go:171","msg":"trace[146354479] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusters/; range_end:/registry/cluster.x-k8s.io/clusters0; response_count:0; response_revision:26024; }","duration":"176.681249ms","start":"2026-08-22T11:31:45.36948Z","end":"2026-08-22T11:31:45.546161Z","steps":["trace[146354479] 'agreement among raft nodes before linearized reading' (duration: 176.614108ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:31:45.546058Z","caller":"traceutil/trace.go:171","msg":"trace[1304243650] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:26024; }","duration":"312.066188ms","start":"2026-08-22T11:31:45.23398Z","end":"2026-08-22T11:31:45.546047Z","steps":["trace[1304243650] 'agreement among raft nodes before linearized reading' (duration: 311.970525ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:31:45.546265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:31:45.182139Z","time spent":"363.973345ms","remote":"127.0.0.1:38810","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-22T11:31:45.546316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:31:45.23396Z","time spent":"312.281992ms","remote":"127.0.0.1:39066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":8,"response size":31,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-08-22T11:31:52.128507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.066358ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T11:31:52.128771Z","caller":"traceutil/trace.go:171","msg":"trace[607963254] transaction","detail":"{read_only:false; response_revision:26094; number_of_response:1; }","duration":"183.922025ms","start":"2026-08-22T11:31:51.944765Z","end":"2026-08-22T11:31:52.128687Z","steps":["trace[607963254] 'process raft request' (duration: 51.57491ms)","trace[607963254] 'compare' (duration: 131.765671ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:31:52.447204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.08516ms","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-22T11:31:52.447327Z","caller":"traceutil/trace.go:171","msg":"trace[753358013] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:26094; }","duration":"170.231594ms","start":"2026-08-22T11:31:52.277067Z","end":"2026-08-22T11:31:52.447299Z","steps":["trace[753358013] 'count revisions from in-memory index tree' (duration: 169.954216ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:31:52.687422Z","caller":"traceutil/trace.go:171","msg":"trace[1096765565] transaction","detail":"{read_only:false; response_revision:26096; number_of_response:1; }","duration":"269.407535ms","start":"2026-08-22T11:31:52.417917Z","end":"2026-08-22T11:31:52.687325Z","steps":["trace[1096765565] 'process raft request' (duration: 269.167499ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:31:52.687657Z","caller":"traceutil/trace.go:171","msg":"trace[524752229] linearizableReadLoop","detail":"{readStateIndex:26955; appliedIndex:26952; }","duration":"241.746444ms","start":"2026-08-22T11:31:52.445867Z","end":"2026-08-22T11:31:52.687613Z","steps":["trace[524752229] 'read index received' (duration: 9.412028ms)","trace[524752229] 'applied index is now lower than readState.Index' (duration: 232.328586ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:31:52.687669Z","caller":"traceutil/trace.go:171","msg":"trace[541352544] transaction","detail":"{read_only:false; response_revision:26097; number_of_response:1; }","duration":"268.221696ms","start":"2026-08-22T11:31:52.419418Z","end":"2026-08-22T11:31:52.68764Z","steps":["trace[541352544] 'process raft request' (duration: 267.859977ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:31:52.687826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.92703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:31:52.687879Z","caller":"traceutil/trace.go:171","msg":"trace[1764979859] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:26097; }","duration":"242.004042ms","start":"2026-08-22T11:31:52.44586Z","end":"2026-08-22T11:31:52.687864Z","steps":["trace[1764979859] 'agreement among raft nodes before linearized reading' (duration: 241.886239ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:32:16.924468Z","caller":"traceutil/trace.go:171","msg":"trace[592489434] linearizableReadLoop","detail":"{readStateIndex:27160; appliedIndex:27157; }","duration":"125.10342ms","start":"2026-08-22T11:32:16.799335Z","end":"2026-08-22T11:32:16.924439Z","steps":["trace[592489434] 'read index received' (duration: 64.38503ms)","trace[592489434] 'applied index is now lower than readState.Index' (duration: 60.71705ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:32:16.924951Z","caller":"traceutil/trace.go:171","msg":"trace[1053963588] transaction","detail":"{read_only:false; response_revision:26296; number_of_response:1; }","duration":"141.633529ms","start":"2026-08-22T11:32:16.783297Z","end":"2026-08-22T11:32:16.92493Z","steps":["trace[1053963588] 'process raft request' (duration: 141.091367ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:32:16.924934Z","caller":"traceutil/trace.go:171","msg":"trace[1071688189] transaction","detail":"{read_only:false; response_revision:26295; number_of_response:1; }","duration":"142.899831ms","start":"2026-08-22T11:32:16.78199Z","end":"2026-08-22T11:32:16.92489Z","steps":["trace[1071688189] 'process raft request' (duration: 142.229865ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:32:16.925051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.668443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/neutron-dhcp-agent-default-jgq66.18ce1c9593237ec1\" ","response":"range_response_count:1 size:957"} {"level":"info","ts":"2026-08-22T11:32:16.925152Z","caller":"traceutil/trace.go:171","msg":"trace[81454778] range","detail":"{range_begin:/registry/events/openstack/neutron-dhcp-agent-default-jgq66.18ce1c9593237ec1; range_end:; response_count:1; response_revision:26296; }","duration":"125.831217ms","start":"2026-08-22T11:32:16.799296Z","end":"2026-08-22T11:32:16.925127Z","steps":["trace[81454778] 'agreement among raft nodes before linearized reading' (duration: 125.481729ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:32:16.92522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.91091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-08-22T11:32:16.925267Z","caller":"traceutil/trace.go:171","msg":"trace[1789687457] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26296; }","duration":"114.000081ms","start":"2026-08-22T11:32:16.811258Z","end":"2026-08-22T11:32:16.925258Z","steps":["trace[1789687457] 'agreement among raft nodes before linearized reading' (duration: 113.835866ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:32:16.925335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.431951ms","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-22T11:32:16.925383Z","caller":"traceutil/trace.go:171","msg":"trace[412429701] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26296; }","duration":"116.508562ms","start":"2026-08-22T11:32:16.808865Z","end":"2026-08-22T11:32:16.925373Z","steps":["trace[412429701] 'agreement among raft nodes before linearized reading' (duration: 116.300867ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:32:16.92549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.293345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:32:16.925536Z","caller":"traceutil/trace.go:171","msg":"trace[1452137993] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:26296; }","duration":"118.354358ms","start":"2026-08-22T11:32:16.807168Z","end":"2026-08-22T11:32:16.925522Z","steps":["trace[1452137993] 'agreement among raft nodes before linearized reading' (duration: 118.281946ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:32:16.92587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.179506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-22T11:32:16.925934Z","caller":"traceutil/trace.go:171","msg":"trace[323126683] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26296; }","duration":"121.257047ms","start":"2026-08-22T11:32:16.804664Z","end":"2026-08-22T11:32:16.925921Z","steps":["trace[323126683] 'agreement among raft nodes before linearized reading' (duration: 120.201161ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:32:17.074893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.851933ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T11:32:17.075172Z","caller":"traceutil/trace.go:171","msg":"trace[970679354] transaction","detail":"{read_only:false; response_revision:26297; number_of_response:1; }","duration":"147.271337ms","start":"2026-08-22T11:32:16.927872Z","end":"2026-08-22T11:32:17.075143Z","steps":["trace[970679354] 'compare' (duration: 138.71658ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:32:17.075415Z","caller":"traceutil/trace.go:171","msg":"trace[1336159835] transaction","detail":"{read_only:false; response_revision:26298; number_of_response:1; }","duration":"145.34292ms","start":"2026-08-22T11:32:16.930035Z","end":"2026-08-22T11:32:17.075378Z","steps":["trace[1336159835] 'process raft request' (duration: 145.018023ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:32:17.075477Z","caller":"traceutil/trace.go:171","msg":"trace[1270382827] transaction","detail":"{read_only:false; response_revision:26299; number_of_response:1; }","duration":"145.125175ms","start":"2026-08-22T11:32:16.930303Z","end":"2026-08-22T11:32:17.075428Z","steps":["trace[1270382827] 'process raft request' (duration: 144.816747ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:32:17.075246Z","caller":"traceutil/trace.go:171","msg":"trace[1551966808] transaction","detail":"{read_only:false; response_revision:26300; number_of_response:1; }","duration":"144.805858ms","start":"2026-08-22T11:32:16.930419Z","end":"2026-08-22T11:32:17.075225Z","steps":["trace[1551966808] 'process raft request' (duration: 144.734056ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:32:17.150935Z","caller":"traceutil/trace.go:171","msg":"trace[2042268180] transaction","detail":"{read_only:false; response_revision:26301; number_of_response:1; }","duration":"172.828386ms","start":"2026-08-22T11:32:16.978077Z","end":"2026-08-22T11:32:17.150905Z","steps":["trace[2042268180] 'process raft request' (duration: 172.653412ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:32:17.29929Z","caller":"traceutil/trace.go:171","msg":"trace[1440711911] linearizableReadLoop","detail":"{readStateIndex:27166; appliedIndex:27164; }","duration":"189.389397ms","start":"2026-08-22T11:32:17.109873Z","end":"2026-08-22T11:32:17.299262Z","steps":["trace[1440711911] 'read index received' (duration: 40.87345ms)","trace[1440711911] 'applied index is now lower than readState.Index' (duration: 148.515047ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:32:17.299337Z","caller":"traceutil/trace.go:171","msg":"trace[2007102961] transaction","detail":"{read_only:false; response_revision:26302; number_of_response:1; }","duration":"236.664521ms","start":"2026-08-22T11:32:17.062632Z","end":"2026-08-22T11:32:17.299296Z","steps":["trace[2007102961] 'process raft request' (duration: 235.608726ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:32:17.299443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.550421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:32:17.299533Z","caller":"traceutil/trace.go:171","msg":"trace[1799818353] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26302; }","duration":"189.702435ms","start":"2026-08-22T11:32:17.109807Z","end":"2026-08-22T11:32:17.299509Z","steps":["trace[1799818353] 'agreement among raft nodes before linearized reading' (duration: 189.538781ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:32:19.198304Z","caller":"traceutil/trace.go:171","msg":"trace[59828965] transaction","detail":"{read_only:false; response_revision:26314; number_of_response:1; }","duration":"109.602595ms","start":"2026-08-22T11:32:19.088673Z","end":"2026-08-22T11:32:19.198275Z","steps":["trace[59828965] 'process raft request' (duration: 109.526763ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:32:19.198391Z","caller":"traceutil/trace.go:171","msg":"trace[1177187509] transaction","detail":"{read_only:false; response_revision:26313; number_of_response:1; }","duration":"113.94429ms","start":"2026-08-22T11:32:19.084432Z","end":"2026-08-22T11:32:19.198376Z","steps":["trace[1177187509] 'process raft request' (duration: 36.175876ms)","trace[1177187509] 'compare' (duration: 77.351384ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:32:19.198545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.635648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-08-22T11:32:19.198312Z","caller":"traceutil/trace.go:171","msg":"trace[1962997409] linearizableReadLoop","detail":"{readStateIndex:27177; appliedIndex:27176; }","duration":"112.392292ms","start":"2026-08-22T11:32:19.085902Z","end":"2026-08-22T11:32:19.198294Z","steps":["trace[1962997409] 'read index received' (duration: 34.675749ms)","trace[1962997409] 'applied index is now lower than readState.Index' (duration: 77.715023ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:32:19.198589Z","caller":"traceutil/trace.go:171","msg":"trace[933382974] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26314; }","duration":"112.74116ms","start":"2026-08-22T11:32:19.085836Z","end":"2026-08-22T11:32:19.198578Z","steps":["trace[933382974] 'agreement among raft nodes before linearized reading' (duration: 112.529275ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:32:19.417665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.553869ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T11:32:19.417772Z","caller":"traceutil/trace.go:171","msg":"trace[1740893606] transaction","detail":"{read_only:false; response_revision:26316; number_of_response:1; }","duration":"214.125316ms","start":"2026-08-22T11:32:19.203629Z","end":"2026-08-22T11:32:19.417754Z","steps":["trace[1740893606] 'process raft request' (duration: 100.400962ms)","trace[1740893606] 'compare' (duration: 113.350415ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:32:19.445957Z","caller":"traceutil/trace.go:171","msg":"trace[371388381] transaction","detail":"{read_only:false; response_revision:26317; number_of_response:1; }","duration":"141.6615ms","start":"2026-08-22T11:32:19.304241Z","end":"2026-08-22T11:32:19.445902Z","steps":["trace[371388381] 'process raft request' (duration: 141.354133ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:32:19.863552Z","caller":"traceutil/trace.go:171","msg":"trace[460865078] transaction","detail":"{read_only:false; response_revision:26319; number_of_response:1; }","duration":"136.658839ms","start":"2026-08-22T11:32:19.726859Z","end":"2026-08-22T11:32:19.863518Z","steps":["trace[460865078] 'process raft request' (duration: 66.081869ms)","trace[460865078] 'compare' (duration: 70.237052ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:32:21.090029Z","caller":"traceutil/trace.go:171","msg":"trace[834708397] transaction","detail":"{read_only:false; response_revision:26326; number_of_response:1; }","duration":"100.925614ms","start":"2026-08-22T11:32:20.989071Z","end":"2026-08-22T11:32:21.089996Z","steps":["trace[834708397] 'process raft request' (duration: 34.595838ms)","trace[834708397] 'compare' (duration: 66.209073ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:32:21.133055Z","caller":"traceutil/trace.go:171","msg":"trace[805804027] transaction","detail":"{read_only:false; response_revision:26327; number_of_response:1; }","duration":"114.836562ms","start":"2026-08-22T11:32:21.018182Z","end":"2026-08-22T11:32:21.133019Z","steps":["trace[805804027] 'process raft request' (duration: 114.651807ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:32:21.320595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.784264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-08-22T11:32:21.320715Z","caller":"traceutil/trace.go:171","msg":"trace[1087321267] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26327; }","duration":"117.954718ms","start":"2026-08-22T11:32:21.20273Z","end":"2026-08-22T11:32:21.320685Z","steps":["trace[1087321267] 'range keys from in-memory index tree' (duration: 117.539276ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:32:21.320599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.013845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-22T11:32:21.320945Z","caller":"traceutil/trace.go:171","msg":"trace[611678607] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26327; }","duration":"117.398913ms","start":"2026-08-22T11:32:21.203511Z","end":"2026-08-22T11:32:21.32091Z","steps":["trace[611678607] 'range keys from in-memory index tree' (duration: 116.815298ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:32:21.499168Z","caller":"traceutil/trace.go:171","msg":"trace[589252028] transaction","detail":"{read_only:false; response_revision:26328; number_of_response:1; }","duration":"173.8275ms","start":"2026-08-22T11:32:21.325298Z","end":"2026-08-22T11:32:21.499125Z","steps":["trace[589252028] 'process raft request' (duration: 173.565333ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:32:21.671621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.473986ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T11:32:21.67204Z","caller":"traceutil/trace.go:171","msg":"trace[225061776] linearizableReadLoop","detail":"{readStateIndex:27197; appliedIndex:27193; }","duration":"111.802618ms","start":"2026-08-22T11:32:21.560211Z","end":"2026-08-22T11:32:21.672014Z","steps":["trace[225061776] 'read index received' (duration: 52.122µs)","trace[225061776] 'applied index is now lower than readState.Index' (duration: 111.748956ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:32:21.672294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.079055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-22T11:32:21.672302Z","caller":"traceutil/trace.go:171","msg":"trace[1044014847] transaction","detail":"{read_only:false; response_revision:26330; number_of_response:1; }","duration":"346.541123ms","start":"2026-08-22T11:32:21.325739Z","end":"2026-08-22T11:32:21.67228Z","steps":["trace[1044014847] 'process raft request' (duration: 346.02228ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:32:21.672354Z","caller":"traceutil/trace.go:171","msg":"trace[110180459] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:26332; }","duration":"112.181217ms","start":"2026-08-22T11:32:21.560154Z","end":"2026-08-22T11:32:21.672335Z","steps":["trace[110180459] 'agreement among raft nodes before linearized reading' (duration: 111.968082ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:32:21.672366Z","caller":"traceutil/trace.go:171","msg":"trace[1489601644] transaction","detail":"{read_only:false; response_revision:26332; number_of_response:1; }","duration":"221.644229ms","start":"2026-08-22T11:32:21.45067Z","end":"2026-08-22T11:32:21.672315Z","steps":["trace[1489601644] 'process raft request' (duration: 221.28049ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:32:21.672379Z","caller":"traceutil/trace.go:171","msg":"trace[1448296429] transaction","detail":"{read_only:false; response_revision:26329; number_of_response:1; }","duration":"346.655446ms","start":"2026-08-22T11:32:21.325679Z","end":"2026-08-22T11:32:21.672334Z","steps":["trace[1448296429] 'process raft request' (duration: 216.300949ms)","trace[1448296429] 'compare' (duration: 129.21002ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:32:21.672398Z","caller":"traceutil/trace.go:171","msg":"trace[1113653888] transaction","detail":"{read_only:false; response_revision:26331; number_of_response:1; }","duration":"346.511962ms","start":"2026-08-22T11:32:21.325789Z","end":"2026-08-22T11:32:21.672301Z","steps":["trace[1113653888] 'process raft request' (duration: 346.108713ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:32:21.672392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:32:21.325733Z","time spent":"346.622395ms","remote":"127.0.0.1:38810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T11:32:21.672588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:32:21.325669Z","time spent":"346.808419ms","remote":"127.0.0.1:38810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T11:32:21.672849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:32:21.325781Z","time spent":"346.718837ms","remote":"127.0.0.1:38810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T11:32:21.988299Z","caller":"traceutil/trace.go:171","msg":"trace[41039444] transaction","detail":"{read_only:false; response_revision:26334; number_of_response:1; }","duration":"117.486146ms","start":"2026-08-22T11:32:21.870773Z","end":"2026-08-22T11:32:21.988259Z","steps":["trace[41039444] 'process raft request' (duration: 117.121347ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:32:22.1262Z","caller":"traceutil/trace.go:171","msg":"trace[1386938840] transaction","detail":"{read_only:false; response_revision:26335; number_of_response:1; }","duration":"133.278467ms","start":"2026-08-22T11:32:21.992886Z","end":"2026-08-22T11:32:22.126165Z","steps":["trace[1386938840] 'process raft request' (duration: 38.509012ms)","trace[1386938840] 'compare' (duration: 94.55481ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:32:22.126184Z","caller":"traceutil/trace.go:171","msg":"trace[1528616975] linearizableReadLoop","detail":"{readStateIndex:27200; appliedIndex:27199; }","duration":"111.0635ms","start":"2026-08-22T11:32:22.015075Z","end":"2026-08-22T11:32:22.126138Z","steps":["trace[1528616975] 'read index received' (duration: 16.254514ms)","trace[1528616975] 'applied index is now lower than readState.Index' (duration: 94.807196ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:32:22.126945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.840769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-08-22T11:32:22.127053Z","caller":"traceutil/trace.go:171","msg":"trace[24606918] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:26335; }","duration":"112.014693ms","start":"2026-08-22T11:32:22.015015Z","end":"2026-08-22T11:32:22.127029Z","steps":["trace[24606918] 'agreement among raft nodes before linearized reading' (duration: 111.332077ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:32:23.242781Z","caller":"traceutil/trace.go:171","msg":"trace[1712088527] transaction","detail":"{read_only:false; response_revision:26340; number_of_response:1; }","duration":"162.761842ms","start":"2026-08-22T11:32:23.079973Z","end":"2026-08-22T11:32:23.242735Z","steps":["trace[1712088527] 'process raft request' (duration: 65.899676ms)","trace[1712088527] 'compare' (duration: 96.547399ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:32:23.24279Z","caller":"traceutil/trace.go:171","msg":"trace[293623505] transaction","detail":"{read_only:false; response_revision:26342; number_of_response:1; }","duration":"103.411095ms","start":"2026-08-22T11:32:23.139346Z","end":"2026-08-22T11:32:23.242757Z","steps":["trace[293623505] 'process raft request' (duration: 103.349553ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:32:23.242808Z","caller":"traceutil/trace.go:171","msg":"trace[1067531800] transaction","detail":"{read_only:false; response_revision:26341; number_of_response:1; }","duration":"148.367694ms","start":"2026-08-22T11:32:23.0944Z","end":"2026-08-22T11:32:23.242768Z","steps":["trace[1067531800] 'process raft request' (duration: 148.234761ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:32:23.449074Z","caller":"traceutil/trace.go:171","msg":"trace[1737676952] transaction","detail":"{read_only:false; response_revision:26343; number_of_response:1; }","duration":"104.980282ms","start":"2026-08-22T11:32:23.344073Z","end":"2026-08-22T11:32:23.449053Z","steps":["trace[1737676952] 'process raft request' (duration: 43.956095ms)","trace[1737676952] 'compare' (duration: 60.889044ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:32:23.722549Z","caller":"traceutil/trace.go:171","msg":"trace[268404678] transaction","detail":"{read_only:false; response_revision:26344; number_of_response:1; }","duration":"215.07028ms","start":"2026-08-22T11:32:23.507434Z","end":"2026-08-22T11:32:23.722505Z","steps":["trace[268404678] 'process raft request' (duration: 143.24737ms)","trace[268404678] 'compare' (duration: 71.513612ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:32:23.925468Z","caller":"traceutil/trace.go:171","msg":"trace[1468277444] transaction","detail":"{read_only:false; response_revision:26346; number_of_response:1; }","duration":"199.44888ms","start":"2026-08-22T11:32:23.725986Z","end":"2026-08-22T11:32:23.925435Z","steps":["trace[1468277444] 'process raft request' (duration: 117.581617ms)","trace[1468277444] 'compare' (duration: 81.72754ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:32:23.950951Z","caller":"traceutil/trace.go:171","msg":"trace[1572179504] linearizableReadLoop","detail":"{readStateIndex:27214; appliedIndex:27210; }","duration":"162.081674ms","start":"2026-08-22T11:32:23.78884Z","end":"2026-08-22T11:32:23.950922Z","steps":["trace[1572179504] 'read index received' (duration: 54.850957ms)","trace[1572179504] 'applied index is now lower than readState.Index' (duration: 107.229337ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:32:23.95122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.307771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:32:23.951264Z","caller":"traceutil/trace.go:171","msg":"trace[1694340654] 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:26349; }","duration":"162.477945ms","start":"2026-08-22T11:32:23.78877Z","end":"2026-08-22T11:32:23.951248Z","steps":["trace[1694340654] 'agreement among raft nodes before linearized reading' (duration: 162.26683ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:32:23.951665Z","caller":"traceutil/trace.go:171","msg":"trace[874833460] transaction","detail":"{read_only:false; response_revision:26347; number_of_response:1; }","duration":"224.426146ms","start":"2026-08-22T11:32:23.727226Z","end":"2026-08-22T11:32:23.951652Z","steps":["trace[874833460] 'process raft request' (duration: 223.468792ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:32:23.951834Z","caller":"traceutil/trace.go:171","msg":"trace[758141578] transaction","detail":"{read_only:false; response_revision:26348; number_of_response:1; }","duration":"222.49431ms","start":"2026-08-22T11:32:23.729329Z","end":"2026-08-22T11:32:23.951823Z","steps":["trace[758141578] 'process raft request' (duration: 221.506155ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:32:23.951923Z","caller":"traceutil/trace.go:171","msg":"trace[1491884946] transaction","detail":"{read_only:false; response_revision:26349; number_of_response:1; }","duration":"218.869201ms","start":"2026-08-22T11:32:23.733045Z","end":"2026-08-22T11:32:23.951914Z","steps":["trace[1491884946] 'process raft request' (duration: 217.835655ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:32:24.153579Z","caller":"traceutil/trace.go:171","msg":"trace[1591782574] transaction","detail":"{read_only:false; response_revision:26350; number_of_response:1; }","duration":"157.365901ms","start":"2026-08-22T11:32:23.996186Z","end":"2026-08-22T11:32:24.153552Z","steps":["trace[1591782574] 'process raft request' (duration: 156.932831ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:32:34.553998Z","caller":"traceutil/trace.go:171","msg":"trace[979363075] transaction","detail":"{read_only:false; response_revision:26425; number_of_response:1; }","duration":"125.670393ms","start":"2026-08-22T11:32:34.428306Z","end":"2026-08-22T11:32:34.553977Z","steps":["trace[979363075] 'process raft request' (duration: 125.480739ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:32:35.501264Z","caller":"traceutil/trace.go:171","msg":"trace[1009391407] transaction","detail":"{read_only:false; response_revision:26429; number_of_response:1; }","duration":"188.848445ms","start":"2026-08-22T11:32:35.312383Z","end":"2026-08-22T11:32:35.501231Z","steps":["trace[1009391407] 'process raft request' (duration: 188.656899ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:32:35.730367Z","caller":"traceutil/trace.go:171","msg":"trace[1660905374] linearizableReadLoop","detail":"{readStateIndex:27298; appliedIndex:27297; }","duration":"394.465053ms","start":"2026-08-22T11:32:35.335878Z","end":"2026-08-22T11:32:35.730343Z","steps":["trace[1660905374] 'read index received' (duration: 165.405556ms)","trace[1660905374] 'applied index is now lower than readState.Index' (duration: 229.058067ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:32:35.730514Z","caller":"traceutil/trace.go:171","msg":"trace[1116964512] transaction","detail":"{read_only:false; response_revision:26430; number_of_response:1; }","duration":"414.742324ms","start":"2026-08-22T11:32:35.315715Z","end":"2026-08-22T11:32:35.730458Z","steps":["trace[1116964512] 'process raft request' (duration: 343.570411ms)","trace[1116964512] 'compare' (duration: 70.757174ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:32:35.73056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.651897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/projects/\" range_end:\"/registry/openstack.k-orc.cloud/projects0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:32:35.730606Z","caller":"traceutil/trace.go:171","msg":"trace[820560564] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/projects/; range_end:/registry/openstack.k-orc.cloud/projects0; response_count:0; response_revision:26430; }","duration":"394.76425ms","start":"2026-08-22T11:32:35.335829Z","end":"2026-08-22T11:32:35.730593Z","steps":["trace[820560564] 'agreement among raft nodes before linearized reading' (duration: 394.613986ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:32:35.730641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:32:35.335809Z","time spent":"394.824282ms","remote":"127.0.0.1:56480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/projects/\" range_end:\"/registry/openstack.k-orc.cloud/projects0\" count_only:true "} {"level":"warn","ts":"2026-08-22T11:32:35.73068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:32:35.315683Z","time spent":"414.887698ms","remote":"127.0.0.1:38810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T11:32:35.944805Z","caller":"traceutil/trace.go:171","msg":"trace[1164851247] linearizableReadLoop","detail":"{readStateIndex:27300; appliedIndex:27299; }","duration":"136.010785ms","start":"2026-08-22T11:32:35.808765Z","end":"2026-08-22T11:32:35.944776Z","steps":["trace[1164851247] 'read index received' (duration: 66.377978ms)","trace[1164851247] 'applied index is now lower than readState.Index' (duration: 69.631807ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:32:35.944892Z","caller":"traceutil/trace.go:171","msg":"trace[90999519] transaction","detail":"{read_only:false; response_revision:26432; number_of_response:1; }","duration":"137.550651ms","start":"2026-08-22T11:32:35.807289Z","end":"2026-08-22T11:32:35.944839Z","steps":["trace[90999519] 'process raft request' (duration: 67.945085ms)","trace[90999519] 'compare' (duration: 68.989742ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:32:35.94505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.268261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18231"} {"level":"info","ts":"2026-08-22T11:32:35.945096Z","caller":"traceutil/trace.go:171","msg":"trace[790700096] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:26432; }","duration":"136.324802ms","start":"2026-08-22T11:32:35.808751Z","end":"2026-08-22T11:32:35.945076Z","steps":["trace[790700096] 'agreement among raft nodes before linearized reading' (duration: 136.126837ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:32:36.300241Z","caller":"traceutil/trace.go:171","msg":"trace[260229598] transaction","detail":"{read_only:false; response_revision:26436; number_of_response:1; }","duration":"106.056399ms","start":"2026-08-22T11:32:36.194131Z","end":"2026-08-22T11:32:36.300188Z","steps":["trace[260229598] 'process raft request' (duration: 105.272799ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:32:36.423466Z","caller":"traceutil/trace.go:171","msg":"trace[102192749] transaction","detail":"{read_only:false; response_revision:26438; number_of_response:1; }","duration":"117.768922ms","start":"2026-08-22T11:32:36.305515Z","end":"2026-08-22T11:32:36.423284Z","steps":["trace[102192749] 'process raft request' (duration: 90.143113ms)","trace[102192749] 'compare' (duration: 27.451535ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:32:36.817576Z","caller":"traceutil/trace.go:171","msg":"trace[1604115575] transaction","detail":"{read_only:false; response_revision:26440; number_of_response:1; }","duration":"103.189609ms","start":"2026-08-22T11:32:36.714339Z","end":"2026-08-22T11:32:36.817529Z","steps":["trace[1604115575] 'process raft request' (duration: 45.807759ms)","trace[1604115575] 'compare' (duration: 57.246386ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:32:36.871777Z","caller":"traceutil/trace.go:171","msg":"trace[1231114564] transaction","detail":"{read_only:false; response_revision:26441; number_of_response:1; }","duration":"126.525284ms","start":"2026-08-22T11:32:36.745219Z","end":"2026-08-22T11:32:36.871744Z","steps":["trace[1231114564] 'process raft request' (duration: 125.742125ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:34:24.825044Z","caller":"traceutil/trace.go:171","msg":"trace[966416151] transaction","detail":"{read_only:false; response_revision:27522; number_of_response:1; }","duration":"128.579783ms","start":"2026-08-22T11:34:24.695039Z","end":"2026-08-22T11:34:24.823618Z","steps":["trace[966416151] 'process raft request' (duration: 128.520282ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:34:24.826139Z","caller":"traceutil/trace.go:171","msg":"trace[1432784362] transaction","detail":"{read_only:false; response_revision:27521; number_of_response:1; }","duration":"137.313905ms","start":"2026-08-22T11:34:24.688743Z","end":"2026-08-22T11:34:24.826057Z","steps":["trace[1432784362] 'process raft request' (duration: 134.539548ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:35:26.572238Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25245} {"level":"info","ts":"2026-08-22T11:35:26.726706Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25245,"took":"149.745815ms","hash":3918794391,"current-db-size-bytes":58941440,"current-db-size":"59 MB","current-db-size-in-use-bytes":37478400,"current-db-size-in-use":"38 MB"} {"level":"info","ts":"2026-08-22T11:35:26.72681Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3918794391,"revision":25245,"compact-revision":22011} {"level":"info","ts":"2026-08-22T11:37:01.482201Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"69895f7c3b831156","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-22T11:37:01.487976Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-08-22T11:37:01.488099Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-08-22T11:40:26.581176Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28101} {"level":"info","ts":"2026-08-22T11:40:26.719965Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28101,"took":"133.234816ms","hash":365595185,"current-db-size-bytes":58941440,"current-db-size":"59 MB","current-db-size-in-use-bytes":33177600,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-08-22T11:40:26.72004Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":365595185,"revision":28101,"compact-revision":25245} {"level":"info","ts":"2026-08-22T11:45:26.592311Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30607} {"level":"info","ts":"2026-08-22T11:45:26.757029Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30607,"took":"160.800505ms","hash":2777063311,"current-db-size-bytes":58941440,"current-db-size":"59 MB","current-db-size-in-use-bytes":30715904,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-22T11:45:26.757175Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2777063311,"revision":30607,"compact-revision":28101} {"level":"info","ts":"2026-08-22T11:50:26.604473Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32864} {"level":"info","ts":"2026-08-22T11:50:26.753856Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32864,"took":"143.395902ms","hash":738207223,"current-db-size-bytes":58941440,"current-db-size":"59 MB","current-db-size-in-use-bytes":30519296,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-22T11:50:26.753922Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":738207223,"revision":32864,"compact-revision":30607} {"level":"info","ts":"2026-08-22T11:55:26.614178Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35100} {"level":"info","ts":"2026-08-22T11:55:26.750563Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35100,"took":"132.627252ms","hash":878471762,"current-db-size-bytes":58941440,"current-db-size":"59 MB","current-db-size-in-use-bytes":31219712,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-22T11:55:26.750642Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":878471762,"revision":35100,"compact-revision":32864} {"level":"info","ts":"2026-08-22T11:58:30.958166Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"69895f7c3b831156","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-22T11:58:30.963666Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-08-22T11:58:30.963811Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-08-22T12:00:26.621855Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37394} {"level":"info","ts":"2026-08-22T12:00:26.745981Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37394,"took":"118.967306ms","hash":1060604969,"current-db-size-bytes":59367424,"current-db-size":"59 MB","current-db-size-in-use-bytes":33038336,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-08-22T12:00:26.746044Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1060604969,"revision":37394,"compact-revision":35100} {"level":"info","ts":"2026-08-22T12:05:26.627222Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39760} {"level":"info","ts":"2026-08-22T12:05:26.760114Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39760,"took":"126.475058ms","hash":2108768853,"current-db-size-bytes":59789312,"current-db-size":"60 MB","current-db-size-in-use-bytes":32047104,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-22T12:05:26.760196Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2108768853,"revision":39760,"compact-revision":37394}