{"level":"warn","ts":"2026-09-09T01:08:18.884429Z","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-09T01:08:18.884601Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.159: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.159:2380","--initial-cluster=instance=https://162.253.55.159:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.159:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.159: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-09T01:08:18.884699Z","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-09T01:08:18.884716Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.159:2380"]} {"level":"info","ts":"2026-09-09T01:08:18.88474Z","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-09T01:08:18.885371Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.159:2379"]} {"level":"info","ts":"2026-09-09T01:08:18.885484Z","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.159:2380"],"listen-peer-urls":["https://162.253.55.159:2380"],"advertise-client-urls":["https://162.253.55.159:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.159:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.159: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-09T01:08:18.888864Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.097326ms"} {"level":"info","ts":"2026-09-09T01:08:18.898379Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"94bfbb0e034a3ac2","cluster-id":"9676abcf1742949"} {"level":"info","ts":"2026-09-09T01:08:18.898461Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 switched to configuration voters=()"} {"level":"info","ts":"2026-09-09T01:08:18.89848Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 became follower at term 0"} {"level":"info","ts":"2026-09-09T01:08:18.898489Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 94bfbb0e034a3ac2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-09T01:08:18.898496Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 became follower at term 1"} {"level":"info","ts":"2026-09-09T01:08:18.898558Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 switched to configuration voters=(10718491307024202434)"} {"level":"warn","ts":"2026-09-09T01:08:18.910393Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-09T01:08:18.91222Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-09T01:08:18.913839Z","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-09T01:08:18.916982Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"94bfbb0e034a3ac2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-09T01:08:18.917582Z","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-09T01:08:18.917757Z","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-09T01:08:18.917784Z","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-09T01:08:18.917681Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"94bfbb0e034a3ac2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-09T01:08:18.923638Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 switched to configuration voters=(10718491307024202434)"} {"level":"info","ts":"2026-09-09T01:08:18.923922Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9676abcf1742949","local-member-id":"94bfbb0e034a3ac2","added-peer-id":"94bfbb0e034a3ac2","added-peer-peer-urls":["https://162.253.55.159:2380"]} {"level":"info","ts":"2026-09-09T01:08:18.924902Z","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-09T01:08:18.92505Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.159:2380"} {"level":"info","ts":"2026-09-09T01:08:18.925118Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.159:2380"} {"level":"info","ts":"2026-09-09T01:08:18.925241Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"94bfbb0e034a3ac2","initial-advertise-peer-urls":["https://162.253.55.159:2380"],"listen-peer-urls":["https://162.253.55.159:2380"],"advertise-client-urls":["https://162.253.55.159:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.159:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-09T01:08:18.925273Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-09T01:08:19.699665Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 is starting a new election at term 1"} {"level":"info","ts":"2026-09-09T01:08:19.699731Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-09T01:08:19.699755Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 received MsgPreVoteResp from 94bfbb0e034a3ac2 at term 1"} {"level":"info","ts":"2026-09-09T01:08:19.699799Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 became candidate at term 2"} {"level":"info","ts":"2026-09-09T01:08:19.699811Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 received MsgVoteResp from 94bfbb0e034a3ac2 at term 2"} {"level":"info","ts":"2026-09-09T01:08:19.699827Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 became leader at term 2"} {"level":"info","ts":"2026-09-09T01:08:19.699846Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 94bfbb0e034a3ac2 elected leader 94bfbb0e034a3ac2 at term 2"} {"level":"info","ts":"2026-09-09T01:08:19.701348Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"94bfbb0e034a3ac2","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.159:2379]}","request-path":"/0/members/94bfbb0e034a3ac2/attributes","cluster-id":"9676abcf1742949","publish-timeout":"7s"} {"level":"info","ts":"2026-09-09T01:08:19.701479Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-09T01:08:19.701662Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-09T01:08:19.701694Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-09T01:08:19.70175Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-09T01:08:19.701502Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-09T01:08:19.702921Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9676abcf1742949","local-member-id":"94bfbb0e034a3ac2","cluster-version":"3.5"} {"level":"info","ts":"2026-09-09T01:08:19.703021Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-09T01:08:19.703056Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-09T01:08:19.704377Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.159:2379"} {"level":"info","ts":"2026-09-09T01:08:19.704581Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-09T01:08:59.296494Z","caller":"traceutil/trace.go:171","msg":"trace[1273142525] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"190.329547ms","start":"2026-09-09T01:08:59.106138Z","end":"2026-09-09T01:08:59.296468Z","steps":["trace[1273142525] 'process raft request' (duration: 190.177883ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:08:59.322775Z","caller":"traceutil/trace.go:171","msg":"trace[2072034965] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"136.47882ms","start":"2026-09-09T01:08:59.186262Z","end":"2026-09-09T01:08:59.32274Z","steps":["trace[2072034965] 'process raft request' (duration: 136.285815ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:08:59.322936Z","caller":"traceutil/trace.go:171","msg":"trace[623257278] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"120.456511ms","start":"2026-09-09T01:08:59.202464Z","end":"2026-09-09T01:08:59.32292Z","steps":["trace[623257278] 'process raft request' (duration: 120.294047ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:08:59.322934Z","caller":"traceutil/trace.go:171","msg":"trace[475280641] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"129.695549ms","start":"2026-09-09T01:08:59.1932Z","end":"2026-09-09T01:08:59.322896Z","steps":["trace[475280641] 'process raft request' (duration: 129.508934ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:08:59.322852Z","caller":"traceutil/trace.go:171","msg":"trace[110627436] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"130.962055ms","start":"2026-09-09T01:08:59.191862Z","end":"2026-09-09T01:08:59.322824Z","steps":["trace[110627436] 'process raft request' (duration: 130.812041ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:08:59.323169Z","caller":"traceutil/trace.go:171","msg":"trace[1579088547] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"118.197367ms","start":"2026-09-09T01:08:59.204956Z","end":"2026-09-09T01:08:59.323153Z","steps":["trace[1579088547] 'process raft request' (duration: 117.953571ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:08:59.323196Z","caller":"traceutil/trace.go:171","msg":"trace[2109340429] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"118.430034ms","start":"2026-09-09T01:08:59.204746Z","end":"2026-09-09T01:08:59.323176Z","steps":["trace[2109340429] 'process raft request' (duration: 118.105595ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:08:59.323304Z","caller":"traceutil/trace.go:171","msg":"trace[898075256] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"110.876822ms","start":"2026-09-09T01:08:59.212412Z","end":"2026-09-09T01:08:59.323289Z","steps":["trace[898075256] 'process raft request' (duration: 110.610885ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:08:59.323373Z","caller":"traceutil/trace.go:171","msg":"trace[2127061347] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"115.753089ms","start":"2026-09-09T01:08:59.207436Z","end":"2026-09-09T01:08:59.323189Z","steps":["trace[2127061347] 'process raft request' (duration: 115.529793ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:09:06.185529Z","caller":"traceutil/trace.go:171","msg":"trace[1062679575] linearizableReadLoop","detail":"{readStateIndex:625; appliedIndex:624; }","duration":"135.974286ms","start":"2026-09-09T01:09:06.049385Z","end":"2026-09-09T01:09:06.185359Z","steps":["trace[1062679575] 'read index received' (duration: 134.505044ms)","trace[1062679575] 'applied index is now lower than readState.Index' (duration: 1.465192ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:09:06.185559Z","caller":"traceutil/trace.go:171","msg":"trace[1659935579] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"202.714244ms","start":"2026-09-09T01:09:05.98275Z","end":"2026-09-09T01:09:06.185464Z","steps":["trace[1659935579] 'process raft request' (duration: 201.406316ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:09:06.186064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.637375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:09:06.186221Z","caller":"traceutil/trace.go:171","msg":"trace[1521783360] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:603; }","duration":"136.85439ms","start":"2026-09-09T01:09:06.049323Z","end":"2026-09-09T01:09:06.186177Z","steps":["trace[1521783360] 'agreement among raft nodes before linearized reading' (duration: 136.47509ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:09:06.405258Z","caller":"traceutil/trace.go:171","msg":"trace[1417888550] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"186.924372ms","start":"2026-09-09T01:09:06.218305Z","end":"2026-09-09T01:09:06.40523Z","steps":["trace[1417888550] 'process raft request' (duration: 186.769457ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:09:06.692005Z","caller":"traceutil/trace.go:171","msg":"trace[425343186] linearizableReadLoop","detail":"{readStateIndex:627; appliedIndex:625; }","duration":"289.834092ms","start":"2026-09-09T01:09:06.402132Z","end":"2026-09-09T01:09:06.691966Z","steps":["trace[425343186] 'read index received' (duration: 2.927592ms)","trace[425343186] 'applied index is now lower than readState.Index' (duration: 286.90496ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:09:06.692231Z","caller":"traceutil/trace.go:171","msg":"trace[613460347] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"439.397588ms","start":"2026-09-09T01:09:06.252779Z","end":"2026-09-09T01:09:06.692176Z","steps":["trace[613460347] 'process raft request' (duration: 386.23778ms)","trace[613460347] 'compare' (duration: 52.683234ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:09:06.692344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.188822ms","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-09T01:09:06.692417Z","caller":"traceutil/trace.go:171","msg":"trace[279885284] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:605; }","duration":"290.320295ms","start":"2026-09-09T01:09:06.402076Z","end":"2026-09-09T01:09:06.692396Z","steps":["trace[279885284] 'agreement among raft nodes before linearized reading' (duration: 290.023347ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:09:06.693088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:09:06.252739Z","time spent":"439.614714ms","remote":"127.0.0.1:33056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-09T01:09:07.226184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.008126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:09:07.226284Z","caller":"traceutil/trace.go:171","msg":"trace[1044602979] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:606; }","duration":"176.197911ms","start":"2026-09-09T01:09:07.050063Z","end":"2026-09-09T01:09:07.226261Z","steps":["trace[1044602979] 'range keys from in-memory index tree' (duration: 175.883623ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:09:07.304913Z","caller":"traceutil/trace.go:171","msg":"trace[785131495] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"177.746294ms","start":"2026-09-09T01:09:07.127081Z","end":"2026-09-09T01:09:07.304827Z","steps":["trace[785131495] 'process raft request' (duration: 176.89342ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:09:15.651496Z","caller":"traceutil/trace.go:171","msg":"trace[1248745382] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"107.82233ms","start":"2026-09-09T01:09:15.543633Z","end":"2026-09-09T01:09:15.651455Z","steps":["trace[1248745382] 'process raft request' (duration: 78.046668ms)","trace[1248745382] 'compare' (duration: 29.536605ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:09:25.248057Z","caller":"traceutil/trace.go:171","msg":"trace[791267584] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"412.087924ms","start":"2026-09-09T01:09:24.835937Z","end":"2026-09-09T01:09:25.248025Z","steps":["trace[791267584] 'process raft request' (duration: 371.788605ms)","trace[791267584] 'compare' (duration: 39.359553ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:09:25.248499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:09:24.835922Z","time spent":"412.257248ms","remote":"127.0.0.1:32776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T01:09:55.862093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.407769ms","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-09T01:09:55.862202Z","caller":"traceutil/trace.go:171","msg":"trace[1501008460] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1182; }","duration":"241.565653ms","start":"2026-09-09T01:09:55.620613Z","end":"2026-09-09T01:09:55.862179Z","steps":["trace[1501008460] 'range keys from in-memory index tree' (duration: 241.199993ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:10:41.78644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.315622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-09-09T01:10:41.786561Z","caller":"traceutil/trace.go:171","msg":"trace[1419090968] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1842; }","duration":"222.474886ms","start":"2026-09-09T01:10:41.564039Z","end":"2026-09-09T01:10:41.786514Z","steps":["trace[1419090968] 'range keys from in-memory index tree' (duration: 222.069715ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:10:41.78677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.691603ms","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-09T01:10:41.786881Z","caller":"traceutil/trace.go:171","msg":"trace[237112020] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1842; }","duration":"236.849277ms","start":"2026-09-09T01:10:41.549997Z","end":"2026-09-09T01:10:41.786846Z","steps":["trace[237112020] 'range keys from in-memory index tree' (duration: 236.506348ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:10:41.941027Z","caller":"traceutil/trace.go:171","msg":"trace[302537749] transaction","detail":"{read_only:false; response_revision:1844; number_of_response:1; }","duration":"149.301054ms","start":"2026-09-09T01:10:41.791687Z","end":"2026-09-09T01:10:41.940988Z","steps":["trace[302537749] 'process raft request' (duration: 143.518303ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:10:41.941167Z","caller":"traceutil/trace.go:171","msg":"trace[1235785648] transaction","detail":"{read_only:false; response_revision:1845; number_of_response:1; }","duration":"146.442555ms","start":"2026-09-09T01:10:41.794683Z","end":"2026-09-09T01:10:41.941126Z","steps":["trace[1235785648] 'process raft request' (duration: 146.202029ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:10:42.346938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.050918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:10:42.347031Z","caller":"traceutil/trace.go:171","msg":"trace[1981922721] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1845; }","duration":"297.220272ms","start":"2026-09-09T01:10:42.049791Z","end":"2026-09-09T01:10:42.347012Z","steps":["trace[1981922721] 'range keys from in-memory index tree' (duration: 296.919403ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:10:42.347353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.3226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-09T01:10:42.347591Z","caller":"traceutil/trace.go:171","msg":"trace[783325246] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1845; }","duration":"237.514045ms","start":"2026-09-09T01:10:42.109935Z","end":"2026-09-09T01:10:42.347449Z","steps":["trace[783325246] 'range keys from in-memory index tree' (duration: 237.058713ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:10:42.347945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.528302ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T01:10:42.348034Z","caller":"traceutil/trace.go:171","msg":"trace[482228291] linearizableReadLoop","detail":"{readStateIndex:1918; appliedIndex:1917; }","duration":"103.975592ms","start":"2026-09-09T01:10:42.244045Z","end":"2026-09-09T01:10:42.348021Z","steps":["trace[482228291] 'read index received' (duration: 38.891µs)","trace[482228291] 'applied index is now lower than readState.Index' (duration: 103.935311ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:10:42.348194Z","caller":"traceutil/trace.go:171","msg":"trace[1273151661] transaction","detail":"{read_only:false; response_revision:1846; number_of_response:1; }","duration":"136.55934ms","start":"2026-09-09T01:10:42.211611Z","end":"2026-09-09T01:10:42.34817Z","steps":["trace[1273151661] 'process raft request' (duration: 29.571704ms)","trace[1273151661] 'compare' (duration: 105.808172ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:10:42.348224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.182607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-09-09T01:10:42.348686Z","caller":"traceutil/trace.go:171","msg":"trace[570488766] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1846; }","duration":"104.65127ms","start":"2026-09-09T01:10:42.243991Z","end":"2026-09-09T01:10:42.348642Z","steps":["trace[570488766] 'agreement among raft nodes before linearized reading' (duration: 104.071414ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:10:55.44415Z","caller":"traceutil/trace.go:171","msg":"trace[881639986] transaction","detail":"{read_only:false; response_revision:1961; number_of_response:1; }","duration":"102.685344ms","start":"2026-09-09T01:10:55.341447Z","end":"2026-09-09T01:10:55.444133Z","steps":["trace[881639986] 'process raft request' (duration: 102.566901ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:11:00.603902Z","caller":"traceutil/trace.go:171","msg":"trace[166037466] linearizableReadLoop","detail":"{readStateIndex:2078; appliedIndex:2077; }","duration":"143.205955ms","start":"2026-09-09T01:11:00.460674Z","end":"2026-09-09T01:11:00.60388Z","steps":["trace[166037466] 'read index received' (duration: 142.786793ms)","trace[166037466] 'applied index is now lower than readState.Index' (duration: 418.162µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:11:00.603963Z","caller":"traceutil/trace.go:171","msg":"trace[1866937896] transaction","detail":"{read_only:false; response_revision:2001; number_of_response:1; }","duration":"149.597124ms","start":"2026-09-09T01:11:00.454345Z","end":"2026-09-09T01:11:00.603942Z","steps":["trace[1866937896] 'process raft request' (duration: 149.394118ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:11:00.604164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.436102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-09T01:11:00.604223Z","caller":"traceutil/trace.go:171","msg":"trace[1531793760] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2001; }","duration":"143.561865ms","start":"2026-09-09T01:11:00.460647Z","end":"2026-09-09T01:11:00.604209Z","steps":["trace[1531793760] 'agreement among raft nodes before linearized reading' (duration: 143.338229ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:11:02.811855Z","caller":"traceutil/trace.go:171","msg":"trace[1650228397] linearizableReadLoop","detail":"{readStateIndex:2093; appliedIndex:2092; }","duration":"147.543976ms","start":"2026-09-09T01:11:02.66428Z","end":"2026-09-09T01:11:02.811824Z","steps":["trace[1650228397] 'read index received' (duration: 73.13611ms)","trace[1650228397] 'applied index is now lower than readState.Index' (duration: 74.406536ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:11:02.811963Z","caller":"traceutil/trace.go:171","msg":"trace[1741388879] transaction","detail":"{read_only:false; response_revision:2016; number_of_response:1; }","duration":"175.311001ms","start":"2026-09-09T01:11:02.636593Z","end":"2026-09-09T01:11:02.811904Z","steps":["trace[1741388879] 'process raft request' (duration: 100.973417ms)","trace[1741388879] 'compare' (duration: 74.142008ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:11:02.812166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.874036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-09T01:11:02.812236Z","caller":"traceutil/trace.go:171","msg":"trace[1612536822] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2016; }","duration":"148.00253ms","start":"2026-09-09T01:11:02.664217Z","end":"2026-09-09T01:11:02.81222Z","steps":["trace[1612536822] 'agreement among raft nodes before linearized reading' (duration: 147.771452ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:11:03.380561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.245063ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T01:11:03.38069Z","caller":"traceutil/trace.go:171","msg":"trace[1430728274] linearizableReadLoop","detail":"{readStateIndex:2098; appliedIndex:2097; }","duration":"164.119048ms","start":"2026-09-09T01:11:03.216549Z","end":"2026-09-09T01:11:03.380668Z","steps":["trace[1430728274] 'read index received' (duration: 9.614118ms)","trace[1430728274] 'applied index is now lower than readState.Index' (duration: 154.50342ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:11:03.380847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.293143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-09T01:11:03.380929Z","caller":"traceutil/trace.go:171","msg":"trace[927374536] transaction","detail":"{read_only:false; response_revision:2020; number_of_response:1; }","duration":"221.673124ms","start":"2026-09-09T01:11:03.159055Z","end":"2026-09-09T01:11:03.380729Z","steps":["trace[927374536] 'process raft request' (duration: 67.146683ms)","trace[927374536] 'compare' (duration: 154.103159ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:11:03.380944Z","caller":"traceutil/trace.go:171","msg":"trace[444826172] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:2020; }","duration":"164.446268ms","start":"2026-09-09T01:11:03.216482Z","end":"2026-09-09T01:11:03.380929Z","steps":["trace[444826172] 'agreement among raft nodes before linearized reading' (duration: 164.241332ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:11:04.597258Z","caller":"traceutil/trace.go:171","msg":"trace[1399009081] transaction","detail":"{read_only:false; response_revision:2028; number_of_response:1; }","duration":"102.200841ms","start":"2026-09-09T01:11:04.495034Z","end":"2026-09-09T01:11:04.597235Z","steps":["trace[1399009081] 'process raft request' (duration: 102.049267ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:11:10.188171Z","caller":"traceutil/trace.go:171","msg":"trace[456531393] linearizableReadLoop","detail":"{readStateIndex:2135; appliedIndex:2134; }","duration":"133.887354ms","start":"2026-09-09T01:11:10.054253Z","end":"2026-09-09T01:11:10.188141Z","steps":["trace[456531393] 'read index received' (duration: 133.512824ms)","trace[456531393] 'applied index is now lower than readState.Index' (duration: 372.84µs)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:11:10.188479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.183823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-09-09T01:11:10.188492Z","caller":"traceutil/trace.go:171","msg":"trace[1575000403] transaction","detail":"{read_only:false; response_revision:2056; number_of_response:1; }","duration":"135.209173ms","start":"2026-09-09T01:11:10.053244Z","end":"2026-09-09T01:11:10.188454Z","steps":["trace[1575000403] 'process raft request' (duration: 134.637426ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:11:10.188558Z","caller":"traceutil/trace.go:171","msg":"trace[1749720206] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2056; }","duration":"134.311307ms","start":"2026-09-09T01:11:10.054226Z","end":"2026-09-09T01:11:10.188537Z","steps":["trace[1749720206] 'agreement among raft nodes before linearized reading' (duration: 134.05217ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:11:11.098995Z","caller":"traceutil/trace.go:171","msg":"trace[1993013655] linearizableReadLoop","detail":"{readStateIndex:2140; appliedIndex:2139; }","duration":"124.138433ms","start":"2026-09-09T01:11:10.97483Z","end":"2026-09-09T01:11:11.098968Z","steps":["trace[1993013655] 'read index received' (duration: 74.310173ms)","trace[1993013655] 'applied index is now lower than readState.Index' (duration: 49.82678ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:11:11.099037Z","caller":"traceutil/trace.go:171","msg":"trace[691062862] transaction","detail":"{read_only:false; response_revision:2061; number_of_response:1; }","duration":"128.3412ms","start":"2026-09-09T01:11:10.970664Z","end":"2026-09-09T01:11:11.099005Z","steps":["trace[691062862] 'process raft request' (duration: 78.52183ms)","trace[691062862] 'compare' (duration: 49.658486ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:11:11.099236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.390611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12383"} {"level":"info","ts":"2026-09-09T01:11:11.099287Z","caller":"traceutil/trace.go:171","msg":"trace[1902978805] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2061; }","duration":"124.496704ms","start":"2026-09-09T01:11:10.974775Z","end":"2026-09-09T01:11:11.099272Z","steps":["trace[1902978805] 'agreement among raft nodes before linearized reading' (duration: 124.303038ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:11:25.88343Z","caller":"traceutil/trace.go:171","msg":"trace[1129401773] transaction","detail":"{read_only:false; response_revision:2139; number_of_response:1; }","duration":"114.611257ms","start":"2026-09-09T01:11:25.768791Z","end":"2026-09-09T01:11:25.883402Z","steps":["trace[1129401773] 'process raft request' (duration: 114.440882ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:11:26.062468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.153755ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T01:11:26.062625Z","caller":"traceutil/trace.go:171","msg":"trace[1598204580] linearizableReadLoop","detail":"{readStateIndex:2222; appliedIndex:2221; }","duration":"141.568878ms","start":"2026-09-09T01:11:25.921033Z","end":"2026-09-09T01:11:26.062602Z","steps":["trace[1598204580] 'read index received' (duration: 18.125055ms)","trace[1598204580] 'applied index is now lower than readState.Index' (duration: 123.442003ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:11:26.06274Z","caller":"traceutil/trace.go:171","msg":"trace[587437808] transaction","detail":"{read_only:false; response_revision:2140; number_of_response:1; }","duration":"213.33938ms","start":"2026-09-09T01:11:25.849382Z","end":"2026-09-09T01:11:26.062722Z","steps":["trace[587437808] 'process raft request' (duration: 89.813535ms)","trace[587437808] 'compare' (duration: 122.946349ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:11:26.063056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.018781ms","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-09T01:11:26.063114Z","caller":"traceutil/trace.go:171","msg":"trace[95180247] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2140; }","duration":"142.120594ms","start":"2026-09-09T01:11:25.92098Z","end":"2026-09-09T01:11:26.063101Z","steps":["trace[95180247] 'agreement among raft nodes before linearized reading' (duration: 141.96669ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:11:26.350257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.220206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12383"} {"level":"info","ts":"2026-09-09T01:11:26.350355Z","caller":"traceutil/trace.go:171","msg":"trace[954396576] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2141; }","duration":"147.33774ms","start":"2026-09-09T01:11:26.202994Z","end":"2026-09-09T01:11:26.350332Z","steps":["trace[954396576] 'range keys from in-memory index tree' (duration: 146.993091ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:11:32.079169Z","caller":"traceutil/trace.go:171","msg":"trace[1934911634] transaction","detail":"{read_only:false; response_revision:2170; number_of_response:1; }","duration":"175.765682ms","start":"2026-09-09T01:11:31.903365Z","end":"2026-09-09T01:11:32.079131Z","steps":["trace[1934911634] 'process raft request' (duration: 175.582557ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:11:32.379456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.786176ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T01:11:32.379569Z","caller":"traceutil/trace.go:171","msg":"trace[2057791507] linearizableReadLoop","detail":"{readStateIndex:2255; appliedIndex:2254; }","duration":"279.832826ms","start":"2026-09-09T01:11:32.099723Z","end":"2026-09-09T01:11:32.379556Z","steps":["trace[2057791507] 'read index received' (duration: 76.764172ms)","trace[2057791507] 'applied index is now lower than readState.Index' (duration: 203.067544ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:11:32.379644Z","caller":"traceutil/trace.go:171","msg":"trace[927716424] transaction","detail":"{read_only:false; response_revision:2171; number_of_response:1; }","duration":"297.809087ms","start":"2026-09-09T01:11:32.08181Z","end":"2026-09-09T01:11:32.379619Z","steps":["trace[927716424] 'process raft request' (duration: 94.755013ms)","trace[927716424] 'compare' (duration: 202.56212ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:11:32.380013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.286588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-09T01:11:32.380039Z","caller":"traceutil/trace.go:171","msg":"trace[2099276709] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:2171; }","duration":"280.34209ms","start":"2026-09-09T01:11:32.099689Z","end":"2026-09-09T01:11:32.380031Z","steps":["trace[2099276709] 'agreement among raft nodes before linearized reading' (duration: 279.937549ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:11:32.380385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.680361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-09-09T01:11:32.380478Z","caller":"traceutil/trace.go:171","msg":"trace[860779472] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2171; }","duration":"232.820145ms","start":"2026-09-09T01:11:32.147635Z","end":"2026-09-09T01:11:32.380455Z","steps":["trace[860779472] 'agreement among raft nodes before linearized reading' (duration: 232.544967ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:13:07.627742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.187592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-09-09T01:13:07.62784Z","caller":"traceutil/trace.go:171","msg":"trace[1311878631] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2847; }","duration":"443.338376ms","start":"2026-09-09T01:13:07.184479Z","end":"2026-09-09T01:13:07.627817Z","steps":["trace[1311878631] 'range keys from in-memory index tree' (duration: 443.059078ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:13:07.627875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:13:07.184456Z","time spent":"443.407798ms","remote":"127.0.0.1:32776","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-09T01:13:07.736475Z","caller":"traceutil/trace.go:171","msg":"trace[654475974] transaction","detail":"{read_only:false; response_revision:2848; number_of_response:1; }","duration":"103.298908ms","start":"2026-09-09T01:13:07.633145Z","end":"2026-09-09T01:13:07.736444Z","steps":["trace[654475974] 'process raft request' (duration: 103.106623ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:13:07.750355Z","caller":"traceutil/trace.go:171","msg":"trace[1230068285] transaction","detail":"{read_only:false; response_revision:2849; number_of_response:1; }","duration":"117.097864ms","start":"2026-09-09T01:13:07.633229Z","end":"2026-09-09T01:13:07.750327Z","steps":["trace[1230068285] 'process raft request' (duration: 116.963881ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:13:37.28242Z","caller":"traceutil/trace.go:171","msg":"trace[1166293831] transaction","detail":"{read_only:false; response_revision:3028; number_of_response:1; }","duration":"173.112248ms","start":"2026-09-09T01:13:37.10926Z","end":"2026-09-09T01:13:37.282373Z","steps":["trace[1166293831] 'process raft request' (duration: 172.868351ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:13:43.039961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.401734ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T01:13:43.040276Z","caller":"traceutil/trace.go:171","msg":"trace[851264118] transaction","detail":"{read_only:false; response_revision:3059; number_of_response:1; }","duration":"236.680218ms","start":"2026-09-09T01:13:42.803577Z","end":"2026-09-09T01:13:43.040257Z","steps":["trace[851264118] 'process raft request' (duration: 76.893143ms)","trace[851264118] 'compare' (duration: 159.23804ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:13:43.301014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.996145ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-09-09T01:16:54.885805Z","caller":"traceutil/trace.go:171","msg":"trace[2034050120] transaction","detail":"{read_only:false; response_revision:4268; number_of_response:1; }","duration":"157.055438ms","start":"2026-09-09T01:16:54.728714Z","end":"2026-09-09T01:16:54.885769Z","steps":["trace[2034050120] 'process raft request' (duration: 156.881103ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:18:19.730218Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2931} {"level":"info","ts":"2026-09-09T01:18:19.806493Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2931,"took":"74.989054ms","hash":3023635348,"current-db-size-bytes":51138560,"current-db-size":"51 MB","current-db-size-in-use-bytes":27009024,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-09-09T01:18:19.806555Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3023635348,"revision":2931,"compact-revision":-1} {"level":"info","ts":"2026-09-09T01:18:23.186102Z","caller":"traceutil/trace.go:171","msg":"trace[470803329] linearizableReadLoop","detail":"{readStateIndex:5484; appliedIndex:5483; }","duration":"101.127513ms","start":"2026-09-09T01:18:23.084939Z","end":"2026-09-09T01:18:23.186067Z","steps":["trace[470803329] 'read index received' (duration: 16.938369ms)","trace[470803329] 'applied index is now lower than readState.Index' (duration: 84.187454ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:18:23.186317Z","caller":"traceutil/trace.go:171","msg":"trace[1549649922] transaction","detail":"{read_only:false; response_revision:5281; number_of_response:1; }","duration":"116.074536ms","start":"2026-09-09T01:18:23.070206Z","end":"2026-09-09T01:18:23.18628Z","steps":["trace[1549649922] 'process raft request' (duration: 31.726358ms)","trace[1549649922] 'compare' (duration: 83.925446ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:18:23.186363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.403822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.159\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-09-09T01:18:23.186417Z","caller":"traceutil/trace.go:171","msg":"trace[1866412272] range","detail":"{range_begin:/registry/masterleases/162.253.55.159; range_end:; response_count:1; response_revision:5281; }","duration":"101.525285ms","start":"2026-09-09T01:18:23.084874Z","end":"2026-09-09T01:18:23.1864Z","steps":["trace[1866412272] 'agreement among raft nodes before linearized reading' (duration: 101.315489ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:18:31.307075Z","caller":"traceutil/trace.go:171","msg":"trace[508679162] transaction","detail":"{read_only:false; response_revision:5550; number_of_response:1; }","duration":"109.367047ms","start":"2026-09-09T01:18:31.197678Z","end":"2026-09-09T01:18:31.307045Z","steps":["trace[508679162] 'process raft request' (duration: 109.230923ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:18:45.024614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.369486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/memcached-metrics\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:18:45.024751Z","caller":"traceutil/trace.go:171","msg":"trace[1563162380] range","detail":"{range_begin:/registry/services/specs/openstack/memcached-metrics; range_end:; response_count:0; response_revision:5816; }","duration":"104.621833ms","start":"2026-09-09T01:18:44.920099Z","end":"2026-09-09T01:18:45.024721Z","steps":["trace[1563162380] 'range keys from in-memory index tree' (duration: 104.278824ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:18:45.176864Z","caller":"traceutil/trace.go:171","msg":"trace[1493547852] transaction","detail":"{read_only:false; response_revision:5817; number_of_response:1; }","duration":"147.500497ms","start":"2026-09-09T01:18:45.029334Z","end":"2026-09-09T01:18:45.176835Z","steps":["trace[1493547852] 'process raft request' (duration: 147.342283ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:18:45.34254Z","caller":"traceutil/trace.go:171","msg":"trace[1741972677] linearizableReadLoop","detail":"{readStateIndex:6043; appliedIndex:6041; }","duration":"312.596973ms","start":"2026-09-09T01:18:45.029919Z","end":"2026-09-09T01:18:45.342516Z","steps":["trace[1741972677] 'read index received' (duration: 146.762757ms)","trace[1741972677] 'applied index is now lower than readState.Index' (duration: 165.833346ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:18:45.342708Z","caller":"traceutil/trace.go:171","msg":"trace[1411695294] transaction","detail":"{read_only:false; response_revision:5818; number_of_response:1; }","duration":"312.808368ms","start":"2026-09-09T01:18:45.029889Z","end":"2026-09-09T01:18:45.342698Z","steps":["trace[1411695294] 'process raft request' (duration: 307.662662ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:18:45.342825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:18:45.029873Z","time spent":"312.85398ms","remote":"127.0.0.1:32776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T01:18:45.342831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.081807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:18:45.342953Z","caller":"traceutil/trace.go:171","msg":"trace[96503101] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5818; }","duration":"294.235122ms","start":"2026-09-09T01:18:45.048704Z","end":"2026-09-09T01:18:45.342939Z","steps":["trace[96503101] 'agreement among raft nodes before linearized reading' (duration: 294.079597ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:18:45.34307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.113257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:127824"} {"level":"info","ts":"2026-09-09T01:18:45.343197Z","caller":"traceutil/trace.go:171","msg":"trace[1169780499] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:5818; }","duration":"313.263401ms","start":"2026-09-09T01:18:45.029903Z","end":"2026-09-09T01:18:45.343166Z","steps":["trace[1169780499] 'agreement among raft nodes before linearized reading' (duration: 312.912031ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:18:45.343257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:18:45.029897Z","time spent":"313.347593ms","remote":"127.0.0.1:60798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":127847,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"info","ts":"2026-09-09T01:18:45.67208Z","caller":"traceutil/trace.go:171","msg":"trace[1838597473] transaction","detail":"{read_only:false; response_revision:5825; number_of_response:1; }","duration":"171.231609ms","start":"2026-09-09T01:18:45.500825Z","end":"2026-09-09T01:18:45.672056Z","steps":["trace[1838597473] 'process raft request' (duration: 171.020543ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:18:46.19482Z","caller":"traceutil/trace.go:171","msg":"trace[1583181449] linearizableReadLoop","detail":"{readStateIndex:6059; appliedIndex:6058; }","duration":"144.649456ms","start":"2026-09-09T01:18:46.05014Z","end":"2026-09-09T01:18:46.194789Z","steps":["trace[1583181449] 'read index received' (duration: 144.385178ms)","trace[1583181449] 'applied index is now lower than readState.Index' (duration: 263.218µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:18:46.195115Z","caller":"traceutil/trace.go:171","msg":"trace[1449413002] transaction","detail":"{read_only:false; response_revision:5834; number_of_response:1; }","duration":"341.84503ms","start":"2026-09-09T01:18:45.853212Z","end":"2026-09-09T01:18:46.195057Z","steps":["trace[1449413002] 'process raft request' (duration: 341.398268ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:18:46.195238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.068197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:18:46.1953Z","caller":"traceutil/trace.go:171","msg":"trace[585912188] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5834; }","duration":"145.17661ms","start":"2026-09-09T01:18:46.050106Z","end":"2026-09-09T01:18:46.195283Z","steps":["trace[585912188] 'agreement among raft nodes before linearized reading' (duration: 145.063897ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:18:46.195308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:18:45.853191Z","time spent":"342.012785ms","remote":"127.0.0.1:32776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T01:18:46.196377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.359355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1633"} {"level":"info","ts":"2026-09-09T01:18:46.196461Z","caller":"traceutil/trace.go:171","msg":"trace[2140896370] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:5835; }","duration":"104.494489ms","start":"2026-09-09T01:18:46.091946Z","end":"2026-09-09T01:18:46.196441Z","steps":["trace[2140896370] 'agreement among raft nodes before linearized reading' (duration: 104.268633ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:18:54.059306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.516582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-41b9b036-99ca-404e-b351-508383dd0559\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-09-09T01:18:54.05939Z","caller":"traceutil/trace.go:171","msg":"trace[1545365308] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-41b9b036-99ca-404e-b351-508383dd0559; range_end:; response_count:1; response_revision:5994; }","duration":"219.666437ms","start":"2026-09-09T01:18:53.839704Z","end":"2026-09-09T01:18:54.05937Z","steps":["trace[1545365308] 'range keys from in-memory index tree' (duration: 219.264946ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:18:56.884776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.891726ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T01:18:56.884882Z","caller":"traceutil/trace.go:171","msg":"trace[2124806066] linearizableReadLoop","detail":"{readStateIndex:6244; appliedIndex:6243; }","duration":"398.450069ms","start":"2026-09-09T01:18:56.486415Z","end":"2026-09-09T01:18:56.884865Z","steps":["trace[2124806066] 'read index received' (duration: 127.302386ms)","trace[2124806066] 'applied index is now lower than readState.Index' (duration: 271.146403ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:18:56.884959Z","caller":"traceutil/trace.go:171","msg":"trace[1444925927] transaction","detail":"{read_only:false; response_revision:6017; number_of_response:1; }","duration":"403.001358ms","start":"2026-09-09T01:18:56.481908Z","end":"2026-09-09T01:18:56.884909Z","steps":["trace[1444925927] 'process raft request' (duration: 131.892955ms)","trace[1444925927] 'compare' (duration: 270.762893ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:18:56.885038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.620435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-09-09T01:18:56.885073Z","caller":"traceutil/trace.go:171","msg":"trace[1548037007] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:6017; }","duration":"398.695707ms","start":"2026-09-09T01:18:56.486363Z","end":"2026-09-09T01:18:56.885059Z","steps":["trace[1548037007] 'agreement among raft nodes before linearized reading' (duration: 398.584424ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:18:56.885103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:18:56.486338Z","time spent":"398.756518ms","remote":"127.0.0.1:60900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2105,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"warn","ts":"2026-09-09T01:18:56.885171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:18:56.481879Z","time spent":"403.180773ms","remote":"127.0.0.1:51606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7439,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T01:18:56.885252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.558138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-09T01:18:56.885396Z","caller":"traceutil/trace.go:171","msg":"trace[1731997140] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:6017; }","duration":"226.725672ms","start":"2026-09-09T01:18:56.658607Z","end":"2026-09-09T01:18:56.885333Z","steps":["trace[1731997140] 'agreement among raft nodes before linearized reading' (duration: 226.549197ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:19:47.083705Z","caller":"traceutil/trace.go:171","msg":"trace[1115899661] transaction","detail":"{read_only:false; response_revision:6459; number_of_response:1; }","duration":"173.27296ms","start":"2026-09-09T01:19:46.910381Z","end":"2026-09-09T01:19:47.083654Z","steps":["trace[1115899661] 'process raft request' (duration: 172.95437ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:23:19.736469Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5171} {"level":"info","ts":"2026-09-09T01:23:19.826943Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5171,"took":"88.697283ms","hash":1276169746,"current-db-size-bytes":51138560,"current-db-size":"51 MB","current-db-size-in-use-bytes":27983872,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-09T01:23:19.827024Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1276169746,"revision":5171,"compact-revision":2931} {"level":"info","ts":"2026-09-09T01:24:51.770218Z","caller":"traceutil/trace.go:171","msg":"trace[1812461936] linearizableReadLoop","detail":"{readStateIndex:9039; appliedIndex:9038; }","duration":"157.267393ms","start":"2026-09-09T01:24:51.612931Z","end":"2026-09-09T01:24:51.770199Z","steps":["trace[1812461936] 'read index received' (duration: 157.14712ms)","trace[1812461936] 'applied index is now lower than readState.Index' (duration: 119.363µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:24:51.770256Z","caller":"traceutil/trace.go:171","msg":"trace[1705418548] transaction","detail":"{read_only:false; response_revision:8727; number_of_response:1; }","duration":"345.030921ms","start":"2026-09-09T01:24:51.425206Z","end":"2026-09-09T01:24:51.770237Z","steps":["trace[1705418548] 'process raft request' (duration: 344.860067ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:24:51.770358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:24:51.425179Z","time spent":"345.116494ms","remote":"127.0.0.1:32776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T01:24:51.770399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.458139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-09T01:24:51.770434Z","caller":"traceutil/trace.go:171","msg":"trace[1232820628] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8727; }","duration":"157.533951ms","start":"2026-09-09T01:24:51.612891Z","end":"2026-09-09T01:24:51.770425Z","steps":["trace[1232820628] 'agreement among raft nodes before linearized reading' (duration: 157.408787ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:24:51.961102Z","caller":"traceutil/trace.go:171","msg":"trace[2053106238] linearizableReadLoop","detail":"{readStateIndex:9040; appliedIndex:9039; }","duration":"190.792803ms","start":"2026-09-09T01:24:51.770283Z","end":"2026-09-09T01:24:51.961075Z","steps":["trace[2053106238] 'read index received' (duration: 150.086451ms)","trace[2053106238] 'applied index is now lower than readState.Index' (duration: 40.704522ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:24:51.961245Z","caller":"traceutil/trace.go:171","msg":"trace[221731184] transaction","detail":"{read_only:false; response_revision:8728; number_of_response:1; }","duration":"261.940537ms","start":"2026-09-09T01:24:51.699289Z","end":"2026-09-09T01:24:51.96123Z","steps":["trace[221731184] 'process raft request' (duration: 221.067361ms)","trace[221731184] 'compare' (duration: 40.520426ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:24:51.96136Z","caller":"traceutil/trace.go:171","msg":"trace[2119290113] transaction","detail":"{read_only:false; response_revision:8729; number_of_response:1; }","duration":"186.836291ms","start":"2026-09-09T01:24:51.774505Z","end":"2026-09-09T01:24:51.961341Z","steps":["trace[2119290113] 'process raft request' (duration: 186.515971ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:24:51.961785Z","caller":"traceutil/trace.go:171","msg":"trace[1271851684] transaction","detail":"{read_only:false; response_revision:8730; number_of_response:1; }","duration":"113.927676ms","start":"2026-09-09T01:24:51.847841Z","end":"2026-09-09T01:24:51.961769Z","steps":["trace[1271851684] 'process raft request' (duration: 113.441252ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:24:51.961835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.257721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-09T01:24:51.961932Z","caller":"traceutil/trace.go:171","msg":"trace[999871688] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8730; }","duration":"319.401075ms","start":"2026-09-09T01:24:51.642504Z","end":"2026-09-09T01:24:51.961905Z","steps":["trace[999871688] 'agreement among raft nodes before linearized reading' (duration: 318.946072ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:24:51.961986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:24:51.642484Z","time spent":"319.488148ms","remote":"127.0.0.1:32776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-09T01:24:51.962283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.86741ms","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-09T01:24:51.962331Z","caller":"traceutil/trace.go:171","msg":"trace[1653996463] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8730; }","duration":"205.940433ms","start":"2026-09-09T01:24:51.756371Z","end":"2026-09-09T01:24:51.962312Z","steps":["trace[1653996463] 'agreement among raft nodes before linearized reading' (duration: 205.064968ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:25:04.800319Z","caller":"traceutil/trace.go:171","msg":"trace[825621896] transaction","detail":"{read_only:false; response_revision:8797; number_of_response:1; }","duration":"243.308861ms","start":"2026-09-09T01:25:04.556974Z","end":"2026-09-09T01:25:04.800282Z","steps":["trace[825621896] 'process raft request' (duration: 230.350364ms)","trace[825621896] 'compare' (duration: 12.579335ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:25:05.429938Z","caller":"traceutil/trace.go:171","msg":"trace[1184922197] transaction","detail":"{read_only:false; response_revision:8800; number_of_response:1; }","duration":"127.824909ms","start":"2026-09-09T01:25:05.302074Z","end":"2026-09-09T01:25:05.429899Z","steps":["trace[1184922197] 'process raft request' (duration: 83.413261ms)","trace[1184922197] 'compare' (duration: 44.175322ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:25:33.530891Z","caller":"traceutil/trace.go:171","msg":"trace[1435646240] linearizableReadLoop","detail":"{readStateIndex:9325; appliedIndex:9324; }","duration":"423.385182ms","start":"2026-09-09T01:25:33.107471Z","end":"2026-09-09T01:25:33.530856Z","steps":["trace[1435646240] 'read index received' (duration: 423.293199ms)","trace[1435646240] 'applied index is now lower than readState.Index' (duration: 90.593µs)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:25:33.531907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.419482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.159\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-09-09T01:25:33.531966Z","caller":"traceutil/trace.go:171","msg":"trace[108368618] range","detail":"{range_begin:/registry/masterleases/162.253.55.159; range_end:; response_count:1; response_revision:9002; }","duration":"424.537555ms","start":"2026-09-09T01:25:33.107411Z","end":"2026-09-09T01:25:33.531949Z","steps":["trace[108368618] 'agreement among raft nodes before linearized reading' (duration: 423.572438ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:25:33.532042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:25:33.107389Z","time spent":"424.642439ms","remote":"127.0.0.1:60776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/162.253.55.159\" "} {"level":"info","ts":"2026-09-09T01:25:33.532501Z","caller":"traceutil/trace.go:171","msg":"trace[1765992070] transaction","detail":"{read_only:false; response_revision:9002; number_of_response:1; }","duration":"450.391973ms","start":"2026-09-09T01:25:33.082092Z","end":"2026-09-09T01:25:33.532484Z","steps":["trace[1765992070] 'process raft request' (duration: 448.577322ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:25:33.53262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:25:33.08206Z","time spent":"450.486726ms","remote":"127.0.0.1:32776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":542,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T01:25:33.656238Z","caller":"traceutil/trace.go:171","msg":"trace[1119883709] transaction","detail":"{read_only:false; response_revision:9003; number_of_response:1; }","duration":"507.854332ms","start":"2026-09-09T01:25:33.148356Z","end":"2026-09-09T01:25:33.656211Z","steps":["trace[1119883709] 'process raft request' (duration: 479.981906ms)","trace[1119883709] 'compare' (duration: 27.451184ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:25:33.656414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:25:33.148327Z","time spent":"507.988516ms","remote":"127.0.0.1:32776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T01:25:39.908062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.723753ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T01:25:39.908204Z","caller":"traceutil/trace.go:171","msg":"trace[1597179236] transaction","detail":"{read_only:false; response_revision:9037; number_of_response:1; }","duration":"187.738916ms","start":"2026-09-09T01:25:39.720446Z","end":"2026-09-09T01:25:39.908185Z","steps":["trace[1597179236] 'process raft request' (duration: 84.618542ms)","trace[1597179236] 'compare' (duration: 102.473926ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:25:40.358028Z","caller":"traceutil/trace.go:171","msg":"trace[1699950103] linearizableReadLoop","detail":"{readStateIndex:9365; appliedIndex:9364; }","duration":"131.951616ms","start":"2026-09-09T01:25:40.226048Z","end":"2026-09-09T01:25:40.358Z","steps":["trace[1699950103] 'read index received' (duration: 78.48138ms)","trace[1699950103] 'applied index is now lower than readState.Index' (duration: 53.469396ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:25:40.358061Z","caller":"traceutil/trace.go:171","msg":"trace[433044750] transaction","detail":"{read_only:false; response_revision:9040; number_of_response:1; }","duration":"162.414573ms","start":"2026-09-09T01:25:40.195608Z","end":"2026-09-09T01:25:40.358022Z","steps":["trace[433044750] 'process raft request' (duration: 109.06795ms)","trace[433044750] 'compare' (duration: 53.208469ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:25:40.358235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.165063ms","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-09T01:25:40.358261Z","caller":"traceutil/trace.go:171","msg":"trace[2045532113] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9040; }","duration":"132.243805ms","start":"2026-09-09T01:25:40.226008Z","end":"2026-09-09T01:25:40.358252Z","steps":["trace[2045532113] 'agreement among raft nodes before linearized reading' (duration: 132.113821ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:25:40.3585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.683338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-09T01:25:40.358608Z","caller":"traceutil/trace.go:171","msg":"trace[537252428] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9040; }","duration":"104.859123ms","start":"2026-09-09T01:25:40.253719Z","end":"2026-09-09T01:25:40.358578Z","steps":["trace[537252428] 'agreement among raft nodes before linearized reading' (duration: 104.442371ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:25:40.514568Z","caller":"traceutil/trace.go:171","msg":"trace[1247727895] linearizableReadLoop","detail":"{readStateIndex:9368; appliedIndex:9365; }","duration":"149.907791ms","start":"2026-09-09T01:25:40.364627Z","end":"2026-09-09T01:25:40.514535Z","steps":["trace[1247727895] 'read index received' (duration: 39.547694ms)","trace[1247727895] 'applied index is now lower than readState.Index' (duration: 110.357927ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:25:40.514672Z","caller":"traceutil/trace.go:171","msg":"trace[704978516] transaction","detail":"{read_only:false; response_revision:9043; number_of_response:1; }","duration":"150.738884ms","start":"2026-09-09T01:25:40.363876Z","end":"2026-09-09T01:25:40.514615Z","steps":["trace[704978516] 'process raft request' (duration: 150.560399ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:25:40.515251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.588941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228571"} {"level":"info","ts":"2026-09-09T01:25:40.515365Z","caller":"traceutil/trace.go:171","msg":"trace[1288649432] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:9043; }","duration":"150.755786ms","start":"2026-09-09T01:25:40.364585Z","end":"2026-09-09T01:25:40.51534Z","steps":["trace[1288649432] 'agreement among raft nodes before linearized reading' (duration: 150.091536ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:25:40.515372Z","caller":"traceutil/trace.go:171","msg":"trace[41375437] transaction","detail":"{read_only:false; response_revision:9042; number_of_response:1; }","duration":"152.024511ms","start":"2026-09-09T01:25:40.362512Z","end":"2026-09-09T01:25:40.514537Z","steps":["trace[41375437] 'process raft request' (duration: 102.943079ms)","trace[41375437] 'compare' (duration: 48.751772ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:26:07.430463Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000025a8.wal"} {"level":"info","ts":"2026-09-09T01:26:42.608315Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"94bfbb0e034a3ac2","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-09T01:26:42.615928Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-09-09T01:26:42.616032Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-09-09T01:27:06.604959Z","caller":"traceutil/trace.go:171","msg":"trace[172371157] transaction","detail":"{read_only:false; response_revision:9820; number_of_response:1; }","duration":"135.052724ms","start":"2026-09-09T01:27:06.469879Z","end":"2026-09-09T01:27:06.604932Z","steps":["trace[172371157] 'process raft request' (duration: 76.447774ms)","trace[172371157] 'compare' (duration: 58.191609ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:27:06.928701Z","caller":"traceutil/trace.go:171","msg":"trace[1249262194] transaction","detail":"{read_only:false; response_revision:9823; number_of_response:1; }","duration":"171.117789ms","start":"2026-09-09T01:27:06.757507Z","end":"2026-09-09T01:27:06.928625Z","steps":["trace[1249262194] 'process raft request' (duration: 170.814271ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:28:19.744962Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7964} {"level":"info","ts":"2026-09-09T01:28:19.841656Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7964,"took":"94.414548ms","hash":4189490088,"current-db-size-bytes":51138560,"current-db-size":"51 MB","current-db-size-in-use-bytes":26472448,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-09T01:28:19.84181Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4189490088,"revision":7964,"compact-revision":5171} {"level":"warn","ts":"2026-09-09T01:30:43.57016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.441901ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T01:30:43.570337Z","caller":"traceutil/trace.go:171","msg":"trace[179658453] transaction","detail":"{read_only:false; response_revision:11724; number_of_response:1; }","duration":"233.595018ms","start":"2026-09-09T01:30:43.336711Z","end":"2026-09-09T01:30:43.570306Z","steps":["trace[179658453] 'process raft request' (duration: 38.90913ms)","trace[179658453] 'compare' (duration: 194.183754ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:30:43.570388Z","caller":"traceutil/trace.go:171","msg":"trace[1900975040] transaction","detail":"{read_only:false; response_revision:11725; number_of_response:1; }","duration":"119.572973ms","start":"2026-09-09T01:30:43.450799Z","end":"2026-09-09T01:30:43.570372Z","steps":["trace[1900975040] 'process raft request' (duration: 119.484411ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:30:43.570413Z","caller":"traceutil/trace.go:171","msg":"trace[303269138] linearizableReadLoop","detail":"{readStateIndex:12130; appliedIndex:12129; }","duration":"231.140371ms","start":"2026-09-09T01:30:43.339246Z","end":"2026-09-09T01:30:43.570386Z","steps":["trace[303269138] 'read index received' (duration: 36.309018ms)","trace[303269138] 'applied index is now lower than readState.Index' (duration: 194.829062ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:30:43.570654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.385877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-09-09T01:30:43.570724Z","caller":"traceutil/trace.go:171","msg":"trace[1336686145] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:11725; }","duration":"231.49513ms","start":"2026-09-09T01:30:43.339214Z","end":"2026-09-09T01:30:43.57071Z","steps":["trace[1336686145] 'agreement among raft nodes before linearized reading' (duration: 231.260454ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:30:43.807366Z","caller":"traceutil/trace.go:171","msg":"trace[432356265] linearizableReadLoop","detail":"{readStateIndex:12133; appliedIndex:12132; }","duration":"101.293801ms","start":"2026-09-09T01:30:43.706038Z","end":"2026-09-09T01:30:43.807332Z","steps":["trace[432356265] 'read index received' (duration: 84.040127ms)","trace[432356265] 'applied index is now lower than readState.Index' (duration: 17.252114ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:30:43.807401Z","caller":"traceutil/trace.go:171","msg":"trace[818456649] transaction","detail":"{read_only:false; response_revision:11727; number_of_response:1; }","duration":"172.695102ms","start":"2026-09-09T01:30:43.634667Z","end":"2026-09-09T01:30:43.807362Z","steps":["trace[818456649] 'process raft request' (duration: 155.488869ms)","trace[818456649] 'compare' (duration: 16.917854ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:30:43.807541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.481086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:30:43.807611Z","caller":"traceutil/trace.go:171","msg":"trace[1481855590] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:11727; }","duration":"101.606389ms","start":"2026-09-09T01:30:43.70599Z","end":"2026-09-09T01:30:43.807596Z","steps":["trace[1481855590] 'agreement among raft nodes before linearized reading' (duration: 101.477986ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:33:19.753721Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10440} {"level":"info","ts":"2026-09-09T01:33:19.844102Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10440,"took":"88.173535ms","hash":1770825811,"current-db-size-bytes":51138560,"current-db-size":"51 MB","current-db-size-in-use-bytes":28348416,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-09T01:33:19.844205Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1770825811,"revision":10440,"compact-revision":7964} {"level":"info","ts":"2026-09-09T01:36:54.557302Z","caller":"traceutil/trace.go:171","msg":"trace[2020401456] transaction","detail":"{read_only:false; response_revision:14234; number_of_response:1; }","duration":"158.158054ms","start":"2026-09-09T01:36:54.39912Z","end":"2026-09-09T01:36:54.557278Z","steps":["trace[2020401456] 'process raft request' (duration: 158.00792ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:37:22.242625Z","caller":"traceutil/trace.go:171","msg":"trace[1680831585] transaction","detail":"{read_only:false; response_revision:14375; number_of_response:1; }","duration":"117.182777ms","start":"2026-09-09T01:37:22.125419Z","end":"2026-09-09T01:37:22.242602Z","steps":["trace[1680831585] 'process raft request' (duration: 116.906369ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:37:22.245039Z","caller":"traceutil/trace.go:171","msg":"trace[609057666] transaction","detail":"{read_only:false; response_revision:14376; number_of_response:1; }","duration":"118.315459ms","start":"2026-09-09T01:37:22.126684Z","end":"2026-09-09T01:37:22.244999Z","steps":["trace[609057666] 'process raft request' (duration: 116.990162ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:37:30.512243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.45941ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T01:37:30.512373Z","caller":"traceutil/trace.go:171","msg":"trace[656115380] transaction","detail":"{read_only:false; response_revision:14431; number_of_response:1; }","duration":"232.41746ms","start":"2026-09-09T01:37:30.279929Z","end":"2026-09-09T01:37:30.512347Z","steps":["trace[656115380] 'process raft request' (duration: 23.726554ms)","trace[656115380] 'compare' (duration: 208.337037ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:37:30.521837Z","caller":"traceutil/trace.go:171","msg":"trace[204032393] linearizableReadLoop","detail":"{readStateIndex:14935; appliedIndex:14933; }","duration":"236.971448ms","start":"2026-09-09T01:37:30.28485Z","end":"2026-09-09T01:37:30.521822Z","steps":["trace[204032393] 'read index received' (duration: 18.996791ms)","trace[204032393] 'applied index is now lower than readState.Index' (duration: 217.973797ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:37:30.521898Z","caller":"traceutil/trace.go:171","msg":"trace[776176706] transaction","detail":"{read_only:false; response_revision:14432; number_of_response:1; }","duration":"239.831047ms","start":"2026-09-09T01:37:30.282047Z","end":"2026-09-09T01:37:30.521878Z","steps":["trace[776176706] 'process raft request' (duration: 239.692784ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:37:30.522004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.146453ms","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-09T01:37:30.52204Z","caller":"traceutil/trace.go:171","msg":"trace[976123869] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14432; }","duration":"237.187424ms","start":"2026-09-09T01:37:30.284844Z","end":"2026-09-09T01:37:30.522031Z","steps":["trace[976123869] 'agreement among raft nodes before linearized reading' (duration: 237.06553ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:37:30.522197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.197664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-jtc2g\" ","response":"range_response_count:1 size:12364"} {"level":"warn","ts":"2026-09-09T01:37:30.5222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.339439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:37:30.522286Z","caller":"traceutil/trace.go:171","msg":"trace[354813628] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-jtc2g; range_end:; response_count:1; response_revision:14432; }","duration":"163.330198ms","start":"2026-09-09T01:37:30.358932Z","end":"2026-09-09T01:37:30.522262Z","steps":["trace[354813628] 'agreement among raft nodes before linearized reading' (duration: 163.086341ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:37:30.522323Z","caller":"traceutil/trace.go:171","msg":"trace[1923899125] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:14432; }","duration":"118.510294ms","start":"2026-09-09T01:37:30.403787Z","end":"2026-09-09T01:37:30.522297Z","steps":["trace[1923899125] 'agreement among raft nodes before linearized reading' (duration: 118.317829ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:37:30.522444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.147424ms","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-09T01:37:30.522572Z","caller":"traceutil/trace.go:171","msg":"trace[1271127394] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:14432; }","duration":"133.32958ms","start":"2026-09-09T01:37:30.389213Z","end":"2026-09-09T01:37:30.522543Z","steps":["trace[1271127394] 'agreement among raft nodes before linearized reading' (duration: 132.900748ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:37:31.172845Z","caller":"traceutil/trace.go:171","msg":"trace[963320708] linearizableReadLoop","detail":"{readStateIndex:14940; appliedIndex:14939; }","duration":"290.954187ms","start":"2026-09-09T01:37:30.881868Z","end":"2026-09-09T01:37:31.172822Z","steps":["trace[963320708] 'read index received' (duration: 290.807943ms)","trace[963320708] 'applied index is now lower than readState.Index' (duration: 145.184µs)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:37:31.172971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.076591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:37:31.173004Z","caller":"traceutil/trace.go:171","msg":"trace[353287455] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:14437; }","duration":"291.174263ms","start":"2026-09-09T01:37:30.881819Z","end":"2026-09-09T01:37:31.172993Z","steps":["trace[353287455] 'agreement among raft nodes before linearized reading' (duration: 291.095481ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:37:31.173071Z","caller":"traceutil/trace.go:171","msg":"trace[1050302812] transaction","detail":"{read_only:false; response_revision:14437; number_of_response:1; }","duration":"313.153309ms","start":"2026-09-09T01:37:30.859895Z","end":"2026-09-09T01:37:31.173049Z","steps":["trace[1050302812] 'process raft request' (duration: 312.764858ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:37:31.174022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:37:30.859872Z","time spent":"313.964661ms","remote":"127.0.0.1:32776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T01:37:31.36807Z","caller":"traceutil/trace.go:171","msg":"trace[830209258] transaction","detail":"{read_only:false; response_revision:14438; number_of_response:1; }","duration":"282.936593ms","start":"2026-09-09T01:37:31.085108Z","end":"2026-09-09T01:37:31.368045Z","steps":["trace[830209258] 'process raft request' (duration: 282.724648ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:37:31.368341Z","caller":"traceutil/trace.go:171","msg":"trace[1127101495] transaction","detail":"{read_only:false; response_revision:14439; number_of_response:1; }","duration":"205.203639ms","start":"2026-09-09T01:37:31.163082Z","end":"2026-09-09T01:37:31.368285Z","steps":["trace[1127101495] 'process raft request' (duration: 204.926641ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:37:54.464351Z","caller":"traceutil/trace.go:171","msg":"trace[1083079544] transaction","detail":"{read_only:false; response_revision:14748; number_of_response:1; }","duration":"348.438044ms","start":"2026-09-09T01:37:54.115886Z","end":"2026-09-09T01:37:54.464324Z","steps":["trace[1083079544] 'process raft request' (duration: 348.217068ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:37:54.464677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:37:54.115869Z","time spent":"348.568168ms","remote":"127.0.0.1:32776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T01:37:54.552993Z","caller":"traceutil/trace.go:171","msg":"trace[579568620] linearizableReadLoop","detail":"{readStateIndex:15265; appliedIndex:15263; }","duration":"225.98101ms","start":"2026-09-09T01:37:54.326978Z","end":"2026-09-09T01:37:54.552959Z","steps":["trace[579568620] 'read index received' (duration: 137.109034ms)","trace[579568620] 'applied index is now lower than readState.Index' (duration: 88.870666ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:37:54.553166Z","caller":"traceutil/trace.go:171","msg":"trace[1586139035] transaction","detail":"{read_only:false; response_revision:14749; number_of_response:1; }","duration":"436.907719ms","start":"2026-09-09T01:37:54.116211Z","end":"2026-09-09T01:37:54.553118Z","steps":["trace[1586139035] 'process raft request' (duration: 410.550742ms)","trace[1586139035] 'compare' (duration: 26.043598ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:37:54.553271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.259348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-lppch\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-09-09T01:37:54.553339Z","caller":"traceutil/trace.go:171","msg":"trace[504009958] range","detail":"{range_begin:/registry/pods/openstack/keepalived-lppch; range_end:; response_count:1; response_revision:14749; }","duration":"226.383272ms","start":"2026-09-09T01:37:54.326938Z","end":"2026-09-09T01:37:54.553321Z","steps":["trace[504009958] 'agreement among raft nodes before linearized reading' (duration: 226.164655ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:37:54.553341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:37:54.116189Z","time spent":"437.058653ms","remote":"127.0.0.1:32776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T01:38:19.761521Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12796} {"level":"info","ts":"2026-09-09T01:38:19.847734Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12796,"took":"83.475483ms","hash":3222754469,"current-db-size-bytes":51138560,"current-db-size":"51 MB","current-db-size-in-use-bytes":28254208,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-09T01:38:19.847816Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3222754469,"revision":12796,"compact-revision":10440} {"level":"warn","ts":"2026-09-09T01:38:47.867661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.067929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-09T01:38:47.867775Z","caller":"traceutil/trace.go:171","msg":"trace[409969727] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:15390; }","duration":"105.235814ms","start":"2026-09-09T01:38:47.76252Z","end":"2026-09-09T01:38:47.867756Z","steps":["trace[409969727] 'count revisions from in-memory index tree' (duration: 104.976407ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:39:21.948973Z","caller":"traceutil/trace.go:171","msg":"trace[1556907342] transaction","detail":"{read_only:false; response_revision:15568; number_of_response:1; }","duration":"159.969964ms","start":"2026-09-09T01:39:21.788974Z","end":"2026-09-09T01:39:21.948943Z","steps":["trace[1556907342] 'process raft request' (duration: 159.792669ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:39:22.105745Z","caller":"traceutil/trace.go:171","msg":"trace[156282838] linearizableReadLoop","detail":"{readStateIndex:16109; appliedIndex:16107; }","duration":"264.631801ms","start":"2026-09-09T01:39:21.841062Z","end":"2026-09-09T01:39:22.105694Z","steps":["trace[156282838] 'read index received' (duration: 107.770394ms)","trace[156282838] 'applied index is now lower than readState.Index' (duration: 156.860197ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:39:22.105817Z","caller":"traceutil/trace.go:171","msg":"trace[1209039843] transaction","detail":"{read_only:false; response_revision:15569; number_of_response:1; }","duration":"307.92498ms","start":"2026-09-09T01:39:21.797857Z","end":"2026-09-09T01:39:22.105782Z","steps":["trace[1209039843] 'process raft request' (duration: 254.674291ms)","trace[1209039843] 'compare' (duration: 52.867399ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:39:22.105879Z","caller":"traceutil/trace.go:171","msg":"trace[538132475] transaction","detail":"{read_only:false; response_revision:15570; number_of_response:1; }","duration":"222.995886ms","start":"2026-09-09T01:39:21.88287Z","end":"2026-09-09T01:39:22.105866Z","steps":["trace[538132475] 'process raft request' (duration: 222.719398ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:39:22.105999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:39:21.797813Z","time spent":"308.086416ms","remote":"127.0.0.1:32776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T01:39:22.106203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.136945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-9qmqn\" ","response":"range_response_count:1 size:9796"} {"level":"info","ts":"2026-09-09T01:39:22.106248Z","caller":"traceutil/trace.go:171","msg":"trace[2059530534] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-9qmqn; range_end:; response_count:1; response_revision:15570; }","duration":"265.237048ms","start":"2026-09-09T01:39:21.841Z","end":"2026-09-09T01:39:22.106237Z","steps":["trace[2059530534] 'agreement among raft nodes before linearized reading' (duration: 265.083324ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:39:22.106311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.976505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"warn","ts":"2026-09-09T01:39:22.106377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.922417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:39:22.106426Z","caller":"traceutil/trace.go:171","msg":"trace[70348739] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15570; }","duration":"118.984619ms","start":"2026-09-09T01:39:21.987425Z","end":"2026-09-09T01:39:22.10641Z","steps":["trace[70348739] 'agreement among raft nodes before linearized reading' (duration: 118.920257ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:39:22.106561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.812451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-09T01:39:22.106583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.684477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:39:22.106624Z","caller":"traceutil/trace.go:171","msg":"trace[1663938533] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:15570; }","duration":"119.79709ms","start":"2026-09-09T01:39:21.986818Z","end":"2026-09-09T01:39:22.106615Z","steps":["trace[1663938533] 'agreement among raft nodes before linearized reading' (duration: 119.731119ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:39:22.106629Z","caller":"traceutil/trace.go:171","msg":"trace[1423994484] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:15570; }","duration":"119.955975ms","start":"2026-09-09T01:39:21.986658Z","end":"2026-09-09T01:39:22.106614Z","steps":["trace[1423994484] 'agreement among raft nodes before linearized reading' (duration: 119.854933ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:39:22.106377Z","caller":"traceutil/trace.go:171","msg":"trace[1185472236] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15570; }","duration":"116.060316ms","start":"2026-09-09T01:39:21.990298Z","end":"2026-09-09T01:39:22.106359Z","steps":["trace[1185472236] 'agreement among raft nodes before linearized reading' (duration: 115.901982ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:39:22.106308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.017932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-09-09T01:39:22.106914Z","caller":"traceutil/trace.go:171","msg":"trace[1193062415] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15570; }","duration":"147.63471ms","start":"2026-09-09T01:39:21.959241Z","end":"2026-09-09T01:39:22.106876Z","steps":["trace[1193062415] 'agreement among raft nodes before linearized reading' (duration: 146.91653ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:43:19.771772Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14923} {"level":"info","ts":"2026-09-09T01:43:19.869775Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14923,"took":"95.091859ms","hash":2956166511,"current-db-size-bytes":51138560,"current-db-size":"51 MB","current-db-size-in-use-bytes":30711808,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-09T01:43:19.869858Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2956166511,"revision":14923,"compact-revision":12796} {"level":"warn","ts":"2026-09-09T01:44:51.011613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.250885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-09-09T01:44:51.011698Z","caller":"traceutil/trace.go:171","msg":"trace[69755505] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:18046; }","duration":"202.376329ms","start":"2026-09-09T01:44:50.809305Z","end":"2026-09-09T01:44:51.011681Z","steps":["trace[69755505] 'range keys from in-memory index tree' (duration: 202.090942ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:44:51.011981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.721789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-09T01:44:51.012012Z","caller":"traceutil/trace.go:171","msg":"trace[267217045] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18046; }","duration":"197.777261ms","start":"2026-09-09T01:44:50.814225Z","end":"2026-09-09T01:44:51.012002Z","steps":["trace[267217045] 'range keys from in-memory index tree' (duration: 197.634917ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:44:56.812433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.220896ms","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-09T01:44:56.812529Z","caller":"traceutil/trace.go:171","msg":"trace[1895643408] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18078; }","duration":"142.375561ms","start":"2026-09-09T01:44:56.670133Z","end":"2026-09-09T01:44:56.812508Z","steps":["trace[1895643408] 'range keys from in-memory index tree' (duration: 141.711912ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:44:57.048249Z","caller":"traceutil/trace.go:171","msg":"trace[803908128] transaction","detail":"{read_only:false; response_revision:18079; number_of_response:1; }","duration":"232.412407ms","start":"2026-09-09T01:44:56.81581Z","end":"2026-09-09T01:44:57.048223Z","steps":["trace[803908128] 'process raft request' (duration: 232.187691ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:44:57.106816Z","caller":"traceutil/trace.go:171","msg":"trace[574995154] transaction","detail":"{read_only:false; response_revision:18080; number_of_response:1; }","duration":"290.459221ms","start":"2026-09-09T01:44:56.816329Z","end":"2026-09-09T01:44:57.106788Z","steps":["trace[574995154] 'process raft request' (duration: 289.968807ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:44:57.107068Z","caller":"traceutil/trace.go:171","msg":"trace[2031806969] transaction","detail":"{read_only:false; response_revision:18081; number_of_response:1; }","duration":"289.461443ms","start":"2026-09-09T01:44:56.817595Z","end":"2026-09-09T01:44:57.107056Z","steps":["trace[2031806969] 'process raft request' (duration: 288.850946ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:47:18.989435Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"94bfbb0e034a3ac2","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-09T01:47:18.995278Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-09-09T01:47:18.995427Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-09-09T01:48:19.780018Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17408} {"level":"info","ts":"2026-09-09T01:48:19.888961Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17408,"took":"105.551672ms","hash":1676855103,"current-db-size-bytes":51138560,"current-db-size":"51 MB","current-db-size-in-use-bytes":34140160,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-09-09T01:48:19.889038Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1676855103,"revision":17408,"compact-revision":14923} {"level":"info","ts":"2026-09-09T01:50:08.550014Z","caller":"traceutil/trace.go:171","msg":"trace[1221938006] transaction","detail":"{read_only:false; response_revision:20567; number_of_response:1; }","duration":"146.387662ms","start":"2026-09-09T01:50:08.4036Z","end":"2026-09-09T01:50:08.549988Z","steps":["trace[1221938006] 'process raft request' (duration: 146.256549ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:50:18.922092Z","caller":"traceutil/trace.go:171","msg":"trace[1619021699] transaction","detail":"{read_only:false; response_revision:20627; number_of_response:1; }","duration":"173.193122ms","start":"2026-09-09T01:50:18.748802Z","end":"2026-09-09T01:50:18.921995Z","steps":["trace[1619021699] 'process raft request' (duration: 172.959565ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:50:24.749069Z","caller":"traceutil/trace.go:171","msg":"trace[880803916] linearizableReadLoop","detail":"{readStateIndex:21369; appliedIndex:21368; }","duration":"118.867725ms","start":"2026-09-09T01:50:24.630156Z","end":"2026-09-09T01:50:24.749024Z","steps":["trace[880803916] 'read index received' (duration: 118.587516ms)","trace[880803916] 'applied index is now lower than readState.Index' (duration: 278.189µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:50:24.749173Z","caller":"traceutil/trace.go:171","msg":"trace[1820375047] transaction","detail":"{read_only:false; response_revision:20658; number_of_response:1; }","duration":"131.677562ms","start":"2026-09-09T01:50:24.617456Z","end":"2026-09-09T01:50:24.749134Z","steps":["trace[1820375047] 'process raft request' (duration: 131.329311ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:50:24.749507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.312447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:418288"} {"level":"info","ts":"2026-09-09T01:50:24.749561Z","caller":"traceutil/trace.go:171","msg":"trace[125858133] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:20658; }","duration":"119.44834ms","start":"2026-09-09T01:50:24.630096Z","end":"2026-09-09T01:50:24.749545Z","steps":["trace[125858133] 'agreement among raft nodes before linearized reading' (duration: 119.07776ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:50:25.06855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.591743ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-09T01:50:25.068679Z","caller":"traceutil/trace.go:171","msg":"trace[1271898845] linearizableReadLoop","detail":"{readStateIndex:21370; appliedIndex:21369; }","duration":"234.323233ms","start":"2026-09-09T01:50:24.834335Z","end":"2026-09-09T01:50:25.068658Z","steps":["trace[1271898845] 'read index received' (duration: 113.539436ms)","trace[1271898845] 'applied index is now lower than readState.Index' (duration: 120.781847ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:50:25.068804Z","caller":"traceutil/trace.go:171","msg":"trace[1607898915] transaction","detail":"{read_only:false; response_revision:20659; number_of_response:1; }","duration":"302.500519ms","start":"2026-09-09T01:50:24.766262Z","end":"2026-09-09T01:50:25.068762Z","steps":["trace[1607898915] 'process raft request' (duration: 181.598208ms)","trace[1607898915] 'compare' (duration: 120.459108ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:50:25.069015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:50:24.766229Z","time spent":"302.674223ms","remote":"127.0.0.1:32776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T01:50:25.068837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.493157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-09T01:50:25.069095Z","caller":"traceutil/trace.go:171","msg":"trace[1274629754] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20659; }","duration":"234.794695ms","start":"2026-09-09T01:50:24.834287Z","end":"2026-09-09T01:50:25.069082Z","steps":["trace[1274629754] 'agreement among raft nodes before linearized reading' (duration: 234.481177ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:50:25.338237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.540379ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-09T01:50:25.338382Z","caller":"traceutil/trace.go:171","msg":"trace[325148279] transaction","detail":"{read_only:false; response_revision:20660; number_of_response:1; }","duration":"266.46358ms","start":"2026-09-09T01:50:25.071893Z","end":"2026-09-09T01:50:25.338356Z","steps":["trace[325148279] 'process raft request' (duration: 100.687645ms)","trace[325148279] 'compare' (duration: 165.392715ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:50:25.340082Z","caller":"traceutil/trace.go:171","msg":"trace[232975793] transaction","detail":"{read_only:false; response_revision:20661; number_of_response:1; }","duration":"267.011787ms","start":"2026-09-09T01:50:25.073056Z","end":"2026-09-09T01:50:25.340068Z","steps":["trace[232975793] 'process raft request' (duration: 266.839721ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:50:25.340372Z","caller":"traceutil/trace.go:171","msg":"trace[463251148] transaction","detail":"{read_only:false; response_revision:20662; number_of_response:1; }","duration":"267.192321ms","start":"2026-09-09T01:50:25.073157Z","end":"2026-09-09T01:50:25.340349Z","steps":["trace[463251148] 'process raft request' (duration: 266.871221ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:50:25.340516Z","caller":"traceutil/trace.go:171","msg":"trace[1876636615] transaction","detail":"{read_only:false; response_revision:20663; number_of_response:1; }","duration":"267.251193ms","start":"2026-09-09T01:50:25.073246Z","end":"2026-09-09T01:50:25.340497Z","steps":["trace[1876636615] 'process raft request' (duration: 267.014817ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:50:25.340531Z","caller":"traceutil/trace.go:171","msg":"trace[888588482] linearizableReadLoop","detail":"{readStateIndex:21375; appliedIndex:21370; }","duration":"265.653709ms","start":"2026-09-09T01:50:25.074846Z","end":"2026-09-09T01:50:25.3405Z","steps":["trace[888588482] 'read index received' (duration: 97.738143ms)","trace[888588482] 'applied index is now lower than readState.Index' (duration: 167.914135ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:50:25.340715Z","caller":"traceutil/trace.go:171","msg":"trace[1922622188] transaction","detail":"{read_only:false; response_revision:20664; number_of_response:1; }","duration":"266.833302ms","start":"2026-09-09T01:50:25.073868Z","end":"2026-09-09T01:50:25.340701Z","steps":["trace[1922622188] 'process raft request' (duration: 266.541514ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:50:25.34082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.943246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-09-09T01:50:25.340873Z","caller":"traceutil/trace.go:171","msg":"trace[226298473] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20664; }","duration":"266.0887ms","start":"2026-09-09T01:50:25.074768Z","end":"2026-09-09T01:50:25.340857Z","steps":["trace[226298473] 'agreement among raft nodes before linearized reading' (duration: 265.844684ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:53:19.787675Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19782} {"level":"info","ts":"2026-09-09T01:53:19.914802Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19782,"took":"123.666667ms","hash":2677520051,"current-db-size-bytes":51138560,"current-db-size":"51 MB","current-db-size-in-use-bytes":30097408,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-09T01:53:19.91489Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2677520051,"revision":19782,"compact-revision":17408} {"level":"info","ts":"2026-09-09T01:54:11.663492Z","caller":"traceutil/trace.go:171","msg":"trace[489105387] transaction","detail":"{read_only:false; response_revision:23028; number_of_response:1; }","duration":"321.118057ms","start":"2026-09-09T01:54:11.342301Z","end":"2026-09-09T01:54:11.663419Z","steps":["trace[489105387] 'process raft request' (duration: 300.397429ms)","trace[489105387] 'compare' (duration: 20.205084ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:54:11.663793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:54:11.34226Z","time spent":"321.377685ms","remote":"127.0.0.1:32776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T01:58:19.794776Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22110} {"level":"info","ts":"2026-09-09T01:58:19.903059Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22110,"took":"104.984025ms","hash":801499912,"current-db-size-bytes":52826112,"current-db-size":"53 MB","current-db-size-in-use-bytes":42131456,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-09-09T01:58:19.903128Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":801499912,"revision":22110,"compact-revision":19782} {"level":"info","ts":"2026-09-09T02:03:19.80199Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25199} {"level":"info","ts":"2026-09-09T02:03:19.947568Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25199,"took":"141.862576ms","hash":2751250114,"current-db-size-bytes":52826112,"current-db-size":"53 MB","current-db-size-in-use-bytes":29806592,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-09T02:03:19.947634Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2751250114,"revision":25199,"compact-revision":22110}