{"level":"warn","ts":"2026-07-22T22:10:19.049783Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-22T22:10:19.049866Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.235:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.235:2380","--initial-cluster=instance=https://199.204.45.235:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.235:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.235: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-07-22T22:10:19.049936Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-22T22:10:19.049953Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.235:2380"]} {"level":"info","ts":"2026-07-22T22:10:19.04999Z","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-07-22T22:10:19.050588Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"]} {"level":"info","ts":"2026-07-22T22:10:19.050655Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.235:2380"],"listen-peer-urls":["https://199.204.45.235:2380"],"advertise-client-urls":["https://199.204.45.235:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.235: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-07-22T22:10:19.053368Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.496089ms"} {"level":"info","ts":"2026-07-22T22:10:19.058191Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"aab6040abcf11","cluster-id":"2bae7d3d8857c059"} {"level":"info","ts":"2026-07-22T22:10:19.058254Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=()"} {"level":"info","ts":"2026-07-22T22:10:19.058272Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became follower at term 0"} {"level":"info","ts":"2026-07-22T22:10:19.058283Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft aab6040abcf11 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-22T22:10:19.058296Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became follower at term 1"} {"level":"info","ts":"2026-07-22T22:10:19.058326Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=(3003179657318161)"} {"level":"warn","ts":"2026-07-22T22:10:19.067771Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-22T22:10:19.069844Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-22T22:10:19.070973Z","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-07-22T22:10:19.076472Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"aab6040abcf11","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-22T22:10:19.076653Z","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-07-22T22:10:19.076738Z","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-07-22T22:10:19.076751Z","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-07-22T22:10:19.076805Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"aab6040abcf11","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-22T22:10:19.079896Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=(3003179657318161)"} {"level":"info","ts":"2026-07-22T22:10:19.080381Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2bae7d3d8857c059","local-member-id":"aab6040abcf11","added-peer-id":"aab6040abcf11","added-peer-peer-urls":["https://199.204.45.235:2380"]} {"level":"info","ts":"2026-07-22T22:10:19.08167Z","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-07-22T22:10:19.081772Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.235:2380"} {"level":"info","ts":"2026-07-22T22:10:19.081821Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.235:2380"} {"level":"info","ts":"2026-07-22T22:10:19.082681Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"aab6040abcf11","initial-advertise-peer-urls":["https://199.204.45.235:2380"],"listen-peer-urls":["https://199.204.45.235:2380"],"advertise-client-urls":["https://199.204.45.235:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-22T22:10:19.082728Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-22T22:10:19.859012Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 is starting a new election at term 1"} {"level":"info","ts":"2026-07-22T22:10:19.859079Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-22T22:10:19.859116Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 received MsgPreVoteResp from aab6040abcf11 at term 1"} {"level":"info","ts":"2026-07-22T22:10:19.859144Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became candidate at term 2"} {"level":"info","ts":"2026-07-22T22:10:19.859154Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 received MsgVoteResp from aab6040abcf11 at term 2"} {"level":"info","ts":"2026-07-22T22:10:19.85917Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became leader at term 2"} {"level":"info","ts":"2026-07-22T22:10:19.859183Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: aab6040abcf11 elected leader aab6040abcf11 at term 2"} {"level":"info","ts":"2026-07-22T22:10:19.861569Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-22T22:10:19.862726Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"aab6040abcf11","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.235:2379]}","request-path":"/0/members/aab6040abcf11/attributes","cluster-id":"2bae7d3d8857c059","publish-timeout":"7s"} {"level":"info","ts":"2026-07-22T22:10:19.862801Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-22T22:10:19.862782Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-22T22:10:19.863091Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-22T22:10:19.863156Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-22T22:10:19.863335Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2bae7d3d8857c059","local-member-id":"aab6040abcf11","cluster-version":"3.5"} {"level":"info","ts":"2026-07-22T22:10:19.863436Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-22T22:10:19.863488Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-22T22:10:19.865928Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.235:2379"} {"level":"info","ts":"2026-07-22T22:10:19.86693Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-22T22:10:55.491498Z","caller":"traceutil/trace.go:171","msg":"trace[1337103868] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"181.844374ms","start":"2026-07-22T22:10:55.309615Z","end":"2026-07-22T22:10:55.491459Z","steps":["trace[1337103868] 'process raft request' (duration: 172.558382ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:10:55.49158Z","caller":"traceutil/trace.go:171","msg":"trace[1753237072] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"180.429414ms","start":"2026-07-22T22:10:55.311137Z","end":"2026-07-22T22:10:55.491567Z","steps":["trace[1753237072] 'process raft request' (duration: 180.181999ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:10:55.491534Z","caller":"traceutil/trace.go:171","msg":"trace[69546209] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"180.050017ms","start":"2026-07-22T22:10:55.311456Z","end":"2026-07-22T22:10:55.491506Z","steps":["trace[69546209] 'process raft request' (duration: 179.915564ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:10:55.518023Z","caller":"traceutil/trace.go:171","msg":"trace[2009204246] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"198.793543ms","start":"2026-07-22T22:10:55.319201Z","end":"2026-07-22T22:10:55.517994Z","steps":["trace[2009204246] 'process raft request' (duration: 198.455257ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:10:55.518064Z","caller":"traceutil/trace.go:171","msg":"trace[497083213] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"191.174987ms","start":"2026-07-22T22:10:55.326836Z","end":"2026-07-22T22:10:55.518011Z","steps":["trace[497083213] 'process raft request' (duration: 191.025484ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:10:55.518317Z","caller":"traceutil/trace.go:171","msg":"trace[1726683774] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"185.857077ms","start":"2026-07-22T22:10:55.332451Z","end":"2026-07-22T22:10:55.518308Z","steps":["trace[1726683774] 'process raft request' (duration: 185.589651ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:10:55.518325Z","caller":"traceutil/trace.go:171","msg":"trace[1461039599] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"140.378898ms","start":"2026-07-22T22:10:55.377926Z","end":"2026-07-22T22:10:55.518304Z","steps":["trace[1461039599] 'process raft request' (duration: 140.307746ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:10:55.518363Z","caller":"traceutil/trace.go:171","msg":"trace[656469540] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"180.840952ms","start":"2026-07-22T22:10:55.337487Z","end":"2026-07-22T22:10:55.518328Z","steps":["trace[656469540] 'process raft request' (duration: 180.595227ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:10:55.51844Z","caller":"traceutil/trace.go:171","msg":"trace[1888359412] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"186.178373ms","start":"2026-07-22T22:10:55.332251Z","end":"2026-07-22T22:10:55.518429Z","steps":["trace[1888359412] 'process raft request' (duration: 185.747904ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:10:55.51845Z","caller":"traceutil/trace.go:171","msg":"trace[520881465] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"170.218553ms","start":"2026-07-22T22:10:55.348181Z","end":"2026-07-22T22:10:55.518399Z","steps":["trace[520881465] 'process raft request' (duration: 169.974639ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:10:55.518533Z","caller":"traceutil/trace.go:171","msg":"trace[848825294] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"186.263775ms","start":"2026-07-22T22:10:55.332169Z","end":"2026-07-22T22:10:55.518433Z","steps":["trace[848825294] 'process raft request' (duration: 185.745405ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:10:55.789914Z","caller":"traceutil/trace.go:171","msg":"trace[400420983] linearizableReadLoop","detail":"{readStateIndex:524; appliedIndex:523; }","duration":"210.552215ms","start":"2026-07-22T22:10:55.579328Z","end":"2026-07-22T22:10:55.78988Z","steps":["trace[400420983] 'read index received' (duration: 196.898603ms)","trace[400420983] 'applied index is now lower than readState.Index' (duration: 13.651712ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:10:55.790015Z","caller":"traceutil/trace.go:171","msg":"trace[1745749488] transaction","detail":"{read_only:false; number_of_response:1; response_revision:501; }","duration":"223.36455ms","start":"2026-07-22T22:10:55.56657Z","end":"2026-07-22T22:10:55.789934Z","steps":["trace[1745749488] 'process raft request' (duration: 209.564896ms)","trace[1745749488] 'compare' (duration: 13.621541ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:10:55.790188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.82572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-22T22:10:55.790357Z","caller":"traceutil/trace.go:171","msg":"trace[24263949] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:501; }","duration":"211.033206ms","start":"2026-07-22T22:10:55.579294Z","end":"2026-07-22T22:10:55.790327Z","steps":["trace[24263949] 'agreement among raft nodes before linearized reading' (duration: 210.743399ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:10:55.790633Z","caller":"traceutil/trace.go:171","msg":"trace[334285290] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"214.748133ms","start":"2026-07-22T22:10:55.575857Z","end":"2026-07-22T22:10:55.790605Z","steps":["trace[334285290] 'process raft request' (duration: 214.14722ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:10:55.790686Z","caller":"traceutil/trace.go:171","msg":"trace[1623283501] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"194.650297ms","start":"2026-07-22T22:10:55.595997Z","end":"2026-07-22T22:10:55.790647Z","steps":["trace[1623283501] 'process raft request' (duration: 194.351742ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:10:55.79076Z","caller":"traceutil/trace.go:171","msg":"trace[2105293522] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"186.256054ms","start":"2026-07-22T22:10:55.604488Z","end":"2026-07-22T22:10:55.790744Z","steps":["trace[2105293522] 'process raft request' (duration: 185.954838ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:10:55.790885Z","caller":"traceutil/trace.go:171","msg":"trace[222724197] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"171.04408ms","start":"2026-07-22T22:10:55.619831Z","end":"2026-07-22T22:10:55.790875Z","steps":["trace[222724197] 'process raft request' (duration: 170.746674ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:10:55.790937Z","caller":"traceutil/trace.go:171","msg":"trace[67504388] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"155.918157ms","start":"2026-07-22T22:10:55.634974Z","end":"2026-07-22T22:10:55.790892Z","steps":["trace[67504388] 'process raft request' (duration: 155.673272ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:01.940148Z","caller":"traceutil/trace.go:171","msg":"trace[1036736407] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"134.22928ms","start":"2026-07-22T22:11:01.805881Z","end":"2026-07-22T22:11:01.940111Z","steps":["trace[1036736407] 'process raft request' (duration: 133.959905ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:01.945089Z","caller":"traceutil/trace.go:171","msg":"trace[44370301] linearizableReadLoop","detail":"{readStateIndex:610; appliedIndex:608; }","duration":"120.344364ms","start":"2026-07-22T22:11:01.824701Z","end":"2026-07-22T22:11:01.945045Z","steps":["trace[44370301] 'read index received' (duration: 115.220389ms)","trace[44370301] 'applied index is now lower than readState.Index' (duration: 5.121745ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:11:01.9452Z","caller":"traceutil/trace.go:171","msg":"trace[264144316] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"122.738963ms","start":"2026-07-22T22:11:01.822423Z","end":"2026-07-22T22:11:01.945162Z","steps":["trace[264144316] 'process raft request' (duration: 122.378016ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:01.945475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.746763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-22T22:11:01.94557Z","caller":"traceutil/trace.go:171","msg":"trace[43433352] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:586; }","duration":"120.899326ms","start":"2026-07-22T22:11:01.824648Z","end":"2026-07-22T22:11:01.945548Z","steps":["trace[43433352] 'agreement among raft nodes before linearized reading' (duration: 120.548498ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:01.946254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.160061ms","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-07-22T22:11:01.946343Z","caller":"traceutil/trace.go:171","msg":"trace[506023257] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:586; }","duration":"121.337364ms","start":"2026-07-22T22:11:01.824984Z","end":"2026-07-22T22:11:01.946321Z","steps":["trace[506023257] 'agreement among raft nodes before linearized reading' (duration: 120.609379ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:02.242665Z","caller":"traceutil/trace.go:171","msg":"trace[502613598] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"278.606501ms","start":"2026-07-22T22:11:01.96403Z","end":"2026-07-22T22:11:02.242637Z","steps":["trace[502613598] 'process raft request' (duration: 265.191364ms)","trace[502613598] 'compare' (duration: 12.524738ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:11:02.422346Z","caller":"traceutil/trace.go:171","msg":"trace[1106641196] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"173.895749ms","start":"2026-07-22T22:11:02.248414Z","end":"2026-07-22T22:11:02.42231Z","steps":["trace[1106641196] 'process raft request' (duration: 166.505727ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:17.128911Z","caller":"traceutil/trace.go:171","msg":"trace[1612548147] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"152.660031ms","start":"2026-07-22T22:11:16.976222Z","end":"2026-07-22T22:11:17.128882Z","steps":["trace[1612548147] 'process raft request' (duration: 152.470437ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:12:31.558245Z","caller":"traceutil/trace.go:171","msg":"trace[1416652963] transaction","detail":"{read_only:false; response_revision:1802; number_of_response:1; }","duration":"145.550144ms","start":"2026-07-22T22:12:31.412658Z","end":"2026-07-22T22:12:31.558208Z","steps":["trace[1416652963] 'process raft request' (duration: 145.184986ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:13:22.415768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.636961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-22T22:13:22.415872Z","caller":"traceutil/trace.go:171","msg":"trace[1024711707] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:2131; }","duration":"122.830954ms","start":"2026-07-22T22:13:22.293019Z","end":"2026-07-22T22:13:22.41585Z","steps":["trace[1024711707] 'count revisions from in-memory index tree' (duration: 122.493488ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:14:10.836151Z","caller":"traceutil/trace.go:171","msg":"trace[1803572048] linearizableReadLoop","detail":"{readStateIndex:2667; appliedIndex:2666; }","duration":"133.138398ms","start":"2026-07-22T22:14:10.702985Z","end":"2026-07-22T22:14:10.836124Z","steps":["trace[1803572048] 'read index received' (duration: 133.043225ms)","trace[1803572048] 'applied index is now lower than readState.Index' (duration: 94.303µs)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:14:10.836245Z","caller":"traceutil/trace.go:171","msg":"trace[1947359244] transaction","detail":"{read_only:false; response_revision:2567; number_of_response:1; }","duration":"168.101547ms","start":"2026-07-22T22:14:10.668118Z","end":"2026-07-22T22:14:10.836219Z","steps":["trace[1947359244] 'process raft request' (duration: 167.858032ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:14:10.83629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.27338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:14:10.836327Z","caller":"traceutil/trace.go:171","msg":"trace[1996244746] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:2567; }","duration":"133.354872ms","start":"2026-07-22T22:14:10.702962Z","end":"2026-07-22T22:14:10.836317Z","steps":["trace[1996244746] 'agreement among raft nodes before linearized reading' (duration: 133.270021ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:18:36.904036Z","caller":"traceutil/trace.go:171","msg":"trace[222948166] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4800; }","duration":"122.529991ms","start":"2026-07-22T22:18:36.781464Z","end":"2026-07-22T22:18:36.903994Z","steps":["trace[222948166] 'process raft request' (duration: 94.621308ms)","trace[222948166] 'compare' (duration: 27.669219ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:18:41.812787Z","caller":"traceutil/trace.go:171","msg":"trace[1368045356] transaction","detail":"{read_only:false; response_revision:4985; number_of_response:1; }","duration":"275.368182ms","start":"2026-07-22T22:18:41.537383Z","end":"2026-07-22T22:18:41.812751Z","steps":["trace[1368045356] 'process raft request' (duration: 259.348986ms)","trace[1368045356] 'compare' (duration: 15.65096ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:18:41.817997Z","caller":"traceutil/trace.go:171","msg":"trace[390424757] linearizableReadLoop","detail":"{readStateIndex:5176; appliedIndex:5174; }","duration":"258.364847ms","start":"2026-07-22T22:18:41.55959Z","end":"2026-07-22T22:18:41.817955Z","steps":["trace[390424757] 'read index received' (duration: 237.253343ms)","trace[390424757] 'applied index is now lower than readState.Index' (duration: 21.110084ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:18:41.818284Z","caller":"traceutil/trace.go:171","msg":"trace[1704581718] transaction","detail":"{read_only:false; response_revision:4986; number_of_response:1; }","duration":"276.680427ms","start":"2026-07-22T22:18:41.541589Z","end":"2026-07-22T22:18:41.818269Z","steps":["trace[1704581718] 'process raft request' (duration: 276.173486ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:18:41.818686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.0811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-07-22T22:18:41.818865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.306554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e512ef99-40c8-4ed0-bce8-a946fb33c65f\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-07-22T22:18:41.818901Z","caller":"traceutil/trace.go:171","msg":"trace[1255667281] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e512ef99-40c8-4ed0-bce8-a946fb33c65f; range_end:; response_count:1; response_revision:4986; }","duration":"102.399346ms","start":"2026-07-22T22:18:41.716494Z","end":"2026-07-22T22:18:41.818893Z","steps":["trace[1255667281] 'agreement among raft nodes before linearized reading' (duration: 102.285674ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:18:41.819043Z","caller":"traceutil/trace.go:171","msg":"trace[1170042898] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4986; }","duration":"259.165062ms","start":"2026-07-22T22:18:41.559557Z","end":"2026-07-22T22:18:41.818722Z","steps":["trace[1170042898] 'agreement among raft nodes before linearized reading' (duration: 258.935588ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:18:42.14743Z","caller":"traceutil/trace.go:171","msg":"trace[1568749789] linearizableReadLoop","detail":"{readStateIndex:5182; appliedIndex:5181; }","duration":"131.630824ms","start":"2026-07-22T22:18:42.01578Z","end":"2026-07-22T22:18:42.147411Z","steps":["trace[1568749789] 'read index received' (duration: 131.553553ms)","trace[1568749789] 'applied index is now lower than readState.Index' (duration: 75.971µs)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:18:42.147569Z","caller":"traceutil/trace.go:171","msg":"trace[54640102] transaction","detail":"{read_only:false; response_revision:4992; number_of_response:1; }","duration":"178.396048ms","start":"2026-07-22T22:18:41.969141Z","end":"2026-07-22T22:18:42.147537Z","steps":["trace[54640102] 'process raft request' (duration: 178.115313ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:18:42.147734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.93145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-22T22:18:42.147771Z","caller":"traceutil/trace.go:171","msg":"trace[417414142] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4992; }","duration":"132.018552ms","start":"2026-07-22T22:18:42.015744Z","end":"2026-07-22T22:18:42.147762Z","steps":["trace[417414142] 'agreement among raft nodes before linearized reading' (duration: 131.767217ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:18:54.411259Z","caller":"traceutil/trace.go:171","msg":"trace[1155440742] linearizableReadLoop","detail":"{readStateIndex:5431; appliedIndex:5429; }","duration":"114.807314ms","start":"2026-07-22T22:18:54.296421Z","end":"2026-07-22T22:18:54.411228Z","steps":["trace[1155440742] 'read index received' (duration: 72.287912ms)","trace[1155440742] 'applied index is now lower than readState.Index' (duration: 42.517922ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:18:54.411484Z","caller":"traceutil/trace.go:171","msg":"trace[1134930747] transaction","detail":"{read_only:false; response_revision:5233; number_of_response:1; }","duration":"125.59016ms","start":"2026-07-22T22:18:54.285846Z","end":"2026-07-22T22:18:54.411436Z","steps":["trace[1134930747] 'process raft request' (duration: 125.147242ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:18:54.411984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.537327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-22T22:18:54.412078Z","caller":"traceutil/trace.go:171","msg":"trace[440808029] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5234; }","duration":"115.69349ms","start":"2026-07-22T22:18:54.296362Z","end":"2026-07-22T22:18:54.412055Z","steps":["trace[440808029] 'agreement among raft nodes before linearized reading' (duration: 115.396825ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:19:02.562035Z","caller":"traceutil/trace.go:171","msg":"trace[1640820076] linearizableReadLoop","detail":"{readStateIndex:5618; appliedIndex:5615; }","duration":"107.014044ms","start":"2026-07-22T22:19:02.45499Z","end":"2026-07-22T22:19:02.562004Z","steps":["trace[1640820076] 'read index received' (duration: 17.78959ms)","trace[1640820076] 'applied index is now lower than readState.Index' (duration: 89.222974ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:19:02.562332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.331362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-22T22:19:02.562379Z","caller":"traceutil/trace.go:171","msg":"trace[808135184] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5417; }","duration":"107.447354ms","start":"2026-07-22T22:19:02.454916Z","end":"2026-07-22T22:19:02.562363Z","steps":["trace[808135184] 'agreement among raft nodes before linearized reading' (duration: 107.2899ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:19:02.562419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.193609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-vxsd5\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-07-22T22:19:02.562503Z","caller":"traceutil/trace.go:171","msg":"trace[1386234566] range","detail":"{range_begin:/registry/pods/openstack/keepalived-vxsd5; range_end:; response_count:1; response_revision:5417; }","duration":"106.339792ms","start":"2026-07-22T22:19:02.456152Z","end":"2026-07-22T22:19:02.562491Z","steps":["trace[1386234566] 'agreement among raft nodes before linearized reading' (duration: 106.081236ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:19:02.562645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.371958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-07-22T22:19:02.562681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.848947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-22T22:19:02.562755Z","caller":"traceutil/trace.go:171","msg":"trace[830969760] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5417; }","duration":"100.508501ms","start":"2026-07-22T22:19:02.462229Z","end":"2026-07-22T22:19:02.562738Z","steps":["trace[830969760] 'agreement among raft nodes before linearized reading' (duration: 100.049682ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:19:02.562837Z","caller":"traceutil/trace.go:171","msg":"trace[1452274220] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5417; }","duration":"101.04043ms","start":"2026-07-22T22:19:02.461762Z","end":"2026-07-22T22:19:02.562803Z","steps":["trace[1452274220] 'agreement among raft nodes before linearized reading' (duration: 100.676143ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:19:02.562824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.330785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-22T22:19:02.562133Z","caller":"traceutil/trace.go:171","msg":"trace[113549747] transaction","detail":"{read_only:false; response_revision:5416; number_of_response:1; }","duration":"125.435956ms","start":"2026-07-22T22:19:02.436637Z","end":"2026-07-22T22:19:02.562073Z","steps":["trace[113549747] 'process raft request' (duration: 67.129223ms)","trace[113549747] 'compare' (duration: 58.048389ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:19:02.562959Z","caller":"traceutil/trace.go:171","msg":"trace[1776044751] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5417; }","duration":"102.526768ms","start":"2026-07-22T22:19:02.460401Z","end":"2026-07-22T22:19:02.562927Z","steps":["trace[1776044751] 'agreement among raft nodes before linearized reading' (duration: 102.08698ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:19:02.562689Z","caller":"traceutil/trace.go:171","msg":"trace[1842615359] transaction","detail":"{read_only:false; response_revision:5417; number_of_response:1; }","duration":"120.994732ms","start":"2026-07-22T22:19:02.441493Z","end":"2026-07-22T22:19:02.562488Z","steps":["trace[1842615359] 'process raft request' (duration: 120.459221ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:20:19.895819Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2967} {"level":"info","ts":"2026-07-22T22:20:19.973017Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2967,"took":"75.180627ms","hash":227914670,"current-db-size-bytes":58212352,"current-db-size":"58 MB","current-db-size-in-use-bytes":34000896,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-22T22:20:19.973094Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":227914670,"revision":2967,"compact-revision":-1} {"level":"info","ts":"2026-07-22T22:25:08.708188Z","caller":"traceutil/trace.go:171","msg":"trace[629367097] linearizableReadLoop","detail":"{readStateIndex:8513; appliedIndex:8512; }","duration":"206.481148ms","start":"2026-07-22T22:25:08.50167Z","end":"2026-07-22T22:25:08.708151Z","steps":["trace[629367097] 'read index received' (duration: 206.275215ms)","trace[629367097] 'applied index is now lower than readState.Index' (duration: 204.453µs)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:25:08.708233Z","caller":"traceutil/trace.go:171","msg":"trace[1031416328] transaction","detail":"{read_only:false; response_revision:8222; number_of_response:1; }","duration":"388.564302ms","start":"2026-07-22T22:25:08.319622Z","end":"2026-07-22T22:25:08.708186Z","steps":["trace[1031416328] 'process raft request' (duration: 388.298167ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:25:08.708326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.630782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:25:08.708386Z","caller":"traceutil/trace.go:171","msg":"trace[281709804] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8222; }","duration":"206.776055ms","start":"2026-07-22T22:25:08.501598Z","end":"2026-07-22T22:25:08.708374Z","steps":["trace[281709804] 'agreement among raft nodes before linearized reading' (duration: 206.650791ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:25:08.70893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:25:08.31958Z","time spent":"388.742616ms","remote":"127.0.0.1:42146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:25:19.947022Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6090} {"level":"warn","ts":"2026-07-22T22:25:20.428164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.151951ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-22T22:25:20.428494Z","caller":"traceutil/trace.go:171","msg":"trace[1959970237] linearizableReadLoop","detail":"{readStateIndex:8580; appliedIndex:8578; }","duration":"189.237309ms","start":"2026-07-22T22:25:20.239232Z","end":"2026-07-22T22:25:20.428469Z","steps":["trace[1959970237] 'read index received' (duration: 100.593119ms)","trace[1959970237] 'applied index is now lower than readState.Index' (duration: 88.64262ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:25:20.428498Z","caller":"traceutil/trace.go:171","msg":"trace[547223437] transaction","detail":"{read_only:false; response_revision:8284; number_of_response:1; }","duration":"344.294928ms","start":"2026-07-22T22:25:20.084146Z","end":"2026-07-22T22:25:20.428441Z","steps":["trace[547223437] 'process raft request' (duration: 16.541766ms)","trace[547223437] 'compare' (duration: 290.783727ms)","trace[547223437] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/openstack/messaging-topology-operator-leader-election; req_size:552; } (duration: 36.076568ms)"],"step_count":3} {"level":"warn","ts":"2026-07-22T22:25:20.428616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.391193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:25:20.428661Z","caller":"traceutil/trace.go:171","msg":"trace[153625227] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:8285; }","duration":"189.436394ms","start":"2026-07-22T22:25:20.239209Z","end":"2026-07-22T22:25:20.428646Z","steps":["trace[153625227] 'agreement among raft nodes before linearized reading' (duration: 189.334532ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:25:20.428633Z","caller":"traceutil/trace.go:171","msg":"trace[1251339802] transaction","detail":"{read_only:false; response_revision:8285; number_of_response:1; }","duration":"339.284961ms","start":"2026-07-22T22:25:20.089296Z","end":"2026-07-22T22:25:20.428581Z","steps":["trace[1251339802] 'process raft request' (duration: 339.044497ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:25:20.428699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:25:20.084115Z","time spent":"344.475441ms","remote":"127.0.0.1:42146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:25:20.428869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:25:20.089271Z","time spent":"339.474895ms","remote":"127.0.0.1:42146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:25:20.558523Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6090,"took":"608.882985ms","hash":771030675,"current-db-size-bytes":58212352,"current-db-size":"58 MB","current-db-size-in-use-bytes":23838720,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-07-22T22:25:20.558615Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":771030675,"revision":6090,"compact-revision":2967} {"level":"info","ts":"2026-07-22T22:25:50.293352Z","caller":"traceutil/trace.go:171","msg":"trace[1708390604] transaction","detail":"{read_only:false; response_revision:8499; number_of_response:1; }","duration":"116.879569ms","start":"2026-07-22T22:25:50.17645Z","end":"2026-07-22T22:25:50.293329Z","steps":["trace[1708390604] 'process raft request' (duration: 116.734477ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:26:02.543209Z","caller":"traceutil/trace.go:171","msg":"trace[894213303] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8601; }","duration":"128.291266ms","start":"2026-07-22T22:26:02.414873Z","end":"2026-07-22T22:26:02.543164Z","steps":["trace[894213303] 'process raft request' (duration: 105.047604ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:26:02.639811Z","caller":"traceutil/trace.go:171","msg":"trace[593826174] linearizableReadLoop","detail":"{readStateIndex:8910; appliedIndex:8908; }","duration":"139.555971ms","start":"2026-07-22T22:26:02.50022Z","end":"2026-07-22T22:26:02.639776Z","steps":["trace[593826174] 'read index received' (duration: 19.716946ms)","trace[593826174] 'applied index is now lower than readState.Index' (duration: 119.837495ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:26:02.640437Z","caller":"traceutil/trace.go:171","msg":"trace[1305230313] transaction","detail":"{read_only:false; response_revision:8602; number_of_response:1; }","duration":"225.051673ms","start":"2026-07-22T22:26:02.415356Z","end":"2026-07-22T22:26:02.640408Z","steps":["trace[1305230313] 'process raft request' (duration: 224.221107ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:26:02.641003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.760694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:26:02.641175Z","caller":"traceutil/trace.go:171","msg":"trace[1026038211] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8602; }","duration":"141.02098ms","start":"2026-07-22T22:26:02.500135Z","end":"2026-07-22T22:26:02.641156Z","steps":["trace[1026038211] 'agreement among raft nodes before linearized reading' (duration: 140.779505ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:26:02.670701Z","caller":"traceutil/trace.go:171","msg":"trace[797040827] transaction","detail":"{read_only:false; response_revision:8603; number_of_response:1; }","duration":"141.918087ms","start":"2026-07-22T22:26:02.528755Z","end":"2026-07-22T22:26:02.670673Z","steps":["trace[797040827] 'process raft request' (duration: 141.325646ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:26:02.67167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.758573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-bbb24\" ","response":"range_response_count:1 size:4648"} {"level":"info","ts":"2026-07-22T22:26:02.671732Z","caller":"traceutil/trace.go:171","msg":"trace[650766631] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-bbb24; range_end:; response_count:1; response_revision:8605; }","duration":"142.882266ms","start":"2026-07-22T22:26:02.528833Z","end":"2026-07-22T22:26:02.671716Z","steps":["trace[650766631] 'agreement among raft nodes before linearized reading' (duration: 142.657342ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:26:03.194923Z","caller":"traceutil/trace.go:171","msg":"trace[1724748816] linearizableReadLoop","detail":"{readStateIndex:8917; appliedIndex:8916; }","duration":"120.473138ms","start":"2026-07-22T22:26:03.074424Z","end":"2026-07-22T22:26:03.194897Z","steps":["trace[1724748816] 'read index received' (duration: 120.221594ms)","trace[1724748816] 'applied index is now lower than readState.Index' (duration: 250.034µs)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:26:03.195256Z","caller":"traceutil/trace.go:171","msg":"trace[1808252602] transaction","detail":"{read_only:false; response_revision:8608; number_of_response:1; }","duration":"133.339783ms","start":"2026-07-22T22:26:03.061901Z","end":"2026-07-22T22:26:03.195241Z","steps":["trace[1808252602] 'process raft request' (duration: 132.830274ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:26:03.195596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.14302ms","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-07-22T22:26:03.195731Z","caller":"traceutil/trace.go:171","msg":"trace[593642717] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8608; }","duration":"121.323114ms","start":"2026-07-22T22:26:03.074388Z","end":"2026-07-22T22:26:03.195712Z","steps":["trace[593642717] 'agreement among raft nodes before linearized reading' (duration: 121.07223ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:26:03.195975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.13986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-07-22T22:26:03.196021Z","caller":"traceutil/trace.go:171","msg":"trace[1010087914] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8608; }","duration":"112.183641ms","start":"2026-07-22T22:26:03.083824Z","end":"2026-07-22T22:26:03.196008Z","steps":["trace[1010087914] 'agreement among raft nodes before linearized reading' (duration: 112.078629ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:26:03.196491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.211219ms","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-07-22T22:26:03.196533Z","caller":"traceutil/trace.go:171","msg":"trace[1063955548] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8608; }","duration":"103.2674ms","start":"2026-07-22T22:26:03.093256Z","end":"2026-07-22T22:26:03.196523Z","steps":["trace[1063955548] 'agreement among raft nodes before linearized reading' (duration: 103.176038ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:26:03.196701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.166164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-22T22:26:03.196735Z","caller":"traceutil/trace.go:171","msg":"trace[1056916906] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8608; }","duration":"107.217105ms","start":"2026-07-22T22:26:03.08951Z","end":"2026-07-22T22:26:03.196727Z","steps":["trace[1056916906] 'agreement among raft nodes before linearized reading' (duration: 107.134063ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:26:03.392131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.675381ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-22T22:26:03.392476Z","caller":"traceutil/trace.go:171","msg":"trace[1612378511] transaction","detail":"{read_only:false; response_revision:8611; number_of_response:1; }","duration":"188.040836ms","start":"2026-07-22T22:26:03.204408Z","end":"2026-07-22T22:26:03.392449Z","steps":["trace[1612378511] 'process raft request' (duration: 187.946685ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:26:03.392574Z","caller":"traceutil/trace.go:171","msg":"trace[358309792] transaction","detail":"{read_only:false; response_revision:8610; number_of_response:1; }","duration":"188.20986ms","start":"2026-07-22T22:26:03.204325Z","end":"2026-07-22T22:26:03.392535Z","steps":["trace[358309792] 'process raft request' (duration: 187.955155ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:26:03.392624Z","caller":"traceutil/trace.go:171","msg":"trace[1513579028] transaction","detail":"{read_only:false; response_revision:8612; number_of_response:1; }","duration":"184.275135ms","start":"2026-07-22T22:26:03.208334Z","end":"2026-07-22T22:26:03.392609Z","steps":["trace[1513579028] 'process raft request' (duration: 184.065281ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:26:03.392483Z","caller":"traceutil/trace.go:171","msg":"trace[11988164] transaction","detail":"{read_only:false; response_revision:8609; number_of_response:1; }","duration":"188.406984ms","start":"2026-07-22T22:26:03.204013Z","end":"2026-07-22T22:26:03.39242Z","steps":["trace[11988164] 'process raft request' (duration: 34.293884ms)","trace[11988164] 'compare' (duration: 153.505568ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:26:04.160198Z","caller":"traceutil/trace.go:171","msg":"trace[1629865065] transaction","detail":"{read_only:false; response_revision:8615; number_of_response:1; }","duration":"184.098951ms","start":"2026-07-22T22:26:03.976066Z","end":"2026-07-22T22:26:04.160165Z","steps":["trace[1629865065] 'process raft request' (duration: 183.883847ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:26:41.348512Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002448.wal"} {"level":"info","ts":"2026-07-22T22:28:00.03605Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"aab6040abcf11","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-22T22:28:00.043848Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-22T22:28:00.04396Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-07-22T22:30:19.954027Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8282} {"level":"info","ts":"2026-07-22T22:30:20.041435Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8282,"took":"84.217827ms","hash":2578662292,"current-db-size-bytes":58212352,"current-db-size":"58 MB","current-db-size-in-use-bytes":25382912,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-07-22T22:30:20.041586Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2578662292,"revision":8282,"compact-revision":6090} {"level":"info","ts":"2026-07-22T22:31:04.025046Z","caller":"traceutil/trace.go:171","msg":"trace[1385955453] transaction","detail":"{read_only:false; response_revision:11217; number_of_response:1; }","duration":"116.146264ms","start":"2026-07-22T22:31:03.908862Z","end":"2026-07-22T22:31:04.025008Z","steps":["trace[1385955453] 'process raft request' (duration: 115.865689ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:35:19.961606Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10774} {"level":"info","ts":"2026-07-22T22:35:20.049965Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10774,"took":"84.875156ms","hash":1063075472,"current-db-size-bytes":58212352,"current-db-size":"58 MB","current-db-size-in-use-bytes":27975680,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-07-22T22:35:20.050041Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1063075472,"revision":10774,"compact-revision":8282} {"level":"info","ts":"2026-07-22T22:36:53.26934Z","caller":"traceutil/trace.go:171","msg":"trace[2028054234] transaction","detail":"{read_only:false; response_revision:13604; number_of_response:1; }","duration":"110.951244ms","start":"2026-07-22T22:36:53.158361Z","end":"2026-07-22T22:36:53.269312Z","steps":["trace[2028054234] 'process raft request' (duration: 110.822972ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:37:17.629699Z","caller":"traceutil/trace.go:171","msg":"trace[489515790] transaction","detail":"{read_only:false; response_revision:13736; number_of_response:1; }","duration":"123.857159ms","start":"2026-07-22T22:37:17.505809Z","end":"2026-07-22T22:37:17.629666Z","steps":["trace[489515790] 'process raft request' (duration: 122.343751ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:37:34.333086Z","caller":"traceutil/trace.go:171","msg":"trace[1400665533] transaction","detail":"{read_only:false; response_revision:13834; number_of_response:1; }","duration":"250.452563ms","start":"2026-07-22T22:37:34.082583Z","end":"2026-07-22T22:37:34.333035Z","steps":["trace[1400665533] 'process raft request' (duration: 250.193118ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:39:24.561511Z","caller":"traceutil/trace.go:171","msg":"trace[1439126233] transaction","detail":"{read_only:false; response_revision:15098; number_of_response:1; }","duration":"103.345494ms","start":"2026-07-22T22:39:24.45814Z","end":"2026-07-22T22:39:24.561485Z","steps":["trace[1439126233] 'process raft request' (duration: 102.894066ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:39:25.622296Z","caller":"traceutil/trace.go:171","msg":"trace[1519456044] transaction","detail":"{read_only:false; response_revision:15099; number_of_response:1; }","duration":"108.113416ms","start":"2026-07-22T22:39:25.514141Z","end":"2026-07-22T22:39:25.622254Z","steps":["trace[1519456044] 'process raft request' (duration: 107.909012ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:39:25.622298Z","caller":"traceutil/trace.go:171","msg":"trace[1720078278] linearizableReadLoop","detail":"{readStateIndex:15610; appliedIndex:15609; }","duration":"105.673849ms","start":"2026-07-22T22:39:25.516593Z","end":"2026-07-22T22:39:25.622266Z","steps":["trace[1720078278] 'read index received' (duration: 105.403353ms)","trace[1720078278] 'applied index is now lower than readState.Index' (duration: 266.946µs)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:39:25.622576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.951285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-22T22:39:25.622691Z","caller":"traceutil/trace.go:171","msg":"trace[403251057] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15099; }","duration":"106.104227ms","start":"2026-07-22T22:39:25.516568Z","end":"2026-07-22T22:39:25.622672Z","steps":["trace[403251057] 'agreement among raft nodes before linearized reading' (duration: 105.765261ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:40:19.970483Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12953} {"level":"info","ts":"2026-07-22T22:40:20.054067Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12953,"took":"79.679992ms","hash":81401837,"current-db-size-bytes":58212352,"current-db-size":"58 MB","current-db-size-in-use-bytes":30396416,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-22T22:40:20.05416Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":81401837,"revision":12953,"compact-revision":10774} {"level":"warn","ts":"2026-07-22T22:45:00.602226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.148046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-22T22:45:00.602355Z","caller":"traceutil/trace.go:171","msg":"trace[1225589951] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:17641; }","duration":"116.296379ms","start":"2026-07-22T22:45:00.486034Z","end":"2026-07-22T22:45:00.60233Z","steps":["trace[1225589951] 'range keys from in-memory index tree' (duration: 116.069785ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:45:00.602632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.451299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-22T22:45:00.602735Z","caller":"traceutil/trace.go:171","msg":"trace[581458758] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17641; }","duration":"101.620664ms","start":"2026-07-22T22:45:00.501095Z","end":"2026-07-22T22:45:00.602716Z","steps":["trace[581458758] 'range keys from in-memory index tree' (duration: 101.376278ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:45:05.742413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.765244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-07-22T22:45:05.74261Z","caller":"traceutil/trace.go:171","msg":"trace[350290658] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:17678; }","duration":"110.919337ms","start":"2026-07-22T22:45:05.631597Z","end":"2026-07-22T22:45:05.742517Z","steps":["trace[350290658] 'range keys from in-memory index tree' (duration: 110.532871ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:45:05.791462Z","caller":"traceutil/trace.go:171","msg":"trace[1417287800] transaction","detail":"{read_only:false; response_revision:17679; number_of_response:1; }","duration":"147.363744ms","start":"2026-07-22T22:45:05.64405Z","end":"2026-07-22T22:45:05.791414Z","steps":["trace[1417287800] 'process raft request' (duration: 147.195921ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:45:05.797936Z","caller":"traceutil/trace.go:171","msg":"trace[44755873] linearizableReadLoop","detail":"{readStateIndex:18275; appliedIndex:18273; }","duration":"126.099183ms","start":"2026-07-22T22:45:05.67181Z","end":"2026-07-22T22:45:05.797909Z","steps":["trace[44755873] 'read index received' (duration: 119.430777ms)","trace[44755873] 'applied index is now lower than readState.Index' (duration: 6.667346ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:45:05.798277Z","caller":"traceutil/trace.go:171","msg":"trace[2125836796] transaction","detail":"{read_only:false; response_revision:17680; number_of_response:1; }","duration":"152.140653ms","start":"2026-07-22T22:45:05.646093Z","end":"2026-07-22T22:45:05.798234Z","steps":["trace[2125836796] 'process raft request' (duration: 151.685975ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:45:05.798299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.469501ms","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-07-22T22:45:05.798433Z","caller":"traceutil/trace.go:171","msg":"trace[530060016] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:17680; }","duration":"126.632014ms","start":"2026-07-22T22:45:05.671789Z","end":"2026-07-22T22:45:05.798421Z","steps":["trace[530060016] 'agreement among raft nodes before linearized reading' (duration: 126.364029ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:45:06.267518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.019167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-22T22:45:06.26774Z","caller":"traceutil/trace.go:171","msg":"trace[658915105] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:17686; }","duration":"283.296843ms","start":"2026-07-22T22:45:05.98442Z","end":"2026-07-22T22:45:06.267716Z","steps":["trace[658915105] 'count revisions from in-memory index tree' (duration: 282.919655ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:45:19.977107Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15441} {"level":"info","ts":"2026-07-22T22:45:20.077659Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15441,"took":"95.660071ms","hash":2375633521,"current-db-size-bytes":58212352,"current-db-size":"58 MB","current-db-size-in-use-bytes":32051200,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-22T22:45:20.077746Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2375633521,"revision":15441,"compact-revision":12953} {"level":"info","ts":"2026-07-22T22:48:36.732542Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"aab6040abcf11","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-22T22:48:36.737689Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-07-22T22:48:36.737807Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-07-22T22:50:19.986246Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17843} {"level":"info","ts":"2026-07-22T22:50:20.098948Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17843,"took":"108.564767ms","hash":1360130429,"current-db-size-bytes":58212352,"current-db-size":"58 MB","current-db-size-in-use-bytes":30146560,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-22T22:50:20.09902Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1360130429,"revision":17843,"compact-revision":15441} {"level":"info","ts":"2026-07-22T22:50:35.451216Z","caller":"traceutil/trace.go:171","msg":"trace[714822523] transaction","detail":"{read_only:false; response_revision:20191; number_of_response:1; }","duration":"237.584ms","start":"2026-07-22T22:50:35.213593Z","end":"2026-07-22T22:50:35.451177Z","steps":["trace[714822523] 'process raft request' (duration: 237.391977ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:50:36.078639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.696255ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-22T22:50:36.079228Z","caller":"traceutil/trace.go:171","msg":"trace[260748750] transaction","detail":"{read_only:false; response_revision:20193; number_of_response:1; }","duration":"281.55402ms","start":"2026-07-22T22:50:35.797643Z","end":"2026-07-22T22:50:36.079197Z","steps":["trace[260748750] 'process raft request' (duration: 150.166802ms)","trace[260748750] 'compare' (duration: 130.087474ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:50:36.07935Z","caller":"traceutil/trace.go:171","msg":"trace[684156876] transaction","detail":"{read_only:false; response_revision:20194; number_of_response:1; }","duration":"281.586301ms","start":"2026-07-22T22:50:35.797749Z","end":"2026-07-22T22:50:36.079335Z","steps":["trace[684156876] 'process raft request' (duration: 281.366317ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:50:36.079762Z","caller":"traceutil/trace.go:171","msg":"trace[695227561] transaction","detail":"{read_only:false; response_revision:20195; number_of_response:1; }","duration":"281.961897ms","start":"2026-07-22T22:50:35.797787Z","end":"2026-07-22T22:50:36.079749Z","steps":["trace[695227561] 'process raft request' (duration: 281.470138ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:50:36.079921Z","caller":"traceutil/trace.go:171","msg":"trace[906307620] transaction","detail":"{read_only:false; response_revision:20196; number_of_response:1; }","duration":"282.09587ms","start":"2026-07-22T22:50:35.797815Z","end":"2026-07-22T22:50:36.079911Z","steps":["trace[906307620] 'process raft request' (duration: 281.874156ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:50:36.080063Z","caller":"traceutil/trace.go:171","msg":"trace[378244570] linearizableReadLoop","detail":"{readStateIndex:20878; appliedIndex:20873; }","duration":"186.977746ms","start":"2026-07-22T22:50:35.893075Z","end":"2026-07-22T22:50:36.080053Z","steps":["trace[378244570] 'read index received' (duration: 54.61202ms)","trace[378244570] 'applied index is now lower than readState.Index' (duration: 132.364726ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:50:36.080165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.080638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-22T22:50:36.080203Z","caller":"traceutil/trace.go:171","msg":"trace[209289099] range","detail":"{range_begin:/registry/monitoring.coreos.com/servicemonitors/; range_end:/registry/monitoring.coreos.com/servicemonitors0; response_count:0; response_revision:20197; }","duration":"187.17522ms","start":"2026-07-22T22:50:35.893019Z","end":"2026-07-22T22:50:36.080194Z","steps":["trace[209289099] 'agreement among raft nodes before linearized reading' (duration: 187.075818ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:50:36.080398Z","caller":"traceutil/trace.go:171","msg":"trace[179415850] transaction","detail":"{read_only:false; response_revision:20197; number_of_response:1; }","duration":"280.745976ms","start":"2026-07-22T22:50:35.799642Z","end":"2026-07-22T22:50:36.080388Z","steps":["trace[179415850] 'process raft request' (duration: 280.231046ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:55:19.993508Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20113} {"level":"info","ts":"2026-07-22T22:55:20.106702Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20113,"took":"108.23077ms","hash":2001874560,"current-db-size-bytes":58212352,"current-db-size":"58 MB","current-db-size-in-use-bytes":41979904,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-07-22T22:55:20.106792Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2001874560,"revision":20113,"compact-revision":17843} {"level":"info","ts":"2026-07-22T22:58:00.739895Z","caller":"traceutil/trace.go:171","msg":"trace[386790474] linearizableReadLoop","detail":"{readStateIndex:25547; appliedIndex:25546; }","duration":"103.085722ms","start":"2026-07-22T22:58:00.636781Z","end":"2026-07-22T22:58:00.739867Z","steps":["trace[386790474] 'read index received' (duration: 102.743275ms)","trace[386790474] 'applied index is now lower than readState.Index' (duration: 341.116µs)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:58:00.740001Z","caller":"traceutil/trace.go:171","msg":"trace[1724070783] transaction","detail":"{read_only:false; response_revision:24736; number_of_response:1; }","duration":"103.198423ms","start":"2026-07-22T22:58:00.636752Z","end":"2026-07-22T22:58:00.73995Z","steps":["trace[1724070783] 'process raft request' (duration: 102.933308ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:58:00.740187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.701746ms","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-07-22T22:58:00.740222Z","caller":"traceutil/trace.go:171","msg":"trace[58646244] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24736; }","duration":"101.802157ms","start":"2026-07-22T22:58:00.638411Z","end":"2026-07-22T22:58:00.740214Z","steps":["trace[58646244] 'agreement among raft nodes before linearized reading' (duration: 101.619473ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:58:00.740195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.383778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-22T22:58:00.740273Z","caller":"traceutil/trace.go:171","msg":"trace[337359661] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24736; }","duration":"103.51668ms","start":"2026-07-22T22:58:00.636743Z","end":"2026-07-22T22:58:00.74026Z","steps":["trace[337359661] 'agreement among raft nodes before linearized reading' (duration: 103.276954ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:58:00.74039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.670795ms","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-07-22T22:58:00.740418Z","caller":"traceutil/trace.go:171","msg":"trace[1863471611] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24736; }","duration":"100.696425ms","start":"2026-07-22T22:58:00.639712Z","end":"2026-07-22T22:58:00.740408Z","steps":["trace[1863471611] 'agreement among raft nodes before linearized reading' (duration: 100.630074ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:58:00.875568Z","caller":"traceutil/trace.go:171","msg":"trace[1384041974] transaction","detail":"{read_only:false; response_revision:24737; number_of_response:1; }","duration":"130.894753ms","start":"2026-07-22T22:58:00.744644Z","end":"2026-07-22T22:58:00.875539Z","steps":["trace[1384041974] 'process raft request' (duration: 129.020087ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:58:01.003896Z","caller":"traceutil/trace.go:171","msg":"trace[1650613453] transaction","detail":"{read_only:false; response_revision:24742; number_of_response:1; }","duration":"229.809515ms","start":"2026-07-22T22:58:00.774067Z","end":"2026-07-22T22:58:01.003876Z","steps":["trace[1650613453] 'process raft request' (duration: 229.759884ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:58:01.003915Z","caller":"traceutil/trace.go:171","msg":"trace[1654144499] transaction","detail":"{read_only:false; response_revision:24739; number_of_response:1; }","duration":"258.440802ms","start":"2026-07-22T22:58:00.745433Z","end":"2026-07-22T22:58:01.003874Z","steps":["trace[1654144499] 'process raft request' (duration: 258.013855ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:58:01.003984Z","caller":"traceutil/trace.go:171","msg":"trace[962786553] transaction","detail":"{read_only:false; response_revision:24741; number_of_response:1; }","duration":"232.673529ms","start":"2026-07-22T22:58:00.771299Z","end":"2026-07-22T22:58:01.003973Z","steps":["trace[962786553] 'process raft request' (duration: 232.473106ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:58:01.003903Z","caller":"traceutil/trace.go:171","msg":"trace[742888777] transaction","detail":"{read_only:false; response_revision:24740; number_of_response:1; }","duration":"258.034884ms","start":"2026-07-22T22:58:00.745818Z","end":"2026-07-22T22:58:01.003853Z","steps":["trace[742888777] 'process raft request' (duration: 257.888451ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:58:01.003588Z","caller":"traceutil/trace.go:171","msg":"trace[1354758685] transaction","detail":"{read_only:false; response_revision:24738; number_of_response:1; }","duration":"258.775479ms","start":"2026-07-22T22:58:00.744758Z","end":"2026-07-22T22:58:01.003533Z","steps":["trace[1354758685] 'process raft request' (duration: 258.35366ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:58:05.708936Z","caller":"traceutil/trace.go:171","msg":"trace[677275779] transaction","detail":"{read_only:false; response_revision:24773; number_of_response:1; }","duration":"253.361765ms","start":"2026-07-22T22:58:05.45555Z","end":"2026-07-22T22:58:05.708912Z","steps":["trace[677275779] 'process raft request' (duration: 253.191052ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:58:05.937878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.804723ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-22T22:58:05.937998Z","caller":"traceutil/trace.go:171","msg":"trace[146806000] linearizableReadLoop","detail":"{readStateIndex:25586; appliedIndex:25584; }","duration":"437.940325ms","start":"2026-07-22T22:58:05.500045Z","end":"2026-07-22T22:58:05.937986Z","steps":["trace[146806000] 'read index received' (duration: 208.750502ms)","trace[146806000] 'applied index is now lower than readState.Index' (duration: 229.189003ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:58:05.938056Z","caller":"traceutil/trace.go:171","msg":"trace[1256813083] transaction","detail":"{read_only:false; response_revision:24774; number_of_response:1; }","duration":"477.287477ms","start":"2026-07-22T22:58:05.460762Z","end":"2026-07-22T22:58:05.938049Z","steps":["trace[1256813083] 'process raft request' (duration: 348.24129ms)","trace[1256813083] 'compare' (duration: 128.66171ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:58:05.938094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:58:05.460735Z","time spent":"477.340479ms","remote":"127.0.0.1:42146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:58:05.938201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.158059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-22T22:58:05.938217Z","caller":"traceutil/trace.go:171","msg":"trace[1860865009] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24774; }","duration":"438.20216ms","start":"2026-07-22T22:58:05.500011Z","end":"2026-07-22T22:58:05.938213Z","steps":["trace[1860865009] 'agreement among raft nodes before linearized reading' (duration: 438.171699ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:58:05.93823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:58:05.499997Z","time spent":"438.2308ms","remote":"127.0.0.1:41876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-22T22:58:05.938339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.450353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-22T22:58:05.938362Z","caller":"traceutil/trace.go:171","msg":"trace[793051494] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:24774; }","duration":"405.513594ms","start":"2026-07-22T22:58:05.532839Z","end":"2026-07-22T22:58:05.938353Z","steps":["trace[793051494] 'agreement among raft nodes before linearized reading' (duration: 405.477513ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:58:05.938374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:58:05.532821Z","time spent":"405.550375ms","remote":"127.0.0.1:38538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true "} {"level":"warn","ts":"2026-07-22T22:58:05.938732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.876987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-22T22:58:05.938883Z","caller":"traceutil/trace.go:171","msg":"trace[520926024] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:24774; }","duration":"297.021621ms","start":"2026-07-22T22:58:05.64182Z","end":"2026-07-22T22:58:05.938841Z","steps":["trace[520926024] 'agreement among raft nodes before linearized reading' (duration: 296.818566ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:58:06.230812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.406763ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-22T22:58:06.231307Z","caller":"traceutil/trace.go:171","msg":"trace[1791395304] transaction","detail":"{read_only:false; response_revision:24775; number_of_response:1; }","duration":"220.922464ms","start":"2026-07-22T22:58:06.010354Z","end":"2026-07-22T22:58:06.231276Z","steps":["trace[1791395304] 'process raft request' (duration: 77.897389ms)","trace[1791395304] 'compare' (duration: 138.863165ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:58:06.231758Z","caller":"traceutil/trace.go:171","msg":"trace[1269149912] transaction","detail":"{read_only:false; response_revision:24776; number_of_response:1; }","duration":"182.292547ms","start":"2026-07-22T22:58:06.049325Z","end":"2026-07-22T22:58:06.231617Z","steps":["trace[1269149912] 'process raft request' (duration: 181.662735ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:58:06.475211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.016809ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-22T22:58:06.4753Z","caller":"traceutil/trace.go:171","msg":"trace[1877052668] linearizableReadLoop","detail":"{readStateIndex:25589; appliedIndex:25588; }","duration":"175.370955ms","start":"2026-07-22T22:58:06.299916Z","end":"2026-07-22T22:58:06.475287Z","steps":["trace[1877052668] 'read index received' (duration: 70.113362ms)","trace[1877052668] 'applied index is now lower than readState.Index' (duration: 105.256683ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:58:06.475384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.462847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-22T22:58:06.4756Z","caller":"traceutil/trace.go:171","msg":"trace[751880722] transaction","detail":"{read_only:false; response_revision:24777; number_of_response:1; }","duration":"180.176707ms","start":"2026-07-22T22:58:06.295414Z","end":"2026-07-22T22:58:06.475591Z","steps":["trace[751880722] 'process raft request' (duration: 74.624468ms)","trace[751880722] 'compare' (duration: 104.910596ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:58:06.475402Z","caller":"traceutil/trace.go:171","msg":"trace[91464369] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:24777; }","duration":"175.497257ms","start":"2026-07-22T22:58:06.299898Z","end":"2026-07-22T22:58:06.475396Z","steps":["trace[91464369] 'agreement among raft nodes before linearized reading' (duration: 175.419635ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:58:06.785491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.822792ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-22T22:58:06.785623Z","caller":"traceutil/trace.go:171","msg":"trace[1283998169] transaction","detail":"{read_only:false; response_revision:24778; number_of_response:1; }","duration":"228.763444ms","start":"2026-07-22T22:58:06.556845Z","end":"2026-07-22T22:58:06.785609Z","steps":["trace[1283998169] 'process raft request' (duration: 109.552575ms)","trace[1283998169] 'compare' (duration: 118.605358ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:58:06.956999Z","caller":"traceutil/trace.go:171","msg":"trace[1607819136] transaction","detail":"{read_only:false; response_revision:24779; number_of_response:1; }","duration":"225.012814ms","start":"2026-07-22T22:58:06.731938Z","end":"2026-07-22T22:58:06.956951Z","steps":["trace[1607819136] 'process raft request' (duration: 154.627617ms)","trace[1607819136] 'compare' (duration: 70.202083ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:58:06.956904Z","caller":"traceutil/trace.go:171","msg":"trace[539872463] linearizableReadLoop","detail":"{readStateIndex:25591; appliedIndex:25590; }","duration":"171.305256ms","start":"2026-07-22T22:58:06.785578Z","end":"2026-07-22T22:58:06.956883Z","steps":["trace[539872463] 'read index received' (duration: 100.9498ms)","trace[539872463] 'applied index is now lower than readState.Index' (duration: 70.353746ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:58:06.957329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.167992ms","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-07-22T22:58:06.957397Z","caller":"traceutil/trace.go:171","msg":"trace[887593370] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24779; }","duration":"195.253043ms","start":"2026-07-22T22:58:06.762128Z","end":"2026-07-22T22:58:06.957381Z","steps":["trace[887593370] 'agreement among raft nodes before linearized reading' (duration: 195.05736ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:58:07.289053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.038809ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-22T22:58:07.289146Z","caller":"traceutil/trace.go:171","msg":"trace[726300858] linearizableReadLoop","detail":"{readStateIndex:25594; appliedIndex:25593; }","duration":"258.209917ms","start":"2026-07-22T22:58:07.030923Z","end":"2026-07-22T22:58:07.289132Z","steps":["trace[726300858] 'read index received' (duration: 97.031435ms)","trace[726300858] 'applied index is now lower than readState.Index' (duration: 161.177192ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:58:07.289331Z","caller":"traceutil/trace.go:171","msg":"trace[725797153] transaction","detail":"{read_only:false; response_revision:24782; number_of_response:1; }","duration":"265.946476ms","start":"2026-07-22T22:58:07.02337Z","end":"2026-07-22T22:58:07.289316Z","steps":["trace[725797153] 'process raft request' (duration: 104.58153ms)","trace[725797153] 'compare' (duration: 160.928138ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:58:07.289549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.602085ms","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-07-22T22:58:07.289658Z","caller":"traceutil/trace.go:171","msg":"trace[1701925514] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24782; }","duration":"258.724017ms","start":"2026-07-22T22:58:07.030914Z","end":"2026-07-22T22:58:07.289638Z","steps":["trace[1701925514] 'agreement among raft nodes before linearized reading' (duration: 258.418161ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:58:07.291357Z","caller":"traceutil/trace.go:171","msg":"trace[692459371] transaction","detail":"{read_only:false; response_revision:24783; number_of_response:1; }","duration":"112.080593ms","start":"2026-07-22T22:58:07.179212Z","end":"2026-07-22T22:58:07.291292Z","steps":["trace[692459371] 'process raft request' (duration: 111.939101ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:58:07.575476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.781214ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-22T22:58:07.575712Z","caller":"traceutil/trace.go:171","msg":"trace[578877563] transaction","detail":"{read_only:false; response_revision:24785; number_of_response:1; }","duration":"280.81014ms","start":"2026-07-22T22:58:07.294881Z","end":"2026-07-22T22:58:07.575692Z","steps":["trace[578877563] 'process raft request' (duration: 280.713889ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:58:07.575807Z","caller":"traceutil/trace.go:171","msg":"trace[181375795] linearizableReadLoop","detail":"{readStateIndex:25596; appliedIndex:25594; }","duration":"286.607471ms","start":"2026-07-22T22:58:07.289165Z","end":"2026-07-22T22:58:07.575772Z","steps":["trace[181375795] 'read index received' (duration: 2.10506ms)","trace[181375795] 'applied index is now lower than readState.Index' (duration: 284.497971ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:58:07.575856Z","caller":"traceutil/trace.go:171","msg":"trace[939487945] transaction","detail":"{read_only:false; response_revision:24784; number_of_response:1; }","duration":"394.798389ms","start":"2026-07-22T22:58:07.180988Z","end":"2026-07-22T22:58:07.575786Z","steps":["trace[939487945] 'process raft request' (duration: 232.611448ms)","trace[939487945] 'compare' (duration: 161.664651ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:58:07.575976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.266497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:672"} {"level":"info","ts":"2026-07-22T22:58:07.576017Z","caller":"traceutil/trace.go:171","msg":"trace[181858194] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:24785; }","duration":"374.329109ms","start":"2026-07-22T22:58:07.201677Z","end":"2026-07-22T22:58:07.576006Z","steps":["trace[181858194] 'agreement among raft nodes before linearized reading' (duration: 374.187637ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:58:07.576059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:58:07.201666Z","time spent":"374.38349ms","remote":"127.0.0.1:42048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":693,"request content":"key:\"/registry/services/endpoints/openstack/octavia-api\" "} {"level":"warn","ts":"2026-07-22T22:58:07.576021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.913509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:672"} {"level":"warn","ts":"2026-07-22T22:58:07.57606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.126099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:672"} {"level":"info","ts":"2026-07-22T22:58:07.57612Z","caller":"traceutil/trace.go:171","msg":"trace[1738582580] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:24785; }","duration":"376.082692ms","start":"2026-07-22T22:58:07.200016Z","end":"2026-07-22T22:58:07.576099Z","steps":["trace[1738582580] 'agreement among raft nodes before linearized reading' (duration: 375.768836ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:58:07.576148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:58:07.180964Z","time spent":"395.005924ms","remote":"127.0.0.1:42146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:58:07.576164Z","caller":"traceutil/trace.go:171","msg":"trace[1329189396] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:24785; }","duration":"380.292813ms","start":"2026-07-22T22:58:07.195849Z","end":"2026-07-22T22:58:07.576141Z","steps":["trace[1329189396] 'agreement among raft nodes before linearized reading' (duration: 379.988947ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:58:07.576181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:58:07.199994Z","time spent":"376.177744ms","remote":"127.0.0.1:42048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":693,"request content":"key:\"/registry/services/endpoints/openstack/octavia-api\" "} {"level":"warn","ts":"2026-07-22T22:58:07.576221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:58:07.195835Z","time spent":"380.375474ms","remote":"127.0.0.1:42048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":693,"request content":"key:\"/registry/services/endpoints/openstack/octavia-api\" "} {"level":"warn","ts":"2026-07-22T22:58:07.57625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.737927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-07-22T22:58:07.576357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.167608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-07-22T22:58:07.576368Z","caller":"traceutil/trace.go:171","msg":"trace[1954192795] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24785; }","duration":"544.903179ms","start":"2026-07-22T22:58:07.031438Z","end":"2026-07-22T22:58:07.576341Z","steps":["trace[1954192795] 'agreement among raft nodes before linearized reading' (duration: 544.500292ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:58:07.576443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:58:07.031427Z","time spent":"545.003161ms","remote":"127.0.0.1:42146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-22T22:58:07.576443Z","caller":"traceutil/trace.go:171","msg":"trace[682767189] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24785; }","duration":"541.26905ms","start":"2026-07-22T22:58:07.035147Z","end":"2026-07-22T22:58:07.576416Z","steps":["trace[682767189] 'agreement among raft nodes before linearized reading' (duration: 541.076526ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:58:07.576512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:58:07.035134Z","time spent":"541.365461ms","remote":"127.0.0.1:42146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-07-22T22:58:08.192218Z","caller":"traceutil/trace.go:171","msg":"trace[1023544652] transaction","detail":"{read_only:false; response_revision:24789; number_of_response:1; }","duration":"245.333281ms","start":"2026-07-22T22:58:07.946854Z","end":"2026-07-22T22:58:08.192187Z","steps":["trace[1023544652] 'process raft request' (duration: 245.140078ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:58:08.56168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.388293ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-22T22:58:08.561991Z","caller":"traceutil/trace.go:171","msg":"trace[792648157] transaction","detail":"{read_only:false; response_revision:24790; number_of_response:1; }","duration":"325.374201ms","start":"2026-07-22T22:58:08.236594Z","end":"2026-07-22T22:58:08.561968Z","steps":["trace[792648157] 'process raft request' (duration: 325.222439ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:58:08.562005Z","caller":"traceutil/trace.go:171","msg":"trace[2069617169] linearizableReadLoop","detail":"{readStateIndex:25602; appliedIndex:25600; }","duration":"454.000071ms","start":"2026-07-22T22:58:08.107954Z","end":"2026-07-22T22:58:08.561954Z","steps":["trace[2069617169] 'read index received' (duration: 84.086528ms)","trace[2069617169] 'applied index is now lower than readState.Index' (duration: 369.909233ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:58:08.562123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:58:08.23656Z","time spent":"325.477184ms","remote":"127.0.0.1:42146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:58:08.562144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.182724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-22T22:58:08.562197Z","caller":"traceutil/trace.go:171","msg":"trace[1226068695] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:24790; }","duration":"454.284216ms","start":"2026-07-22T22:58:08.107895Z","end":"2026-07-22T22:58:08.56218Z","steps":["trace[1226068695] 'agreement among raft nodes before linearized reading' (duration: 454.182454ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:58:08.562267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:58:08.10787Z","time spent":"454.386959ms","remote":"127.0.0.1:42392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":6,"response size":29,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-07-22T22:58:08.562258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.434973ms","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-07-22T22:58:08.562366Z","caller":"traceutil/trace.go:171","msg":"trace[1997448511] 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:24790; }","duration":"416.571565ms","start":"2026-07-22T22:58:08.14577Z","end":"2026-07-22T22:58:08.562341Z","steps":["trace[1997448511] 'agreement among raft nodes before linearized reading' (duration: 416.375352ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:58:08.562418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:58:08.145693Z","time spent":"416.714719ms","remote":"127.0.0.1:47508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":152,"response count":0,"response size":27,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" "} {"level":"warn","ts":"2026-07-22T22:58:08.562477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.901382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-07-22T22:58:08.562514Z","caller":"traceutil/trace.go:171","msg":"trace[923122640] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24790; }","duration":"131.024305ms","start":"2026-07-22T22:58:08.431482Z","end":"2026-07-22T22:58:08.562506Z","steps":["trace[923122640] 'agreement among raft nodes before linearized reading' (duration: 130.902553ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:58:08.562486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.943423ms","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-07-22T22:58:08.562613Z","caller":"traceutil/trace.go:171","msg":"trace[408980144] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:24790; }","duration":"279.116637ms","start":"2026-07-22T22:58:08.283468Z","end":"2026-07-22T22:58:08.562585Z","steps":["trace[408980144] 'agreement among raft nodes before linearized reading' (duration: 278.7659ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:58:08.562626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.424827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-22T22:58:08.562733Z","caller":"traceutil/trace.go:171","msg":"trace[943948563] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:24790; }","duration":"298.58311ms","start":"2026-07-22T22:58:08.264131Z","end":"2026-07-22T22:58:08.562714Z","steps":["trace[943948563] 'agreement among raft nodes before linearized reading' (duration: 298.427806ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:58:08.927357Z","caller":"traceutil/trace.go:171","msg":"trace[1404727234] transaction","detail":"{read_only:false; response_revision:24792; number_of_response:1; }","duration":"138.916336ms","start":"2026-07-22T22:58:08.78841Z","end":"2026-07-22T22:58:08.927326Z","steps":["trace[1404727234] 'process raft request' (duration: 138.249733ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:58:09.088335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.613745ms","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-07-22T22:58:09.088436Z","caller":"traceutil/trace.go:171","msg":"trace[1592128203] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24792; }","duration":"110.792639ms","start":"2026-07-22T22:58:08.977619Z","end":"2026-07-22T22:58:09.088412Z","steps":["trace[1592128203] 'range keys from in-memory index tree' (duration: 110.37441ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:58:09.599907Z","caller":"traceutil/trace.go:171","msg":"trace[1253453944] transaction","detail":"{read_only:false; response_revision:24795; number_of_response:1; }","duration":"303.168727ms","start":"2026-07-22T22:58:09.296704Z","end":"2026-07-22T22:58:09.599873Z","steps":["trace[1253453944] 'process raft request' (duration: 302.959493ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:58:09.600113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:58:09.296668Z","time spent":"303.34146ms","remote":"127.0.0.1:42146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:58:09.638902Z","caller":"traceutil/trace.go:171","msg":"trace[1907977290] transaction","detail":"{read_only:false; response_revision:24796; number_of_response:1; }","duration":"341.116063ms","start":"2026-07-22T22:58:09.29777Z","end":"2026-07-22T22:58:09.638886Z","steps":["trace[1907977290] 'process raft request' (duration: 340.96045ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:58:09.639014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:58:09.297739Z","time spent":"341.219034ms","remote":"127.0.0.1:42146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:58:09.639263Z","caller":"traceutil/trace.go:171","msg":"trace[1089922767] linearizableReadLoop","detail":"{readStateIndex:25610; appliedIndex:25607; }","duration":"137.469998ms","start":"2026-07-22T22:58:09.501769Z","end":"2026-07-22T22:58:09.639239Z","steps":["trace[1089922767] 'read index received' (duration: 97.996684ms)","trace[1089922767] 'applied index is now lower than readState.Index' (duration: 39.472044ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:58:09.639476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.688053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-22T22:58:09.639336Z","caller":"traceutil/trace.go:171","msg":"trace[548588863] transaction","detail":"{read_only:false; response_revision:24797; number_of_response:1; }","duration":"290.930963ms","start":"2026-07-22T22:58:09.348355Z","end":"2026-07-22T22:58:09.639285Z","steps":["trace[548588863] 'process raft request' (duration: 290.484585ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:58:09.639512Z","caller":"traceutil/trace.go:171","msg":"trace[731487169] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24798; }","duration":"137.805665ms","start":"2026-07-22T22:58:09.501695Z","end":"2026-07-22T22:58:09.639501Z","steps":["trace[731487169] 'agreement among raft nodes before linearized reading' (duration: 137.689713ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:58:09.763044Z","caller":"traceutil/trace.go:171","msg":"trace[1423704576] transaction","detail":"{read_only:false; response_revision:24799; number_of_response:1; }","duration":"118.577808ms","start":"2026-07-22T22:58:09.644431Z","end":"2026-07-22T22:58:09.763009Z","steps":["trace[1423704576] 'process raft request' (duration: 104.724153ms)","trace[1423704576] 'compare' (duration: 13.634571ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:58:10.062713Z","caller":"traceutil/trace.go:171","msg":"trace[1050916396] transaction","detail":"{read_only:false; response_revision:24801; number_of_response:1; }","duration":"417.685337ms","start":"2026-07-22T22:58:09.644989Z","end":"2026-07-22T22:58:10.062675Z","steps":["trace[1050916396] 'process raft request' (duration: 417.506824ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:58:10.062802Z","caller":"traceutil/trace.go:171","msg":"trace[96552462] linearizableReadLoop","detail":"{readStateIndex:25614; appliedIndex:25611; }","duration":"409.562243ms","start":"2026-07-22T22:58:09.653227Z","end":"2026-07-22T22:58:10.062789Z","steps":["trace[96552462] 'read index received' (duration: 95.930855ms)","trace[96552462] 'applied index is now lower than readState.Index' (duration: 313.630188ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:58:10.062907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:58:09.644959Z","time spent":"417.84708ms","remote":"127.0.0.1:42146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:58:10.062894Z","caller":"traceutil/trace.go:171","msg":"trace[1360445722] transaction","detail":"{read_only:false; response_revision:24800; number_of_response:1; }","duration":"417.992153ms","start":"2026-07-22T22:58:09.644843Z","end":"2026-07-22T22:58:10.062835Z","steps":["trace[1360445722] 'process raft request' (duration: 397.566692ms)","trace[1360445722] 'compare' (duration: 19.477803ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:58:10.06271Z","caller":"traceutil/trace.go:171","msg":"trace[1761872491] transaction","detail":"{read_only:false; response_revision:24802; number_of_response:1; }","duration":"279.392733ms","start":"2026-07-22T22:58:09.783281Z","end":"2026-07-22T22:58:10.062674Z","steps":["trace[1761872491] 'process raft request' (duration: 279.338292ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:58:10.063031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.228452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-22T22:58:10.06307Z","caller":"traceutil/trace.go:171","msg":"trace[1881878199] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesets/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesets0; response_count:0; response_revision:24802; }","duration":"140.304562ms","start":"2026-07-22T22:58:09.922752Z","end":"2026-07-22T22:58:10.063056Z","steps":["trace[1881878199] 'agreement among raft nodes before linearized reading' (duration: 140.251962ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:58:10.063077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:58:09.644831Z","time spent":"418.148837ms","remote":"127.0.0.1:42146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:58:10.06297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.708155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-22T22:58:10.063212Z","caller":"traceutil/trace.go:171","msg":"trace[1834563834] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:24802; }","duration":"410.007081ms","start":"2026-07-22T22:58:09.653188Z","end":"2026-07-22T22:58:10.063195Z","steps":["trace[1834563834] 'agreement among raft nodes before linearized reading' (duration: 409.702395ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:58:10.063261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:58:09.65318Z","time spent":"410.060912ms","remote":"127.0.0.1:53284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":29,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-07-22T22:58:10.8369Z","caller":"traceutil/trace.go:171","msg":"trace[1560233122] transaction","detail":"{read_only:false; response_revision:24805; number_of_response:1; }","duration":"323.381914ms","start":"2026-07-22T22:58:10.513488Z","end":"2026-07-22T22:58:10.83687Z","steps":["trace[1560233122] 'process raft request' (duration: 323.14956ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:58:10.837094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:58:10.513452Z","time spent":"323.548397ms","remote":"127.0.0.1:42146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:58:10.947392Z","caller":"traceutil/trace.go:171","msg":"trace[202400542] linearizableReadLoop","detail":"{readStateIndex:25619; appliedIndex:25617; }","duration":"367.198803ms","start":"2026-07-22T22:58:10.580165Z","end":"2026-07-22T22:58:10.947364Z","steps":["trace[202400542] 'read index received' (duration: 256.574368ms)","trace[202400542] 'applied index is now lower than readState.Index' (duration: 110.623275ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:58:10.947504Z","caller":"traceutil/trace.go:171","msg":"trace[1201676802] transaction","detail":"{read_only:false; response_revision:24806; number_of_response:1; }","duration":"379.465536ms","start":"2026-07-22T22:58:10.568025Z","end":"2026-07-22T22:58:10.947491Z","steps":["trace[1201676802] 'process raft request' (duration: 366.352945ms)","trace[1201676802] 'compare' (duration: 12.873387ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:58:10.947659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:58:10.567968Z","time spent":"379.574308ms","remote":"127.0.0.1:42146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:58:10.947794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.61988ms","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-07-22T22:58:10.947874Z","caller":"traceutil/trace.go:171","msg":"trace[908225889] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24806; }","duration":"367.747573ms","start":"2026-07-22T22:58:10.580109Z","end":"2026-07-22T22:58:10.947856Z","steps":["trace[908225889] 'agreement among raft nodes before linearized reading' (duration: 367.57421ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:58:10.94791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:58:10.580074Z","time spent":"367.828784ms","remote":"127.0.0.1:42146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-22T22:58:10.988931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.167743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-22T22:58:10.989056Z","caller":"traceutil/trace.go:171","msg":"trace[686627380] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0; response_count:0; response_revision:24807; }","duration":"171.374437ms","start":"2026-07-22T22:58:10.817657Z","end":"2026-07-22T22:58:10.989032Z","steps":["trace[686627380] 'agreement among raft nodes before linearized reading' (duration: 171.134263ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:58:11.234705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.177183ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-22T22:58:11.234822Z","caller":"traceutil/trace.go:171","msg":"trace[1030020704] linearizableReadLoop","detail":"{readStateIndex:25621; appliedIndex:25620; }","duration":"241.767034ms","start":"2026-07-22T22:58:10.993037Z","end":"2026-07-22T22:58:11.234804Z","steps":["trace[1030020704] 'read index received' (duration: 92.427407ms)","trace[1030020704] 'applied index is now lower than readState.Index' (duration: 149.338257ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:58:11.235058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.804834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/openstack/container-infra\" ","response":"range_response_count:1 size:729"} {"level":"info","ts":"2026-07-22T22:58:11.23508Z","caller":"traceutil/trace.go:171","msg":"trace[412591311] range","detail":"{range_begin:/registry/ingress/openstack/container-infra; range_end:; response_count:1; response_revision:24808; }","duration":"241.859705ms","start":"2026-07-22T22:58:10.993212Z","end":"2026-07-22T22:58:11.235071Z","steps":["trace[412591311] 'agreement among raft nodes before linearized reading' (duration: 241.693962ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:58:11.235079Z","caller":"traceutil/trace.go:171","msg":"trace[325714960] transaction","detail":"{read_only:false; response_revision:24808; number_of_response:1; }","duration":"283.146674ms","start":"2026-07-22T22:58:10.951892Z","end":"2026-07-22T22:58:11.235038Z","steps":["trace[325714960] 'process raft request' (duration: 133.516692ms)","trace[325714960] 'compare' (duration: 148.870288ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:58:11.235128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.058869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/openstack/container-infra-registry\" ","response":"range_response_count:1 size:784"} {"level":"info","ts":"2026-07-22T22:58:11.235216Z","caller":"traceutil/trace.go:171","msg":"trace[764331154] range","detail":"{range_begin:/registry/ingress/openstack/container-infra-registry; range_end:; response_count:1; response_revision:24808; }","duration":"242.165501ms","start":"2026-07-22T22:58:10.993028Z","end":"2026-07-22T22:58:11.235194Z","steps":["trace[764331154] 'agreement among raft nodes before linearized reading' (duration: 241.870155ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:58:11.235688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.120549ms","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-07-22T22:58:11.235618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.869664ms","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-07-22T22:58:11.235792Z","caller":"traceutil/trace.go:171","msg":"trace[1043524583] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24808; }","duration":"128.545788ms","start":"2026-07-22T22:58:11.107222Z","end":"2026-07-22T22:58:11.235767Z","steps":["trace[1043524583] 'agreement among raft nodes before linearized reading' (duration: 127.954346ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:58:11.235816Z","caller":"traceutil/trace.go:171","msg":"trace[792239170] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24808; }","duration":"129.164261ms","start":"2026-07-22T22:58:11.106629Z","end":"2026-07-22T22:58:11.235793Z","steps":["trace[792239170] 'agreement among raft nodes before linearized reading' (duration: 128.558139ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:58:11.444473Z","caller":"traceutil/trace.go:171","msg":"trace[315662019] transaction","detail":"{read_only:false; response_revision:24809; number_of_response:1; }","duration":"203.998171ms","start":"2026-07-22T22:58:11.240436Z","end":"2026-07-22T22:58:11.444434Z","steps":["trace[315662019] 'process raft request' (duration: 134.482872ms)","trace[315662019] 'compare' (duration: 69.367327ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:58:11.811289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.627379ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-22T22:58:11.811545Z","caller":"traceutil/trace.go:171","msg":"trace[427572170] transaction","detail":"{read_only:false; response_revision:24811; number_of_response:1; }","duration":"570.432278ms","start":"2026-07-22T22:58:11.241093Z","end":"2026-07-22T22:58:11.811525Z","steps":["trace[427572170] 'process raft request' (duration: 570.307656ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:58:11.811539Z","caller":"traceutil/trace.go:171","msg":"trace[263787801] transaction","detail":"{read_only:false; response_revision:24810; number_of_response:1; }","duration":"570.751574ms","start":"2026-07-22T22:58:11.240744Z","end":"2026-07-22T22:58:11.811496Z","steps":["trace[263787801] 'process raft request' (duration: 406.81968ms)","trace[263787801] 'compare' (duration: 163.238991ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:58:11.811623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:58:11.241086Z","time spent":"570.50459ms","remote":"127.0.0.1:42174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":870,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:58:11.811767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:58:11.240727Z","time spent":"570.901358ms","remote":"127.0.0.1:42174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":925,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:58:11.812077Z","caller":"traceutil/trace.go:171","msg":"trace[628529820] linearizableReadLoop","detail":"{readStateIndex:25626; appliedIndex:25622; }","duration":"349.891671ms","start":"2026-07-22T22:58:11.462153Z","end":"2026-07-22T22:58:11.812045Z","steps":["trace[628529820] 'read index received' (duration: 185.426316ms)","trace[628529820] 'applied index is now lower than readState.Index' (duration: 164.463905ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:58:11.812205Z","caller":"traceutil/trace.go:171","msg":"trace[638219482] transaction","detail":"{read_only:false; response_revision:24813; number_of_response:1; }","duration":"569.745445ms","start":"2026-07-22T22:58:11.242397Z","end":"2026-07-22T22:58:11.812142Z","steps":["trace[638219482] 'process raft request' (duration: 569.502131ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:58:11.812287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.105245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-22T22:58:11.812274Z","caller":"traceutil/trace.go:171","msg":"trace[1527999517] transaction","detail":"{read_only:false; response_revision:24812; number_of_response:1; }","duration":"570.458729ms","start":"2026-07-22T22:58:11.241797Z","end":"2026-07-22T22:58:11.812255Z","steps":["trace[1527999517] 'process raft request' (duration: 569.672424ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:58:11.812393Z","caller":"traceutil/trace.go:171","msg":"trace[1924653327] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:24813; }","duration":"350.238249ms","start":"2026-07-22T22:58:11.462132Z","end":"2026-07-22T22:58:11.81237Z","steps":["trace[1924653327] 'agreement among raft nodes before linearized reading' (duration: 350.035104ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:58:11.812458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:58:11.462052Z","time spent":"350.394611ms","remote":"127.0.0.1:49328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true "} {"level":"warn","ts":"2026-07-22T22:58:11.812468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:58:11.242388Z","time spent":"569.938719ms","remote":"127.0.0.1:42146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:58:11.812492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:58:11.241782Z","time spent":"570.667013ms","remote":"127.0.0.1:42146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:58:11.812688Z","caller":"traceutil/trace.go:171","msg":"trace[1344340243] transaction","detail":"{read_only:false; response_revision:24816; number_of_response:1; }","duration":"166.080826ms","start":"2026-07-22T22:58:11.646586Z","end":"2026-07-22T22:58:11.812667Z","steps":["trace[1344340243] 'process raft request' (duration: 166.033735ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:58:11.812727Z","caller":"traceutil/trace.go:171","msg":"trace[554855054] transaction","detail":"{read_only:false; response_revision:24814; number_of_response:1; }","duration":"206.49914ms","start":"2026-07-22T22:58:11.606197Z","end":"2026-07-22T22:58:11.812696Z","steps":["trace[554855054] 'process raft request' (duration: 206.245165ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:58:11.812844Z","caller":"traceutil/trace.go:171","msg":"trace[1723930826] transaction","detail":"{read_only:false; response_revision:24815; number_of_response:1; }","duration":"167.735548ms","start":"2026-07-22T22:58:11.645097Z","end":"2026-07-22T22:58:11.812833Z","steps":["trace[1723930826] 'process raft request' (duration: 167.470923ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:58:11.813201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.855504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-22T22:58:11.81326Z","caller":"traceutil/trace.go:171","msg":"trace[724946869] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24816; }","duration":"311.948116ms","start":"2026-07-22T22:58:11.501297Z","end":"2026-07-22T22:58:11.813245Z","steps":["trace[724946869] 'agreement among raft nodes before linearized reading' (duration: 311.854284ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:58:11.813299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:58:11.501282Z","time spent":"312.010197ms","remote":"127.0.0.1:41876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-22T22:58:11.813347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.907961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:672"} {"level":"info","ts":"2026-07-22T22:58:11.813409Z","caller":"traceutil/trace.go:171","msg":"trace[81635189] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:24816; }","duration":"167.996313ms","start":"2026-07-22T22:58:11.645399Z","end":"2026-07-22T22:58:11.813396Z","steps":["trace[81635189] 'agreement among raft nodes before linearized reading' (duration: 167.778259ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:58:11.813615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.295385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-22T22:58:11.813671Z","caller":"traceutil/trace.go:171","msg":"trace[943466678] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:24816; }","duration":"183.411107ms","start":"2026-07-22T22:58:11.630248Z","end":"2026-07-22T22:58:11.813659Z","steps":["trace[943466678] 'agreement among raft nodes before linearized reading' (duration: 183.208674ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:58:11.813761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.078525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:672"} {"level":"info","ts":"2026-07-22T22:58:11.813809Z","caller":"traceutil/trace.go:171","msg":"trace[418035499] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:24816; }","duration":"168.141786ms","start":"2026-07-22T22:58:11.645656Z","end":"2026-07-22T22:58:11.813798Z","steps":["trace[418035499] 'agreement among raft nodes before linearized reading' (duration: 167.984152ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:58:11.815145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.641314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:672"} {"level":"info","ts":"2026-07-22T22:58:11.815208Z","caller":"traceutil/trace.go:171","msg":"trace[1668792971] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:24816; }","duration":"169.745037ms","start":"2026-07-22T22:58:11.645447Z","end":"2026-07-22T22:58:11.815192Z","steps":["trace[1668792971] 'agreement among raft nodes before linearized reading' (duration: 167.724777ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:58:12.128876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.415752ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-22T22:58:12.129041Z","caller":"traceutil/trace.go:171","msg":"trace[1922072956] transaction","detail":"{read_only:false; response_revision:24819; number_of_response:1; }","duration":"252.799364ms","start":"2026-07-22T22:58:11.876197Z","end":"2026-07-22T22:58:12.128996Z","steps":["trace[1922072956] 'process raft request' (duration: 119.169618ms)","trace[1922072956] 'compare' (duration: 133.064815ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:58:12.268638Z","caller":"traceutil/trace.go:171","msg":"trace[1158391401] linearizableReadLoop","detail":"{readStateIndex:25633; appliedIndex:25632; }","duration":"139.646339ms","start":"2026-07-22T22:58:12.12896Z","end":"2026-07-22T22:58:12.268606Z","steps":["trace[1158391401] 'read index received' (duration: 139.435016ms)","trace[1158391401] 'applied index is now lower than readState.Index' (duration: 209.323µs)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:58:12.268799Z","caller":"traceutil/trace.go:171","msg":"trace[912462688] transaction","detail":"{read_only:false; response_revision:24820; number_of_response:1; }","duration":"198.500635ms","start":"2026-07-22T22:58:12.070264Z","end":"2026-07-22T22:58:12.268764Z","steps":["trace[912462688] 'process raft request' (duration: 198.133269ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:58:12.268912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.955024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-07-22T22:58:12.268964Z","caller":"traceutil/trace.go:171","msg":"trace[1577305555] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24820; }","duration":"200.065606ms","start":"2026-07-22T22:58:12.06888Z","end":"2026-07-22T22:58:12.268945Z","steps":["trace[1577305555] 'agreement among raft nodes before linearized reading' (duration: 199.849891ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:58:12.269787Z","caller":"traceutil/trace.go:171","msg":"trace[714598508] transaction","detail":"{read_only:false; response_revision:24821; number_of_response:1; }","duration":"136.297646ms","start":"2026-07-22T22:58:12.133467Z","end":"2026-07-22T22:58:12.269764Z","steps":["trace[714598508] 'process raft request' (duration: 136.00458ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:58:17.272708Z","caller":"traceutil/trace.go:171","msg":"trace[364665219] transaction","detail":"{read_only:false; response_revision:24853; number_of_response:1; }","duration":"452.021243ms","start":"2026-07-22T22:58:16.820658Z","end":"2026-07-22T22:58:17.27268Z","steps":["trace[364665219] 'process raft request' (duration: 451.833799ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:58:17.272904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:58:16.82064Z","time spent":"452.148375ms","remote":"127.0.0.1:42146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:58:17.505966Z","caller":"traceutil/trace.go:171","msg":"trace[1361560986] transaction","detail":"{read_only:false; response_revision:24854; number_of_response:1; }","duration":"537.414297ms","start":"2026-07-22T22:58:16.968463Z","end":"2026-07-22T22:58:17.505877Z","steps":["trace[1361560986] 'process raft request' (duration: 535.532531ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:58:17.506178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:58:16.968437Z","time spent":"537.628101ms","remote":"127.0.0.1:42146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:58:17.506185Z","caller":"traceutil/trace.go:171","msg":"trace[1617680948] linearizableReadLoop","detail":"{readStateIndex:25669; appliedIndex:25666; }","duration":"453.617135ms","start":"2026-07-22T22:58:17.052536Z","end":"2026-07-22T22:58:17.506153Z","steps":["trace[1617680948] 'read index received' (duration: 219.934436ms)","trace[1617680948] 'applied index is now lower than readState.Index' (duration: 233.681399ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:58:17.506378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.447774ms","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":"info","ts":"2026-07-22T22:58:17.50634Z","caller":"traceutil/trace.go:171","msg":"trace[1992069282] transaction","detail":"{read_only:false; response_revision:24855; number_of_response:1; }","duration":"492.62491ms","start":"2026-07-22T22:58:17.013671Z","end":"2026-07-22T22:58:17.506296Z","steps":["trace[1992069282] 'process raft request' (duration: 492.118591ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:58:17.506368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.814178ms","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-07-22T22:58:17.506457Z","caller":"traceutil/trace.go:171","msg":"trace[2129162311] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:24855; }","duration":"453.969431ms","start":"2026-07-22T22:58:17.052467Z","end":"2026-07-22T22:58:17.506437Z","steps":["trace[2129162311] 'agreement among raft nodes before linearized reading' (duration: 453.826448ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:58:17.506511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:58:17.05244Z","time spent":"454.055033ms","remote":"127.0.0.1:49284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true "} {"level":"warn","ts":"2026-07-22T22:58:17.506532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.108948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-07-22T22:58:17.506555Z","caller":"traceutil/trace.go:171","msg":"trace[1719637871] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24855; }","duration":"244.132028ms","start":"2026-07-22T22:58:17.262417Z","end":"2026-07-22T22:58:17.506549Z","steps":["trace[1719637871] 'agreement among raft nodes before linearized reading' (duration: 244.012476ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:58:17.506577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:58:17.013617Z","time spent":"492.824534ms","remote":"127.0.0.1:42146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:58:17.506413Z","caller":"traceutil/trace.go:171","msg":"trace[1627687556] 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:24855; }","duration":"381.555905ms","start":"2026-07-22T22:58:17.124848Z","end":"2026-07-22T22:58:17.506404Z","steps":["trace[1627687556] 'agreement among raft nodes before linearized reading' (duration: 381.467924ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:58:17.506691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.983416ms","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":"warn","ts":"2026-07-22T22:58:17.506703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:58:17.12482Z","time spent":"381.870273ms","remote":"127.0.0.1:47318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":144,"response count":0,"response size":27,"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-07-22T22:58:17.506714Z","caller":"traceutil/trace.go:171","msg":"trace[1193829459] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/networks/; range_end:/registry/openstack.k-orc.cloud/networks0; response_count:0; response_revision:24855; }","duration":"334.045828ms","start":"2026-07-22T22:58:17.172661Z","end":"2026-07-22T22:58:17.506706Z","steps":["trace[1193829459] 'agreement among raft nodes before linearized reading' (duration: 334.000176ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:58:17.506739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:58:17.172639Z","time spent":"334.091659ms","remote":"127.0.0.1:34454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":27,"request content":"key:\"/registry/openstack.k-orc.cloud/networks/\" range_end:\"/registry/openstack.k-orc.cloud/networks0\" count_only:true "} {"level":"info","ts":"2026-07-22T22:58:17.765505Z","caller":"traceutil/trace.go:171","msg":"trace[1686168906] transaction","detail":"{read_only:false; response_revision:24857; number_of_response:1; }","duration":"251.459068ms","start":"2026-07-22T22:58:17.514005Z","end":"2026-07-22T22:58:17.765464Z","steps":["trace[1686168906] 'process raft request' (duration: 236.261857ms)","trace[1686168906] 'compare' (duration: 14.371916ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:58:18.338125Z","caller":"traceutil/trace.go:171","msg":"trace[1455916756] linearizableReadLoop","detail":"{readStateIndex:25672; appliedIndex:25671; }","duration":"492.325734ms","start":"2026-07-22T22:58:17.845759Z","end":"2026-07-22T22:58:18.338085Z","steps":["trace[1455916756] 'read index received' (duration: 492.060629ms)","trace[1455916756] 'applied index is now lower than readState.Index' (duration: 263.255µs)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:58:18.338218Z","caller":"traceutil/trace.go:171","msg":"trace[1537482703] transaction","detail":"{read_only:false; response_revision:24858; number_of_response:1; }","duration":"497.725607ms","start":"2026-07-22T22:58:17.840466Z","end":"2026-07-22T22:58:18.338191Z","steps":["trace[1537482703] 'process raft request' (duration: 497.418622ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:58:18.338408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:58:17.840444Z","time spent":"497.85083ms","remote":"127.0.0.1:42146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:58:18.338542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.746873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:672"} {"level":"info","ts":"2026-07-22T22:58:18.338638Z","caller":"traceutil/trace.go:171","msg":"trace[1952566385] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:24858; }","duration":"492.925116ms","start":"2026-07-22T22:58:17.845691Z","end":"2026-07-22T22:58:18.338616Z","steps":["trace[1952566385] 'agreement among raft nodes before linearized reading' (duration: 492.537918ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:58:18.338698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:58:17.845651Z","time spent":"493.037198ms","remote":"127.0.0.1:42048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":693,"request content":"key:\"/registry/services/endpoints/openstack/octavia-api\" "} {"level":"info","ts":"2026-07-22T22:58:18.39031Z","caller":"traceutil/trace.go:171","msg":"trace[2135951757] transaction","detail":"{read_only:false; response_revision:24859; number_of_response:1; }","duration":"543.119936ms","start":"2026-07-22T22:58:17.847162Z","end":"2026-07-22T22:58:18.390282Z","steps":["trace[2135951757] 'process raft request' (duration: 520.33226ms)","trace[2135951757] 'compare' (duration: 22.48173ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:58:18.390457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:58:17.847146Z","time spent":"543.238658ms","remote":"127.0.0.1:42146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:58:18.39045Z","caller":"traceutil/trace.go:171","msg":"trace[327242800] transaction","detail":"{read_only:false; response_revision:24860; number_of_response:1; }","duration":"541.994455ms","start":"2026-07-22T22:58:17.848427Z","end":"2026-07-22T22:58:18.390421Z","steps":["trace[327242800] 'process raft request' (duration: 541.770991ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:58:18.390614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:58:17.848408Z","time spent":"542.106597ms","remote":"127.0.0.1:42146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:58:18.395756Z","caller":"traceutil/trace.go:171","msg":"trace[1869871841] transaction","detail":"{read_only:false; response_revision:24861; number_of_response:1; }","duration":"100.170595ms","start":"2026-07-22T22:58:18.294059Z","end":"2026-07-22T22:58:18.39423Z","steps":["trace[1869871841] 'process raft request' (duration: 99.761017ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:58:18.396133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.484602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-22T22:58:18.396229Z","caller":"traceutil/trace.go:171","msg":"trace[38447071] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24862; }","duration":"544.618825ms","start":"2026-07-22T22:58:17.851569Z","end":"2026-07-22T22:58:18.396188Z","steps":["trace[38447071] 'agreement among raft nodes before linearized reading' (duration: 542.514644ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:58:18.396274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:58:17.851547Z","time spent":"544.713036ms","remote":"127.0.0.1:42146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-22T22:58:18.39629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.239857ms","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-07-22T22:58:18.396371Z","caller":"traceutil/trace.go:171","msg":"trace[1593388730] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24862; }","duration":"100.370819ms","start":"2026-07-22T22:58:18.295983Z","end":"2026-07-22T22:58:18.396354Z","steps":["trace[1593388730] 'agreement among raft nodes before linearized reading' (duration: 98.141436ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:58:18.396602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.616798ms","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-07-22T22:58:18.39666Z","caller":"traceutil/trace.go:171","msg":"trace[166738642] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24862; }","duration":"539.73448ms","start":"2026-07-22T22:58:17.856909Z","end":"2026-07-22T22:58:18.396643Z","steps":["trace[166738642] 'agreement among raft nodes before linearized reading' (duration: 537.275233ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:58:18.396713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:58:17.856883Z","time spent":"539.820762ms","remote":"127.0.0.1:42146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-22T22:58:23.604311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.948854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-825f65cc-32d6-4446-938e-44df0cb73acf\" ","response":"range_response_count:1 size:3764"} {"level":"warn","ts":"2026-07-22T22:58:23.60435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.081811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-22T22:58:23.6044Z","caller":"traceutil/trace.go:171","msg":"trace[1954177837] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-825f65cc-32d6-4446-938e-44df0cb73acf; range_end:; response_count:1; response_revision:24954; }","duration":"131.099177ms","start":"2026-07-22T22:58:23.473283Z","end":"2026-07-22T22:58:23.604382Z","steps":["trace[1954177837] 'range keys from in-memory index tree' (duration: 130.841722ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:58:23.604454Z","caller":"traceutil/trace.go:171","msg":"trace[212266024] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24954; }","duration":"105.256504ms","start":"2026-07-22T22:58:23.499167Z","end":"2026-07-22T22:58:23.604423Z","steps":["trace[212266024] 'range keys from in-memory index tree' (duration: 104.981688ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:58:23.844415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.135902ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-22T22:58:23.844607Z","caller":"traceutil/trace.go:171","msg":"trace[1057117589] transaction","detail":"{read_only:false; response_revision:24956; number_of_response:1; }","duration":"320.123681ms","start":"2026-07-22T22:58:23.524461Z","end":"2026-07-22T22:58:23.844585Z","steps":["trace[1057117589] 'process raft request' (duration: 204.704514ms)","trace[1057117589] 'compare' (duration: 115.02219ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:58:23.844683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:58:23.524434Z","time spent":"320.209973ms","remote":"127.0.0.1:42146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:58:23.844611Z","caller":"traceutil/trace.go:171","msg":"trace[1040444009] linearizableReadLoop","detail":"{readStateIndex:25773; appliedIndex:25771; }","duration":"292.921891ms","start":"2026-07-22T22:58:23.551675Z","end":"2026-07-22T22:58:23.844597Z","steps":["trace[1040444009] 'read index received' (duration: 177.364182ms)","trace[1040444009] 'applied index is now lower than readState.Index' (duration: 115.556939ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:58:23.844681Z","caller":"traceutil/trace.go:171","msg":"trace[1493563420] transaction","detail":"{read_only:false; response_revision:24957; number_of_response:1; }","duration":"320.161532ms","start":"2026-07-22T22:58:23.524471Z","end":"2026-07-22T22:58:23.844633Z","steps":["trace[1493563420] 'process raft request' (duration: 320.05754ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:58:23.844735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.064124ms","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-07-22T22:58:23.844781Z","caller":"traceutil/trace.go:171","msg":"trace[1705182071] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24957; }","duration":"293.108964ms","start":"2026-07-22T22:58:23.551661Z","end":"2026-07-22T22:58:23.84477Z","steps":["trace[1705182071] 'agreement among raft nodes before linearized reading' (duration: 292.976512ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:58:23.844904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:58:23.524439Z","time spent":"320.336846ms","remote":"127.0.0.1:42146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:58:23.844987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.472437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-22T22:58:23.845045Z","caller":"traceutil/trace.go:171","msg":"trace[1183068076] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:24957; }","duration":"207.576569ms","start":"2026-07-22T22:58:23.637453Z","end":"2026-07-22T22:58:23.845029Z","steps":["trace[1183068076] 'agreement among raft nodes before linearized reading' (duration: 207.483068ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:58:23.845197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.393343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-22T22:58:23.845226Z","caller":"traceutil/trace.go:171","msg":"trace[1960888337] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:24957; }","duration":"234.462934ms","start":"2026-07-22T22:58:23.610756Z","end":"2026-07-22T22:58:23.845219Z","steps":["trace[1960888337] 'agreement among raft nodes before linearized reading' (duration: 234.387482ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:58:23.844952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.637513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-22T22:58:23.845819Z","caller":"traceutil/trace.go:171","msg":"trace[248638258] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24957; }","duration":"239.574231ms","start":"2026-07-22T22:58:23.606188Z","end":"2026-07-22T22:58:23.845762Z","steps":["trace[248638258] 'agreement among raft nodes before linearized reading' (duration: 238.667873ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:58:24.150118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.040482ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-22T22:58:24.150233Z","caller":"traceutil/trace.go:171","msg":"trace[1494662697] linearizableReadLoop","detail":"{readStateIndex:25775; appliedIndex:25773; }","duration":"298.515378ms","start":"2026-07-22T22:58:23.851698Z","end":"2026-07-22T22:58:24.150214Z","steps":["trace[1494662697] 'read index received' (duration: 59.458197ms)","trace[1494662697] 'applied index is now lower than readState.Index' (duration: 239.055311ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:58:24.150353Z","caller":"traceutil/trace.go:171","msg":"trace[821948713] transaction","detail":"{read_only:false; response_revision:24959; number_of_response:1; }","duration":"300.334803ms","start":"2026-07-22T22:58:23.849972Z","end":"2026-07-22T22:58:24.150306Z","steps":["trace[821948713] 'process raft request' (duration: 152.990526ms)","trace[821948713] 'compare' (duration: 146.681005ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:58:24.150606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:58:23.849938Z","time spent":"300.531508ms","remote":"127.0.0.1:42146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:58:24.150827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.300059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-07-22T22:58:24.150907Z","caller":"traceutil/trace.go:171","msg":"trace[34585761] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24959; }","duration":"157.435491ms","start":"2026-07-22T22:58:23.993455Z","end":"2026-07-22T22:58:24.15089Z","steps":["trace[34585761] 'agreement among raft nodes before linearized reading' (duration: 157.159696ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:58:24.150909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.206342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:672"} {"level":"info","ts":"2026-07-22T22:58:24.150946Z","caller":"traceutil/trace.go:171","msg":"trace[1544914015] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:24959; }","duration":"299.326904ms","start":"2026-07-22T22:58:23.851608Z","end":"2026-07-22T22:58:24.150935Z","steps":["trace[1544914015] 'agreement among raft nodes before linearized reading' (duration: 298.701292ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:58:24.508298Z","caller":"traceutil/trace.go:171","msg":"trace[452070556] transaction","detail":"{read_only:false; response_revision:24961; number_of_response:1; }","duration":"100.841538ms","start":"2026-07-22T22:58:24.407423Z","end":"2026-07-22T22:58:24.508265Z","steps":["trace[452070556] 'process raft request' (duration: 100.362539ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:58:26.629901Z","caller":"traceutil/trace.go:171","msg":"trace[1826079865] transaction","detail":"{read_only:false; response_revision:24981; number_of_response:1; }","duration":"116.768443ms","start":"2026-07-22T22:58:26.513105Z","end":"2026-07-22T22:58:26.629874Z","steps":["trace[1826079865] 'process raft request' (duration: 116.57976ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:58:26.90291Z","caller":"traceutil/trace.go:171","msg":"trace[1685208569] linearizableReadLoop","detail":"{readStateIndex:25800; appliedIndex:25796; }","duration":"346.485985ms","start":"2026-07-22T22:58:26.556399Z","end":"2026-07-22T22:58:26.902885Z","steps":["trace[1685208569] 'read index received' (duration: 73.371502ms)","trace[1685208569] 'applied index is now lower than readState.Index' (duration: 273.113053ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:58:26.903203Z","caller":"traceutil/trace.go:171","msg":"trace[189081903] transaction","detail":"{read_only:false; response_revision:24982; number_of_response:1; }","duration":"389.316325ms","start":"2026-07-22T22:58:26.513872Z","end":"2026-07-22T22:58:26.903188Z","steps":["trace[189081903] 'process raft request' (duration: 369.997395ms)","trace[189081903] 'compare' (duration: 18.760758ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:58:26.903334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:58:26.51386Z","time spent":"389.380976ms","remote":"127.0.0.1:42146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:58:26.903492Z","caller":"traceutil/trace.go:171","msg":"trace[1857956912] transaction","detail":"{read_only:false; response_revision:24983; number_of_response:1; }","duration":"388.753765ms","start":"2026-07-22T22:58:26.514731Z","end":"2026-07-22T22:58:26.903484Z","steps":["trace[1857956912] 'process raft request' (duration: 388.069861ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:58:26.903532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:58:26.514716Z","time spent":"388.789755ms","remote":"127.0.0.1:42146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:58:26.90362Z","caller":"traceutil/trace.go:171","msg":"trace[1038705036] transaction","detail":"{read_only:false; response_revision:24984; number_of_response:1; }","duration":"386.727065ms","start":"2026-07-22T22:58:26.516887Z","end":"2026-07-22T22:58:26.903614Z","steps":["trace[1038705036] 'process raft request' (duration: 385.95679ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:58:26.903684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:58:26.51688Z","time spent":"386.780246ms","remote":"127.0.0.1:42146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:58:26.903854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.454995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-22T22:58:26.903878Z","caller":"traceutil/trace.go:171","msg":"trace[658931212] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24984; }","duration":"347.501785ms","start":"2026-07-22T22:58:26.556369Z","end":"2026-07-22T22:58:26.903871Z","steps":["trace[658931212] 'agreement among raft nodes before linearized reading' (duration: 347.404933ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:58:26.903903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:58:26.556357Z","time spent":"347.540616ms","remote":"127.0.0.1:42146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-22T22:58:26.904046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.585917ms","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-07-22T22:58:26.90407Z","caller":"traceutil/trace.go:171","msg":"trace[855880640] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24984; }","duration":"346.637689ms","start":"2026-07-22T22:58:26.557426Z","end":"2026-07-22T22:58:26.904064Z","steps":["trace[855880640] 'agreement among raft nodes before linearized reading' (duration: 346.572517ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:58:26.904088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:58:26.557418Z","time spent":"346.665429ms","remote":"127.0.0.1:42146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-22T22:58:26.905504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.727882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-825f65cc-32d6-4446-938e-44df0cb73acf\" ","response":"range_response_count:1 size:3950"} {"level":"info","ts":"2026-07-22T22:58:26.905546Z","caller":"traceutil/trace.go:171","msg":"trace[1479073120] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-825f65cc-32d6-4446-938e-44df0cb73acf; range_end:; response_count:1; response_revision:24984; }","duration":"287.792873ms","start":"2026-07-22T22:58:26.617742Z","end":"2026-07-22T22:58:26.905535Z","steps":["trace[1479073120] 'agreement among raft nodes before linearized reading' (duration: 287.679121ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:58:26.905593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.936176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-22T22:58:26.905622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.110166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-22T22:58:26.905663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.978334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-07-22T22:58:26.905713Z","caller":"traceutil/trace.go:171","msg":"trace[1482529877] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:24984; }","duration":"162.127139ms","start":"2026-07-22T22:58:26.743556Z","end":"2026-07-22T22:58:26.905683Z","steps":["trace[1482529877] 'agreement among raft nodes before linearized reading' (duration: 161.874585ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:58:26.905721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.958952ms","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-07-22T22:58:26.905738Z","caller":"traceutil/trace.go:171","msg":"trace[1488928892] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24984; }","duration":"348.238899ms","start":"2026-07-22T22:58:26.557475Z","end":"2026-07-22T22:58:26.905714Z","steps":["trace[1488928892] 'agreement among raft nodes before linearized reading' (duration: 347.995364ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:58:26.905768Z","caller":"traceutil/trace.go:171","msg":"trace[554658439] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24984; }","duration":"348.131346ms","start":"2026-07-22T22:58:26.55761Z","end":"2026-07-22T22:58:26.905741Z","steps":["trace[554658439] 'agreement among raft nodes before linearized reading' (duration: 347.824021ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:58:26.905796Z","caller":"traceutil/trace.go:171","msg":"trace[224180175] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusters/; range_end:/registry/cluster.x-k8s.io/clusters0; response_count:0; response_revision:24984; }","duration":"191.077073ms","start":"2026-07-22T22:58:26.7147Z","end":"2026-07-22T22:58:26.905777Z","steps":["trace[224180175] 'agreement among raft nodes before linearized reading' (duration: 190.956021ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:58:26.905805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:58:26.557469Z","time spent":"348.318741ms","remote":"127.0.0.1:42146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-22T22:58:26.90583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:58:26.557603Z","time spent":"348.216749ms","remote":"127.0.0.1:42146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-07-22T22:58:26.906419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.137576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-22T22:58:26.906494Z","caller":"traceutil/trace.go:171","msg":"trace[95141795] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0; response_count:0; response_revision:24984; }","duration":"146.540293ms","start":"2026-07-22T22:58:26.759935Z","end":"2026-07-22T22:58:26.906475Z","steps":["trace[95141795] 'agreement among raft nodes before linearized reading' (duration: 145.126556ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:58:30.069177Z","caller":"traceutil/trace.go:171","msg":"trace[656164634] linearizableReadLoop","detail":"{readStateIndex:25845; appliedIndex:25843; }","duration":"132.434303ms","start":"2026-07-22T22:58:29.936699Z","end":"2026-07-22T22:58:30.069134Z","steps":["trace[656164634] 'read index received' (duration: 21.197665ms)","trace[656164634] 'applied index is now lower than readState.Index' (duration: 111.235638ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:58:30.069225Z","caller":"traceutil/trace.go:171","msg":"trace[545923147] transaction","detail":"{read_only:false; response_revision:25027; number_of_response:1; }","duration":"205.831825ms","start":"2026-07-22T22:58:29.863347Z","end":"2026-07-22T22:58:30.069179Z","steps":["trace[545923147] 'process raft request' (duration: 204.48659ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:58:30.06939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.684778ms","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-07-22T22:58:30.069421Z","caller":"traceutil/trace.go:171","msg":"trace[1446478010] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25027; }","duration":"132.78865ms","start":"2026-07-22T22:58:29.936624Z","end":"2026-07-22T22:58:30.069412Z","steps":["trace[1446478010] 'agreement among raft nodes before linearized reading' (duration: 132.636697ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:58:50.870125Z","caller":"traceutil/trace.go:171","msg":"trace[84612434] transaction","detail":"{read_only:false; response_revision:25197; number_of_response:1; }","duration":"135.484481ms","start":"2026-07-22T22:58:50.734603Z","end":"2026-07-22T22:58:50.870088Z","steps":["trace[84612434] 'process raft request' (duration: 61.399705ms)","trace[84612434] 'compare' (duration: 73.924823ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:58:51.297261Z","caller":"traceutil/trace.go:171","msg":"trace[338959261] transaction","detail":"{read_only:false; response_revision:25199; number_of_response:1; }","duration":"289.407523ms","start":"2026-07-22T22:58:51.007803Z","end":"2026-07-22T22:58:51.297211Z","steps":["trace[338959261] 'process raft request' (duration: 262.855195ms)","trace[338959261] 'compare' (duration: 26.234592ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:58:51.297447Z","caller":"traceutil/trace.go:171","msg":"trace[401992529] linearizableReadLoop","detail":"{readStateIndex:26023; appliedIndex:26021; }","duration":"244.538165ms","start":"2026-07-22T22:58:51.052846Z","end":"2026-07-22T22:58:51.297385Z","steps":["trace[401992529] 'read index received' (duration: 217.837495ms)","trace[401992529] 'applied index is now lower than readState.Index' (duration: 26.69871ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:58:51.297475Z","caller":"traceutil/trace.go:171","msg":"trace[512654862] transaction","detail":"{read_only:false; response_revision:25200; number_of_response:1; }","duration":"288.837463ms","start":"2026-07-22T22:58:51.008595Z","end":"2026-07-22T22:58:51.297433Z","steps":["trace[512654862] 'process raft request' (duration: 288.562378ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:58:51.297683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.814272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-07-22T22:58:51.297732Z","caller":"traceutil/trace.go:171","msg":"trace[712211777] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25200; }","duration":"244.927133ms","start":"2026-07-22T22:58:51.052788Z","end":"2026-07-22T22:58:51.297716Z","steps":["trace[712211777] 'agreement among raft nodes before linearized reading' (duration: 244.74875ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:58:51.48268Z","caller":"traceutil/trace.go:171","msg":"trace[1659729184] linearizableReadLoop","detail":"{readStateIndex:26024; appliedIndex:26023; }","duration":"185.089059ms","start":"2026-07-22T22:58:51.297536Z","end":"2026-07-22T22:58:51.482625Z","steps":["trace[1659729184] 'read index received' (duration: 124.416699ms)","trace[1659729184] 'applied index is now lower than readState.Index' (duration: 60.67073ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:58:51.482698Z","caller":"traceutil/trace.go:171","msg":"trace[1748789983] transaction","detail":"{read_only:false; response_revision:25201; number_of_response:1; }","duration":"396.804038ms","start":"2026-07-22T22:58:51.08582Z","end":"2026-07-22T22:58:51.482624Z","steps":["trace[1748789983] 'process raft request' (duration: 336.24015ms)","trace[1748789983] 'compare' (duration: 60.279473ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:58:51.482878Z","caller":"traceutil/trace.go:171","msg":"trace[1111812253] transaction","detail":"{read_only:false; response_revision:25202; number_of_response:1; }","duration":"180.367419ms","start":"2026-07-22T22:58:51.302476Z","end":"2026-07-22T22:58:51.482844Z","steps":["trace[1111812253] 'process raft request' (duration: 180.106644ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:58:51.482955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:58:51.085802Z","time spent":"397.002793ms","remote":"127.0.0.1:42146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:58:51.482995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.562802ms","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-07-22T22:58:51.483042Z","caller":"traceutil/trace.go:171","msg":"trace[1214224420] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25202; }","duration":"420.682594ms","start":"2026-07-22T22:58:51.062347Z","end":"2026-07-22T22:58:51.483029Z","steps":["trace[1214224420] 'agreement among raft nodes before linearized reading' (duration: 420.435939ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:58:51.483082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:58:51.062334Z","time spent":"420.739005ms","remote":"127.0.0.1:42146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-22T22:58:51.483142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.48764ms","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-07-22T22:58:51.483194Z","caller":"traceutil/trace.go:171","msg":"trace[2111517768] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25202; }","duration":"420.575462ms","start":"2026-07-22T22:58:51.062608Z","end":"2026-07-22T22:58:51.483183Z","steps":["trace[2111517768] 'agreement among raft nodes before linearized reading' (duration: 420.362849ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:58:51.48322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:58:51.062594Z","time spent":"420.621693ms","remote":"127.0.0.1:42146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-22T22:58:51.483324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.891917ms","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-07-22T22:58:51.483414Z","caller":"traceutil/trace.go:171","msg":"trace[311790904] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25202; }","duration":"421.04526ms","start":"2026-07-22T22:58:51.062347Z","end":"2026-07-22T22:58:51.483392Z","steps":["trace[311790904] 'agreement among raft nodes before linearized reading' (duration: 420.761405ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:58:51.483454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:58:51.062326Z","time spent":"421.123592ms","remote":"127.0.0.1:42146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-22T22:58:51.76267Z","caller":"traceutil/trace.go:171","msg":"trace[1804883280] transaction","detail":"{read_only:false; response_revision:25204; number_of_response:1; }","duration":"274.00717ms","start":"2026-07-22T22:58:51.488617Z","end":"2026-07-22T22:58:51.762625Z","steps":["trace[1804883280] 'process raft request' (duration: 204.319277ms)","trace[1804883280] 'compare' (duration: 69.324435ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:58:51.762698Z","caller":"traceutil/trace.go:171","msg":"trace[535197707] transaction","detail":"{read_only:false; response_revision:25205; number_of_response:1; }","duration":"272.911599ms","start":"2026-07-22T22:58:51.489756Z","end":"2026-07-22T22:58:51.762667Z","steps":["trace[535197707] 'process raft request' (duration: 272.757856ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:58:52.249437Z","caller":"traceutil/trace.go:171","msg":"trace[1995787440] transaction","detail":"{read_only:false; response_revision:25207; number_of_response:1; }","duration":"209.837992ms","start":"2026-07-22T22:58:52.039579Z","end":"2026-07-22T22:58:52.249417Z","steps":["trace[1995787440] 'process raft request' (duration: 209.702209ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:58:52.276864Z","caller":"traceutil/trace.go:171","msg":"trace[627574058] transaction","detail":"{read_only:false; response_revision:25208; number_of_response:1; }","duration":"111.282878ms","start":"2026-07-22T22:58:52.16554Z","end":"2026-07-22T22:58:52.276823Z","steps":["trace[627574058] 'process raft request' (duration: 111.087185ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:58:52.47308Z","caller":"traceutil/trace.go:171","msg":"trace[1252186776] linearizableReadLoop","detail":"{readStateIndex:26032; appliedIndex:26031; }","duration":"170.369349ms","start":"2026-07-22T22:58:52.302681Z","end":"2026-07-22T22:58:52.473051Z","steps":["trace[1252186776] 'read index received' (duration: 99.195457ms)","trace[1252186776] 'applied index is now lower than readState.Index' (duration: 71.172102ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:58:52.473138Z","caller":"traceutil/trace.go:171","msg":"trace[888833269] transaction","detail":"{read_only:false; response_revision:25209; number_of_response:1; }","duration":"191.309119ms","start":"2026-07-22T22:58:52.281764Z","end":"2026-07-22T22:58:52.473073Z","steps":["trace[888833269] 'process raft request' (duration: 120.095667ms)","trace[888833269] 'compare' (duration: 70.921757ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:58:52.473301Z","caller":"traceutil/trace.go:171","msg":"trace[1207076680] transaction","detail":"{read_only:false; response_revision:25210; number_of_response:1; }","duration":"144.269639ms","start":"2026-07-22T22:58:52.328995Z","end":"2026-07-22T22:58:52.473265Z","steps":["trace[1207076680] 'process raft request' (duration: 144.009434ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:58:52.47345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.750835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3235"} {"level":"info","ts":"2026-07-22T22:58:52.473514Z","caller":"traceutil/trace.go:171","msg":"trace[215848531] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25210; }","duration":"170.887238ms","start":"2026-07-22T22:58:52.302609Z","end":"2026-07-22T22:58:52.473496Z","steps":["trace[215848531] 'agreement among raft nodes before linearized reading' (duration: 170.591052ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:58:52.473922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.468094ms","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-07-22T22:58:52.473983Z","caller":"traceutil/trace.go:171","msg":"trace[291027373] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25210; }","duration":"166.575235ms","start":"2026-07-22T22:58:52.307389Z","end":"2026-07-22T22:58:52.473964Z","steps":["trace[291027373] 'agreement among raft nodes before linearized reading' (duration: 166.189837ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:58:52.632003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.694588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-22T22:58:52.632079Z","caller":"traceutil/trace.go:171","msg":"trace[1264815335] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25212; }","duration":"131.831171ms","start":"2026-07-22T22:58:52.500231Z","end":"2026-07-22T22:58:52.632062Z","steps":["trace[1264815335] 'agreement among raft nodes before linearized reading' (duration: 131.686058ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:58:53.261304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.200711ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"info","ts":"2026-07-22T22:58:53.2616Z","caller":"traceutil/trace.go:171","msg":"trace[951157417] transaction","detail":"{read_only:false; response_revision:25214; number_of_response:1; }","duration":"203.647194ms","start":"2026-07-22T22:58:53.05793Z","end":"2026-07-22T22:58:53.261577Z","steps":["trace[951157417] 'process raft request' (duration: 203.498961ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:58:54.489739Z","caller":"traceutil/trace.go:171","msg":"trace[1480252688] linearizableReadLoop","detail":"{readStateIndex:26047; appliedIndex:26045; }","duration":"203.47732ms","start":"2026-07-22T22:58:54.286233Z","end":"2026-07-22T22:58:54.48971Z","steps":["trace[1480252688] 'read index received' (duration: 45.777565ms)","trace[1480252688] 'applied index is now lower than readState.Index' (duration: 157.698755ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:58:54.489884Z","caller":"traceutil/trace.go:171","msg":"trace[1839051206] transaction","detail":"{read_only:false; response_revision:25223; number_of_response:1; }","duration":"208.565698ms","start":"2026-07-22T22:58:54.281307Z","end":"2026-07-22T22:58:54.489873Z","steps":["trace[1839051206] 'process raft request' (duration: 196.692631ms)","trace[1839051206] 'compare' (duration: 11.599731ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:58:54.490263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.009862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:672"} {"level":"info","ts":"2026-07-22T22:58:54.490339Z","caller":"traceutil/trace.go:171","msg":"trace[771652150] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25223; }","duration":"204.136354ms","start":"2026-07-22T22:58:54.286185Z","end":"2026-07-22T22:58:54.490321Z","steps":["trace[771652150] 'agreement among raft nodes before linearized reading' (duration: 203.984141ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:58:54.743098Z","caller":"traceutil/trace.go:171","msg":"trace[830085385] transaction","detail":"{read_only:false; response_revision:25227; number_of_response:1; }","duration":"104.922407ms","start":"2026-07-22T22:58:54.638134Z","end":"2026-07-22T22:58:54.743056Z","steps":["trace[830085385] 'process raft request' (duration: 74.834702ms)","trace[830085385] 'compare' (duration: 29.868451ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:58:54.853615Z","caller":"traceutil/trace.go:171","msg":"trace[1464886077] linearizableReadLoop","detail":"{readStateIndex:26052; appliedIndex:26051; }","duration":"104.652301ms","start":"2026-07-22T22:58:54.748931Z","end":"2026-07-22T22:58:54.853584Z","steps":["trace[1464886077] 'read index received' (duration: 104.367145ms)","trace[1464886077] 'applied index is now lower than readState.Index' (duration: 283.966µs)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:58:54.853774Z","caller":"traceutil/trace.go:171","msg":"trace[1525828777] transaction","detail":"{read_only:false; response_revision:25228; number_of_response:1; }","duration":"105.753902ms","start":"2026-07-22T22:58:54.748007Z","end":"2026-07-22T22:58:54.853761Z","steps":["trace[1525828777] 'process raft request' (duration: 105.412166ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:58:54.854154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.205172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" ","response":"range_response_count:1 size:1155"} {"level":"info","ts":"2026-07-22T22:58:54.854246Z","caller":"traceutil/trace.go:171","msg":"trace[131485387] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-manila-default-user; range_end:; response_count:1; response_revision:25228; }","duration":"105.359754ms","start":"2026-07-22T22:58:54.748867Z","end":"2026-07-22T22:58:54.854226Z","steps":["trace[131485387] 'agreement among raft nodes before linearized reading' (duration: 105.193741ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:58:55.963874Z","caller":"traceutil/trace.go:171","msg":"trace[2030378509] transaction","detail":"{read_only:false; response_revision:25236; number_of_response:1; }","duration":"179.520253ms","start":"2026-07-22T22:58:55.7843Z","end":"2026-07-22T22:58:55.963821Z","steps":["trace[2030378509] 'process raft request' (duration: 113.134703ms)","trace[2030378509] 'compare' (duration: 65.943661ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:58:58.702395Z","caller":"traceutil/trace.go:171","msg":"trace[835964588] transaction","detail":"{read_only:false; response_revision:25254; number_of_response:1; }","duration":"100.256637ms","start":"2026-07-22T22:58:58.602101Z","end":"2026-07-22T22:58:58.702357Z","steps":["trace[835964588] 'process raft request' (duration: 55.95017ms)","trace[835964588] 'compare' (duration: 43.754817ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:59:04.544209Z","caller":"traceutil/trace.go:171","msg":"trace[717692393] transaction","detail":"{read_only:false; response_revision:25295; number_of_response:1; }","duration":"105.63705ms","start":"2026-07-22T22:59:04.438527Z","end":"2026-07-22T22:59:04.544164Z","steps":["trace[717692393] 'process raft request' (duration: 105.341694ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:59:10.041821Z","caller":"traceutil/trace.go:171","msg":"trace[455644132] linearizableReadLoop","detail":"{readStateIndex:26162; appliedIndex:26161; }","duration":"175.127939ms","start":"2026-07-22T22:59:09.86667Z","end":"2026-07-22T22:59:10.041798Z","steps":["trace[455644132] 'read index received' (duration: 174.907175ms)","trace[455644132] 'applied index is now lower than readState.Index' (duration: 220.004µs)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:59:10.042105Z","caller":"traceutil/trace.go:171","msg":"trace[1803162341] transaction","detail":"{read_only:false; response_revision:25335; number_of_response:1; }","duration":"178.110416ms","start":"2026-07-22T22:59:09.863977Z","end":"2026-07-22T22:59:10.042087Z","steps":["trace[1803162341] 'process raft request' (duration: 177.647767ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:59:10.042345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.641569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-3071857f-e3f6-4707-b493-45df7f6aafe8\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-07-22T22:59:10.042393Z","caller":"traceutil/trace.go:171","msg":"trace[1998620660] range","detail":"{range_begin:/registry/persistentvolumes/pvc-3071857f-e3f6-4707-b493-45df7f6aafe8; range_end:; response_count:1; response_revision:25335; }","duration":"175.72852ms","start":"2026-07-22T22:59:09.866652Z","end":"2026-07-22T22:59:10.04238Z","steps":["trace[1998620660] 'agreement among raft nodes before linearized reading' (duration: 175.591357ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:59:10.042585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.114644ms","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-07-22T22:59:10.04262Z","caller":"traceutil/trace.go:171","msg":"trace[500479521] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25335; }","duration":"113.150664ms","start":"2026-07-22T22:59:09.929461Z","end":"2026-07-22T22:59:10.042611Z","steps":["trace[500479521] 'agreement among raft nodes before linearized reading' (duration: 113.059442ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:59:10.180611Z","caller":"traceutil/trace.go:171","msg":"trace[1347980338] linearizableReadLoop","detail":"{readStateIndex:26163; appliedIndex:26162; }","duration":"124.865488ms","start":"2026-07-22T22:59:10.055705Z","end":"2026-07-22T22:59:10.180571Z","steps":["trace[1347980338] 'read index received' (duration: 61.629029ms)","trace[1347980338] 'applied index is now lower than readState.Index' (duration: 63.234799ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:59:10.18065Z","caller":"traceutil/trace.go:171","msg":"trace[196737669] transaction","detail":"{read_only:false; response_revision:25336; number_of_response:1; }","duration":"132.863301ms","start":"2026-07-22T22:59:10.04774Z","end":"2026-07-22T22:59:10.180603Z","steps":["trace[196737669] 'process raft request' (duration: 69.528891ms)","trace[196737669] 'compare' (duration: 62.952943ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:59:10.180842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.134402ms","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-07-22T22:59:10.180894Z","caller":"traceutil/trace.go:171","msg":"trace[1329061876] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25336; }","duration":"125.208494ms","start":"2026-07-22T22:59:10.055671Z","end":"2026-07-22T22:59:10.18088Z","steps":["trace[1329061876] 'agreement among raft nodes before linearized reading' (duration: 125.084072ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:59:10.763622Z","caller":"traceutil/trace.go:171","msg":"trace[707419819] linearizableReadLoop","detail":"{readStateIndex:26166; appliedIndex:26165; }","duration":"111.735777ms","start":"2026-07-22T22:59:10.651855Z","end":"2026-07-22T22:59:10.763591Z","steps":["trace[707419819] 'read index received' (duration: 43.637915ms)","trace[707419819] 'applied index is now lower than readState.Index' (duration: 68.096742ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:59:10.763747Z","caller":"traceutil/trace.go:171","msg":"trace[2021745409] transaction","detail":"{read_only:false; response_revision:25339; number_of_response:1; }","duration":"137.674633ms","start":"2026-07-22T22:59:10.62603Z","end":"2026-07-22T22:59:10.763705Z","steps":["trace[2021745409] 'process raft request' (duration: 69.406808ms)","trace[2021745409] 'compare' (duration: 68.04453ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:59:10.763902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.093794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:672"} {"level":"info","ts":"2026-07-22T22:59:10.763941Z","caller":"traceutil/trace.go:171","msg":"trace[2036181139] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25339; }","duration":"134.195596ms","start":"2026-07-22T22:59:10.629735Z","end":"2026-07-22T22:59:10.763931Z","steps":["trace[2036181139] 'agreement among raft nodes before linearized reading' (duration: 133.985692ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:59:10.951583Z","caller":"traceutil/trace.go:171","msg":"trace[1809213451] linearizableReadLoop","detail":"{readStateIndex:26168; appliedIndex:26166; }","duration":"133.200747ms","start":"2026-07-22T22:59:10.818352Z","end":"2026-07-22T22:59:10.951552Z","steps":["trace[1809213451] 'read index received' (duration: 9.678355ms)","trace[1809213451] 'applied index is now lower than readState.Index' (duration: 123.520982ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:59:10.951722Z","caller":"traceutil/trace.go:171","msg":"trace[617900478] transaction","detail":"{read_only:false; response_revision:25341; number_of_response:1; }","duration":"134.967791ms","start":"2026-07-22T22:59:10.81669Z","end":"2026-07-22T22:59:10.951658Z","steps":["trace[617900478] 'process raft request' (duration: 68.625932ms)","trace[617900478] 'compare' (duration: 66.070814ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:59:10.952051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.627545ms","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-07-22T22:59:10.9523Z","caller":"traceutil/trace.go:171","msg":"trace[284272822] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25341; }","duration":"133.799028ms","start":"2026-07-22T22:59:10.818322Z","end":"2026-07-22T22:59:10.952121Z","steps":["trace[284272822] 'agreement among raft nodes before linearized reading' (duration: 133.3587ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:59:14.841293Z","caller":"traceutil/trace.go:171","msg":"trace[342154395] transaction","detail":"{read_only:false; response_revision:25381; number_of_response:1; }","duration":"173.868265ms","start":"2026-07-22T22:59:14.667377Z","end":"2026-07-22T22:59:14.841245Z","steps":["trace[342154395] 'process raft request' (duration: 76.036774ms)","trace[342154395] 'compare' (duration: 97.616277ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:59:14.994961Z","caller":"traceutil/trace.go:171","msg":"trace[168543802] transaction","detail":"{read_only:false; response_revision:25383; number_of_response:1; }","duration":"147.631974ms","start":"2026-07-22T22:59:14.847301Z","end":"2026-07-22T22:59:14.994933Z","steps":["trace[168543802] 'process raft request' (duration: 118.004077ms)","trace[168543802] 'compare' (duration: 29.381611ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:59:21.131124Z","caller":"traceutil/trace.go:171","msg":"trace[164382400] linearizableReadLoop","detail":"{readStateIndex:26252; appliedIndex:26251; }","duration":"110.416681ms","start":"2026-07-22T22:59:21.020666Z","end":"2026-07-22T22:59:21.131083Z","steps":["trace[164382400] 'read index received' (duration: 83.941725ms)","trace[164382400] 'applied index is now lower than readState.Index' (duration: 26.472536ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:59:21.131157Z","caller":"traceutil/trace.go:171","msg":"trace[768708972] transaction","detail":"{read_only:false; response_revision:25423; number_of_response:1; }","duration":"113.091973ms","start":"2026-07-22T22:59:21.018031Z","end":"2026-07-22T22:59:21.131123Z","steps":["trace[768708972] 'process raft request' (duration: 86.563276ms)","trace[768708972] 'compare' (duration: 26.12512ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:59:21.13179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.102075ms","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-07-22T22:59:21.131849Z","caller":"traceutil/trace.go:171","msg":"trace[2048702884] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25423; }","duration":"111.189427ms","start":"2026-07-22T22:59:21.020642Z","end":"2026-07-22T22:59:21.131831Z","steps":["trace[2048702884] 'agreement among raft nodes before linearized reading' (duration: 110.628586ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:59:21.404131Z","caller":"traceutil/trace.go:171","msg":"trace[2077222088] transaction","detail":"{read_only:false; response_revision:25425; number_of_response:1; }","duration":"266.786151ms","start":"2026-07-22T22:59:21.137306Z","end":"2026-07-22T22:59:21.404092Z","steps":["trace[2077222088] 'process raft request' (duration: 205.997589ms)","trace[2077222088] 'compare' (duration: 60.13483ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:59:21.408459Z","caller":"traceutil/trace.go:171","msg":"trace[1898490484] transaction","detail":"{read_only:false; response_revision:25426; number_of_response:1; }","duration":"261.125713ms","start":"2026-07-22T22:59:21.147308Z","end":"2026-07-22T22:59:21.408433Z","steps":["trace[1898490484] 'process raft request' (duration: 261.002301ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:59:21.628332Z","caller":"traceutil/trace.go:171","msg":"trace[2035087359] transaction","detail":"{read_only:false; response_revision:25428; number_of_response:1; }","duration":"179.834848ms","start":"2026-07-22T22:59:21.448459Z","end":"2026-07-22T22:59:21.628293Z","steps":["trace[2035087359] 'process raft request' (duration: 101.599362ms)","trace[2035087359] 'compare' (duration: 77.950951ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:59:21.628554Z","caller":"traceutil/trace.go:171","msg":"trace[704353000] linearizableReadLoop","detail":"{readStateIndex:26258; appliedIndex:26256; }","duration":"150.440526ms","start":"2026-07-22T22:59:21.47808Z","end":"2026-07-22T22:59:21.62852Z","steps":["trace[704353000] 'read index received' (duration: 72.035247ms)","trace[704353000] 'applied index is now lower than readState.Index' (duration: 78.402299ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:59:21.628661Z","caller":"traceutil/trace.go:171","msg":"trace[1405594476] transaction","detail":"{read_only:false; response_revision:25429; number_of_response:1; }","duration":"165.696638ms","start":"2026-07-22T22:59:21.462823Z","end":"2026-07-22T22:59:21.628519Z","steps":["trace[1405594476] 'process raft request' (duration: 165.392363ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:59:21.62881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.535746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-22T22:59:21.628935Z","caller":"traceutil/trace.go:171","msg":"trace[26846094] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:25429; }","duration":"154.757929ms","start":"2026-07-22T22:59:21.474147Z","end":"2026-07-22T22:59:21.628905Z","steps":["trace[26846094] 'agreement among raft nodes before linearized reading' (duration: 154.535595ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:59:21.628924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.672412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-22T22:59:21.629004Z","caller":"traceutil/trace.go:171","msg":"trace[916371236] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25429; }","duration":"127.820265ms","start":"2026-07-22T22:59:21.501168Z","end":"2026-07-22T22:59:21.628989Z","steps":["trace[916371236] 'agreement among raft nodes before linearized reading' (duration: 127.594671ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T23:00:00.191128Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006889.wal"} {"level":"info","ts":"2026-07-22T23:00:19.999353Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23114} {"level":"info","ts":"2026-07-22T23:00:20.13189Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23114,"took":"128.107331ms","hash":1637454352,"current-db-size-bytes":59916288,"current-db-size":"60 MB","current-db-size-in-use-bytes":36519936,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-07-22T23:00:20.131974Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1637454352,"revision":23114,"compact-revision":20113} {"level":"info","ts":"2026-07-22T23:05:20.008214Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26116} {"level":"info","ts":"2026-07-22T23:05:20.159863Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26116,"took":"146.9221ms","hash":822757959,"current-db-size-bytes":59916288,"current-db-size":"60 MB","current-db-size-in-use-bytes":33132544,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-07-22T23:05:20.160031Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":822757959,"revision":26116,"compact-revision":23114} {"level":"info","ts":"2026-07-22T23:06:19.243396Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"aab6040abcf11","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-22T23:06:19.250299Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-07-22T23:06:19.250472Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003}