{"level":"warn","ts":"2026-09-01T18:29:30.905695Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-01T18:29:30.905803Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.115: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://162.253.55.115:2380","--initial-cluster=instance=https://162.253.55.115:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.115:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.115: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-09-01T18:29:30.905888Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-01T18:29:30.905898Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.115:2380"]} {"level":"info","ts":"2026-09-01T18:29:30.905918Z","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-09-01T18:29:30.906723Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.115:2379"]} {"level":"info","ts":"2026-09-01T18:29:30.90683Z","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://162.253.55.115:2380"],"listen-peer-urls":["https://162.253.55.115:2380"],"advertise-client-urls":["https://162.253.55.115:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.115:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.115: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-09-01T18:29:30.909815Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.681229ms"} {"level":"info","ts":"2026-09-01T18:29:30.916181Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3fd97315619643d2","cluster-id":"5ce44a81422ca797"} {"level":"info","ts":"2026-09-01T18:29:30.916242Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3fd97315619643d2 switched to configuration voters=()"} {"level":"info","ts":"2026-09-01T18:29:30.916258Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3fd97315619643d2 became follower at term 0"} {"level":"info","ts":"2026-09-01T18:29:30.916266Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3fd97315619643d2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-01T18:29:30.916274Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3fd97315619643d2 became follower at term 1"} {"level":"info","ts":"2026-09-01T18:29:30.916299Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3fd97315619643d2 switched to configuration voters=(4600835030004417490)"} {"level":"warn","ts":"2026-09-01T18:29:30.921845Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-01T18:29:30.923513Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-01T18:29:30.925348Z","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-09-01T18:29:30.927684Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3fd97315619643d2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-01T18:29:30.927912Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3fd97315619643d2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-01T18:29:30.928047Z","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-09-01T18:29:30.928644Z","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-09-01T18:29:30.928672Z","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-09-01T18:29:30.929892Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3fd97315619643d2 switched to configuration voters=(4600835030004417490)"} {"level":"info","ts":"2026-09-01T18:29:30.930088Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"5ce44a81422ca797","local-member-id":"3fd97315619643d2","added-peer-id":"3fd97315619643d2","added-peer-peer-urls":["https://162.253.55.115:2380"]} {"level":"info","ts":"2026-09-01T18:29:30.932592Z","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-09-01T18:29:30.932704Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.115:2380"} {"level":"info","ts":"2026-09-01T18:29:30.932797Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.115:2380"} {"level":"info","ts":"2026-09-01T18:29:30.93297Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3fd97315619643d2","initial-advertise-peer-urls":["https://162.253.55.115:2380"],"listen-peer-urls":["https://162.253.55.115:2380"],"advertise-client-urls":["https://162.253.55.115:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.115:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-01T18:29:30.933047Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-01T18:29:31.516623Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3fd97315619643d2 is starting a new election at term 1"} {"level":"info","ts":"2026-09-01T18:29:31.516684Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3fd97315619643d2 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-01T18:29:31.516721Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3fd97315619643d2 received MsgPreVoteResp from 3fd97315619643d2 at term 1"} {"level":"info","ts":"2026-09-01T18:29:31.51674Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3fd97315619643d2 became candidate at term 2"} {"level":"info","ts":"2026-09-01T18:29:31.516751Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3fd97315619643d2 received MsgVoteResp from 3fd97315619643d2 at term 2"} {"level":"info","ts":"2026-09-01T18:29:31.516769Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3fd97315619643d2 became leader at term 2"} {"level":"info","ts":"2026-09-01T18:29:31.516782Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3fd97315619643d2 elected leader 3fd97315619643d2 at term 2"} {"level":"info","ts":"2026-09-01T18:29:31.518061Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-01T18:29:31.519088Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-01T18:29:31.519082Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3fd97315619643d2","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.115:2379]}","request-path":"/0/members/3fd97315619643d2/attributes","cluster-id":"5ce44a81422ca797","publish-timeout":"7s"} {"level":"info","ts":"2026-09-01T18:29:31.51923Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-01T18:29:31.519533Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-01T18:29:31.519617Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-01T18:29:31.519749Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"5ce44a81422ca797","local-member-id":"3fd97315619643d2","cluster-version":"3.5"} {"level":"info","ts":"2026-09-01T18:29:31.519912Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-01T18:29:31.519959Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-01T18:29:31.522587Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-01T18:29:31.525429Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.115:2379"} {"level":"warn","ts":"2026-09-01T18:29:48.149312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.726265ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-01T18:29:48.149596Z","caller":"traceutil/trace.go:171","msg":"trace[2096710233] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"355.889404ms","start":"2026-09-01T18:29:47.793632Z","end":"2026-09-01T18:29:48.149521Z","steps":["trace[2096710233] 'process raft request' (duration: 169.035185ms)","trace[2096710233] 'compare' (duration: 184.617083ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:29:48.149854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:29:47.793598Z","time spent":"356.126041ms","remote":"127.0.0.1:39134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7096,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T18:29:53.628319Z","caller":"traceutil/trace.go:171","msg":"trace[907026553] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"147.441929ms","start":"2026-09-01T18:29:53.480848Z","end":"2026-09-01T18:29:53.62829Z","steps":["trace[907026553] 'process raft request' (duration: 147.248593ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:30:10.346906Z","caller":"traceutil/trace.go:171","msg":"trace[193850776] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"106.931005ms","start":"2026-09-01T18:30:10.239948Z","end":"2026-09-01T18:30:10.346879Z","steps":["trace[193850776] 'process raft request' (duration: 106.682147ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:30:10.347254Z","caller":"traceutil/trace.go:171","msg":"trace[217495768] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"104.206544ms","start":"2026-09-01T18:30:10.243033Z","end":"2026-09-01T18:30:10.347239Z","steps":["trace[217495768] 'process raft request' (duration: 103.779892ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:30:16.588204Z","caller":"traceutil/trace.go:171","msg":"trace[1678900834] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"197.732342ms","start":"2026-09-01T18:30:16.390403Z","end":"2026-09-01T18:30:16.588136Z","steps":["trace[1678900834] 'process raft request' (duration: 171.035304ms)","trace[1678900834] 'compare' (duration: 26.496502ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:30:16.62858Z","caller":"traceutil/trace.go:171","msg":"trace[1986631095] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"237.454864ms","start":"2026-09-01T18:30:16.391107Z","end":"2026-09-01T18:30:16.628562Z","steps":["trace[1986631095] 'process raft request' (duration: 237.421383ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:30:16.628754Z","caller":"traceutil/trace.go:171","msg":"trace[246457526] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"238.085682ms","start":"2026-09-01T18:30:16.390638Z","end":"2026-09-01T18:30:16.628724Z","steps":["trace[246457526] 'process raft request' (duration: 237.796043ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:30:17.128248Z","caller":"traceutil/trace.go:171","msg":"trace[2047162837] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:648; }","duration":"153.889559ms","start":"2026-09-01T18:30:16.974328Z","end":"2026-09-01T18:30:17.128218Z","steps":["trace[2047162837] 'read index received' (duration: 152.950621ms)","trace[2047162837] 'applied index is now lower than readState.Index' (duration: 937.918µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:30:17.128343Z","caller":"traceutil/trace.go:171","msg":"trace[1960269775] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"298.387641ms","start":"2026-09-01T18:30:16.829902Z","end":"2026-09-01T18:30:17.128289Z","steps":["trace[1960269775] 'process raft request' (duration: 297.71309ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:30:17.128468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.120876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T18:30:17.128728Z","caller":"traceutil/trace.go:171","msg":"trace[177979771] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:628; }","duration":"154.445204ms","start":"2026-09-01T18:30:16.974257Z","end":"2026-09-01T18:30:17.128702Z","steps":["trace[177979771] 'agreement among raft nodes before linearized reading' (duration: 154.075094ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:30:33.793453Z","caller":"traceutil/trace.go:171","msg":"trace[430013674] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"137.455624ms","start":"2026-09-01T18:30:33.655974Z","end":"2026-09-01T18:30:33.793429Z","steps":["trace[430013674] 'process raft request' (duration: 137.31142ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:30:33.80271Z","caller":"traceutil/trace.go:171","msg":"trace[1449831810] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"145.846611ms","start":"2026-09-01T18:30:33.656834Z","end":"2026-09-01T18:30:33.802681Z","steps":["trace[1449831810] 'process raft request' (duration: 145.699647ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:31:54.476629Z","caller":"traceutil/trace.go:171","msg":"trace[1937820484] transaction","detail":"{read_only:false; response_revision:1884; number_of_response:1; }","duration":"211.700782ms","start":"2026-09-01T18:31:54.264883Z","end":"2026-09-01T18:31:54.476584Z","steps":["trace[1937820484] 'process raft request' (duration: 211.407234ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:31:54.601819Z","caller":"traceutil/trace.go:171","msg":"trace[1176661018] linearizableReadLoop","detail":"{readStateIndex:1954; appliedIndex:1952; }","duration":"240.163241ms","start":"2026-09-01T18:31:54.361617Z","end":"2026-09-01T18:31:54.601781Z","steps":["trace[1176661018] 'read index received' (duration: 114.584569ms)","trace[1176661018] 'applied index is now lower than readState.Index' (duration: 125.576912ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:31:54.601896Z","caller":"traceutil/trace.go:171","msg":"trace[1069246255] transaction","detail":"{read_only:false; response_revision:1886; number_of_response:1; }","duration":"226.802797ms","start":"2026-09-01T18:31:54.37506Z","end":"2026-09-01T18:31:54.601863Z","steps":["trace[1069246255] 'process raft request' (duration: 226.570649ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:31:54.602033Z","caller":"traceutil/trace.go:171","msg":"trace[998382350] transaction","detail":"{read_only:false; response_revision:1885; number_of_response:1; }","duration":"336.106809ms","start":"2026-09-01T18:31:54.265861Z","end":"2026-09-01T18:31:54.601968Z","steps":["trace[998382350] 'process raft request' (duration: 313.596875ms)","trace[998382350] 'compare' (duration: 21.675629ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:31:54.60215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.48939ms","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-09-01T18:31:54.602225Z","caller":"traceutil/trace.go:171","msg":"trace[225646819] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1886; }","duration":"240.601124ms","start":"2026-09-01T18:31:54.361601Z","end":"2026-09-01T18:31:54.602202Z","steps":["trace[225646819] 'agreement among raft nodes before linearized reading' (duration: 240.348556ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:31:54.602226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.546254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1d1bd8cc-128b-486f-9737-81946b8b6be3\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-09-01T18:31:54.602292Z","caller":"traceutil/trace.go:171","msg":"trace[1487211365] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1d1bd8cc-128b-486f-9737-81946b8b6be3; range_end:; response_count:1; response_revision:1886; }","duration":"159.673647ms","start":"2026-09-01T18:31:54.442594Z","end":"2026-09-01T18:31:54.602268Z","steps":["trace[1487211365] 'agreement among raft nodes before linearized reading' (duration: 159.396279ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:31:54.602348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:31:54.265836Z","time spent":"336.355236ms","remote":"127.0.0.1:39218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T18:31:54.851314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.038456ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-01T18:31:54.851567Z","caller":"traceutil/trace.go:171","msg":"trace[934310530] transaction","detail":"{read_only:false; response_revision:1887; number_of_response:1; }","duration":"245.381154ms","start":"2026-09-01T18:31:54.60613Z","end":"2026-09-01T18:31:54.851511Z","steps":["trace[934310530] 'process raft request' (duration: 117.032459ms)","trace[934310530] 'compare' (duration: 127.865761ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:31:54.85167Z","caller":"traceutil/trace.go:171","msg":"trace[527604872] transaction","detail":"{read_only:false; response_revision:1888; number_of_response:1; }","duration":"239.514911ms","start":"2026-09-01T18:31:54.612121Z","end":"2026-09-01T18:31:54.851636Z","steps":["trace[527604872] 'process raft request' (duration: 239.302555ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:31:55.051855Z","caller":"traceutil/trace.go:171","msg":"trace[228745755] linearizableReadLoop","detail":"{readStateIndex:1959; appliedIndex:1957; }","duration":"143.193632ms","start":"2026-09-01T18:31:54.90864Z","end":"2026-09-01T18:31:55.051834Z","steps":["trace[228745755] 'read index received' (duration: 22.579376ms)","trace[228745755] 'applied index is now lower than readState.Index' (duration: 120.612986ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:31:55.051917Z","caller":"traceutil/trace.go:171","msg":"trace[706496133] transaction","detail":"{read_only:false; response_revision:1889; number_of_response:1; }","duration":"176.447893ms","start":"2026-09-01T18:31:54.875405Z","end":"2026-09-01T18:31:55.051853Z","steps":["trace[706496133] 'process raft request' (duration: 139.465463ms)","trace[706496133] 'compare' (duration: 36.754903ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:31:55.052157Z","caller":"traceutil/trace.go:171","msg":"trace[880119943] transaction","detail":"{read_only:false; response_revision:1890; number_of_response:1; }","duration":"119.651387ms","start":"2026-09-01T18:31:54.932492Z","end":"2026-09-01T18:31:55.052144Z","steps":["trace[880119943] 'process raft request' (duration: 119.297007ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:31:55.052401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.731677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1d1bd8cc-128b-486f-9737-81946b8b6be3\" ","response":"range_response_count:1 size:3747"} {"level":"info","ts":"2026-09-01T18:31:55.052485Z","caller":"traceutil/trace.go:171","msg":"trace[564580849] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1d1bd8cc-128b-486f-9737-81946b8b6be3; range_end:; response_count:1; response_revision:1890; }","duration":"143.877591ms","start":"2026-09-01T18:31:54.908588Z","end":"2026-09-01T18:31:55.052466Z","steps":["trace[564580849] 'agreement among raft nodes before linearized reading' (duration: 143.360166ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:32:07.321135Z","caller":"traceutil/trace.go:171","msg":"trace[517006762] linearizableReadLoop","detail":"{readStateIndex:2063; appliedIndex:2062; }","duration":"127.954723ms","start":"2026-09-01T18:32:07.193161Z","end":"2026-09-01T18:32:07.321115Z","steps":["trace[517006762] 'read index received' (duration: 127.805999ms)","trace[517006762] 'applied index is now lower than readState.Index' (duration: 147.564µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:32:07.321206Z","caller":"traceutil/trace.go:171","msg":"trace[1907250925] transaction","detail":"{read_only:false; response_revision:1991; number_of_response:1; }","duration":"143.039047ms","start":"2026-09-01T18:32:07.178146Z","end":"2026-09-01T18:32:07.321185Z","steps":["trace[1907250925] 'process raft request' (duration: 142.827481ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:32:07.321317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.148198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-01T18:32:07.321362Z","caller":"traceutil/trace.go:171","msg":"trace[1357478843] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1991; }","duration":"128.216371ms","start":"2026-09-01T18:32:07.193121Z","end":"2026-09-01T18:32:07.321337Z","steps":["trace[1357478843] 'agreement among raft nodes before linearized reading' (duration: 128.103057ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:32:39.783178Z","caller":"traceutil/trace.go:171","msg":"trace[1842330178] linearizableReadLoop","detail":"{readStateIndex:2258; appliedIndex:2257; }","duration":"282.977986ms","start":"2026-09-01T18:32:39.500157Z","end":"2026-09-01T18:32:39.783135Z","steps":["trace[1842330178] 'read index received' (duration: 201.327616ms)","trace[1842330178] 'applied index is now lower than readState.Index' (duration: 81.64842ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:32:39.783398Z","caller":"traceutil/trace.go:171","msg":"trace[1954727124] transaction","detail":"{read_only:false; response_revision:2179; number_of_response:1; }","duration":"318.743636ms","start":"2026-09-01T18:32:39.464607Z","end":"2026-09-01T18:32:39.783351Z","steps":["trace[1954727124] 'process raft request' (duration: 236.88888ms)","trace[1954727124] 'compare' (duration: 81.469034ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:32:39.783565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.381967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-09-01T18:32:39.783617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:32:39.464584Z","time spent":"318.917932ms","remote":"127.0.0.1:39218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T18:32:39.783642Z","caller":"traceutil/trace.go:171","msg":"trace[744510232] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2179; }","duration":"283.557042ms","start":"2026-09-01T18:32:39.500062Z","end":"2026-09-01T18:32:39.783619Z","steps":["trace[744510232] 'agreement among raft nodes before linearized reading' (duration: 283.289955ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:32:39.78399Z","caller":"traceutil/trace.go:171","msg":"trace[393276193] transaction","detail":"{read_only:false; response_revision:2180; number_of_response:1; }","duration":"134.662727ms","start":"2026-09-01T18:32:39.649307Z","end":"2026-09-01T18:32:39.78397Z","steps":["trace[393276193] 'process raft request' (duration: 134.486222ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:32:39.784197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.574364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-01T18:32:39.784269Z","caller":"traceutil/trace.go:171","msg":"trace[141836663] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2180; }","duration":"279.648156ms","start":"2026-09-01T18:32:39.504603Z","end":"2026-09-01T18:32:39.784251Z","steps":["trace[141836663] 'agreement among raft nodes before linearized reading' (duration: 279.351218ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:32:39.78424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.832856ms","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":"info","ts":"2026-09-01T18:32:39.784378Z","caller":"traceutil/trace.go:171","msg":"trace[2098279912] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2180; }","duration":"273.98593ms","start":"2026-09-01T18:32:39.510364Z","end":"2026-09-01T18:32:39.78435Z","steps":["trace[2098279912] 'agreement among raft nodes before linearized reading' (duration: 273.614419ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:34:29.528359Z","caller":"traceutil/trace.go:171","msg":"trace[97291846] transaction","detail":"{read_only:false; response_revision:2967; number_of_response:1; }","duration":"104.054622ms","start":"2026-09-01T18:34:29.424265Z","end":"2026-09-01T18:34:29.52832Z","steps":["trace[97291846] 'process raft request' (duration: 103.761063ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:34:30.06835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.545828ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-01T18:34:30.068486Z","caller":"traceutil/trace.go:171","msg":"trace[2070471545] linearizableReadLoop","detail":"{readStateIndex:3080; appliedIndex:3078; }","duration":"296.67753ms","start":"2026-09-01T18:34:29.771787Z","end":"2026-09-01T18:34:30.068464Z","steps":["trace[2070471545] 'read index received' (duration: 53.789897ms)","trace[2070471545] 'applied index is now lower than readState.Index' (duration: 242.886363ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:34:30.068629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.846196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T18:34:30.068669Z","caller":"traceutil/trace.go:171","msg":"trace[999594979] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:2971; }","duration":"296.930988ms","start":"2026-09-01T18:34:29.771724Z","end":"2026-09-01T18:34:30.068655Z","steps":["trace[999594979] 'agreement among raft nodes before linearized reading' (duration: 296.862586ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:34:30.06863Z","caller":"traceutil/trace.go:171","msg":"trace[931861501] transaction","detail":"{read_only:false; response_revision:2971; number_of_response:1; }","duration":"307.315324ms","start":"2026-09-01T18:34:29.761277Z","end":"2026-09-01T18:34:30.068592Z","steps":["trace[931861501] 'process raft request' (duration: 130.402644ms)","trace[931861501] 'compare' (duration: 176.381484ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:34:30.069191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:34:29.76125Z","time spent":"307.696604ms","remote":"127.0.0.1:39218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T18:34:30.06947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.039048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/valkey-node-0.18d1461feb483005\" ","response":"range_response_count:1 size:768"} {"level":"info","ts":"2026-09-01T18:34:30.06956Z","caller":"traceutil/trace.go:171","msg":"trace[83254887] range","detail":"{range_begin:/registry/events/openstack/valkey-node-0.18d1461feb483005; range_end:; response_count:1; response_revision:2971; }","duration":"213.215973ms","start":"2026-09-01T18:34:29.856324Z","end":"2026-09-01T18:34:30.06954Z","steps":["trace[83254887] 'agreement among raft nodes before linearized reading' (duration: 212.77245ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:39:29.353433Z","caller":"traceutil/trace.go:171","msg":"trace[768796255] linearizableReadLoop","detail":"{readStateIndex:5720; appliedIndex:5719; }","duration":"135.827841ms","start":"2026-09-01T18:39:29.217577Z","end":"2026-09-01T18:39:29.353405Z","steps":["trace[768796255] 'read index received' (duration: 135.675607ms)","trace[768796255] 'applied index is now lower than readState.Index' (duration: 150.724µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:39:29.353484Z","caller":"traceutil/trace.go:171","msg":"trace[1783357287] transaction","detail":"{read_only:false; response_revision:5513; number_of_response:1; }","duration":"144.753073ms","start":"2026-09-01T18:39:29.208696Z","end":"2026-09-01T18:39:29.353449Z","steps":["trace[1783357287] 'process raft request' (duration: 144.516177ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:39:29.353861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.241363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-09-01T18:39:29.353976Z","caller":"traceutil/trace.go:171","msg":"trace[1878659838] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5513; }","duration":"136.451019ms","start":"2026-09-01T18:39:29.2175Z","end":"2026-09-01T18:39:29.353951Z","steps":["trace[1878659838] 'agreement among raft nodes before linearized reading' (duration: 136.057387ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:39:31.562334Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2979} {"level":"info","ts":"2026-09-01T18:39:31.653905Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2979,"took":"89.794835ms","hash":3217146174,"current-db-size-bytes":54796288,"current-db-size":"55 MB","current-db-size-in-use-bytes":30326784,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-01T18:39:31.65402Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3217146174,"revision":2979,"compact-revision":-1} {"level":"info","ts":"2026-09-01T18:39:43.36446Z","caller":"traceutil/trace.go:171","msg":"trace[735415086] transaction","detail":"{read_only:false; response_revision:5763; number_of_response:1; }","duration":"181.567439ms","start":"2026-09-01T18:39:43.182869Z","end":"2026-09-01T18:39:43.364437Z","steps":["trace[735415086] 'process raft request' (duration: 181.433794ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:39:45.545767Z","caller":"traceutil/trace.go:171","msg":"trace[497530112] transaction","detail":"{read_only:false; response_revision:5805; number_of_response:1; }","duration":"120.259895ms","start":"2026-09-01T18:39:45.425476Z","end":"2026-09-01T18:39:45.545736Z","steps":["trace[497530112] 'process raft request' (duration: 120.07752ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:40:46.445418Z","caller":"traceutil/trace.go:171","msg":"trace[2098493745] transaction","detail":"{read_only:false; response_revision:6455; number_of_response:1; }","duration":"165.046156ms","start":"2026-09-01T18:40:46.280348Z","end":"2026-09-01T18:40:46.445394Z","steps":["trace[2098493745] 'process raft request' (duration: 164.87626ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:44:31.569269Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5564} {"level":"info","ts":"2026-09-01T18:44:31.654012Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5564,"took":"82.53679ms","hash":2094372385,"current-db-size-bytes":54796288,"current-db-size":"55 MB","current-db-size-in-use-bytes":28344320,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-01T18:44:31.654113Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2094372385,"revision":5564,"compact-revision":2979} {"level":"warn","ts":"2026-09-01T18:45:57.257438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.129717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-09-01T18:45:57.257526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.397118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-2jcw6\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-09-01T18:45:57.257593Z","caller":"traceutil/trace.go:171","msg":"trace[1975769624] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:8752; }","duration":"270.319292ms","start":"2026-09-01T18:45:56.987233Z","end":"2026-09-01T18:45:57.257552Z","steps":["trace[1975769624] 'count revisions from in-memory index tree' (duration: 270.061084ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:45:57.257649Z","caller":"traceutil/trace.go:171","msg":"trace[348070106] range","detail":"{range_begin:/registry/pods/openstack/keepalived-2jcw6; range_end:; response_count:1; response_revision:8752; }","duration":"335.577653ms","start":"2026-09-01T18:45:56.922043Z","end":"2026-09-01T18:45:57.25762Z","steps":["trace[348070106] 'range keys from in-memory index tree' (duration: 335.183822ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:45:57.257693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:45:56.921996Z","time spent":"335.687767ms","remote":"127.0.0.1:39134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6546,"request content":"key:\"/registry/pods/openstack/keepalived-2jcw6\" "} {"level":"info","ts":"2026-09-01T18:45:57.25876Z","caller":"traceutil/trace.go:171","msg":"trace[1436727224] transaction","detail":"{read_only:false; response_revision:8753; number_of_response:1; }","duration":"178.476358ms","start":"2026-09-01T18:45:57.080251Z","end":"2026-09-01T18:45:57.258727Z","steps":["trace[1436727224] 'process raft request' (duration: 178.303993ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:45:57.259656Z","caller":"traceutil/trace.go:171","msg":"trace[581145513] linearizableReadLoop","detail":"{readStateIndex:9062; appliedIndex:9060; }","duration":"114.680029ms","start":"2026-09-01T18:45:57.144938Z","end":"2026-09-01T18:45:57.259618Z","steps":["trace[581145513] 'read index received' (duration: 113.746793ms)","trace[581145513] 'applied index is now lower than readState.Index' (duration: 931.926µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:45:57.259861Z","caller":"traceutil/trace.go:171","msg":"trace[255750172] transaction","detail":"{read_only:false; response_revision:8754; number_of_response:1; }","duration":"149.433922ms","start":"2026-09-01T18:45:57.110346Z","end":"2026-09-01T18:45:57.25978Z","steps":["trace[255750172] 'process raft request' (duration: 149.132833ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:45:57.259968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.01201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-01T18:45:57.260047Z","caller":"traceutil/trace.go:171","msg":"trace[136979619] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:8754; }","duration":"115.117083ms","start":"2026-09-01T18:45:57.144885Z","end":"2026-09-01T18:45:57.260002Z","steps":["trace[136979619] 'agreement among raft nodes before linearized reading' (duration: 114.917498ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:46:07.44154Z","caller":"traceutil/trace.go:171","msg":"trace[693872927] transaction","detail":"{read_only:false; response_revision:8807; number_of_response:1; }","duration":"120.404547ms","start":"2026-09-01T18:46:07.321103Z","end":"2026-09-01T18:46:07.441508Z","steps":["trace[693872927] 'process raft request' (duration: 120.195801ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:46:07.949798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.226431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-01T18:46:07.949928Z","caller":"traceutil/trace.go:171","msg":"trace[1530244851] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:8811; }","duration":"188.435928ms","start":"2026-09-01T18:46:07.761465Z","end":"2026-09-01T18:46:07.949901Z","steps":["trace[1530244851] 'count revisions from in-memory index tree' (duration: 188.067806ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:46:08.86221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.505933ms","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-09-01T18:46:08.862305Z","caller":"traceutil/trace.go:171","msg":"trace[1946467701] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8814; }","duration":"105.656317ms","start":"2026-09-01T18:46:08.756624Z","end":"2026-09-01T18:46:08.86228Z","steps":["trace[1946467701] 'range keys from in-memory index tree' (duration: 105.243085ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:46:41.2819Z","caller":"traceutil/trace.go:171","msg":"trace[408583787] transaction","detail":"{read_only:false; response_revision:9041; number_of_response:1; }","duration":"181.828846ms","start":"2026-09-01T18:46:41.100016Z","end":"2026-09-01T18:46:41.281845Z","steps":["trace[408583787] 'process raft request' (duration: 181.200907ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:46:54.653634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.711061ms","expected-duration":"100ms","prefix":"","request":"header: txn: success: > failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-01T18:46:54.653744Z","caller":"traceutil/trace.go:171","msg":"trace[874413259] transaction","detail":"{read_only:false; number_of_response:1; response_revision:9155; }","duration":"260.95868ms","start":"2026-09-01T18:46:54.392765Z","end":"2026-09-01T18:46:54.653724Z","steps":["trace[874413259] 'process raft request' (duration: 133.931501ms)","trace[874413259] 'compare' (duration: 126.609297ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:46:54.662583Z","caller":"traceutil/trace.go:171","msg":"trace[238168842] transaction","detail":"{read_only:false; response_revision:9156; number_of_response:1; }","duration":"269.269192ms","start":"2026-09-01T18:46:54.392992Z","end":"2026-09-01T18:46:54.662261Z","steps":["trace[238168842] 'process raft request' (duration: 269.114607ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:46:55.045634Z","caller":"traceutil/trace.go:171","msg":"trace[703267513] transaction","detail":"{read_only:false; response_revision:9159; number_of_response:1; }","duration":"118.665056ms","start":"2026-09-01T18:46:54.926938Z","end":"2026-09-01T18:46:55.045603Z","steps":["trace[703267513] 'process raft request' (duration: 100.436395ms)","trace[703267513] 'compare' (duration: 17.721426ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:46:55.398726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.695946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-l4fsb\" ","response":"range_response_count:1 size:4650"} {"level":"info","ts":"2026-09-01T18:46:55.398742Z","caller":"traceutil/trace.go:171","msg":"trace[364622280] transaction","detail":"{read_only:false; response_revision:9160; number_of_response:1; }","duration":"106.704358ms","start":"2026-09-01T18:46:55.292012Z","end":"2026-09-01T18:46:55.398716Z","steps":["trace[364622280] 'process raft request' (duration: 60.282486ms)","trace[364622280] 'compare' (duration: 46.297948ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:46:55.398799Z","caller":"traceutil/trace.go:171","msg":"trace[1752314539] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-l4fsb; range_end:; response_count:1; response_revision:9159; }","duration":"107.82068ms","start":"2026-09-01T18:46:55.290964Z","end":"2026-09-01T18:46:55.398785Z","steps":["trace[1752314539] 'range keys from in-memory index tree' (duration: 107.544422ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:46:55.418477Z","caller":"traceutil/trace.go:171","msg":"trace[2002033822] transaction","detail":"{read_only:false; response_revision:9161; number_of_response:1; }","duration":"118.615005ms","start":"2026-09-01T18:46:55.299814Z","end":"2026-09-01T18:46:55.418429Z","steps":["trace[2002033822] 'process raft request' (duration: 118.346077ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:46:55.941615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.502164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-09-01T18:46:55.941735Z","caller":"traceutil/trace.go:171","msg":"trace[709539722] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:9165; }","duration":"149.673149ms","start":"2026-09-01T18:46:55.792021Z","end":"2026-09-01T18:46:55.941694Z","steps":["trace[709539722] 'range keys from in-memory index tree' (duration: 149.229976ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:46:55.94174Z","caller":"traceutil/trace.go:171","msg":"trace[1421182573] transaction","detail":"{read_only:false; response_revision:9166; number_of_response:1; }","duration":"123.676803ms","start":"2026-09-01T18:46:55.81802Z","end":"2026-09-01T18:46:55.941697Z","steps":["trace[1421182573] 'process raft request' (duration: 48.557105ms)","trace[1421182573] 'compare' (duration: 74.925792ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:47:05.182787Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000257c.wal"} {"level":"info","ts":"2026-09-01T18:47:45.750263Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3fd97315619643d2","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-01T18:47:45.756489Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-09-01T18:47:45.756619Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-09-01T18:48:10.285049Z","caller":"traceutil/trace.go:171","msg":"trace[846138725] transaction","detail":"{read_only:false; response_revision:9835; number_of_response:1; }","duration":"229.347534ms","start":"2026-09-01T18:48:10.055631Z","end":"2026-09-01T18:48:10.284979Z","steps":["trace[846138725] 'process raft request' (duration: 229.159198ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:48:10.29223Z","caller":"traceutil/trace.go:171","msg":"trace[1677739713] transaction","detail":"{read_only:false; response_revision:9836; number_of_response:1; }","duration":"223.045371ms","start":"2026-09-01T18:48:10.069157Z","end":"2026-09-01T18:48:10.292202Z","steps":["trace[1677739713] 'process raft request' (duration: 222.868326ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:48:10.526549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.639023ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-09-01T18:48:10.526718Z","caller":"traceutil/trace.go:171","msg":"trace[1722950653] linearizableReadLoop","detail":"{readStateIndex:10181; appliedIndex:10180; }","duration":"214.870132ms","start":"2026-09-01T18:48:10.311821Z","end":"2026-09-01T18:48:10.526692Z","steps":["trace[1722950653] 'read index received' (duration: 33.978922ms)","trace[1722950653] 'applied index is now lower than readState.Index' (duration: 180.8893ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:48:10.527079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.231831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-2jcw6\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-09-01T18:48:10.527209Z","caller":"traceutil/trace.go:171","msg":"trace[586927234] range","detail":"{range_begin:/registry/pods/openstack/keepalived-2jcw6; range_end:; response_count:1; response_revision:9836; }","duration":"215.416217ms","start":"2026-09-01T18:48:10.311765Z","end":"2026-09-01T18:48:10.527181Z","steps":["trace[586927234] 'agreement among raft nodes before linearized reading' (duration: 214.985224ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:49:31.575462Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8049} {"level":"info","ts":"2026-09-01T18:49:31.663748Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8049,"took":"85.65177ms","hash":3441008970,"current-db-size-bytes":54796288,"current-db-size":"55 MB","current-db-size-in-use-bytes":26390528,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-01T18:49:31.663825Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3441008970,"revision":8049,"compact-revision":5564} {"level":"info","ts":"2026-09-01T18:51:55.416753Z","caller":"traceutil/trace.go:171","msg":"trace[1220083318] transaction","detail":"{read_only:false; response_revision:11785; number_of_response:1; }","duration":"213.15362ms","start":"2026-09-01T18:51:55.20356Z","end":"2026-09-01T18:51:55.416714Z","steps":["trace[1220083318] 'process raft request' (duration: 212.882542ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:51:55.552496Z","caller":"traceutil/trace.go:171","msg":"trace[1012208944] linearizableReadLoop","detail":"{readStateIndex:12189; appliedIndex:12187; }","duration":"326.626268ms","start":"2026-09-01T18:51:55.225848Z","end":"2026-09-01T18:51:55.552474Z","steps":["trace[1012208944] 'read index received' (duration: 190.547732ms)","trace[1012208944] 'applied index is now lower than readState.Index' (duration: 136.077516ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:51:55.552609Z","caller":"traceutil/trace.go:171","msg":"trace[995394065] transaction","detail":"{read_only:false; response_revision:11786; number_of_response:1; }","duration":"332.976005ms","start":"2026-09-01T18:51:55.219624Z","end":"2026-09-01T18:51:55.5526Z","steps":["trace[995394065] 'process raft request' (duration: 279.388834ms)","trace[995394065] 'compare' (duration: 53.255342ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:51:55.552699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:51:55.219577Z","time spent":"333.049617ms","remote":"127.0.0.1:39218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T18:51:55.552698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.598465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T18:51:55.552778Z","caller":"traceutil/trace.go:171","msg":"trace[1622863579] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:11786; }","duration":"127.690898ms","start":"2026-09-01T18:51:55.425068Z","end":"2026-09-01T18:51:55.552758Z","steps":["trace[1622863579] 'agreement among raft nodes before linearized reading' (duration: 127.558394ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:51:55.552981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.140572ms","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":"info","ts":"2026-09-01T18:51:55.55301Z","caller":"traceutil/trace.go:171","msg":"trace[1434418486] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:11786; }","duration":"327.192594ms","start":"2026-09-01T18:51:55.225807Z","end":"2026-09-01T18:51:55.553Z","steps":["trace[1434418486] 'agreement among raft nodes before linearized reading' (duration: 327.076711ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:51:55.553028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:51:55.225798Z","time spent":"327.225845ms","remote":"127.0.0.1:39218","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-09-01T18:54:31.592469Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10504} {"level":"info","ts":"2026-09-01T18:54:31.688962Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10504,"took":"92.996588ms","hash":3959100607,"current-db-size-bytes":54796288,"current-db-size":"55 MB","current-db-size-in-use-bytes":28753920,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-09-01T18:54:31.689034Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3959100607,"revision":10504,"compact-revision":8049} {"level":"info","ts":"2026-09-01T18:57:45.169787Z","caller":"traceutil/trace.go:171","msg":"trace[1585014033] transaction","detail":"{read_only:false; response_revision:14175; number_of_response:1; }","duration":"121.153874ms","start":"2026-09-01T18:57:45.048611Z","end":"2026-09-01T18:57:45.169765Z","steps":["trace[1585014033] 'process raft request' (duration: 121.029171ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:58:26.890831Z","caller":"traceutil/trace.go:171","msg":"trace[2061970564] transaction","detail":"{read_only:false; response_revision:14407; number_of_response:1; }","duration":"114.258542ms","start":"2026-09-01T18:58:26.776466Z","end":"2026-09-01T18:58:26.890725Z","steps":["trace[2061970564] 'process raft request' (duration: 114.072667ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:58:26.942817Z","caller":"traceutil/trace.go:171","msg":"trace[865789905] transaction","detail":"{read_only:false; response_revision:14408; number_of_response:1; }","duration":"164.232588ms","start":"2026-09-01T18:58:26.778551Z","end":"2026-09-01T18:58:26.942784Z","steps":["trace[865789905] 'process raft request' (duration: 149.630789ms)","trace[865789905] 'compare' (duration: 14.475966ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:58:27.819533Z","caller":"traceutil/trace.go:171","msg":"trace[1488703101] linearizableReadLoop","detail":"{readStateIndex:14907; appliedIndex:14906; }","duration":"288.655409ms","start":"2026-09-01T18:58:27.530845Z","end":"2026-09-01T18:58:27.819501Z","steps":["trace[1488703101] 'read index received' (duration: 288.390721ms)","trace[1488703101] 'applied index is now lower than readState.Index' (duration: 263.838µs)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:58:27.819809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.946157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-x8hcq\" ","response":"range_response_count:1 size:12364"} {"level":"info","ts":"2026-09-01T18:58:27.819848Z","caller":"traceutil/trace.go:171","msg":"trace[58011396] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-x8hcq; range_end:; response_count:1; response_revision:14412; }","duration":"289.07431ms","start":"2026-09-01T18:58:27.530764Z","end":"2026-09-01T18:58:27.819838Z","steps":["trace[58011396] 'agreement among raft nodes before linearized reading' (duration: 288.851094ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:58:27.820198Z","caller":"traceutil/trace.go:171","msg":"trace[335072337] transaction","detail":"{read_only:false; response_revision:14412; number_of_response:1; }","duration":"424.509075ms","start":"2026-09-01T18:58:27.395679Z","end":"2026-09-01T18:58:27.820188Z","steps":["trace[335072337] 'process raft request' (duration: 423.64977ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:58:27.820303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:58:27.395636Z","time spent":"424.594337ms","remote":"127.0.0.1:39218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T18:58:38.670671Z","caller":"traceutil/trace.go:171","msg":"trace[2106959823] linearizableReadLoop","detail":"{readStateIndex:15078; appliedIndex:15077; }","duration":"423.805595ms","start":"2026-09-01T18:58:38.246839Z","end":"2026-09-01T18:58:38.670645Z","steps":["trace[2106959823] 'read index received' (duration: 423.572708ms)","trace[2106959823] 'applied index is now lower than readState.Index' (duration: 232.167µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:58:38.670728Z","caller":"traceutil/trace.go:171","msg":"trace[1801930361] transaction","detail":"{read_only:false; response_revision:14580; number_of_response:1; }","duration":"450.668062ms","start":"2026-09-01T18:58:38.220029Z","end":"2026-09-01T18:58:38.670697Z","steps":["trace[1801930361] 'process raft request' (duration: 450.459856ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:58:38.670884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:58:38.220002Z","time spent":"450.785195ms","remote":"127.0.0.1:39218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T18:58:38.670931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.083483ms","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-09-01T18:58:38.670966Z","caller":"traceutil/trace.go:171","msg":"trace[212633115] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14580; }","duration":"424.172815ms","start":"2026-09-01T18:58:38.246781Z","end":"2026-09-01T18:58:38.670954Z","steps":["trace[212633115] 'agreement among raft nodes before linearized reading' (duration: 423.99275ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:58:38.671001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:58:38.246755Z","time spent":"424.238337ms","remote":"127.0.0.1:39218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-01T18:58:38.671068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.98528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-09-01T18:58:38.671154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.039672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-2jcw6\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-09-01T18:58:38.671194Z","caller":"traceutil/trace.go:171","msg":"trace[474047130] range","detail":"{range_begin:/registry/pods/openstack/keepalived-2jcw6; range_end:; response_count:1; response_revision:14580; }","duration":"421.103084ms","start":"2026-09-01T18:58:38.25008Z","end":"2026-09-01T18:58:38.671183Z","steps":["trace[474047130] 'agreement among raft nodes before linearized reading' (duration: 421.007522ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:58:38.671223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:58:38.250066Z","time spent":"421.151195ms","remote":"127.0.0.1:39134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6546,"request content":"key:\"/registry/pods/openstack/keepalived-2jcw6\" "} {"level":"info","ts":"2026-09-01T18:58:38.671153Z","caller":"traceutil/trace.go:171","msg":"trace[252017546] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14580; }","duration":"422.120774ms","start":"2026-09-01T18:58:38.249014Z","end":"2026-09-01T18:58:38.671135Z","steps":["trace[252017546] 'agreement among raft nodes before linearized reading' (duration: 421.831586ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:58:38.671349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:58:38.249003Z","time spent":"422.334271ms","remote":"127.0.0.1:39218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-01T18:58:38.671817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.557628ms","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":"info","ts":"2026-09-01T18:58:38.671868Z","caller":"traceutil/trace.go:171","msg":"trace[142399830] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:14580; }","duration":"421.95237ms","start":"2026-09-01T18:58:38.2499Z","end":"2026-09-01T18:58:38.671852Z","steps":["trace[142399830] 'agreement among raft nodes before linearized reading' (duration: 421.278259ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:58:38.672089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:58:38.24989Z","time spent":"422.184906ms","remote":"127.0.0.1:39218","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":"warn","ts":"2026-09-01T18:58:38.672401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.809366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/ovn-controller-px4dj.18d1477a252e9dc9\" ","response":"range_response_count:1 size:759"} {"level":"info","ts":"2026-09-01T18:58:38.672957Z","caller":"traceutil/trace.go:171","msg":"trace[34496051] range","detail":"{range_begin:/registry/events/openstack/ovn-controller-px4dj.18d1477a252e9dc9; range_end:; response_count:1; response_revision:14580; }","duration":"164.452394ms","start":"2026-09-01T18:58:38.50826Z","end":"2026-09-01T18:58:38.672713Z","steps":["trace[34496051] 'agreement among raft nodes before linearized reading' (duration: 163.018542ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:59:31.599978Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12854} {"level":"info","ts":"2026-09-01T18:59:31.689011Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12854,"took":"85.379974ms","hash":3540720857,"current-db-size-bytes":54796288,"current-db-size":"55 MB","current-db-size-in-use-bytes":29184000,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-09-01T18:59:31.689275Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3540720857,"revision":12854,"compact-revision":10504} {"level":"warn","ts":"2026-09-01T19:00:22.177486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.820857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T19:00:22.17757Z","caller":"traceutil/trace.go:171","msg":"trace[955823052] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15716; }","duration":"203.968232ms","start":"2026-09-01T19:00:21.973585Z","end":"2026-09-01T19:00:22.177553Z","steps":["trace[955823052] 'range keys from in-memory index tree' (duration: 203.665823ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T19:00:22.177811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.504712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-2jcw6\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-09-01T19:00:22.177836Z","caller":"traceutil/trace.go:171","msg":"trace[719363872] range","detail":"{range_begin:/registry/pods/openstack/keepalived-2jcw6; range_end:; response_count:1; response_revision:15716; }","duration":"197.553684ms","start":"2026-09-01T19:00:21.980275Z","end":"2026-09-01T19:00:22.177828Z","steps":["trace[719363872] 'range keys from in-memory index tree' (duration: 197.338857ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T19:04:31.608435Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15105} {"level":"info","ts":"2026-09-01T19:04:31.710283Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15105,"took":"97.567078ms","hash":1563063108,"current-db-size-bytes":54796288,"current-db-size":"55 MB","current-db-size-in-use-bytes":31125504,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-01T19:04:31.710366Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1563063108,"revision":15105,"compact-revision":12854} {"level":"info","ts":"2026-09-01T19:06:00.251345Z","caller":"traceutil/trace.go:171","msg":"trace[1085667494] linearizableReadLoop","detail":"{readStateIndex:18887; appliedIndex:18886; }","duration":"194.427959ms","start":"2026-09-01T19:06:00.056888Z","end":"2026-09-01T19:06:00.251316Z","steps":["trace[1085667494] 'read index received' (duration: 129.490156ms)","trace[1085667494] 'applied index is now lower than readState.Index' (duration: 64.936123ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T19:06:00.251435Z","caller":"traceutil/trace.go:171","msg":"trace[1699425493] transaction","detail":"{read_only:false; response_revision:18270; number_of_response:1; }","duration":"200.725466ms","start":"2026-09-01T19:06:00.050626Z","end":"2026-09-01T19:06:00.251352Z","steps":["trace[1699425493] 'process raft request' (duration: 135.776511ms)","trace[1699425493] 'compare' (duration: 64.733058ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T19:06:00.282084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.318801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:898"} {"level":"warn","ts":"2026-09-01T19:06:00.282089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.174926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-heat-server\" ","response":"range_response_count:1 size:9566"} {"level":"info","ts":"2026-09-01T19:06:00.282179Z","caller":"traceutil/trace.go:171","msg":"trace[736331397] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-heat-server; range_end:; response_count:1; response_revision:18270; }","duration":"225.303811ms","start":"2026-09-01T19:06:00.056851Z","end":"2026-09-01T19:06:00.282155Z","steps":["trace[736331397] 'agreement among raft nodes before linearized reading' (duration: 194.677168ms)","trace[736331397] 'range keys from in-memory index tree' (duration: 30.373215ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T19:06:00.283006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.686643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1822"} {"level":"info","ts":"2026-09-01T19:06:00.283059Z","caller":"traceutil/trace.go:171","msg":"trace[1411704552] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:18270; }","duration":"203.782256ms","start":"2026-09-01T19:06:00.079261Z","end":"2026-09-01T19:06:00.283043Z","steps":["trace[1411704552] 'agreement among raft nodes before linearized reading' (duration: 203.618781ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T19:06:00.284677Z","caller":"traceutil/trace.go:171","msg":"trace[1787006390] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:18270; }","duration":"205.421484ms","start":"2026-09-01T19:06:00.076724Z","end":"2026-09-01T19:06:00.282146Z","steps":["trace[1787006390] 'agreement among raft nodes before linearized reading' (duration: 205.185867ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T19:06:00.576103Z","caller":"traceutil/trace.go:171","msg":"trace[1939431218] linearizableReadLoop","detail":"{readStateIndex:18889; appliedIndex:18888; }","duration":"277.455728ms","start":"2026-09-01T19:06:00.298607Z","end":"2026-09-01T19:06:00.576062Z","steps":["trace[1939431218] 'read index received' (duration: 277.16186ms)","trace[1939431218] 'applied index is now lower than readState.Index' (duration: 292.418µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T19:06:00.576236Z","caller":"traceutil/trace.go:171","msg":"trace[800010305] transaction","detail":"{read_only:false; response_revision:18271; number_of_response:1; }","duration":"283.506577ms","start":"2026-09-01T19:06:00.292681Z","end":"2026-09-01T19:06:00.576187Z","steps":["trace[800010305] 'process raft request' (duration: 283.115315ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T19:06:00.576568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.424081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3685"} {"level":"warn","ts":"2026-09-01T19:06:00.576562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.904902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-heat\" ","response":"range_response_count:1 size:3227"} {"level":"info","ts":"2026-09-01T19:06:00.576649Z","caller":"traceutil/trace.go:171","msg":"trace[893740160] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:18271; }","duration":"251.577105ms","start":"2026-09-01T19:06:00.325046Z","end":"2026-09-01T19:06:00.576623Z","steps":["trace[893740160] 'agreement among raft nodes before linearized reading' (duration: 251.262346ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T19:06:00.576677Z","caller":"traceutil/trace.go:171","msg":"trace[1541273129] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-heat; range_end:; response_count:1; response_revision:18271; }","duration":"278.049616ms","start":"2026-09-01T19:06:00.298596Z","end":"2026-09-01T19:06:00.576646Z","steps":["trace[1541273129] 'agreement among raft nodes before linearized reading' (duration: 277.621364ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T19:06:00.576667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.43475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-09-01T19:06:00.576769Z","caller":"traceutil/trace.go:171","msg":"trace[147419307] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:18271; }","duration":"234.605306ms","start":"2026-09-01T19:06:00.34214Z","end":"2026-09-01T19:06:00.576745Z","steps":["trace[147419307] 'agreement among raft nodes before linearized reading' (duration: 234.277456ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T19:06:01.045043Z","caller":"traceutil/trace.go:171","msg":"trace[1258790255] transaction","detail":"{read_only:false; response_revision:18272; number_of_response:1; }","duration":"460.432191ms","start":"2026-09-01T19:06:00.584574Z","end":"2026-09-01T19:06:01.045006Z","steps":["trace[1258790255] 'process raft request' (duration: 460.114172ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T19:06:01.045063Z","caller":"traceutil/trace.go:171","msg":"trace[269498113] linearizableReadLoop","detail":"{readStateIndex:18890; appliedIndex:18889; }","duration":"447.736906ms","start":"2026-09-01T19:06:00.597223Z","end":"2026-09-01T19:06:01.04496Z","steps":["trace[269498113] 'read index received' (duration: 447.484319ms)","trace[269498113] 'applied index is now lower than readState.Index' (duration: 250.797µs)"],"step_count":2} {"level":"warn","ts":"2026-09-01T19:06:01.045257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T19:06:00.584545Z","time spent":"460.594616ms","remote":"127.0.0.1:39218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T19:06:01.045505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.21743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-09-01T19:06:01.045584Z","caller":"traceutil/trace.go:171","msg":"trace[1149454367] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:18272; }","duration":"448.371605ms","start":"2026-09-01T19:06:00.597188Z","end":"2026-09-01T19:06:01.04556Z","steps":["trace[1149454367] 'agreement among raft nodes before linearized reading' (duration: 448.054296ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T19:06:01.045636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T19:06:00.597172Z","time spent":"448.450949ms","remote":"127.0.0.1:39048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":1,"response size":1365,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" "} {"level":"info","ts":"2026-09-01T19:06:01.064351Z","caller":"traceutil/trace.go:171","msg":"trace[1496716437] transaction","detail":"{read_only:false; response_revision:18273; number_of_response:1; }","duration":"408.241502ms","start":"2026-09-01T19:06:00.656087Z","end":"2026-09-01T19:06:01.064329Z","steps":["trace[1496716437] 'process raft request' (duration: 407.890622ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T19:06:01.064481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T19:06:00.656062Z","time spent":"408.342525ms","remote":"127.0.0.1:39218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T19:06:01.064588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.514348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ec77446a-1085-4c5a-bb4b-0d906294854c\" ","response":"range_response_count:1 size:3640"} {"level":"warn","ts":"2026-09-01T19:06:01.064646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.219352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-heat-nodes\" ","response":"range_response_count:1 size:1248"} {"level":"info","ts":"2026-09-01T19:06:01.064688Z","caller":"traceutil/trace.go:171","msg":"trace[1409293990] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ec77446a-1085-4c5a-bb4b-0d906294854c; range_end:; response_count:1; response_revision:18274; }","duration":"222.651612ms","start":"2026-09-01T19:06:00.842015Z","end":"2026-09-01T19:06:01.064667Z","steps":["trace[1409293990] 'agreement among raft nodes before linearized reading' (duration: 222.371714ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T19:06:01.064665Z","caller":"traceutil/trace.go:171","msg":"trace[1401394648] transaction","detail":"{read_only:false; response_revision:18274; number_of_response:1; }","duration":"177.486831ms","start":"2026-09-01T19:06:00.887122Z","end":"2026-09-01T19:06:01.064609Z","steps":["trace[1401394648] 'process raft request' (duration: 177.063239ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T19:06:01.064695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.513932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-wpccw\" ","response":"range_response_count:1 size:20755"} {"level":"warn","ts":"2026-09-01T19:06:01.064766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.084392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-29804820-xj2bc\" ","response":"range_response_count:1 size:7041"} {"level":"info","ts":"2026-09-01T19:06:01.06473Z","caller":"traceutil/trace.go:171","msg":"trace[70292196] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-heat-nodes; range_end:; response_count:1; response_revision:18274; }","duration":"465.341096ms","start":"2026-09-01T19:06:00.599366Z","end":"2026-09-01T19:06:01.064707Z","steps":["trace[70292196] 'agreement among raft nodes before linearized reading' (duration: 465.064668ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T19:06:01.064811Z","caller":"traceutil/trace.go:171","msg":"trace[468360260] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-wpccw; range_end:; response_count:1; response_revision:18274; }","duration":"384.680077ms","start":"2026-09-01T19:06:00.680106Z","end":"2026-09-01T19:06:01.064786Z","steps":["trace[468360260] 'agreement among raft nodes before linearized reading' (duration: 384.292296ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T19:06:01.064831Z","caller":"traceutil/trace.go:171","msg":"trace[396217842] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-29804820-xj2bc; range_end:; response_count:1; response_revision:18274; }","duration":"456.190455ms","start":"2026-09-01T19:06:00.608627Z","end":"2026-09-01T19:06:01.064818Z","steps":["trace[396217842] 'agreement among raft nodes before linearized reading' (duration: 455.978149ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T19:06:01.064853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T19:06:00.599353Z","time spent":"465.48638ms","remote":"127.0.0.1:39144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1270,"request content":"key:\"/registry/services/specs/openstack/rabbitmq-heat-nodes\" "} {"level":"warn","ts":"2026-09-01T19:06:01.064871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T19:06:00.608599Z","time spent":"456.263908ms","remote":"127.0.0.1:39134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":7063,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-29804820-xj2bc\" "} {"level":"warn","ts":"2026-09-01T19:06:01.064878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T19:06:00.680091Z","time spent":"384.774451ms","remote":"127.0.0.1:39134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":20777,"request content":"key:\"/registry/pods/openstack/libvirt-libvirt-default-wpccw\" "} {"level":"info","ts":"2026-09-01T19:07:57.84501Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3fd97315619643d2","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-01T19:07:57.852256Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-09-01T19:07:57.852395Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-09-01T19:09:31.615291Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17569} {"level":"info","ts":"2026-09-01T19:09:31.721801Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17569,"took":"102.41701ms","hash":249850482,"current-db-size-bytes":54796288,"current-db-size":"55 MB","current-db-size-in-use-bytes":34758656,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-09-01T19:09:31.721892Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":249850482,"revision":17569,"compact-revision":15105} {"level":"info","ts":"2026-09-01T19:11:46.756446Z","caller":"traceutil/trace.go:171","msg":"trace[1827478750] transaction","detail":"{read_only:false; response_revision:20915; number_of_response:1; }","duration":"146.218966ms","start":"2026-09-01T19:11:46.610194Z","end":"2026-09-01T19:11:46.756413Z","steps":["trace[1827478750] 'process raft request' (duration: 146.043231ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T19:11:52.772516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.35988ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-01T19:11:52.772663Z","caller":"traceutil/trace.go:171","msg":"trace[746326024] transaction","detail":"{read_only:false; response_revision:20944; number_of_response:1; }","duration":"195.849535ms","start":"2026-09-01T19:11:52.576784Z","end":"2026-09-01T19:11:52.772634Z","steps":["trace[746326024] 'process raft request' (duration: 32.228117ms)","trace[746326024] 'compare' (duration: 162.925197ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T19:11:53.032663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.758165ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-01T19:11:53.033105Z","caller":"traceutil/trace.go:171","msg":"trace[1818999100] transaction","detail":"{read_only:false; response_revision:20945; number_of_response:1; }","duration":"255.273671ms","start":"2026-09-01T19:11:52.777745Z","end":"2026-09-01T19:11:53.033018Z","steps":["trace[1818999100] 'process raft request' (duration: 146.056652ms)","trace[1818999100] 'compare' (duration: 108.614451ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T19:11:53.575898Z","caller":"traceutil/trace.go:171","msg":"trace[1164100677] transaction","detail":"{read_only:false; response_revision:20946; number_of_response:1; }","duration":"245.857914ms","start":"2026-09-01T19:11:53.330007Z","end":"2026-09-01T19:11:53.575865Z","steps":["trace[1164100677] 'process raft request' (duration: 245.668638ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T19:11:53.625096Z","caller":"traceutil/trace.go:171","msg":"trace[421031870] transaction","detail":"{read_only:false; response_revision:20948; number_of_response:1; }","duration":"293.732541ms","start":"2026-09-01T19:11:53.331336Z","end":"2026-09-01T19:11:53.625069Z","steps":["trace[421031870] 'process raft request' (duration: 293.625468ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T19:11:53.625222Z","caller":"traceutil/trace.go:171","msg":"trace[1767896312] linearizableReadLoop","detail":"{readStateIndex:21656; appliedIndex:21653; }","duration":"231.582155ms","start":"2026-09-01T19:11:53.393628Z","end":"2026-09-01T19:11:53.62521Z","steps":["trace[1767896312] 'read index received' (duration: 182.138222ms)","trace[1767896312] 'applied index is now lower than readState.Index' (duration: 49.442323ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T19:11:53.62512Z","caller":"traceutil/trace.go:171","msg":"trace[361063325] transaction","detail":"{read_only:false; response_revision:20947; number_of_response:1; }","duration":"293.862535ms","start":"2026-09-01T19:11:53.331224Z","end":"2026-09-01T19:11:53.625087Z","steps":["trace[361063325] 'process raft request' (duration: 293.554405ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T19:11:53.625336Z","caller":"traceutil/trace.go:171","msg":"trace[1952062656] transaction","detail":"{read_only:false; response_revision:20949; number_of_response:1; }","duration":"101.467212ms","start":"2026-09-01T19:11:53.523845Z","end":"2026-09-01T19:11:53.625312Z","steps":["trace[1952062656] 'process raft request' (duration: 101.171424ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T19:11:53.625404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.755741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-01T19:11:53.625449Z","caller":"traceutil/trace.go:171","msg":"trace[565218006] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; response_count:0; response_revision:20949; }","duration":"232.07909ms","start":"2026-09-01T19:11:53.393355Z","end":"2026-09-01T19:11:53.625434Z","steps":["trace[565218006] 'agreement among raft nodes before linearized reading' (duration: 231.933016ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T19:14:31.631093Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19990} {"level":"info","ts":"2026-09-01T19:14:31.752861Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19990,"took":"116.816336ms","hash":2602144736,"current-db-size-bytes":54796288,"current-db-size":"55 MB","current-db-size-in-use-bytes":29581312,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-01T19:14:31.752953Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2602144736,"revision":19990,"compact-revision":17569} {"level":"info","ts":"2026-09-01T19:15:55.466864Z","caller":"traceutil/trace.go:171","msg":"trace[559900718] linearizableReadLoop","detail":"{readStateIndex:24227; appliedIndex:24226; }","duration":"413.928806ms","start":"2026-09-01T19:15:55.0529Z","end":"2026-09-01T19:15:55.466829Z","steps":["trace[559900718] 'read index received' (duration: 319.831707ms)","trace[559900718] 'applied index is now lower than readState.Index' (duration: 94.095529ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T19:15:55.467212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.519795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-01T19:15:55.467247Z","caller":"traceutil/trace.go:171","msg":"trace[786543698] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23441; }","duration":"412.598728ms","start":"2026-09-01T19:15:55.054636Z","end":"2026-09-01T19:15:55.467235Z","steps":["trace[786543698] 'agreement among raft nodes before linearized reading' (duration: 412.400692ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T19:15:55.46722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.310627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-09-01T19:15:55.467286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T19:15:55.054628Z","time spent":"412.647979ms","remote":"127.0.0.1:39218","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":"info","ts":"2026-09-01T19:15:55.467325Z","caller":"traceutil/trace.go:171","msg":"trace[949405788] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:23441; }","duration":"414.456411ms","start":"2026-09-01T19:15:55.052839Z","end":"2026-09-01T19:15:55.467295Z","steps":["trace[949405788] 'agreement among raft nodes before linearized reading' (duration: 414.163103ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T19:15:55.467379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T19:15:55.052826Z","time spent":"414.535933ms","remote":"127.0.0.1:39218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-01T19:15:55.467353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.934501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"warn","ts":"2026-09-01T19:15:55.467361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.7116ms","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-09-01T19:15:55.467478Z","caller":"traceutil/trace.go:171","msg":"trace[678153931] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:23441; }","duration":"148.126487ms","start":"2026-09-01T19:15:55.319307Z","end":"2026-09-01T19:15:55.467434Z","steps":["trace[678153931] 'agreement among raft nodes before linearized reading' (duration: 147.754987ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T19:15:55.467492Z","caller":"traceutil/trace.go:171","msg":"trace[961364478] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23441; }","duration":"293.896185ms","start":"2026-09-01T19:15:55.173567Z","end":"2026-09-01T19:15:55.467463Z","steps":["trace[961364478] 'agreement among raft nodes before linearized reading' (duration: 293.583196ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T19:15:55.46781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.670157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-01T19:15:55.467921Z","caller":"traceutil/trace.go:171","msg":"trace[1850113003] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:23441; }","duration":"346.828232ms","start":"2026-09-01T19:15:55.121071Z","end":"2026-09-01T19:15:55.467899Z","steps":["trace[1850113003] 'agreement among raft nodes before linearized reading' (duration: 346.584545ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T19:15:55.467964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T19:15:55.121047Z","time spent":"346.906733ms","remote":"127.0.0.1:39270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"info","ts":"2026-09-01T19:15:55.467976Z","caller":"traceutil/trace.go:171","msg":"trace[697051588] transaction","detail":"{read_only:false; response_revision:23441; number_of_response:1; }","duration":"418.456059ms","start":"2026-09-01T19:15:55.049497Z","end":"2026-09-01T19:15:55.467953Z","steps":["trace[697051588] 'process raft request' (duration: 323.362702ms)","trace[697051588] 'compare' (duration: 93.801219ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T19:15:55.468106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T19:15:55.049474Z","time spent":"418.547012ms","remote":"127.0.0.1:39218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":518,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T19:17:26.528083Z","caller":"traceutil/trace.go:171","msg":"trace[351033630] transaction","detail":"{read_only:false; response_revision:24392; number_of_response:1; }","duration":"114.432735ms","start":"2026-09-01T19:17:26.413622Z","end":"2026-09-01T19:17:26.528055Z","steps":["trace[351033630] 'process raft request' (duration: 113.953742ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T19:19:31.639147Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22163} {"level":"info","ts":"2026-09-01T19:19:31.758734Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22163,"took":"114.367309ms","hash":3031468597,"current-db-size-bytes":56066048,"current-db-size":"56 MB","current-db-size-in-use-bytes":43323392,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-09-01T19:19:31.75881Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3031468597,"revision":22163,"compact-revision":19990} {"level":"info","ts":"2026-09-01T19:24:31.648016Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25335} {"level":"info","ts":"2026-09-01T19:24:31.809246Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25335,"took":"154.231005ms","hash":3912502404,"current-db-size-bytes":56066048,"current-db-size":"56 MB","current-db-size-in-use-bytes":29827072,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-01T19:24:31.809336Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3912502404,"revision":25335,"compact-revision":22163}