{"level":"warn","ts":"2026-10-26T19:18:07.512966Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-26T19:18:07.513066Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.72: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.72:2380","--initial-cluster=instance=https://162.253.55.72:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.72:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.72: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-10-26T19:18:07.513136Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-26T19:18:07.513145Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.72:2380"]} {"level":"info","ts":"2026-10-26T19:18:07.513163Z","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-10-26T19:18:07.513813Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.72:2379"]} {"level":"info","ts":"2026-10-26T19:18:07.51389Z","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.72:2380"],"listen-peer-urls":["https://162.253.55.72:2380"],"advertise-client-urls":["https://162.253.55.72:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.72:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.72: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-10-26T19:18:07.519305Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.161273ms"} {"level":"info","ts":"2026-10-26T19:18:07.526049Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"638d1b11488f2a2e","cluster-id":"9ca31ccb1db898d"} {"level":"info","ts":"2026-10-26T19:18:07.526209Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"638d1b11488f2a2e switched to configuration voters=()"} {"level":"info","ts":"2026-10-26T19:18:07.526247Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"638d1b11488f2a2e became follower at term 0"} {"level":"info","ts":"2026-10-26T19:18:07.526305Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 638d1b11488f2a2e [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-26T19:18:07.526345Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"638d1b11488f2a2e became follower at term 1"} {"level":"info","ts":"2026-10-26T19:18:07.526502Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"638d1b11488f2a2e switched to configuration voters=(7173419542516804142)"} {"level":"warn","ts":"2026-10-26T19:18:07.541163Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-26T19:18:07.543495Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-26T19:18:07.545137Z","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-10-26T19:18:07.548667Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"638d1b11488f2a2e","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-26T19:18:07.548969Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"638d1b11488f2a2e","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-26T19:18:07.549056Z","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-10-26T19:18:07.549387Z","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-10-26T19:18:07.549441Z","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-10-26T19:18:07.550851Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"638d1b11488f2a2e switched to configuration voters=(7173419542516804142)"} {"level":"info","ts":"2026-10-26T19:18:07.550991Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9ca31ccb1db898d","local-member-id":"638d1b11488f2a2e","added-peer-id":"638d1b11488f2a2e","added-peer-peer-urls":["https://162.253.55.72:2380"]} {"level":"info","ts":"2026-10-26T19:18:07.553201Z","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-10-26T19:18:07.553316Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.72:2380"} {"level":"info","ts":"2026-10-26T19:18:07.553399Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.72:2380"} {"level":"info","ts":"2026-10-26T19:18:07.553491Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"638d1b11488f2a2e","initial-advertise-peer-urls":["https://162.253.55.72:2380"],"listen-peer-urls":["https://162.253.55.72:2380"],"advertise-client-urls":["https://162.253.55.72:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.72:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-26T19:18:07.553529Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-26T19:18:08.227462Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"638d1b11488f2a2e is starting a new election at term 1"} {"level":"info","ts":"2026-10-26T19:18:08.227531Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"638d1b11488f2a2e became pre-candidate at term 1"} {"level":"info","ts":"2026-10-26T19:18:08.227576Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"638d1b11488f2a2e received MsgPreVoteResp from 638d1b11488f2a2e at term 1"} {"level":"info","ts":"2026-10-26T19:18:08.227599Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"638d1b11488f2a2e became candidate at term 2"} {"level":"info","ts":"2026-10-26T19:18:08.227614Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"638d1b11488f2a2e received MsgVoteResp from 638d1b11488f2a2e at term 2"} {"level":"info","ts":"2026-10-26T19:18:08.227648Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"638d1b11488f2a2e became leader at term 2"} {"level":"info","ts":"2026-10-26T19:18:08.22767Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 638d1b11488f2a2e elected leader 638d1b11488f2a2e at term 2"} {"level":"info","ts":"2026-10-26T19:18:08.22926Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"638d1b11488f2a2e","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.72:2379]}","request-path":"/0/members/638d1b11488f2a2e/attributes","cluster-id":"9ca31ccb1db898d","publish-timeout":"7s"} {"level":"info","ts":"2026-10-26T19:18:08.229276Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T19:18:08.229552Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-26T19:18:08.230048Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-26T19:18:08.230654Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9ca31ccb1db898d","local-member-id":"638d1b11488f2a2e","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T19:18:08.230689Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-26T19:18:08.230718Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T19:18:08.230724Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-26T19:18:08.230737Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T19:18:08.232901Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.72:2379"} {"level":"info","ts":"2026-10-26T19:18:08.232927Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-26T19:18:25.9312Z","caller":"traceutil/trace.go:171","msg":"trace[1384675951] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"100.058784ms","start":"2026-10-26T19:18:25.83111Z","end":"2026-10-26T19:18:25.931169Z","steps":["trace[1384675951] 'process raft request' (duration: 99.928581ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:18:25.938698Z","caller":"traceutil/trace.go:171","msg":"trace[916114089] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"105.955037ms","start":"2026-10-26T19:18:25.832719Z","end":"2026-10-26T19:18:25.938674Z","steps":["trace[916114089] 'process raft request' (duration: 105.820764ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:18:36.766295Z","caller":"traceutil/trace.go:171","msg":"trace[483333712] linearizableReadLoop","detail":"{readStateIndex:421; appliedIndex:420; }","duration":"143.18875ms","start":"2026-10-26T19:18:36.623084Z","end":"2026-10-26T19:18:36.766273Z","steps":["trace[483333712] 'read index received' (duration: 121.293793ms)","trace[483333712] 'applied index is now lower than readState.Index' (duration: 21.893257ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:18:36.766427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.317113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:18:36.766466Z","caller":"traceutil/trace.go:171","msg":"trace[750787423] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:406; }","duration":"143.438667ms","start":"2026-10-26T19:18:36.62302Z","end":"2026-10-26T19:18:36.766459Z","steps":["trace[750787423] 'agreement among raft nodes before linearized reading' (duration: 143.335034ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:18:54.707487Z","caller":"traceutil/trace.go:171","msg":"trace[330159189] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"237.069923ms","start":"2026-10-26T19:18:54.470382Z","end":"2026-10-26T19:18:54.707452Z","steps":["trace[330159189] 'process raft request' (duration: 222.54624ms)","trace[330159189] 'compare' (duration: 14.189313ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:18:54.707857Z","caller":"traceutil/trace.go:171","msg":"trace[227257321] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"235.562911ms","start":"2026-10-26T19:18:54.472263Z","end":"2026-10-26T19:18:54.707826Z","steps":["trace[227257321] 'process raft request' (duration: 235.078728ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:18:54.70816Z","caller":"traceutil/trace.go:171","msg":"trace[1334781540] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"232.841066ms","start":"2026-10-26T19:18:54.475287Z","end":"2026-10-26T19:18:54.708128Z","steps":["trace[1334781540] 'process raft request' (duration: 232.390174ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:18:54.708225Z","caller":"traceutil/trace.go:171","msg":"trace[63636328] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"222.413627ms","start":"2026-10-26T19:18:54.485793Z","end":"2026-10-26T19:18:54.708207Z","steps":["trace[63636328] 'process raft request' (duration: 222.18109ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:18:54.708502Z","caller":"traceutil/trace.go:171","msg":"trace[1887375339] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"213.961153ms","start":"2026-10-26T19:18:54.494399Z","end":"2026-10-26T19:18:54.708361Z","steps":["trace[1887375339] 'process raft request' (duration: 213.735717ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:18:54.709272Z","caller":"traceutil/trace.go:171","msg":"trace[415729259] linearizableReadLoop","detail":"{readStateIndex:558; appliedIndex:556; }","duration":"236.307952ms","start":"2026-10-26T19:18:54.472947Z","end":"2026-10-26T19:18:54.709255Z","steps":["trace[415729259] 'read index received' (duration: 220.005489ms)","trace[415729259] 'applied index is now lower than readState.Index' (duration: 16.301282ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:18:54.709406Z","caller":"traceutil/trace.go:171","msg":"trace[1176378145] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"124.371958ms","start":"2026-10-26T19:18:54.585023Z","end":"2026-10-26T19:18:54.709395Z","steps":["trace[1176378145] 'process raft request' (duration: 123.319619ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:18:54.709614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.639982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-10-26T19:18:54.709689Z","caller":"traceutil/trace.go:171","msg":"trace[1902621370] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:540; }","duration":"236.771185ms","start":"2026-10-26T19:18:54.472904Z","end":"2026-10-26T19:18:54.709675Z","steps":["trace[1902621370] 'agreement among raft nodes before linearized reading' (duration: 236.635841ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:19:01.623596Z","caller":"traceutil/trace.go:171","msg":"trace[272104671] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"253.930298ms","start":"2026-10-26T19:19:01.369618Z","end":"2026-10-26T19:19:01.623548Z","steps":["trace[272104671] 'process raft request' (duration: 252.250732ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:19:02.196278Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3039544342370560825,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-26T19:19:02.226182Z","caller":"traceutil/trace.go:171","msg":"trace[238194434] linearizableReadLoop","detail":"{readStateIndex:647; appliedIndex:646; }","duration":"530.257692ms","start":"2026-10-26T19:19:01.695892Z","end":"2026-10-26T19:19:02.22615Z","steps":["trace[238194434] 'read index received' (duration: 506.115008ms)","trace[238194434] 'applied index is now lower than readState.Index' (duration: 24.141454ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:19:02.226364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.462177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-10-26T19:19:02.226412Z","caller":"traceutil/trace.go:171","msg":"trace[681024636] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:623; }","duration":"530.56113ms","start":"2026-10-26T19:19:01.695835Z","end":"2026-10-26T19:19:02.226396Z","steps":["trace[681024636] 'agreement among raft nodes before linearized reading' (duration: 530.421256ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:19:02.226485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:19:01.695775Z","time spent":"530.695964ms","remote":"127.0.0.1:38054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":443,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-10-26T19:19:02.226527Z","caller":"traceutil/trace.go:171","msg":"trace[1392952414] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"532.488354ms","start":"2026-10-26T19:19:01.693974Z","end":"2026-10-26T19:19:02.226463Z","steps":["trace[1392952414] 'process raft request' (duration: 508.076062ms)","trace[1392952414] 'compare' (duration: 23.547478ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:19:02.227101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:19:01.693945Z","time spent":"532.656188ms","remote":"127.0.0.1:38134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-26T19:19:17.895808Z","caller":"traceutil/trace.go:171","msg":"trace[147765583] linearizableReadLoop","detail":"{readStateIndex:810; appliedIndex:809; }","duration":"272.430937ms","start":"2026-10-26T19:19:17.623354Z","end":"2026-10-26T19:19:17.895785Z","steps":["trace[147765583] 'read index received' (duration: 272.296084ms)","trace[147765583] 'applied index is now lower than readState.Index' (duration: 134.093µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:19:17.895879Z","caller":"traceutil/trace.go:171","msg":"trace[422674580] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"328.034647ms","start":"2026-10-26T19:19:17.567822Z","end":"2026-10-26T19:19:17.895857Z","steps":["trace[422674580] 'process raft request' (duration: 327.833312ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:19:17.895965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.601553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:19:17.896005Z","caller":"traceutil/trace.go:171","msg":"trace[751214385] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:780; }","duration":"272.676434ms","start":"2026-10-26T19:19:17.623311Z","end":"2026-10-26T19:19:17.895988Z","steps":["trace[751214385] 'agreement among raft nodes before linearized reading' (duration: 272.606812ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:19:17.896219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.867229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-ckjjc\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-10-26T19:19:17.896321Z","caller":"traceutil/trace.go:171","msg":"trace[249929772] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-ckjjc; range_end:; response_count:1; response_revision:780; }","duration":"241.009062ms","start":"2026-10-26T19:19:17.655288Z","end":"2026-10-26T19:19:17.896297Z","steps":["trace[249929772] 'agreement among raft nodes before linearized reading' (duration: 240.714274ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:19:17.896519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:19:17.5678Z","time spent":"328.12899ms","remote":"127.0.0.1:38166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T19:19:25.511725Z","caller":"traceutil/trace.go:171","msg":"trace[1184213651] transaction","detail":"{read_only:false; response_revision:861; number_of_response:1; }","duration":"121.36148ms","start":"2026-10-26T19:19:25.390327Z","end":"2026-10-26T19:19:25.511688Z","steps":["trace[1184213651] 'process raft request' (duration: 117.113753ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:21:11.438561Z","caller":"traceutil/trace.go:171","msg":"trace[912092674] linearizableReadLoop","detail":"{readStateIndex:2192; appliedIndex:2191; }","duration":"169.630246ms","start":"2026-10-26T19:21:11.268879Z","end":"2026-10-26T19:21:11.438509Z","steps":["trace[912092674] 'read index received' (duration: 115.526498ms)","trace[912092674] 'applied index is now lower than readState.Index' (duration: 54.102128ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:21:11.439113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.223812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5732"} {"level":"info","ts":"2026-10-26T19:21:11.439161Z","caller":"traceutil/trace.go:171","msg":"trace[1699766911] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2110; }","duration":"170.313375ms","start":"2026-10-26T19:21:11.268832Z","end":"2026-10-26T19:21:11.439145Z","steps":["trace[1699766911] 'agreement among raft nodes before linearized reading' (duration: 170.102629ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:21:11.439339Z","caller":"traceutil/trace.go:171","msg":"trace[1103724936] transaction","detail":"{read_only:false; response_revision:2110; number_of_response:1; }","duration":"180.029482ms","start":"2026-10-26T19:21:11.258818Z","end":"2026-10-26T19:21:11.438848Z","steps":["trace[1103724936] 'process raft request' (duration: 125.574235ms)","trace[1103724936] 'compare' (duration: 53.662796ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:21:11.453397Z","caller":"traceutil/trace.go:171","msg":"trace[66075840] transaction","detail":"{read_only:false; response_revision:2111; number_of_response:1; }","duration":"145.222894ms","start":"2026-10-26T19:21:11.308132Z","end":"2026-10-26T19:21:11.453355Z","steps":["trace[66075840] 'process raft request' (duration: 144.954727ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:21:11.453503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.054225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18e22a9e5d14e193\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-10-26T19:21:11.453683Z","caller":"traceutil/trace.go:171","msg":"trace[581193210] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18e22a9e5d14e193; range_end:; response_count:1; response_revision:2111; }","duration":"147.290392ms","start":"2026-10-26T19:21:11.306358Z","end":"2026-10-26T19:21:11.453648Z","steps":["trace[581193210] 'agreement among raft nodes before linearized reading' (duration: 146.932472ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:21:16.796709Z","caller":"traceutil/trace.go:171","msg":"trace[115107266] linearizableReadLoop","detail":"{readStateIndex:2219; appliedIndex:2218; }","duration":"171.819106ms","start":"2026-10-26T19:21:16.624871Z","end":"2026-10-26T19:21:16.79669Z","steps":["trace[115107266] 'read index received' (duration: 171.709983ms)","trace[115107266] 'applied index is now lower than readState.Index' (duration: 108.173µs)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:21:16.796824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.94755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:21:16.796849Z","caller":"traceutil/trace.go:171","msg":"trace[652223222] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2136; }","duration":"172.020442ms","start":"2026-10-26T19:21:16.624821Z","end":"2026-10-26T19:21:16.796842Z","steps":["trace[652223222] 'agreement among raft nodes before linearized reading' (duration: 171.95751ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:21:16.796817Z","caller":"traceutil/trace.go:171","msg":"trace[711877434] transaction","detail":"{read_only:false; response_revision:2136; number_of_response:1; }","duration":"172.119615ms","start":"2026-10-26T19:21:16.624646Z","end":"2026-10-26T19:21:16.796765Z","steps":["trace[711877434] 'process raft request' (duration: 171.916989ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:21:16.927576Z","caller":"traceutil/trace.go:171","msg":"trace[98668872] linearizableReadLoop","detail":"{readStateIndex:2220; appliedIndex:2219; }","duration":"130.787637ms","start":"2026-10-26T19:21:16.796761Z","end":"2026-10-26T19:21:16.927549Z","steps":["trace[98668872] 'read index received' (duration: 56.041891ms)","trace[98668872] 'applied index is now lower than readState.Index' (duration: 74.743606ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:21:16.927756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.77408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-26T19:21:16.927795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.440434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12601"} {"level":"info","ts":"2026-10-26T19:21:16.927808Z","caller":"traceutil/trace.go:171","msg":"trace[1179935179] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2136; }","duration":"129.881883ms","start":"2026-10-26T19:21:16.797911Z","end":"2026-10-26T19:21:16.927793Z","steps":["trace[1179935179] 'agreement among raft nodes before linearized reading' (duration: 129.78267ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:21:16.927854Z","caller":"traceutil/trace.go:171","msg":"trace[2001983626] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2136; }","duration":"216.525346ms","start":"2026-10-26T19:21:16.711306Z","end":"2026-10-26T19:21:16.927831Z","steps":["trace[2001983626] 'agreement among raft nodes before linearized reading' (duration: 216.347251ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:21:17.113269Z","caller":"traceutil/trace.go:171","msg":"trace[1651382193] linearizableReadLoop","detail":"{readStateIndex:2223; appliedIndex:2222; }","duration":"128.472364ms","start":"2026-10-26T19:21:16.984773Z","end":"2026-10-26T19:21:17.113245Z","steps":["trace[1651382193] 'read index received' (duration: 93.948094ms)","trace[1651382193] 'applied index is now lower than readState.Index' (duration: 34.52284ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:21:17.1133Z","caller":"traceutil/trace.go:171","msg":"trace[75954144] transaction","detail":"{read_only:false; response_revision:2139; number_of_response:1; }","duration":"133.366399ms","start":"2026-10-26T19:21:16.979903Z","end":"2026-10-26T19:21:17.11327Z","steps":["trace[75954144] 'process raft request' (duration: 98.826909ms)","trace[75954144] 'compare' (duration: 34.297233ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:21:17.113499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.721501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5732"} {"level":"info","ts":"2026-10-26T19:21:17.113542Z","caller":"traceutil/trace.go:171","msg":"trace[821425132] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2139; }","duration":"128.814463ms","start":"2026-10-26T19:21:16.984713Z","end":"2026-10-26T19:21:17.113527Z","steps":["trace[821425132] 'agreement among raft nodes before linearized reading' (duration: 128.629298ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:25:40.904017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.405204ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T19:25:40.904096Z","caller":"traceutil/trace.go:171","msg":"trace[1280240190] linearizableReadLoop","detail":"{readStateIndex:4089; appliedIndex:4088; }","duration":"335.284261ms","start":"2026-10-26T19:25:40.568801Z","end":"2026-10-26T19:25:40.904085Z","steps":["trace[1280240190] 'read index received' (duration: 108.551149ms)","trace[1280240190] 'applied index is now lower than readState.Index' (duration: 226.732072ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:25:40.904184Z","caller":"traceutil/trace.go:171","msg":"trace[699079900] transaction","detail":"{read_only:false; response_revision:3939; number_of_response:1; }","duration":"387.606758ms","start":"2026-10-26T19:25:40.516526Z","end":"2026-10-26T19:25:40.904132Z","steps":["trace[699079900] 'process raft request' (duration: 160.921267ms)","trace[699079900] 'compare' (duration: 226.133395ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:25:40.904238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.432445ms","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-10-26T19:25:40.904258Z","caller":"traceutil/trace.go:171","msg":"trace[1205609514] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3939; }","duration":"335.481827ms","start":"2026-10-26T19:25:40.568766Z","end":"2026-10-26T19:25:40.904248Z","steps":["trace[1205609514] 'agreement among raft nodes before linearized reading' (duration: 335.372884ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:25:40.904275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:25:40.568751Z","time spent":"335.518508ms","remote":"127.0.0.1:38166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-10-26T19:25:40.904272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.524046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:25:40.904311Z","caller":"traceutil/trace.go:171","msg":"trace[1557949978] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3939; }","duration":"281.603628ms","start":"2026-10-26T19:25:40.622694Z","end":"2026-10-26T19:25:40.904298Z","steps":["trace[1557949978] 'agreement among raft nodes before linearized reading' (duration: 281.530446ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:25:40.904307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.251458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-9dgvj\" ","response":"range_response_count:1 size:6527"} {"level":"warn","ts":"2026-10-26T19:25:40.90433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:25:40.516489Z","time spent":"387.762952ms","remote":"127.0.0.1:38166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T19:25:40.904346Z","caller":"traceutil/trace.go:171","msg":"trace[1574159595] range","detail":"{range_begin:/registry/pods/openstack/keepalived-9dgvj; range_end:; response_count:1; response_revision:3939; }","duration":"328.32162ms","start":"2026-10-26T19:25:40.576013Z","end":"2026-10-26T19:25:40.904335Z","steps":["trace[1574159595] 'agreement among raft nodes before linearized reading' (duration: 328.172676ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:25:40.904373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:25:40.575997Z","time spent":"328.367722ms","remote":"127.0.0.1:38064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6549,"request content":"key:\"/registry/pods/openstack/keepalived-9dgvj\" "} {"level":"warn","ts":"2026-10-26T19:25:40.904414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.7082ms","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-10-26T19:25:40.90447Z","caller":"traceutil/trace.go:171","msg":"trace[646880614] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3939; }","duration":"172.821263ms","start":"2026-10-26T19:25:40.731629Z","end":"2026-10-26T19:25:40.90445Z","steps":["trace[646880614] 'agreement among raft nodes before linearized reading' (duration: 172.566406ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:25:40.904458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.990447ms","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-10-26T19:25:40.904579Z","caller":"traceutil/trace.go:171","msg":"trace[12187829] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3939; }","duration":"126.147032ms","start":"2026-10-26T19:25:40.778386Z","end":"2026-10-26T19:25:40.904533Z","steps":["trace[12187829] 'agreement among raft nodes before linearized reading' (duration: 125.858284ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:27:03.386711Z","caller":"traceutil/trace.go:171","msg":"trace[432971952] transaction","detail":"{read_only:false; response_revision:4978; number_of_response:1; }","duration":"139.950738ms","start":"2026-10-26T19:27:03.246649Z","end":"2026-10-26T19:27:03.386599Z","steps":["trace[432971952] 'process raft request' (duration: 139.682941ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:27:03.692319Z","caller":"traceutil/trace.go:171","msg":"trace[1862015169] linearizableReadLoop","detail":"{readStateIndex:5175; appliedIndex:5173; }","duration":"161.399467ms","start":"2026-10-26T19:27:03.530886Z","end":"2026-10-26T19:27:03.692285Z","steps":["trace[1862015169] 'read index received' (duration: 69.977959ms)","trace[1862015169] 'applied index is now lower than readState.Index' (duration: 91.419588ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:27:03.692585Z","caller":"traceutil/trace.go:171","msg":"trace[1666382984] transaction","detail":"{read_only:false; response_revision:4985; number_of_response:1; }","duration":"160.847092ms","start":"2026-10-26T19:27:03.531717Z","end":"2026-10-26T19:27:03.692564Z","steps":["trace[1666382984] 'process raft request' (duration: 160.488262ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:27:03.692695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.799778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/loki-headless\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:27:03.692767Z","caller":"traceutil/trace.go:171","msg":"trace[1948336262] range","detail":"{range_begin:/registry/services/specs/monitoring/loki-headless; range_end:; response_count:0; response_revision:4985; }","duration":"161.937772ms","start":"2026-10-26T19:27:03.530818Z","end":"2026-10-26T19:27:03.692756Z","steps":["trace[1948336262] 'agreement among raft nodes before linearized reading' (duration: 161.739787ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:27:03.692667Z","caller":"traceutil/trace.go:171","msg":"trace[1339371654] transaction","detail":"{read_only:false; response_revision:4984; number_of_response:1; }","duration":"162.930128ms","start":"2026-10-26T19:27:03.529694Z","end":"2026-10-26T19:27:03.692624Z","steps":["trace[1339371654] 'process raft request' (duration: 162.316011ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:27:03.830081Z","caller":"traceutil/trace.go:171","msg":"trace[299964277] linearizableReadLoop","detail":"{readStateIndex:5179; appliedIndex:5176; }","duration":"127.737244ms","start":"2026-10-26T19:27:03.702319Z","end":"2026-10-26T19:27:03.830056Z","steps":["trace[299964277] 'read index received' (duration: 9.255764ms)","trace[299964277] 'applied index is now lower than readState.Index' (duration: 118.48031ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:27:03.830308Z","caller":"traceutil/trace.go:171","msg":"trace[2075068241] transaction","detail":"{read_only:false; response_revision:4987; number_of_response:1; }","duration":"129.79257ms","start":"2026-10-26T19:27:03.700504Z","end":"2026-10-26T19:27:03.830297Z","steps":["trace[2075068241] 'process raft request' (duration: 126.974912ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:27:03.83056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.226217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/monitoring/loki-chunks-cache\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:27:03.830627Z","caller":"traceutil/trace.go:171","msg":"trace[558033019] range","detail":"{range_begin:/registry/statefulsets/monitoring/loki-chunks-cache; range_end:; response_count:0; response_revision:4988; }","duration":"128.360581ms","start":"2026-10-26T19:27:03.702246Z","end":"2026-10-26T19:27:03.830606Z","steps":["trace[558033019] 'agreement among raft nodes before linearized reading' (duration: 128.222607ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:27:03.831252Z","caller":"traceutil/trace.go:171","msg":"trace[1291249252] transaction","detail":"{read_only:false; response_revision:4988; number_of_response:1; }","duration":"130.14607ms","start":"2026-10-26T19:27:03.701068Z","end":"2026-10-26T19:27:03.831214Z","steps":["trace[1291249252] 'process raft request' (duration: 128.835754ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:27:05.623875Z","caller":"traceutil/trace.go:171","msg":"trace[1445962264] linearizableReadLoop","detail":"{readStateIndex:5291; appliedIndex:5290; }","duration":"174.793145ms","start":"2026-10-26T19:27:05.449055Z","end":"2026-10-26T19:27:05.623848Z","steps":["trace[1445962264] 'read index received' (duration: 167.484874ms)","trace[1445962264] 'applied index is now lower than readState.Index' (duration: 7.307011ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:27:05.624188Z","caller":"traceutil/trace.go:171","msg":"trace[321104368] transaction","detail":"{read_only:false; response_revision:5098; number_of_response:1; }","duration":"176.565723ms","start":"2026-10-26T19:27:05.447573Z","end":"2026-10-26T19:27:05.624139Z","steps":["trace[321104368] 'process raft request' (duration: 168.994835ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:27:05.624222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.151084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-9dgvj\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-10-26T19:27:05.624378Z","caller":"traceutil/trace.go:171","msg":"trace[2018836626] range","detail":"{range_begin:/registry/pods/openstack/keepalived-9dgvj; range_end:; response_count:1; response_revision:5098; }","duration":"175.332969ms","start":"2026-10-26T19:27:05.449035Z","end":"2026-10-26T19:27:05.624368Z","steps":["trace[2018836626] 'agreement among raft nodes before linearized reading' (duration: 175.069022ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:27:05.651216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.784198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:27:05.651291Z","caller":"traceutil/trace.go:171","msg":"trace[6320767] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:5099; }","duration":"130.9074ms","start":"2026-10-26T19:27:05.520367Z","end":"2026-10-26T19:27:05.651274Z","steps":["trace[6320767] 'agreement among raft nodes before linearized reading' (duration: 130.771216ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:27:05.651324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.940346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:27:05.651373Z","caller":"traceutil/trace.go:171","msg":"trace[751719699] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:5099; }","duration":"168.034839ms","start":"2026-10-26T19:27:05.483324Z","end":"2026-10-26T19:27:05.651359Z","steps":["trace[751719699] 'agreement among raft nodes before linearized reading' (duration: 167.970828ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:27:05.651366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.30038ms","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-10-26T19:27:05.651399Z","caller":"traceutil/trace.go:171","msg":"trace[344621612] transaction","detail":"{read_only:false; response_revision:5099; number_of_response:1; }","duration":"120.729142ms","start":"2026-10-26T19:27:05.530599Z","end":"2026-10-26T19:27:05.651328Z","steps":["trace[344621612] 'process raft request' (duration: 120.336301ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:27:05.651481Z","caller":"traceutil/trace.go:171","msg":"trace[563704415] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5099; }","duration":"120.444413ms","start":"2026-10-26T19:27:05.530988Z","end":"2026-10-26T19:27:05.651432Z","steps":["trace[563704415] 'agreement among raft nodes before linearized reading' (duration: 120.222907ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:27:05.651741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.770222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:24 size:728944"} {"level":"info","ts":"2026-10-26T19:27:05.651771Z","caller":"traceutil/trace.go:171","msg":"trace[583791277] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:24; response_revision:5099; }","duration":"186.835415ms","start":"2026-10-26T19:27:05.464927Z","end":"2026-10-26T19:27:05.651762Z","steps":["trace[583791277] 'agreement among raft nodes before linearized reading' (duration: 186.507396ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:27:08.640546Z","caller":"traceutil/trace.go:171","msg":"trace[1178294242] transaction","detail":"{read_only:false; response_revision:5155; number_of_response:1; }","duration":"120.124305ms","start":"2026-10-26T19:27:08.520385Z","end":"2026-10-26T19:27:08.640509Z","steps":["trace[1178294242] 'process raft request' (duration: 68.321164ms)","trace[1178294242] 'compare' (duration: 51.249536ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:27:10.612133Z","caller":"traceutil/trace.go:171","msg":"trace[240131058] transaction","detail":"{read_only:false; response_revision:5202; number_of_response:1; }","duration":"107.063507ms","start":"2026-10-26T19:27:10.505035Z","end":"2026-10-26T19:27:10.612098Z","steps":["trace[240131058] 'process raft request' (duration: 102.166353ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:27:19.306528Z","caller":"traceutil/trace.go:171","msg":"trace[1629495010] transaction","detail":"{read_only:false; response_revision:5359; number_of_response:1; }","duration":"168.514422ms","start":"2026-10-26T19:27:19.137964Z","end":"2026-10-26T19:27:19.306478Z","steps":["trace[1629495010] 'process raft request' (duration: 168.074ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:27:19.442852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.457373ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T19:27:19.443043Z","caller":"traceutil/trace.go:171","msg":"trace[1568145407] transaction","detail":"{read_only:false; response_revision:5360; number_of_response:1; }","duration":"127.60924ms","start":"2026-10-26T19:27:19.315405Z","end":"2026-10-26T19:27:19.443015Z","steps":["trace[1568145407] 'process raft request' (duration: 17.85943ms)","trace[1568145407] 'compare' (duration: 109.196585ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:27:19.514651Z","caller":"traceutil/trace.go:171","msg":"trace[911415713] transaction","detail":"{read_only:false; response_revision:5361; number_of_response:1; }","duration":"198.805243ms","start":"2026-10-26T19:27:19.315782Z","end":"2026-10-26T19:27:19.514587Z","steps":["trace[911415713] 'process raft request' (duration: 198.554417ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:27:19.514901Z","caller":"traceutil/trace.go:171","msg":"trace[1127226698] transaction","detail":"{read_only:false; response_revision:5362; number_of_response:1; }","duration":"198.66447ms","start":"2026-10-26T19:27:19.316221Z","end":"2026-10-26T19:27:19.514885Z","steps":["trace[1127226698] 'process raft request' (duration: 198.32057ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:27:19.515123Z","caller":"traceutil/trace.go:171","msg":"trace[1304181034] transaction","detail":"{read_only:false; response_revision:5363; number_of_response:1; }","duration":"198.180046ms","start":"2026-10-26T19:27:19.316914Z","end":"2026-10-26T19:27:19.515094Z","steps":["trace[1304181034] 'process raft request' (duration: 197.905639ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:27:27.184995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.728154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-33931264-1bac-4f07-92f4-50437a2b8131\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-10-26T19:27:27.185108Z","caller":"traceutil/trace.go:171","msg":"trace[2093416843] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-33931264-1bac-4f07-92f4-50437a2b8131; range_end:; response_count:1; response_revision:5496; }","duration":"251.895569ms","start":"2026-10-26T19:27:26.933189Z","end":"2026-10-26T19:27:27.185084Z","steps":["trace[2093416843] 'range keys from in-memory index tree' (duration: 251.56733ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:27:27.311744Z","caller":"traceutil/trace.go:171","msg":"trace[1119079144] transaction","detail":"{read_only:false; response_revision:5498; number_of_response:1; }","duration":"116.65959ms","start":"2026-10-26T19:27:27.195046Z","end":"2026-10-26T19:27:27.311705Z","steps":["trace[1119079144] 'process raft request' (duration: 113.937565ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:27:29.642015Z","caller":"traceutil/trace.go:171","msg":"trace[965842776] linearizableReadLoop","detail":"{readStateIndex:5715; appliedIndex:5714; }","duration":"301.649894ms","start":"2026-10-26T19:27:29.340338Z","end":"2026-10-26T19:27:29.641988Z","steps":["trace[965842776] 'read index received' (duration: 301.415948ms)","trace[965842776] 'applied index is now lower than readState.Index' (duration: 226.656µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:27:29.64215Z","caller":"traceutil/trace.go:171","msg":"trace[611998665] transaction","detail":"{read_only:false; response_revision:5511; number_of_response:1; }","duration":"365.173286ms","start":"2026-10-26T19:27:29.276962Z","end":"2026-10-26T19:27:29.642135Z","steps":["trace[611998665] 'process raft request' (duration: 364.832157ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:27:29.642278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:27:29.276951Z","time spent":"365.232258ms","remote":"127.0.0.1:38166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T19:27:29.642453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.070525ms","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-10-26T19:27:29.642536Z","caller":"traceutil/trace.go:171","msg":"trace[182088458] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5511; }","duration":"302.23982ms","start":"2026-10-26T19:27:29.340278Z","end":"2026-10-26T19:27:29.642518Z","steps":["trace[182088458] 'agreement among raft nodes before linearized reading' (duration: 302.071125ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:27:29.64258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:27:29.340254Z","time spent":"302.312842ms","remote":"127.0.0.1:60802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":29,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-10-26T19:27:42.087272Z","caller":"traceutil/trace.go:171","msg":"trace[390390026] linearizableReadLoop","detail":"{readStateIndex:5901; appliedIndex:5900; }","duration":"166.778825ms","start":"2026-10-26T19:27:41.920465Z","end":"2026-10-26T19:27:42.087244Z","steps":["trace[390390026] 'read index received' (duration: 166.522168ms)","trace[390390026] 'applied index is now lower than readState.Index' (duration: 251.667µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:27:42.08733Z","caller":"traceutil/trace.go:171","msg":"trace[1490454961] transaction","detail":"{read_only:false; response_revision:5692; number_of_response:1; }","duration":"214.836932ms","start":"2026-10-26T19:27:41.87246Z","end":"2026-10-26T19:27:42.087297Z","steps":["trace[1490454961] 'process raft request' (duration: 214.620486ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:27:42.087525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.044252ms","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-10-26T19:27:42.087587Z","caller":"traceutil/trace.go:171","msg":"trace[509524899] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5692; }","duration":"167.120914ms","start":"2026-10-26T19:27:41.92045Z","end":"2026-10-26T19:27:42.087571Z","steps":["trace[509524899] 'agreement among raft nodes before linearized reading' (duration: 166.895488ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:27:42.571703Z","caller":"traceutil/trace.go:171","msg":"trace[1747744291] transaction","detail":"{read_only:false; response_revision:5697; number_of_response:1; }","duration":"121.78491ms","start":"2026-10-26T19:27:42.449902Z","end":"2026-10-26T19:27:42.571687Z","steps":["trace[1747744291] 'process raft request' (duration: 121.721779ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:27:42.571771Z","caller":"traceutil/trace.go:171","msg":"trace[1830751993] transaction","detail":"{read_only:false; response_revision:5696; number_of_response:1; }","duration":"122.554181ms","start":"2026-10-26T19:27:42.449167Z","end":"2026-10-26T19:27:42.571721Z","steps":["trace[1830751993] 'process raft request' (duration: 122.371636ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:27:42.571916Z","caller":"traceutil/trace.go:171","msg":"trace[649135268] transaction","detail":"{read_only:false; response_revision:5698; number_of_response:1; }","duration":"121.205934ms","start":"2026-10-26T19:27:42.450694Z","end":"2026-10-26T19:27:42.5719Z","steps":["trace[649135268] 'process raft request' (duration: 120.966178ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:27:42.572121Z","caller":"traceutil/trace.go:171","msg":"trace[1789879561] linearizableReadLoop","detail":"{readStateIndex:5905; appliedIndex:5903; }","duration":"122.479579ms","start":"2026-10-26T19:27:42.449602Z","end":"2026-10-26T19:27:42.572082Z","steps":["trace[1789879561] 'read index received' (duration: 43.991687ms)","trace[1789879561] 'applied index is now lower than readState.Index' (duration: 78.485292ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:27:42.572536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.905891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-33931264-1bac-4f07-92f4-50437a2b8131\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-10-26T19:27:42.572645Z","caller":"traceutil/trace.go:171","msg":"trace[2052231068] range","detail":"{range_begin:/registry/persistentvolumes/pvc-33931264-1bac-4f07-92f4-50437a2b8131; range_end:; response_count:1; response_revision:5699; }","duration":"123.070586ms","start":"2026-10-26T19:27:42.449544Z","end":"2026-10-26T19:27:42.572614Z","steps":["trace[2052231068] 'agreement among raft nodes before linearized reading' (duration: 122.644084ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:28:08.266258Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2940} {"level":"info","ts":"2026-10-26T19:28:08.348206Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2940,"took":"79.620354ms","hash":547065640,"current-db-size-bytes":56033280,"current-db-size":"56 MB","current-db-size-in-use-bytes":32399360,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-26T19:28:08.348276Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":547065640,"revision":2940,"compact-revision":-1} {"level":"info","ts":"2026-10-26T19:33:08.301517Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5871} {"level":"info","ts":"2026-10-26T19:33:08.398398Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5871,"took":"94.927736ms","hash":745186767,"current-db-size-bytes":56033280,"current-db-size":"56 MB","current-db-size-in-use-bytes":26038272,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-26T19:33:08.398583Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":745186767,"revision":5871,"compact-revision":2940} {"level":"info","ts":"2026-10-26T19:33:41.504986Z","caller":"traceutil/trace.go:171","msg":"trace[965908250] transaction","detail":"{read_only:false; response_revision:8397; number_of_response:1; }","duration":"366.244274ms","start":"2026-10-26T19:33:41.138722Z","end":"2026-10-26T19:33:41.504967Z","steps":["trace[965908250] 'process raft request' (duration: 366.10038ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:33:41.505119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:33:41.138709Z","time spent":"366.342407ms","remote":"127.0.0.1:38166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T19:33:41.507207Z","caller":"traceutil/trace.go:171","msg":"trace[1867748032] transaction","detail":"{read_only:false; response_revision:8398; number_of_response:1; }","duration":"275.124152ms","start":"2026-10-26T19:33:41.232061Z","end":"2026-10-26T19:33:41.507185Z","steps":["trace[1867748032] 'process raft request' (duration: 274.990129ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:33:51.755186Z","caller":"traceutil/trace.go:171","msg":"trace[797882432] linearizableReadLoop","detail":"{readStateIndex:8750; appliedIndex:8748; }","duration":"183.900748ms","start":"2026-10-26T19:33:51.571261Z","end":"2026-10-26T19:33:51.755161Z","steps":["trace[797882432] 'read index received' (duration: 28.893843ms)","trace[797882432] 'applied index is now lower than readState.Index' (duration: 155.005605ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:33:51.755389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.125005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.72\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-10-26T19:33:51.755398Z","caller":"traceutil/trace.go:171","msg":"trace[900822190] transaction","detail":"{read_only:false; response_revision:8450; number_of_response:1; }","duration":"202.682674ms","start":"2026-10-26T19:33:51.552696Z","end":"2026-10-26T19:33:51.755379Z","steps":["trace[900822190] 'process raft request' (duration: 104.300183ms)","trace[900822190] 'compare' (duration: 97.921659ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:33:51.755418Z","caller":"traceutil/trace.go:171","msg":"trace[1793010787] range","detail":"{range_begin:/registry/masterleases/162.253.55.72; range_end:; response_count:1; response_revision:8450; }","duration":"184.202847ms","start":"2026-10-26T19:33:51.571207Z","end":"2026-10-26T19:33:51.75541Z","steps":["trace[1793010787] 'agreement among raft nodes before linearized reading' (duration: 184.041603ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:33:51.964927Z","caller":"traceutil/trace.go:171","msg":"trace[1637144712] transaction","detail":"{read_only:false; response_revision:8451; number_of_response:1; }","duration":"147.443887ms","start":"2026-10-26T19:33:51.817453Z","end":"2026-10-26T19:33:51.964897Z","steps":["trace[1637144712] 'process raft request' (duration: 74.292639ms)","trace[1637144712] 'compare' (duration: 73.029605ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:33:52.358241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.74906ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T19:33:52.358484Z","caller":"traceutil/trace.go:171","msg":"trace[1109868425] transaction","detail":"{read_only:false; response_revision:8452; number_of_response:1; }","duration":"390.083859ms","start":"2026-10-26T19:33:51.968374Z","end":"2026-10-26T19:33:52.358457Z","steps":["trace[1109868425] 'process raft request' (duration: 169.02331ms)","trace[1109868425] 'compare' (duration: 220.189945ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:33:52.358793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:33:51.968361Z","time spent":"390.381988ms","remote":"127.0.0.1:38166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T19:33:52.415634Z","caller":"traceutil/trace.go:171","msg":"trace[167592321] transaction","detail":"{read_only:false; response_revision:8454; number_of_response:1; }","duration":"446.409185ms","start":"2026-10-26T19:33:51.969194Z","end":"2026-10-26T19:33:52.415603Z","steps":["trace[167592321] 'process raft request' (duration: 446.308973ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:33:52.415844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:33:51.969178Z","time spent":"446.557919ms","remote":"127.0.0.1:38166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T19:33:52.416026Z","caller":"traceutil/trace.go:171","msg":"trace[1676344988] transaction","detail":"{read_only:false; response_revision:8455; number_of_response:1; }","duration":"446.186039ms","start":"2026-10-26T19:33:51.969825Z","end":"2026-10-26T19:33:52.416011Z","steps":["trace[1676344988] 'process raft request' (duration: 445.730136ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:33:52.416079Z","caller":"traceutil/trace.go:171","msg":"trace[88173442] linearizableReadLoop","detail":"{readStateIndex:8755; appliedIndex:8752; }","duration":"446.288772ms","start":"2026-10-26T19:33:51.969509Z","end":"2026-10-26T19:33:52.415798Z","steps":["trace[88173442] 'read index received' (duration: 167.898329ms)","trace[88173442] 'applied index is now lower than readState.Index' (duration: 278.388733ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:33:52.416096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:33:51.969811Z","time spent":"446.244331ms","remote":"127.0.0.1:38166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T19:33:52.416329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.813116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-10-26T19:33:52.416398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.986795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-10-26T19:33:52.41638Z","caller":"traceutil/trace.go:171","msg":"trace[35594551] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:8455; }","duration":"446.888769ms","start":"2026-10-26T19:33:51.969479Z","end":"2026-10-26T19:33:52.416368Z","steps":["trace[35594551] 'agreement among raft nodes before linearized reading' (duration: 446.703753ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:33:52.416435Z","caller":"traceutil/trace.go:171","msg":"trace[515301150] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8455; }","duration":"261.059697ms","start":"2026-10-26T19:33:52.155364Z","end":"2026-10-26T19:33:52.416424Z","steps":["trace[515301150] 'agreement among raft nodes before linearized reading' (duration: 260.942254ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:33:52.416463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:33:51.96947Z","time spent":"446.96546ms","remote":"127.0.0.1:37898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"warn","ts":"2026-10-26T19:33:52.416594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.462214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12431"} {"level":"info","ts":"2026-10-26T19:33:52.416619Z","caller":"traceutil/trace.go:171","msg":"trace[145235905] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8455; }","duration":"188.535816ms","start":"2026-10-26T19:33:52.228076Z","end":"2026-10-26T19:33:52.416612Z","steps":["trace[145235905] 'agreement among raft nodes before linearized reading' (duration: 188.452294ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:33:52.416713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.825434ms","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-10-26T19:33:52.416788Z","caller":"traceutil/trace.go:171","msg":"trace[1788213446] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8455; }","duration":"188.940358ms","start":"2026-10-26T19:33:52.227826Z","end":"2026-10-26T19:33:52.416767Z","steps":["trace[1788213446] 'agreement among raft nodes before linearized reading' (duration: 188.723292ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:33:52.416877Z","caller":"traceutil/trace.go:171","msg":"trace[324230923] transaction","detail":"{read_only:false; response_revision:8453; number_of_response:1; }","duration":"447.087324ms","start":"2026-10-26T19:33:51.968604Z","end":"2026-10-26T19:33:52.415692Z","steps":["trace[324230923] 'process raft request' (duration: 446.734754ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:33:52.416977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:33:51.968571Z","time spent":"448.353579ms","remote":"127.0.0.1:38166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T19:33:53.490091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"866.930189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:33:53.490194Z","caller":"traceutil/trace.go:171","msg":"trace[1905487908] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8458; }","duration":"867.090223ms","start":"2026-10-26T19:33:52.623081Z","end":"2026-10-26T19:33:53.490172Z","steps":["trace[1905487908] 'range keys from in-memory index tree' (duration: 866.828177ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:33:53.490252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:33:52.623056Z","time spent":"867.172666ms","remote":"127.0.0.1:37880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-26T19:33:53.490471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"733.977659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:33:53.490554Z","caller":"traceutil/trace.go:171","msg":"trace[1463603439] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:8458; }","duration":"734.126123ms","start":"2026-10-26T19:33:52.75641Z","end":"2026-10-26T19:33:53.490536Z","steps":["trace[1463603439] 'count revisions from in-memory index tree' (duration: 733.799435ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:33:53.490604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:33:52.756389Z","time spent":"734.197656ms","remote":"127.0.0.1:57214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":27,"request content":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true "} {"level":"warn","ts":"2026-10-26T19:33:53.4906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.415052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-26T19:33:53.490784Z","caller":"traceutil/trace.go:171","msg":"trace[1838691568] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:8458; }","duration":"655.647889ms","start":"2026-10-26T19:33:52.835112Z","end":"2026-10-26T19:33:53.49076Z","steps":["trace[1838691568] 'count revisions from in-memory index tree' (duration: 655.30097ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:33:53.490963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:33:52.835077Z","time spent":"655.863514ms","remote":"127.0.0.1:38254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":58,"response size":29,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"info","ts":"2026-10-26T19:33:53.56021Z","caller":"traceutil/trace.go:171","msg":"trace[1204001783] transaction","detail":"{read_only:false; response_revision:8459; number_of_response:1; }","duration":"607.183968ms","start":"2026-10-26T19:33:52.953002Z","end":"2026-10-26T19:33:53.560186Z","steps":["trace[1204001783] 'process raft request' (duration: 606.981213ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:33:53.560402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:33:52.952962Z","time spent":"607.353373ms","remote":"127.0.0.1:38060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":19635,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T19:33:53.664774Z","caller":"traceutil/trace.go:171","msg":"trace[656684939] transaction","detail":"{read_only:false; response_revision:8460; number_of_response:1; }","duration":"597.938304ms","start":"2026-10-26T19:33:53.066808Z","end":"2026-10-26T19:33:53.664746Z","steps":["trace[656684939] 'process raft request' (duration: 534.717618ms)","trace[656684939] 'compare' (duration: 63.098483ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:33:53.664828Z","caller":"traceutil/trace.go:171","msg":"trace[193845970] linearizableReadLoop","detail":"{readStateIndex:8761; appliedIndex:8759; }","duration":"173.224345ms","start":"2026-10-26T19:33:53.491565Z","end":"2026-10-26T19:33:53.664789Z","steps":["trace[193845970] 'read index received' (duration: 68.408588ms)","trace[193845970] 'applied index is now lower than readState.Index' (duration: 104.808977ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:33:53.664918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:33:53.066771Z","time spent":"598.078398ms","remote":"127.0.0.1:38166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T19:33:53.664978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.407241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:33:53.665019Z","caller":"traceutil/trace.go:171","msg":"trace[136943977] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8461; }","duration":"173.475532ms","start":"2026-10-26T19:33:53.491531Z","end":"2026-10-26T19:33:53.665006Z","steps":["trace[136943977] 'agreement among raft nodes before linearized reading' (duration: 173.3844ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:33:53.889932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.440032ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T19:33:53.890138Z","caller":"traceutil/trace.go:171","msg":"trace[1589021561] transaction","detail":"{read_only:false; response_revision:8462; number_of_response:1; }","duration":"129.256599ms","start":"2026-10-26T19:33:53.760843Z","end":"2026-10-26T19:33:53.8901Z","steps":["trace[1589021561] 'process raft request' (duration: 22.559189ms)","trace[1589021561] 'compare' (duration: 106.35144ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:33:54.723616Z","caller":"traceutil/trace.go:171","msg":"trace[2142735526] transaction","detail":"{read_only:false; response_revision:8466; number_of_response:1; }","duration":"299.263015ms","start":"2026-10-26T19:33:54.424324Z","end":"2026-10-26T19:33:54.723587Z","steps":["trace[2142735526] 'process raft request' (duration: 299.205064ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:33:54.723705Z","caller":"traceutil/trace.go:171","msg":"trace[17527872] transaction","detail":"{read_only:false; response_revision:8465; number_of_response:1; }","duration":"299.332677ms","start":"2026-10-26T19:33:54.424325Z","end":"2026-10-26T19:33:54.723658Z","steps":["trace[17527872] 'process raft request' (duration: 299.05547ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:33:54.745298Z","caller":"traceutil/trace.go:171","msg":"trace[656758046] linearizableReadLoop","detail":"{readStateIndex:8768; appliedIndex:8765; }","duration":"298.111674ms","start":"2026-10-26T19:33:54.447166Z","end":"2026-10-26T19:33:54.745278Z","steps":["trace[656758046] 'read index received' (duration: 276.212443ms)","trace[656758046] 'applied index is now lower than readState.Index' (duration: 21.898411ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:33:54.745331Z","caller":"traceutil/trace.go:171","msg":"trace[1822204506] transaction","detail":"{read_only:false; response_revision:8467; number_of_response:1; }","duration":"320.515739ms","start":"2026-10-26T19:33:54.424785Z","end":"2026-10-26T19:33:54.7453Z","steps":["trace[1822204506] 'process raft request' (duration: 320.299164ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:33:54.745394Z","caller":"traceutil/trace.go:171","msg":"trace[1533526789] transaction","detail":"{read_only:false; response_revision:8468; number_of_response:1; }","duration":"152.938539ms","start":"2026-10-26T19:33:54.592417Z","end":"2026-10-26T19:33:54.745356Z","steps":["trace[1533526789] 'process raft request' (duration: 152.813976ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:33:54.745481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:33:54.424766Z","time spent":"320.635862ms","remote":"127.0.0.1:38166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T19:33:54.745492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.091873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-9dgvj\" ","response":"range_response_count:1 size:6527"} {"level":"warn","ts":"2026-10-26T19:33:54.745494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.323149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-10-26T19:33:54.745517Z","caller":"traceutil/trace.go:171","msg":"trace[2080216237] range","detail":"{range_begin:/registry/pods/openstack/keepalived-9dgvj; range_end:; response_count:1; response_revision:8468; }","duration":"294.168576ms","start":"2026-10-26T19:33:54.451341Z","end":"2026-10-26T19:33:54.74551Z","steps":["trace[2080216237] 'agreement among raft nodes before linearized reading' (duration: 294.057863ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:33:54.745528Z","caller":"traceutil/trace.go:171","msg":"trace[258553471] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8468; }","duration":"298.392241ms","start":"2026-10-26T19:33:54.447126Z","end":"2026-10-26T19:33:54.745518Z","steps":["trace[258553471] 'agreement among raft nodes before linearized reading' (duration: 298.241997ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:33:54.745587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.791241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:33:54.74563Z","caller":"traceutil/trace.go:171","msg":"trace[626633630] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8468; }","duration":"118.828592ms","start":"2026-10-26T19:33:54.626788Z","end":"2026-10-26T19:33:54.745616Z","steps":["trace[626633630] 'agreement among raft nodes before linearized reading' (duration: 118.74905ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:33:54.74568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.213345ms","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-10-26T19:33:54.745703Z","caller":"traceutil/trace.go:171","msg":"trace[205509144] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8468; }","duration":"236.278636ms","start":"2026-10-26T19:33:54.509417Z","end":"2026-10-26T19:33:54.745696Z","steps":["trace[205509144] 'agreement among raft nodes before linearized reading' (duration: 236.129642ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:34:23.474036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.656076ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T19:34:23.474136Z","caller":"traceutil/trace.go:171","msg":"trace[588163621] linearizableReadLoop","detail":"{readStateIndex:8980; appliedIndex:8979; }","duration":"578.590274ms","start":"2026-10-26T19:34:22.895531Z","end":"2026-10-26T19:34:23.474121Z","steps":["trace[588163621] 'read index received' (duration: 333.693701ms)","trace[588163621] 'applied index is now lower than readState.Index' (duration: 244.895553ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:34:23.474215Z","caller":"traceutil/trace.go:171","msg":"trace[1651768160] transaction","detail":"{read_only:false; response_revision:8671; number_of_response:1; }","duration":"586.80612ms","start":"2026-10-26T19:34:22.887397Z","end":"2026-10-26T19:34:23.474203Z","steps":["trace[1651768160] 'process raft request' (duration: 341.905117ms)","trace[1651768160] 'compare' (duration: 244.286396ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:34:23.474293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:34:22.887377Z","time spent":"586.865881ms","remote":"127.0.0.1:38166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T19:34:23.4745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.966395ms","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-10-26T19:34:23.474534Z","caller":"traceutil/trace.go:171","msg":"trace[441334966] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8671; }","duration":"579.026456ms","start":"2026-10-26T19:34:22.895497Z","end":"2026-10-26T19:34:23.474523Z","steps":["trace[441334966] 'agreement among raft nodes before linearized reading' (duration: 578.896812ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:34:23.474565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:34:22.895484Z","time spent":"579.073618ms","remote":"127.0.0.1:38166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-26T19:34:23.474691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.183923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-10-26T19:34:23.474764Z","caller":"traceutil/trace.go:171","msg":"trace[149893749] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8671; }","duration":"574.304046ms","start":"2026-10-26T19:34:22.900443Z","end":"2026-10-26T19:34:23.474747Z","steps":["trace[149893749] 'agreement among raft nodes before linearized reading' (duration: 574.09502ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:34:23.474773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.756551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-10-26T19:34:23.474807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:34:22.900425Z","time spent":"574.371598ms","remote":"127.0.0.1:38166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-26T19:34:23.474792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.95048ms","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-10-26T19:34:23.474925Z","caller":"traceutil/trace.go:171","msg":"trace[2109308303] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8671; }","duration":"549.126645ms","start":"2026-10-26T19:34:22.925775Z","end":"2026-10-26T19:34:23.474902Z","steps":["trace[2109308303] 'agreement among raft nodes before linearized reading' (duration: 548.862538ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:34:23.474953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.799741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12431"} {"level":"info","ts":"2026-10-26T19:34:23.474975Z","caller":"traceutil/trace.go:171","msg":"trace[1480748765] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8671; }","duration":"299.850731ms","start":"2026-10-26T19:34:23.175119Z","end":"2026-10-26T19:34:23.47497Z","steps":["trace[1480748765] 'agreement among raft nodes before linearized reading' (duration: 299.738468ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:34:23.474989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:34:22.925758Z","time spent":"549.213397ms","remote":"127.0.0.1:38166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-10-26T19:34:23.475058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.752338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:34:23.474838Z","caller":"traceutil/trace.go:171","msg":"trace[1299021675] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8671; }","duration":"526.867654ms","start":"2026-10-26T19:34:22.947956Z","end":"2026-10-26T19:34:23.474823Z","steps":["trace[1299021675] 'agreement among raft nodes before linearized reading' (duration: 526.666959ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:34:23.475103Z","caller":"traceutil/trace.go:171","msg":"trace[1336037280] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:8671; }","duration":"527.83245ms","start":"2026-10-26T19:34:22.947257Z","end":"2026-10-26T19:34:23.47509Z","steps":["trace[1336037280] 'agreement among raft nodes before linearized reading' (duration: 527.767779ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:34:23.475132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:34:22.947242Z","time spent":"527.881802ms","remote":"127.0.0.1:57220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true "} {"level":"warn","ts":"2026-10-26T19:34:23.475133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:34:22.947941Z","time spent":"527.179673ms","remote":"127.0.0.1:38166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-26T19:34:23.475129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.123683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-9dgvj\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-10-26T19:34:23.475218Z","caller":"traceutil/trace.go:171","msg":"trace[1958857271] range","detail":"{range_begin:/registry/pods/openstack/keepalived-9dgvj; range_end:; response_count:1; response_revision:8671; }","duration":"550.251976ms","start":"2026-10-26T19:34:22.924948Z","end":"2026-10-26T19:34:23.4752Z","steps":["trace[1958857271] 'agreement among raft nodes before linearized reading' (duration: 550.0023ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:34:23.475279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:34:22.92493Z","time spent":"550.321828ms","remote":"127.0.0.1:38064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6549,"request content":"key:\"/registry/pods/openstack/keepalived-9dgvj\" "} {"level":"info","ts":"2026-10-26T19:34:29.641137Z","caller":"traceutil/trace.go:171","msg":"trace[702119083] linearizableReadLoop","detail":"{readStateIndex:9015; appliedIndex:9014; }","duration":"121.68198ms","start":"2026-10-26T19:34:29.519436Z","end":"2026-10-26T19:34:29.641118Z","steps":["trace[702119083] 'read index received' (duration: 121.487245ms)","trace[702119083] 'applied index is now lower than readState.Index' (duration: 194.025µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:34:29.641237Z","caller":"traceutil/trace.go:171","msg":"trace[2118041857] transaction","detail":"{read_only:false; response_revision:8705; number_of_response:1; }","duration":"137.643659ms","start":"2026-10-26T19:34:29.503552Z","end":"2026-10-26T19:34:29.641196Z","steps":["trace[2118041857] 'process raft request' (duration: 137.425113ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:34:29.641366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.924308ms","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-10-26T19:34:29.641394Z","caller":"traceutil/trace.go:171","msg":"trace[554367100] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8705; }","duration":"122.017ms","start":"2026-10-26T19:34:29.519369Z","end":"2026-10-26T19:34:29.641386Z","steps":["trace[554367100] 'agreement among raft nodes before linearized reading' (duration: 121.875726ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:34:29.641575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.155172ms","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-10-26T19:34:29.64169Z","caller":"traceutil/trace.go:171","msg":"trace[794304719] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8705; }","duration":"103.363188ms","start":"2026-10-26T19:34:29.538305Z","end":"2026-10-26T19:34:29.641668Z","steps":["trace[794304719] 'agreement among raft nodes before linearized reading' (duration: 103.021978ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:34:29.812729Z","caller":"traceutil/trace.go:171","msg":"trace[674195284] transaction","detail":"{read_only:false; response_revision:8706; number_of_response:1; }","duration":"166.201513ms","start":"2026-10-26T19:34:29.646493Z","end":"2026-10-26T19:34:29.812694Z","steps":["trace[674195284] 'process raft request' (duration: 146.913173ms)","trace[674195284] 'compare' (duration: 19.100365ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:34:29.990685Z","caller":"traceutil/trace.go:171","msg":"trace[458798480] transaction","detail":"{read_only:false; response_revision:8708; number_of_response:1; }","duration":"342.887903ms","start":"2026-10-26T19:34:29.647771Z","end":"2026-10-26T19:34:29.990658Z","steps":["trace[458798480] 'process raft request' (duration: 342.76154ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:34:29.990741Z","caller":"traceutil/trace.go:171","msg":"trace[1840721787] transaction","detail":"{read_only:false; response_revision:8707; number_of_response:1; }","duration":"343.320545ms","start":"2026-10-26T19:34:29.647358Z","end":"2026-10-26T19:34:29.990678Z","steps":["trace[1840721787] 'process raft request' (duration: 330.954485ms)","trace[1840721787] 'compare' (duration: 12.103393ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:34:29.990837Z","caller":"traceutil/trace.go:171","msg":"trace[1051782036] linearizableReadLoop","detail":"{readStateIndex:9018; appliedIndex:9015; }","duration":"342.963426ms","start":"2026-10-26T19:34:29.64785Z","end":"2026-10-26T19:34:29.990813Z","steps":["trace[1051782036] 'read index received' (duration: 145.543956ms)","trace[1051782036] 'applied index is now lower than readState.Index' (duration: 197.41725ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:34:29.990854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:34:29.647734Z","time spent":"343.034197ms","remote":"127.0.0.1:38166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T19:34:29.990988Z","caller":"traceutil/trace.go:171","msg":"trace[343169696] transaction","detail":"{read_only:false; response_revision:8709; number_of_response:1; }","duration":"341.797614ms","start":"2026-10-26T19:34:29.649159Z","end":"2026-10-26T19:34:29.990956Z","steps":["trace[343169696] 'process raft request' (duration: 341.434074ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:34:29.991027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:34:29.647332Z","time spent":"343.518101ms","remote":"127.0.0.1:38166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T19:34:29.991097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:34:29.649135Z","time spent":"341.892336ms","remote":"127.0.0.1:38166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T19:34:29.991242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.375357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:229876"} {"level":"info","ts":"2026-10-26T19:34:29.991329Z","caller":"traceutil/trace.go:171","msg":"trace[1434547812] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8709; }","duration":"343.50647ms","start":"2026-10-26T19:34:29.647809Z","end":"2026-10-26T19:34:29.991316Z","steps":["trace[1434547812] 'agreement among raft nodes before linearized reading' (duration: 343.156101ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:34:29.991357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:34:29.647799Z","time spent":"343.550131ms","remote":"127.0.0.1:38064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":26,"response size":229898,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"warn","ts":"2026-10-26T19:34:29.992822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.586682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:34:29.992873Z","caller":"traceutil/trace.go:171","msg":"trace[1322407419] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8710; }","duration":"343.651524ms","start":"2026-10-26T19:34:29.64921Z","end":"2026-10-26T19:34:29.992861Z","steps":["trace[1322407419] 'agreement among raft nodes before linearized reading' (duration: 343.537351ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:34:29.99301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.800276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-26T19:34:29.993194Z","caller":"traceutil/trace.go:171","msg":"trace[1895028371] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:8710; }","duration":"192.022702ms","start":"2026-10-26T19:34:29.801136Z","end":"2026-10-26T19:34:29.993158Z","steps":["trace[1895028371] 'agreement among raft nodes before linearized reading' (duration: 191.782395ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:34:29.993146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:34:29.649203Z","time spent":"343.874721ms","remote":"127.0.0.1:37870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-26T19:34:38.035768Z","caller":"traceutil/trace.go:171","msg":"trace[182666919] transaction","detail":"{read_only:false; response_revision:8789; number_of_response:1; }","duration":"157.314379ms","start":"2026-10-26T19:34:37.8784Z","end":"2026-10-26T19:34:38.035714Z","steps":["trace[182666919] 'process raft request' (duration: 136.628181ms)","trace[182666919] 'compare' (duration: 20.267526ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:34:38.093476Z","caller":"traceutil/trace.go:171","msg":"trace[11360938] transaction","detail":"{read_only:false; response_revision:8790; number_of_response:1; }","duration":"201.032979ms","start":"2026-10-26T19:34:37.892422Z","end":"2026-10-26T19:34:38.093455Z","steps":["trace[11360938] 'process raft request' (duration: 200.819643ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:34:38.183996Z","caller":"traceutil/trace.go:171","msg":"trace[1814212744] linearizableReadLoop","detail":"{readStateIndex:9106; appliedIndex:9104; }","duration":"150.297537ms","start":"2026-10-26T19:34:38.033665Z","end":"2026-10-26T19:34:38.183963Z","steps":["trace[1814212744] 'read index received' (duration: 59.736581ms)","trace[1814212744] 'applied index is now lower than readState.Index' (duration: 90.559626ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:34:38.184144Z","caller":"traceutil/trace.go:171","msg":"trace[569372084] transaction","detail":"{read_only:false; response_revision:8791; number_of_response:1; }","duration":"156.447385ms","start":"2026-10-26T19:34:38.027655Z","end":"2026-10-26T19:34:38.184102Z","steps":["trace[569372084] 'process raft request' (duration: 130.318747ms)","trace[569372084] 'compare' (duration: 25.82646ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:34:38.184332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.657156ms","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-10-26T19:34:38.18442Z","caller":"traceutil/trace.go:171","msg":"trace[925197086] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8791; }","duration":"150.79846ms","start":"2026-10-26T19:34:38.033599Z","end":"2026-10-26T19:34:38.184397Z","steps":["trace[925197086] 'agreement among raft nodes before linearized reading' (duration: 150.522152ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:34:38.322738Z","caller":"traceutil/trace.go:171","msg":"trace[1511431350] linearizableReadLoop","detail":"{readStateIndex:9107; appliedIndex:9106; }","duration":"138.625056ms","start":"2026-10-26T19:34:38.184088Z","end":"2026-10-26T19:34:38.322714Z","steps":["trace[1511431350] 'read index received' (duration: 132.100947ms)","trace[1511431350] 'applied index is now lower than readState.Index' (duration: 6.523149ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:34:38.322896Z","caller":"traceutil/trace.go:171","msg":"trace[231265235] transaction","detail":"{read_only:false; response_revision:8792; number_of_response:1; }","duration":"156.930409ms","start":"2026-10-26T19:34:38.165922Z","end":"2026-10-26T19:34:38.322853Z","steps":["trace[231265235] 'process raft request' (duration: 150.039199ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:34:38.323154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.028585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-10-26T19:34:38.323179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.965568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-10-26T19:34:38.323294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.252572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-9dgvj\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-10-26T19:34:38.323543Z","caller":"traceutil/trace.go:171","msg":"trace[1113397661] range","detail":"{range_begin:/registry/pods/openstack/keepalived-9dgvj; range_end:; response_count:1; response_revision:8792; }","duration":"265.636723ms","start":"2026-10-26T19:34:38.057886Z","end":"2026-10-26T19:34:38.323522Z","steps":["trace[1113397661] 'agreement among raft nodes before linearized reading' (duration: 264.905573ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:34:38.323249Z","caller":"traceutil/trace.go:171","msg":"trace[154883223] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8792; }","duration":"283.110992ms","start":"2026-10-26T19:34:38.040119Z","end":"2026-10-26T19:34:38.32323Z","steps":["trace[154883223] 'agreement among raft nodes before linearized reading' (duration: 282.910387ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:34:38.323223Z","caller":"traceutil/trace.go:171","msg":"trace[1405746232] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8792; }","duration":"289.142588ms","start":"2026-10-26T19:34:38.034061Z","end":"2026-10-26T19:34:38.323204Z","steps":["trace[1405746232] 'agreement among raft nodes before linearized reading' (duration: 288.745987ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:34:38.33074Z","caller":"traceutil/trace.go:171","msg":"trace[1274899105] transaction","detail":"{read_only:false; response_revision:8793; number_of_response:1; }","duration":"139.12328ms","start":"2026-10-26T19:34:38.191596Z","end":"2026-10-26T19:34:38.330719Z","steps":["trace[1274899105] 'process raft request' (duration: 139.020547ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:34:38.48315Z","caller":"traceutil/trace.go:171","msg":"trace[1343587214] transaction","detail":"{read_only:false; response_revision:8794; number_of_response:1; }","duration":"155.285233ms","start":"2026-10-26T19:34:38.32784Z","end":"2026-10-26T19:34:38.483125Z","steps":["trace[1343587214] 'process raft request' (duration: 127.549521ms)","trace[1343587214] 'compare' (duration: 27.587508ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:34:38.483308Z","caller":"traceutil/trace.go:171","msg":"trace[840166329] linearizableReadLoop","detail":"{readStateIndex:9110; appliedIndex:9107; }","duration":"154.003568ms","start":"2026-10-26T19:34:38.32928Z","end":"2026-10-26T19:34:38.483283Z","steps":["trace[840166329] 'read index received' (duration: 1.332157ms)","trace[840166329] 'applied index is now lower than readState.Index' (duration: 152.670241ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:34:38.483425Z","caller":"traceutil/trace.go:171","msg":"trace[382785541] transaction","detail":"{read_only:false; response_revision:8795; number_of_response:1; }","duration":"154.42044ms","start":"2026-10-26T19:34:38.328995Z","end":"2026-10-26T19:34:38.483415Z","steps":["trace[382785541] 'process raft request' (duration: 154.097221ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:34:38.483832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.563384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:229742"} {"level":"info","ts":"2026-10-26T19:34:38.48387Z","caller":"traceutil/trace.go:171","msg":"trace[2079867923] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8795; }","duration":"154.644886ms","start":"2026-10-26T19:34:38.329216Z","end":"2026-10-26T19:34:38.483861Z","steps":["trace[2079867923] 'agreement among raft nodes before linearized reading' (duration: 154.325807ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:34:57.671079Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002460.wal"} {"level":"warn","ts":"2026-10-26T19:35:56.345044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.891741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2225401"} {"level":"info","ts":"2026-10-26T19:35:56.34513Z","caller":"traceutil/trace.go:171","msg":"trace[1336236036] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:9487; }","duration":"103.990224ms","start":"2026-10-26T19:35:56.241127Z","end":"2026-10-26T19:35:56.345117Z","steps":["trace[1336236036] 'range keys from in-memory index tree' (duration: 102.896884ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:35:56.450165Z","caller":"traceutil/trace.go:171","msg":"trace[1679835938] transaction","detail":"{read_only:false; response_revision:9488; number_of_response:1; }","duration":"101.379963ms","start":"2026-10-26T19:35:56.348752Z","end":"2026-10-26T19:35:56.450132Z","steps":["trace[1679835938] 'process raft request' (duration: 101.158607ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:35:56.694661Z","caller":"traceutil/trace.go:171","msg":"trace[781762889] transaction","detail":"{read_only:false; response_revision:9490; number_of_response:1; }","duration":"147.801196ms","start":"2026-10-26T19:35:56.546839Z","end":"2026-10-26T19:35:56.69464Z","steps":["trace[781762889] 'process raft request' (duration: 147.670593ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:35:56.739852Z","caller":"traceutil/trace.go:171","msg":"trace[2073348009] linearizableReadLoop","detail":"{readStateIndex:9825; appliedIndex:9824; }","duration":"106.522703ms","start":"2026-10-26T19:35:56.633295Z","end":"2026-10-26T19:35:56.739818Z","steps":["trace[2073348009] 'read index received' (duration: 61.422135ms)","trace[2073348009] 'applied index is now lower than readState.Index' (duration: 45.099358ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:35:56.740094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.779101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-10-26T19:35:56.740146Z","caller":"traceutil/trace.go:171","msg":"trace[727199398] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:9491; }","duration":"106.901164ms","start":"2026-10-26T19:35:56.633227Z","end":"2026-10-26T19:35:56.740129Z","steps":["trace[727199398] 'agreement among raft nodes before linearized reading' (duration: 106.709089ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:35:56.740329Z","caller":"traceutil/trace.go:171","msg":"trace[1700729685] transaction","detail":"{read_only:false; response_revision:9491; number_of_response:1; }","duration":"116.806056ms","start":"2026-10-26T19:35:56.623507Z","end":"2026-10-26T19:35:56.740313Z","steps":["trace[1700729685] 'process raft request' (duration: 116.168508ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:36:09.620145Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"638d1b11488f2a2e","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-26T19:36:09.626395Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-26T19:36:09.626473Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-26T19:38:08.324011Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8047} {"level":"info","ts":"2026-10-26T19:38:08.394029Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8047,"took":"67.993465ms","hash":2524683012,"current-db-size-bytes":56033280,"current-db-size":"56 MB","current-db-size-in-use-bytes":29134848,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-26T19:38:08.394094Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2524683012,"revision":8047,"compact-revision":5871} {"level":"warn","ts":"2026-10-26T19:39:34.388466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.613016ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T19:39:34.388677Z","caller":"traceutil/trace.go:171","msg":"trace[357887175] linearizableReadLoop","detail":"{readStateIndex:11793; appliedIndex:11791; }","duration":"115.818273ms","start":"2026-10-26T19:39:34.27284Z","end":"2026-10-26T19:39:34.388659Z","steps":["trace[357887175] 'read index received' (duration: 2.909839ms)","trace[357887175] 'applied index is now lower than readState.Index' (duration: 112.906924ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:39:34.388685Z","caller":"traceutil/trace.go:171","msg":"trace[81213823] transaction","detail":"{read_only:false; response_revision:11402; number_of_response:1; }","duration":"180.162349ms","start":"2026-10-26T19:39:34.208491Z","end":"2026-10-26T19:39:34.388653Z","steps":["trace[81213823] 'process raft request' (duration: 67.261925ms)","trace[81213823] 'compare' (duration: 112.40095ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:39:34.388823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.988417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-10-26T19:39:34.38886Z","caller":"traceutil/trace.go:171","msg":"trace[486885006] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:11403; }","duration":"116.061389ms","start":"2026-10-26T19:39:34.272787Z","end":"2026-10-26T19:39:34.388849Z","steps":["trace[486885006] 'agreement among raft nodes before linearized reading' (duration: 115.929766ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:39:34.388896Z","caller":"traceutil/trace.go:171","msg":"trace[1019750654] transaction","detail":"{read_only:false; response_revision:11403; number_of_response:1; }","duration":"179.792159ms","start":"2026-10-26T19:39:34.209097Z","end":"2026-10-26T19:39:34.388889Z","steps":["trace[1019750654] 'process raft request' (duration: 179.496831ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:43:08.33268Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10516} {"level":"info","ts":"2026-10-26T19:43:08.421433Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10516,"took":"86.426846ms","hash":3349590502,"current-db-size-bytes":56033280,"current-db-size":"56 MB","current-db-size-in-use-bytes":27045888,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-10-26T19:43:08.421507Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3349590502,"revision":10516,"compact-revision":8047} {"level":"info","ts":"2026-10-26T19:45:51.145459Z","caller":"traceutil/trace.go:171","msg":"trace[2013203469] transaction","detail":"{read_only:false; response_revision:13931; number_of_response:1; }","duration":"114.860625ms","start":"2026-10-26T19:45:51.030561Z","end":"2026-10-26T19:45:51.145421Z","steps":["trace[2013203469] 'process raft request' (duration: 114.67219ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:45:51.492215Z","caller":"traceutil/trace.go:171","msg":"trace[654287882] transaction","detail":"{read_only:false; response_revision:13932; number_of_response:1; }","duration":"125.874441ms","start":"2026-10-26T19:45:51.366317Z","end":"2026-10-26T19:45:51.492192Z","steps":["trace[654287882] 'process raft request' (duration: 125.729677ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:46:07.027213Z","caller":"traceutil/trace.go:171","msg":"trace[1293835398] linearizableReadLoop","detail":"{readStateIndex:14509; appliedIndex:14508; }","duration":"164.099546ms","start":"2026-10-26T19:46:06.863086Z","end":"2026-10-26T19:46:07.027185Z","steps":["trace[1293835398] 'read index received' (duration: 68.57491ms)","trace[1293835398] 'applied index is now lower than readState.Index' (duration: 95.523496ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:46:07.027445Z","caller":"traceutil/trace.go:171","msg":"trace[595360658] transaction","detail":"{read_only:false; response_revision:14027; number_of_response:1; }","duration":"186.201551ms","start":"2026-10-26T19:46:06.841212Z","end":"2026-10-26T19:46:07.027414Z","steps":["trace[595360658] 'process raft request' (duration: 90.429498ms)","trace[595360658] 'compare' (duration: 95.419163ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:46:07.02747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.369433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-987gl\" ","response":"range_response_count:1 size:12360"} {"level":"info","ts":"2026-10-26T19:46:07.027537Z","caller":"traceutil/trace.go:171","msg":"trace[612493177] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-987gl; range_end:; response_count:1; response_revision:14027; }","duration":"164.490346ms","start":"2026-10-26T19:46:06.863033Z","end":"2026-10-26T19:46:07.027523Z","steps":["trace[612493177] 'agreement among raft nodes before linearized reading' (duration: 164.26552ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:46:07.937805Z","caller":"traceutil/trace.go:171","msg":"trace[1280013170] linearizableReadLoop","detail":"{readStateIndex:14513; appliedIndex:14512; }","duration":"312.206009ms","start":"2026-10-26T19:46:07.625523Z","end":"2026-10-26T19:46:07.937729Z","steps":["trace[1280013170] 'read index received' (duration: 312.154767ms)","trace[1280013170] 'applied index is now lower than readState.Index' (duration: 47.302µs)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:46:07.937922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.378224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:46:07.938007Z","caller":"traceutil/trace.go:171","msg":"trace[1581126032] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14030; }","duration":"312.511428ms","start":"2026-10-26T19:46:07.62548Z","end":"2026-10-26T19:46:07.937991Z","steps":["trace[1581126032] 'agreement among raft nodes before linearized reading' (duration: 312.363294ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:46:07.938067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:46:07.625459Z","time spent":"312.598219ms","remote":"127.0.0.1:37880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-26T19:46:07.937794Z","caller":"traceutil/trace.go:171","msg":"trace[1838800278] transaction","detail":"{read_only:false; response_revision:14030; number_of_response:1; }","duration":"363.360169ms","start":"2026-10-26T19:46:07.574347Z","end":"2026-10-26T19:46:07.937707Z","steps":["trace[1838800278] 'process raft request' (duration: 363.206615ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:46:07.938534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:46:07.574321Z","time spent":"364.142979ms","remote":"127.0.0.1:38166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T19:46:07.97623Z","caller":"traceutil/trace.go:171","msg":"trace[519585866] transaction","detail":"{read_only:false; response_revision:14031; number_of_response:1; }","duration":"142.695878ms","start":"2026-10-26T19:46:07.833495Z","end":"2026-10-26T19:46:07.976191Z","steps":["trace[519585866] 'process raft request' (duration: 140.660143ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:46:07.976319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.960342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-10-26T19:46:07.976409Z","caller":"traceutil/trace.go:171","msg":"trace[233725184] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14031; }","duration":"101.099076ms","start":"2026-10-26T19:46:07.875288Z","end":"2026-10-26T19:46:07.976387Z","steps":["trace[233725184] 'agreement among raft nodes before linearized reading' (duration: 100.850799ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:46:09.137547Z","caller":"traceutil/trace.go:171","msg":"trace[1280051521] transaction","detail":"{read_only:false; response_revision:14041; number_of_response:1; }","duration":"105.644719ms","start":"2026-10-26T19:46:09.031872Z","end":"2026-10-26T19:46:09.137517Z","steps":["trace[1280051521] 'process raft request' (duration: 92.380321ms)","trace[1280051521] 'compare' (duration: 12.740264ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:46:24.255029Z","caller":"traceutil/trace.go:171","msg":"trace[1915564437] transaction","detail":"{read_only:false; response_revision:14214; number_of_response:1; }","duration":"204.72107ms","start":"2026-10-26T19:46:24.050198Z","end":"2026-10-26T19:46:24.254919Z","steps":["trace[1915564437] 'process raft request' (duration: 130.069237ms)","trace[1915564437] 'compare' (duration: 74.101649ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:46:24.255182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.998592ms","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-10-26T19:46:24.255237Z","caller":"traceutil/trace.go:171","msg":"trace[144396900] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:14214; }","duration":"194.109295ms","start":"2026-10-26T19:46:24.061112Z","end":"2026-10-26T19:46:24.255221Z","steps":["trace[144396900] 'agreement among raft nodes before linearized reading' (duration: 193.999492ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:46:24.254731Z","caller":"traceutil/trace.go:171","msg":"trace[1652905584] linearizableReadLoop","detail":"{readStateIndex:14708; appliedIndex:14707; }","duration":"193.532129ms","start":"2026-10-26T19:46:24.061163Z","end":"2026-10-26T19:46:24.254695Z","steps":["trace[1652905584] 'read index received' (duration: 119.067591ms)","trace[1652905584] 'applied index is now lower than readState.Index' (duration: 74.461888ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:46:24.255806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.954331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-10-26T19:46:24.255846Z","caller":"traceutil/trace.go:171","msg":"trace[2090307891] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14214; }","duration":"174.079995ms","start":"2026-10-26T19:46:24.081755Z","end":"2026-10-26T19:46:24.255835Z","steps":["trace[2090307891] 'agreement among raft nodes before linearized reading' (duration: 173.920751ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:46:24.256164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.938758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-fp2p5\" ","response":"range_response_count:1 size:20669"} {"level":"info","ts":"2026-10-26T19:46:24.256228Z","caller":"traceutil/trace.go:171","msg":"trace[563711195] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-fp2p5; range_end:; response_count:1; response_revision:14214; }","duration":"122.054111ms","start":"2026-10-26T19:46:24.134158Z","end":"2026-10-26T19:46:24.256212Z","steps":["trace[563711195] 'agreement among raft nodes before linearized reading' (duration: 121.896687ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:46:24.465014Z","caller":"traceutil/trace.go:171","msg":"trace[1498287937] transaction","detail":"{read_only:false; response_revision:14215; number_of_response:1; }","duration":"205.013139ms","start":"2026-10-26T19:46:24.259961Z","end":"2026-10-26T19:46:24.464974Z","steps":["trace[1498287937] 'process raft request' (duration: 193.410206ms)","trace[1498287937] 'compare' (duration: 10.38194ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:46:24.465489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.648232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12431"} {"level":"info","ts":"2026-10-26T19:46:24.46557Z","caller":"traceutil/trace.go:171","msg":"trace[1437181798] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:14215; }","duration":"203.780875ms","start":"2026-10-26T19:46:24.261768Z","end":"2026-10-26T19:46:24.465549Z","steps":["trace[1437181798] 'agreement among raft nodes before linearized reading' (duration: 203.536239ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:46:24.465963Z","caller":"traceutil/trace.go:171","msg":"trace[1213827851] linearizableReadLoop","detail":"{readStateIndex:14709; appliedIndex:14708; }","duration":"203.404495ms","start":"2026-10-26T19:46:24.26181Z","end":"2026-10-26T19:46:24.465215Z","steps":["trace[1213827851] 'read index received' (duration: 191.412512ms)","trace[1213827851] 'applied index is now lower than readState.Index' (duration: 11.989423ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:46:24.72743Z","caller":"traceutil/trace.go:171","msg":"trace[519926866] linearizableReadLoop","detail":"{readStateIndex:14711; appliedIndex:14709; }","duration":"261.374708ms","start":"2026-10-26T19:46:24.466017Z","end":"2026-10-26T19:46:24.727392Z","steps":["trace[519926866] 'read index received' (duration: 259.292642ms)","trace[519926866] 'applied index is now lower than readState.Index' (duration: 2.080506ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:46:24.727489Z","caller":"traceutil/trace.go:171","msg":"trace[797623721] transaction","detail":"{read_only:false; response_revision:14217; number_of_response:1; }","duration":"328.141469ms","start":"2026-10-26T19:46:24.399294Z","end":"2026-10-26T19:46:24.727436Z","steps":["trace[797623721] 'process raft request' (duration: 328.011446ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:46:24.727862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:46:24.399267Z","time spent":"328.340774ms","remote":"127.0.0.1:38166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T19:46:24.728067Z","caller":"traceutil/trace.go:171","msg":"trace[2000146936] transaction","detail":"{read_only:false; response_revision:14216; number_of_response:1; }","duration":"456.388347ms","start":"2026-10-26T19:46:24.27163Z","end":"2026-10-26T19:46:24.728019Z","steps":["trace[2000146936] 'process raft request' (duration: 453.742016ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:46:24.728149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.680165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:91 size:960588"} {"level":"info","ts":"2026-10-26T19:46:24.728204Z","caller":"traceutil/trace.go:171","msg":"trace[1619630257] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:91; response_revision:14217; }","duration":"311.777848ms","start":"2026-10-26T19:46:24.416415Z","end":"2026-10-26T19:46:24.728193Z","steps":["trace[1619630257] 'agreement among raft nodes before linearized reading' (duration: 311.11924ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:46:24.728219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.93714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-10-26T19:46:24.728243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:46:24.271618Z","time spent":"456.539382ms","remote":"127.0.0.1:38064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20788,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T19:46:24.728286Z","caller":"traceutil/trace.go:171","msg":"trace[1914834894] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:14217; }","duration":"217.038453ms","start":"2026-10-26T19:46:24.511227Z","end":"2026-10-26T19:46:24.728266Z","steps":["trace[1914834894] 'agreement among raft nodes before linearized reading' (duration: 216.895339ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:46:24.728085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.247125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19138"} {"level":"info","ts":"2026-10-26T19:46:24.728357Z","caller":"traceutil/trace.go:171","msg":"trace[2084179287] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:14217; }","duration":"261.570233ms","start":"2026-10-26T19:46:24.466768Z","end":"2026-10-26T19:46:24.728338Z","steps":["trace[2084179287] 'agreement among raft nodes before linearized reading' (duration: 261.157332ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:46:24.728235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:46:24.416397Z","time spent":"311.830969ms","remote":"127.0.0.1:37966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":91,"response size":960610,"request content":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" "} {"level":"warn","ts":"2026-10-26T19:46:24.728384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.901598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-10-26T19:46:24.728466Z","caller":"traceutil/trace.go:171","msg":"trace[1425642091] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:14217; }","duration":"227.016181ms","start":"2026-10-26T19:46:24.501424Z","end":"2026-10-26T19:46:24.728441Z","steps":["trace[1425642091] 'agreement among raft nodes before linearized reading' (duration: 226.736534ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:46:24.728542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.101845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:64 size:618733"} {"level":"info","ts":"2026-10-26T19:46:24.728584Z","caller":"traceutil/trace.go:171","msg":"trace[2046566680] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:64; response_revision:14217; }","duration":"464.205548ms","start":"2026-10-26T19:46:24.264367Z","end":"2026-10-26T19:46:24.728573Z","steps":["trace[2046566680] 'agreement among raft nodes before linearized reading' (duration: 463.216011ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:46:24.728618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:46:24.264356Z","time spent":"464.248259ms","remote":"127.0.0.1:38064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":64,"response size":618755,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"warn","ts":"2026-10-26T19:46:24.729986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.209409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:356"} {"level":"info","ts":"2026-10-26T19:46:24.730051Z","caller":"traceutil/trace.go:171","msg":"trace[1595300304] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:14217; }","duration":"279.305692ms","start":"2026-10-26T19:46:24.450729Z","end":"2026-10-26T19:46:24.730035Z","steps":["trace[1595300304] 'agreement among raft nodes before linearized reading' (duration: 276.693842ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:47:54.966012Z","caller":"traceutil/trace.go:171","msg":"trace[1016285929] transaction","detail":"{read_only:false; response_revision:15143; number_of_response:1; }","duration":"139.795729ms","start":"2026-10-26T19:47:54.826197Z","end":"2026-10-26T19:47:54.965993Z","steps":["trace[1016285929] 'process raft request' (duration: 139.374558ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:47:55.264228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.78619ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T19:47:55.264306Z","caller":"traceutil/trace.go:171","msg":"trace[1857921526] linearizableReadLoop","detail":"{readStateIndex:15662; appliedIndex:15661; }","duration":"102.089363ms","start":"2026-10-26T19:47:55.162204Z","end":"2026-10-26T19:47:55.264293Z","steps":["trace[1857921526] 'read index received' (duration: 32.051µs)","trace[1857921526] 'applied index is now lower than readState.Index' (duration: 102.056432ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:47:55.264478Z","caller":"traceutil/trace.go:171","msg":"trace[1571979755] transaction","detail":"{read_only:false; response_revision:15144; number_of_response:1; }","duration":"263.249529ms","start":"2026-10-26T19:47:55.001218Z","end":"2026-10-26T19:47:55.264468Z","steps":["trace[1571979755] 'process raft request' (duration: 143.160521ms)","trace[1571979755] 'compare' (duration: 119.44709ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:47:55.264719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.511544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-74fxs\" ","response":"range_response_count:1 size:22128"} {"level":"info","ts":"2026-10-26T19:47:55.26475Z","caller":"traceutil/trace.go:171","msg":"trace[1428862982] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-74fxs; range_end:; response_count:1; response_revision:15144; }","duration":"102.571366ms","start":"2026-10-26T19:47:55.162171Z","end":"2026-10-26T19:47:55.264742Z","steps":["trace[1428862982] 'agreement among raft nodes before linearized reading' (duration: 102.460463ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:47:55.458628Z","caller":"traceutil/trace.go:171","msg":"trace[1840381166] linearizableReadLoop","detail":"{readStateIndex:15663; appliedIndex:15662; }","duration":"126.980844ms","start":"2026-10-26T19:47:55.331616Z","end":"2026-10-26T19:47:55.458597Z","steps":["trace[1840381166] 'read index received' (duration: 117.048666ms)","trace[1840381166] 'applied index is now lower than readState.Index' (duration: 9.931008ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:47:55.458822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.190089ms","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-10-26T19:47:55.45886Z","caller":"traceutil/trace.go:171","msg":"trace[1171286335] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15145; }","duration":"127.270382ms","start":"2026-10-26T19:47:55.33158Z","end":"2026-10-26T19:47:55.458851Z","steps":["trace[1171286335] 'agreement among raft nodes before linearized reading' (duration: 127.112117ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:47:55.458732Z","caller":"traceutil/trace.go:171","msg":"trace[1746149062] transaction","detail":"{read_only:false; response_revision:15145; number_of_response:1; }","duration":"155.885234ms","start":"2026-10-26T19:47:55.302813Z","end":"2026-10-26T19:47:55.458698Z","steps":["trace[1746149062] 'process raft request' (duration: 145.847983ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:47:55.459264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.203203ms","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-10-26T19:47:55.45947Z","caller":"traceutil/trace.go:171","msg":"trace[1652706750] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15145; }","duration":"126.43834ms","start":"2026-10-26T19:47:55.333013Z","end":"2026-10-26T19:47:55.459452Z","steps":["trace[1652706750] 'agreement among raft nodes before linearized reading' (duration: 126.143542ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:47:55.459595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.686927ms","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-10-26T19:47:55.459681Z","caller":"traceutil/trace.go:171","msg":"trace[222835097] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:15145; }","duration":"106.79184ms","start":"2026-10-26T19:47:55.35287Z","end":"2026-10-26T19:47:55.459662Z","steps":["trace[222835097] 'agreement among raft nodes before linearized reading' (duration: 106.696327ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:47:55.459799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.719064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-10-26T19:47:55.459858Z","caller":"traceutil/trace.go:171","msg":"trace[1750380321] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15145; }","duration":"104.806357ms","start":"2026-10-26T19:47:55.355037Z","end":"2026-10-26T19:47:55.459844Z","steps":["trace[1750380321] 'agreement among raft nodes before linearized reading' (duration: 104.654292ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:47:55.459351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.42974ms","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-10-26T19:47:55.459927Z","caller":"traceutil/trace.go:171","msg":"trace[125738571] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15145; }","duration":"107.018566ms","start":"2026-10-26T19:47:55.352884Z","end":"2026-10-26T19:47:55.459903Z","steps":["trace[125738571] 'agreement among raft nodes before linearized reading' (duration: 106.41961ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:47:55.459493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.948214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:47:55.460105Z","caller":"traceutil/trace.go:171","msg":"trace[1024413755] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:15145; }","duration":"107.611132ms","start":"2026-10-26T19:47:55.352482Z","end":"2026-10-26T19:47:55.460093Z","steps":["trace[1024413755] 'agreement among raft nodes before linearized reading' (duration: 106.894463ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:47:55.460177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.819748ms","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-10-26T19:47:55.460235Z","caller":"traceutil/trace.go:171","msg":"trace[472284228] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15145; }","duration":"110.910401ms","start":"2026-10-26T19:47:55.349309Z","end":"2026-10-26T19:47:55.460219Z","steps":["trace[472284228] 'agreement among raft nodes before linearized reading' (duration: 110.775037ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:47:55.768513Z","caller":"traceutil/trace.go:171","msg":"trace[207159902] transaction","detail":"{read_only:false; response_revision:15149; number_of_response:1; }","duration":"131.568508ms","start":"2026-10-26T19:47:55.636913Z","end":"2026-10-26T19:47:55.768481Z","steps":["trace[207159902] 'process raft request' (duration: 95.002832ms)","trace[207159902] 'compare' (duration: 36.197086ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:47:55.935047Z","caller":"traceutil/trace.go:171","msg":"trace[595389420] transaction","detail":"{read_only:false; response_revision:15150; number_of_response:1; }","duration":"198.869773ms","start":"2026-10-26T19:47:55.73616Z","end":"2026-10-26T19:47:55.935029Z","steps":["trace[595389420] 'process raft request' (duration: 191.983638ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:48:01.895348Z","caller":"traceutil/trace.go:171","msg":"trace[773653385] transaction","detail":"{read_only:false; response_revision:15182; number_of_response:1; }","duration":"110.698165ms","start":"2026-10-26T19:48:01.784606Z","end":"2026-10-26T19:48:01.895304Z","steps":["trace[773653385] 'process raft request' (duration: 31.613713ms)","trace[773653385] 'compare' (duration: 78.888167ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:48:08.339442Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12771} {"level":"info","ts":"2026-10-26T19:48:08.434353Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12771,"took":"92.530316ms","hash":667004447,"current-db-size-bytes":56033280,"current-db-size":"56 MB","current-db-size-in-use-bytes":30289920,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-26T19:48:08.434421Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":667004447,"revision":12771,"compact-revision":10516} {"level":"info","ts":"2026-10-26T19:53:08.346701Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15213} {"level":"info","ts":"2026-10-26T19:53:08.447769Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15213,"took":"98.279845ms","hash":627903516,"current-db-size-bytes":56033280,"current-db-size":"56 MB","current-db-size-in-use-bytes":31789056,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-26T19:53:08.447826Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":627903516,"revision":15213,"compact-revision":12771} {"level":"info","ts":"2026-10-26T19:53:44.585798Z","caller":"traceutil/trace.go:171","msg":"trace[532530754] transaction","detail":"{read_only:false; response_revision:17734; number_of_response:1; }","duration":"110.516557ms","start":"2026-10-26T19:53:44.475261Z","end":"2026-10-26T19:53:44.585778Z","steps":["trace[532530754] 'process raft request' (duration: 110.390573ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:53:49.832579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.354371ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-26T19:53:49.832703Z","caller":"traceutil/trace.go:171","msg":"trace[1975662330] linearizableReadLoop","detail":"{readStateIndex:18367; appliedIndex:18366; }","duration":"372.127542ms","start":"2026-10-26T19:53:49.460553Z","end":"2026-10-26T19:53:49.832681Z","steps":["trace[1975662330] 'read index received' (duration: 198.503073ms)","trace[1975662330] 'applied index is now lower than readState.Index' (duration: 173.622428ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:53:49.833238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.677967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-26T19:53:49.833436Z","caller":"traceutil/trace.go:171","msg":"trace[2108110371] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:17760; }","duration":"372.775299ms","start":"2026-10-26T19:53:49.46051Z","end":"2026-10-26T19:53:49.833286Z","steps":["trace[2108110371] 'agreement among raft nodes before linearized reading' (duration: 372.557233ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:53:49.833511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:53:49.460495Z","time spent":"372.998786ms","remote":"127.0.0.1:38166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-26T19:53:49.83363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.714442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T19:53:49.833734Z","caller":"traceutil/trace.go:171","msg":"trace[1567920731] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17760; }","duration":"204.870316ms","start":"2026-10-26T19:53:49.62884Z","end":"2026-10-26T19:53:49.833711Z","steps":["trace[1567920731] 'agreement among raft nodes before linearized reading' (duration: 204.725252ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:53:49.834123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.406498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-26T19:53:49.834176Z","caller":"traceutil/trace.go:171","msg":"trace[1131263357] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17760; }","duration":"119.500641ms","start":"2026-10-26T19:53:49.714662Z","end":"2026-10-26T19:53:49.834163Z","steps":["trace[1131263357] 'agreement among raft nodes before linearized reading' (duration: 118.861493ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:53:49.834357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.372132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-26T19:53:49.834405Z","caller":"traceutil/trace.go:171","msg":"trace[864197396] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:17760; }","duration":"239.555696ms","start":"2026-10-26T19:53:49.594838Z","end":"2026-10-26T19:53:49.834394Z","steps":["trace[864197396] 'agreement among raft nodes before linearized reading' (duration: 238.57997ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:53:49.833088Z","caller":"traceutil/trace.go:171","msg":"trace[1814586580] transaction","detail":"{read_only:false; response_revision:17760; number_of_response:1; }","duration":"385.168086ms","start":"2026-10-26T19:53:49.447892Z","end":"2026-10-26T19:53:49.83306Z","steps":["trace[1814586580] 'process raft request' (duration: 211.227719ms)","trace[1814586580] 'compare' (duration: 172.95426ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:53:49.834908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:53:49.447838Z","time spent":"386.916123ms","remote":"127.0.0.1:38166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T19:53:50.106566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.623328ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-26T19:53:50.106981Z","caller":"traceutil/trace.go:171","msg":"trace[1771287282] transaction","detail":"{read_only:false; response_revision:17762; number_of_response:1; }","duration":"266.856828ms","start":"2026-10-26T19:53:49.840091Z","end":"2026-10-26T19:53:50.106948Z","steps":["trace[1771287282] 'process raft request' (duration: 99.783207ms)","trace[1771287282] 'compare' (duration: 166.461664ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:53:50.106981Z","caller":"traceutil/trace.go:171","msg":"trace[120074789] transaction","detail":"{read_only:false; response_revision:17763; number_of_response:1; }","duration":"266.756435ms","start":"2026-10-26T19:53:49.840189Z","end":"2026-10-26T19:53:50.106946Z","steps":["trace[120074789] 'process raft request' (duration: 266.494027ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:53:50.107Z","caller":"traceutil/trace.go:171","msg":"trace[1490832447] transaction","detail":"{read_only:false; response_revision:17764; number_of_response:1; }","duration":"266.502977ms","start":"2026-10-26T19:53:49.840469Z","end":"2026-10-26T19:53:50.106972Z","steps":["trace[1490832447] 'process raft request' (duration: 266.294952ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:53:50.107174Z","caller":"traceutil/trace.go:171","msg":"trace[62205628] linearizableReadLoop","detail":"{readStateIndex:18371; appliedIndex:18367; }","duration":"265.84845ms","start":"2026-10-26T19:53:49.840973Z","end":"2026-10-26T19:53:50.106822Z","steps":["trace[62205628] 'read index received' (duration: 79.494577ms)","trace[62205628] 'applied index is now lower than readState.Index' (duration: 186.352643ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:53:50.107193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.186629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12432"} {"level":"info","ts":"2026-10-26T19:53:50.107236Z","caller":"traceutil/trace.go:171","msg":"trace[1514843780] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:17764; }","duration":"266.279401ms","start":"2026-10-26T19:53:49.840944Z","end":"2026-10-26T19:53:50.107224Z","steps":["trace[1514843780] 'agreement among raft nodes before linearized reading' (duration: 266.120757ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:53:50.10765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.108631ms","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-10-26T19:53:50.107726Z","caller":"traceutil/trace.go:171","msg":"trace[268847173] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:17764; }","duration":"261.218574ms","start":"2026-10-26T19:53:49.84649Z","end":"2026-10-26T19:53:50.107708Z","steps":["trace[268847173] 'agreement among raft nodes before linearized reading' (duration: 261.023049ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:53:50.107924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.680698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-74fxs\" ","response":"range_response_count:1 size:22129"} {"level":"warn","ts":"2026-10-26T19:53:50.108077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.738902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-fp2p5\" ","response":"range_response_count:1 size:20906"} {"level":"info","ts":"2026-10-26T19:53:50.108145Z","caller":"traceutil/trace.go:171","msg":"trace[981037632] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-fp2p5; range_end:; response_count:1; response_revision:17764; }","duration":"114.838675ms","start":"2026-10-26T19:53:49.99329Z","end":"2026-10-26T19:53:50.108128Z","steps":["trace[981037632] 'agreement among raft nodes before linearized reading' (duration: 114.641769ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:53:50.108118Z","caller":"traceutil/trace.go:171","msg":"trace[859305984] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-74fxs; range_end:; response_count:1; response_revision:17764; }","duration":"118.927726ms","start":"2026-10-26T19:53:49.989161Z","end":"2026-10-26T19:53:50.108088Z","steps":["trace[859305984] 'agreement among raft nodes before linearized reading' (duration: 118.333019ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:53:51.01131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.960307ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-26T19:53:51.011442Z","caller":"traceutil/trace.go:171","msg":"trace[1817894383] linearizableReadLoop","detail":"{readStateIndex:18372; appliedIndex:18371; }","duration":"885.928256ms","start":"2026-10-26T19:53:50.125487Z","end":"2026-10-26T19:53:51.011416Z","steps":["trace[1817894383] 'read index received' (duration: 367.746233ms)","trace[1817894383] 'applied index is now lower than readState.Index' (duration: 518.180283ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:53:51.011732Z","caller":"traceutil/trace.go:171","msg":"trace[2117003698] transaction","detail":"{read_only:false; response_revision:17765; number_of_response:1; }","duration":"900.284856ms","start":"2026-10-26T19:53:50.111427Z","end":"2026-10-26T19:53:51.011712Z","steps":["trace[2117003698] 'process raft request' (duration: 381.790984ms)","trace[2117003698] 'compare' (duration: 517.210117ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:53:51.011828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:53:50.11141Z","time spent":"900.368697ms","remote":"127.0.0.1:38166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T19:53:51.012911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"887.400596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:94 size:948533"} {"level":"info","ts":"2026-10-26T19:53:51.012986Z","caller":"traceutil/trace.go:171","msg":"trace[1082732778] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:94; response_revision:17765; }","duration":"887.513439ms","start":"2026-10-26T19:53:50.125452Z","end":"2026-10-26T19:53:51.012965Z","steps":["trace[1082732778] 'agreement among raft nodes before linearized reading' (duration: 886.546392ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:53:51.013025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:53:50.125435Z","time spent":"887.577871ms","remote":"127.0.0.1:38064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":94,"response size":948555,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-10-26T19:53:51.108402Z","caller":"traceutil/trace.go:171","msg":"trace[1317535753] transaction","detail":"{read_only:false; response_revision:17767; number_of_response:1; }","duration":"970.88442ms","start":"2026-10-26T19:53:50.137425Z","end":"2026-10-26T19:53:51.108309Z","steps":["trace[1317535753] 'process raft request' (duration: 970.813788ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:53:51.108545Z","caller":"traceutil/trace.go:171","msg":"trace[1107878086] transaction","detail":"{read_only:false; response_revision:17766; number_of_response:1; }","duration":"971.379333ms","start":"2026-10-26T19:53:50.137116Z","end":"2026-10-26T19:53:51.108495Z","steps":["trace[1107878086] 'process raft request' (duration: 970.993743ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:53:51.108652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:53:50.137387Z","time spent":"971.149178ms","remote":"127.0.0.1:38064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":21021,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T19:53:51.108729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:53:50.137099Z","time spent":"971.529688ms","remote":"127.0.0.1:38166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T19:53:51.273437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.626448ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-26T19:53:51.273524Z","caller":"traceutil/trace.go:171","msg":"trace[1529394627] linearizableReadLoop","detail":"{readStateIndex:18375; appliedIndex:18374; }","duration":"262.031027ms","start":"2026-10-26T19:53:51.011481Z","end":"2026-10-26T19:53:51.273512Z","steps":["trace[1529394627] 'read index received' (duration: 97.348171ms)","trace[1529394627] 'applied index is now lower than readState.Index' (duration: 164.681766ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:53:51.273617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"693.280352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-26T19:53:51.273642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.677042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-10-26T19:53:51.27368Z","caller":"traceutil/trace.go:171","msg":"trace[934666259] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:17768; }","duration":"693.392205ms","start":"2026-10-26T19:53:50.580273Z","end":"2026-10-26T19:53:51.273665Z","steps":["trace[934666259] 'agreement among raft nodes before linearized reading' (duration: 693.287952ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:53:51.273646Z","caller":"traceutil/trace.go:171","msg":"trace[1171677814] transaction","detail":"{read_only:false; response_revision:17768; number_of_response:1; }","duration":"375.179544ms","start":"2026-10-26T19:53:50.898395Z","end":"2026-10-26T19:53:51.273574Z","steps":["trace[1171677814] 'process raft request' (duration: 232.342811ms)","trace[1171677814] 'compare' (duration: 142.33652ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:53:51.273718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:53:50.580255Z","time spent":"693.455876ms","remote":"127.0.0.1:38314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"info","ts":"2026-10-26T19:53:51.273682Z","caller":"traceutil/trace.go:171","msg":"trace[1663431204] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:17768; }","duration":"662.740283ms","start":"2026-10-26T19:53:50.610928Z","end":"2026-10-26T19:53:51.273668Z","steps":["trace[1663431204] 'agreement among raft nodes before linearized reading' (duration: 662.611559ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:53:51.27373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.495527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-26T19:53:51.273874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:53:50.61084Z","time spent":"662.99048ms","remote":"127.0.0.1:38166","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-10-26T19:53:51.273692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"885.470974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-7zx2n\" ","response":"range_response_count:1 size:9796"} {"level":"info","ts":"2026-10-26T19:53:51.273959Z","caller":"traceutil/trace.go:171","msg":"trace[1367726126] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:17768; }","duration":"148.829936ms","start":"2026-10-26T19:53:51.125116Z","end":"2026-10-26T19:53:51.273946Z","steps":["trace[1367726126] 'agreement among raft nodes before linearized reading' (duration: 148.478167ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:53:51.274199Z","caller":"traceutil/trace.go:171","msg":"trace[1673226888] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-7zx2n; range_end:; response_count:1; response_revision:17768; }","duration":"885.971138ms","start":"2026-10-26T19:53:50.38817Z","end":"2026-10-26T19:53:51.274141Z","steps":["trace[1673226888] 'agreement among raft nodes before linearized reading' (duration: 885.411612ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:53:51.274388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:53:50.388137Z","time spent":"886.183103ms","remote":"127.0.0.1:38064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9818,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-7zx2n\" "} {"level":"warn","ts":"2026-10-26T19:53:51.27382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.079377962s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T19:53:51.274462Z","caller":"traceutil/trace.go:171","msg":"trace[762840759] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:17768; }","duration":"1.080068171s","start":"2026-10-26T19:53:50.194382Z","end":"2026-10-26T19:53:51.27445Z","steps":["trace[762840759] 'agreement among raft nodes before linearized reading' (duration: 1.079337971s)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:53:51.27449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:53:50.194365Z","time spent":"1.080114693s","remote":"127.0.0.1:38344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-10-26T19:53:51.273817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:53:50.898372Z","time spent":"375.36001ms","remote":"127.0.0.1:38166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T19:53:51.273753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.593898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-26T19:53:51.274709Z","caller":"traceutil/trace.go:171","msg":"trace[1265049653] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:17768; }","duration":"521.621936ms","start":"2026-10-26T19:53:50.753067Z","end":"2026-10-26T19:53:51.274689Z","steps":["trace[1265049653] 'agreement among raft nodes before linearized reading' (duration: 520.520057ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:53:51.274764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:53:50.753052Z","time spent":"521.697998ms","remote":"127.0.0.1:38390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":4,"response size":30,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"info","ts":"2026-10-26T19:54:26.469933Z","caller":"traceutil/trace.go:171","msg":"trace[1690190202] linearizableReadLoop","detail":"{readStateIndex:18740; appliedIndex:18739; }","duration":"102.089749ms","start":"2026-10-26T19:54:26.367812Z","end":"2026-10-26T19:54:26.469902Z","steps":["trace[1690190202] 'read index received' (duration: 13.860656ms)","trace[1690190202] 'applied index is now lower than readState.Index' (duration: 88.227582ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:54:26.470212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.368466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-26T19:54:26.470225Z","caller":"traceutil/trace.go:171","msg":"trace[264198270] transaction","detail":"{read_only:false; response_revision:18120; number_of_response:1; }","duration":"104.74386ms","start":"2026-10-26T19:54:26.365444Z","end":"2026-10-26T19:54:26.470188Z","steps":["trace[264198270] 'process raft request' (duration: 16.200659ms)","trace[264198270] 'compare' (duration: 88.11097ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:54:26.470267Z","caller":"traceutil/trace.go:171","msg":"trace[1302976624] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18120; }","duration":"102.484929ms","start":"2026-10-26T19:54:26.367766Z","end":"2026-10-26T19:54:26.470251Z","steps":["trace[1302976624] 'agreement among raft nodes before linearized reading' (duration: 102.294964ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:56:57.249362Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"638d1b11488f2a2e","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-26T19:56:57.25678Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-26T19:56:57.256867Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-26T19:58:08.352873Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17490} {"level":"info","ts":"2026-10-26T19:58:08.458119Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17490,"took":"101.952267ms","hash":3181948436,"current-db-size-bytes":56033280,"current-db-size":"56 MB","current-db-size-in-use-bytes":34557952,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-10-26T19:58:08.458204Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3181948436,"revision":17490,"compact-revision":15213} {"level":"info","ts":"2026-10-26T19:59:40.69543Z","caller":"traceutil/trace.go:171","msg":"trace[450999162] transaction","detail":"{read_only:false; response_revision:20464; number_of_response:1; }","duration":"118.513461ms","start":"2026-10-26T19:59:40.5769Z","end":"2026-10-26T19:59:40.695413Z","steps":["trace[450999162] 'process raft request' (duration: 118.087819ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:59:40.940968Z","caller":"traceutil/trace.go:171","msg":"trace[520478422] linearizableReadLoop","detail":"{readStateIndex:21164; appliedIndex:21163; }","duration":"136.83297ms","start":"2026-10-26T19:59:40.804109Z","end":"2026-10-26T19:59:40.940942Z","steps":["trace[520478422] 'read index received' (duration: 123.717245ms)","trace[520478422] 'applied index is now lower than readState.Index' (duration: 13.114395ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:59:40.941035Z","caller":"traceutil/trace.go:171","msg":"trace[1730742947] transaction","detail":"{read_only:false; response_revision:20466; number_of_response:1; }","duration":"201.923397ms","start":"2026-10-26T19:59:40.739051Z","end":"2026-10-26T19:59:40.940975Z","steps":["trace[1730742947] 'process raft request' (duration: 188.847233ms)","trace[1730742947] 'compare' (duration: 12.677303ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:59:40.941187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.056146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"warn","ts":"2026-10-26T19:59:40.941297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.725073ms","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-10-26T19:59:40.941358Z","caller":"traceutil/trace.go:171","msg":"trace[1838371011] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20466; }","duration":"120.811355ms","start":"2026-10-26T19:59:40.820529Z","end":"2026-10-26T19:59:40.941341Z","steps":["trace[1838371011] 'agreement among raft nodes before linearized reading' (duration: 120.587169ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:59:40.941357Z","caller":"traceutil/trace.go:171","msg":"trace[1448209364] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:20466; }","duration":"137.158509ms","start":"2026-10-26T19:59:40.804077Z","end":"2026-10-26T19:59:40.941235Z","steps":["trace[1448209364] 'agreement among raft nodes before linearized reading' (duration: 136.954263ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:59:56.873486Z","caller":"traceutil/trace.go:171","msg":"trace[632759863] transaction","detail":"{read_only:false; response_revision:20549; number_of_response:1; }","duration":"120.454634ms","start":"2026-10-26T19:59:56.753007Z","end":"2026-10-26T19:59:56.873462Z","steps":["trace[632759863] 'process raft request' (duration: 120.28747ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:59:57.202651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.934065ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-26T19:59:57.202937Z","caller":"traceutil/trace.go:171","msg":"trace[377408916] linearizableReadLoop","detail":"{readStateIndex:21251; appliedIndex:21250; }","duration":"148.559005ms","start":"2026-10-26T19:59:57.05436Z","end":"2026-10-26T19:59:57.202919Z","steps":["trace[377408916] 'read index received' (duration: 55.031µs)","trace[377408916] 'applied index is now lower than readState.Index' (duration: 148.502514ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:59:57.203003Z","caller":"traceutil/trace.go:171","msg":"trace[1659635624] transaction","detail":"{read_only:false; response_revision:20550; number_of_response:1; }","duration":"184.921615ms","start":"2026-10-26T19:59:57.018037Z","end":"2026-10-26T19:59:57.202958Z","steps":["trace[1659635624] 'compare' (duration: 175.695209ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:59:57.203211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.856044ms","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-10-26T19:59:57.203285Z","caller":"traceutil/trace.go:171","msg":"trace[2011027242] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20550; }","duration":"148.971637ms","start":"2026-10-26T19:59:57.0543Z","end":"2026-10-26T19:59:57.203272Z","steps":["trace[2011027242] 'agreement among raft nodes before linearized reading' (duration: 148.741871ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:59:57.503441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.02337ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-10-26T19:59:57.504147Z","caller":"traceutil/trace.go:171","msg":"trace[713219917] transaction","detail":"{read_only:false; response_revision:20551; number_of_response:1; }","duration":"295.006101ms","start":"2026-10-26T19:59:57.209118Z","end":"2026-10-26T19:59:57.504125Z","steps":["trace[713219917] 'process raft request' (duration: 294.443526ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:59:57.504303Z","caller":"traceutil/trace.go:171","msg":"trace[1727159604] transaction","detail":"{read_only:false; response_revision:20552; number_of_response:1; }","duration":"173.777254ms","start":"2026-10-26T19:59:57.330491Z","end":"2026-10-26T19:59:57.504269Z","steps":["trace[1727159604] 'process raft request' (duration: 173.567818ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:59:57.504343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.210873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T19:59:57.504448Z","caller":"traceutil/trace.go:171","msg":"trace[335310191] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:20552; }","duration":"404.364298ms","start":"2026-10-26T19:59:57.100061Z","end":"2026-10-26T19:59:57.504426Z","steps":["trace[335310191] 'agreement among raft nodes before linearized reading' (duration: 404.169292ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:59:57.504505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:59:57.100041Z","time spent":"404.44879ms","remote":"127.0.0.1:42338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true "} {"level":"info","ts":"2026-10-26T19:59:57.50465Z","caller":"traceutil/trace.go:171","msg":"trace[768054617] linearizableReadLoop","detail":"{readStateIndex:21252; appliedIndex:21251; }","duration":"301.030028ms","start":"2026-10-26T19:59:57.203015Z","end":"2026-10-26T19:59:57.504045Z","steps":["trace[768054617] 'read index received' (duration: 58.528945ms)","trace[768054617] 'applied index is now lower than readState.Index' (duration: 242.494723ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:59:57.50492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.2539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T19:59:57.504981Z","caller":"traceutil/trace.go:171","msg":"trace[2111633707] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:20552; }","duration":"218.357712ms","start":"2026-10-26T19:59:57.286603Z","end":"2026-10-26T19:59:57.50496Z","steps":["trace[2111633707] 'agreement among raft nodes before linearized reading' (duration: 218.235659ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:59:57.50516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.617345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-10-26T19:59:57.505323Z","caller":"traceutil/trace.go:171","msg":"trace[334554329] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20552; }","duration":"171.80542ms","start":"2026-10-26T19:59:57.333496Z","end":"2026-10-26T19:59:57.505301Z","steps":["trace[334554329] 'agreement among raft nodes before linearized reading' (duration: 171.513752ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:59:57.838778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.663037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T19:59:57.838938Z","caller":"traceutil/trace.go:171","msg":"trace[1637549008] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20553; }","duration":"211.848492ms","start":"2026-10-26T19:59:57.627073Z","end":"2026-10-26T19:59:57.838921Z","steps":["trace[1637549008] 'range keys from in-memory index tree' (duration: 211.576985ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:59:57.839108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.620608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T19:59:57.839171Z","caller":"traceutil/trace.go:171","msg":"trace[380951791] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:20553; }","duration":"179.726791ms","start":"2026-10-26T19:59:57.659429Z","end":"2026-10-26T19:59:57.839156Z","steps":["trace[380951791] 'count revisions from in-memory index tree' (duration: 179.540236ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:03:08.376624Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19959} {"level":"info","ts":"2026-10-26T20:03:08.572389Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19959,"took":"187.21373ms","hash":3376019539,"current-db-size-bytes":56033280,"current-db-size":"56 MB","current-db-size-in-use-bytes":30814208,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-26T20:03:08.573175Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3376019539,"revision":19959,"compact-revision":17490} {"level":"info","ts":"2026-10-26T20:03:32.973815Z","caller":"traceutil/trace.go:171","msg":"trace[1791529226] transaction","detail":"{read_only:false; response_revision:22516; number_of_response:1; }","duration":"217.049057ms","start":"2026-10-26T20:03:32.756725Z","end":"2026-10-26T20:03:32.973774Z","steps":["trace[1791529226] 'process raft request' (duration: 161.786713ms)","trace[1791529226] 'compare' (duration: 55.12697ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:03:33.00501Z","caller":"traceutil/trace.go:171","msg":"trace[744890600] transaction","detail":"{read_only:false; response_revision:22517; number_of_response:1; }","duration":"242.554937ms","start":"2026-10-26T20:03:32.762408Z","end":"2026-10-26T20:03:33.004963Z","steps":["trace[744890600] 'process raft request' (duration: 242.3133ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:03:33.005194Z","caller":"traceutil/trace.go:171","msg":"trace[1883727139] transaction","detail":"{read_only:false; response_revision:22518; number_of_response:1; }","duration":"214.22933ms","start":"2026-10-26T20:03:32.790937Z","end":"2026-10-26T20:03:33.005166Z","steps":["trace[1883727139] 'process raft request' (duration: 213.945273ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:03:33.005382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.714589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/machinedrainrules.cluster.x-k8s.io\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T20:03:33.005472Z","caller":"traceutil/trace.go:171","msg":"trace[846066586] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/machinedrainrules.cluster.x-k8s.io; range_end:; response_count:0; response_revision:22518; }","duration":"235.850102ms","start":"2026-10-26T20:03:32.769607Z","end":"2026-10-26T20:03:33.005457Z","steps":["trace[846066586] 'agreement among raft nodes before linearized reading' (duration: 235.641027ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:03:33.005224Z","caller":"traceutil/trace.go:171","msg":"trace[1952744735] linearizableReadLoop","detail":"{readStateIndex:23283; appliedIndex:23281; }","duration":"235.547445ms","start":"2026-10-26T20:03:32.769633Z","end":"2026-10-26T20:03:33.005181Z","steps":["trace[1952744735] 'read index received' (duration: 148.950792ms)","trace[1952744735] 'applied index is now lower than readState.Index' (duration: 86.556122ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T20:03:33.006369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.203058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-10-26T20:03:33.00642Z","caller":"traceutil/trace.go:171","msg":"trace[180214546] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:22518; }","duration":"130.29622ms","start":"2026-10-26T20:03:32.876109Z","end":"2026-10-26T20:03:33.006405Z","steps":["trace[180214546] 'agreement among raft nodes before linearized reading' (duration: 129.971571ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:03:43.768288Z","caller":"traceutil/trace.go:171","msg":"trace[1889347007] linearizableReadLoop","detail":"{readStateIndex:23791; appliedIndex:23790; }","duration":"258.073442ms","start":"2026-10-26T20:03:43.510192Z","end":"2026-10-26T20:03:43.768266Z","steps":["trace[1889347007] 'read index received' (duration: 257.842015ms)","trace[1889347007] 'applied index is now lower than readState.Index' (duration: 230.617µs)"],"step_count":2} {"level":"warn","ts":"2026-10-26T20:03:43.768782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.08179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-26T20:03:43.768838Z","caller":"traceutil/trace.go:171","msg":"trace[1602847143] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23015; }","duration":"224.185663ms","start":"2026-10-26T20:03:43.544639Z","end":"2026-10-26T20:03:43.768825Z","steps":["trace[1602847143] 'agreement among raft nodes before linearized reading' (duration: 224.007418ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:03:43.769119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.087678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T20:03:43.769147Z","caller":"traceutil/trace.go:171","msg":"trace[1456192670] transaction","detail":"{read_only:false; response_revision:23015; number_of_response:1; }","duration":"321.433858ms","start":"2026-10-26T20:03:43.447691Z","end":"2026-10-26T20:03:43.769125Z","steps":["trace[1456192670] 'process raft request' (duration: 320.40987ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:03:43.769262Z","caller":"traceutil/trace.go:171","msg":"trace[1278710390] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23015; }","duration":"144.240612ms","start":"2026-10-26T20:03:43.624992Z","end":"2026-10-26T20:03:43.769232Z","steps":["trace[1278710390] 'agreement among raft nodes before linearized reading' (duration: 144.015396ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:03:43.769352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.158302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-26T20:03:43.769415Z","caller":"traceutil/trace.go:171","msg":"trace[300766861] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:23015; }","duration":"259.274805ms","start":"2026-10-26T20:03:43.510121Z","end":"2026-10-26T20:03:43.769396Z","steps":["trace[300766861] 'agreement among raft nodes before linearized reading' (duration: 259.076729ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:03:43.769365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:03:43.447661Z","time spent":"321.616693ms","remote":"127.0.0.1:38166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T20:07:00.292963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.189873ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-26T20:07:00.29311Z","caller":"traceutil/trace.go:171","msg":"trace[1189665762] transaction","detail":"{read_only:false; response_revision:25025; number_of_response:1; }","duration":"121.091726ms","start":"2026-10-26T20:07:00.171995Z","end":"2026-10-26T20:07:00.293086Z","steps":["trace[1189665762] 'compare' (duration: 112.039308ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:07:00.293719Z","caller":"traceutil/trace.go:171","msg":"trace[896163420] transaction","detail":"{read_only:false; response_revision:25026; number_of_response:1; }","duration":"100.430329ms","start":"2026-10-26T20:07:00.193257Z","end":"2026-10-26T20:07:00.293687Z","steps":["trace[896163420] 'process raft request' (duration: 100.292186ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:07:00.325628Z","caller":"traceutil/trace.go:171","msg":"trace[2098893872] transaction","detail":"{read_only:false; response_revision:25027; number_of_response:1; }","duration":"117.689242ms","start":"2026-10-26T20:07:00.207898Z","end":"2026-10-26T20:07:00.325587Z","steps":["trace[2098893872] 'process raft request' (duration: 117.355813ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:07:10.182523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.43789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T20:07:10.182609Z","caller":"traceutil/trace.go:171","msg":"trace[1105993169] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:25106; }","duration":"100.567863ms","start":"2026-10-26T20:07:10.082022Z","end":"2026-10-26T20:07:10.18259Z","steps":["trace[1105993169] 'count revisions from in-memory index tree' (duration: 100.376298ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:07:10.745807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.078915ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-26T20:07:10.745925Z","caller":"traceutil/trace.go:171","msg":"trace[214485472] transaction","detail":"{read_only:false; response_revision:25109; number_of_response:1; }","duration":"368.467048ms","start":"2026-10-26T20:07:10.377413Z","end":"2026-10-26T20:07:10.74588Z","steps":["trace[214485472] 'process raft request' (duration: 171.241078ms)","trace[214485472] 'compare' (duration: 196.984603ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T20:07:10.745976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:07:10.377393Z","time spent":"368.5592ms","remote":"127.0.0.1:38166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T20:07:10.983335Z","caller":"traceutil/trace.go:171","msg":"trace[151856801] linearizableReadLoop","detail":"{readStateIndex:25936; appliedIndex:25935; }","duration":"391.426227ms","start":"2026-10-26T20:07:10.591874Z","end":"2026-10-26T20:07:10.9833Z","steps":["trace[151856801] 'read index received' (duration: 391.087068ms)","trace[151856801] 'applied index is now lower than readState.Index' (duration: 338.069µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:07:10.983418Z","caller":"traceutil/trace.go:171","msg":"trace[752021639] transaction","detail":"{read_only:false; response_revision:25110; number_of_response:1; }","duration":"461.888876ms","start":"2026-10-26T20:07:10.521495Z","end":"2026-10-26T20:07:10.983383Z","steps":["trace[752021639] 'process raft request' (duration: 461.100235ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:07:10.983528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.627202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-26T20:07:10.983568Z","caller":"traceutil/trace.go:171","msg":"trace[1574586783] range","detail":"{range_begin:/registry/monitoring.coreos.com/servicemonitors/; range_end:/registry/monitoring.coreos.com/servicemonitors0; response_count:0; response_revision:25110; }","duration":"391.688904ms","start":"2026-10-26T20:07:10.591869Z","end":"2026-10-26T20:07:10.983557Z","steps":["trace[1574586783] 'agreement among raft nodes before linearized reading' (duration: 391.55498ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:07:10.983603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:07:10.59183Z","time spent":"391.763396ms","remote":"127.0.0.1:36934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":23,"response size":30,"request content":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true "} {"level":"warn","ts":"2026-10-26T20:07:10.98362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:07:10.521423Z","time spent":"462.102452ms","remote":"127.0.0.1:38166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T20:07:10.997837Z","caller":"traceutil/trace.go:171","msg":"trace[295353180] transaction","detail":"{read_only:false; response_revision:25111; number_of_response:1; }","duration":"230.867711ms","start":"2026-10-26T20:07:10.766934Z","end":"2026-10-26T20:07:10.997801Z","steps":["trace[295353180] 'process raft request' (duration: 230.669395ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:07:10.998163Z","caller":"traceutil/trace.go:171","msg":"trace[586118147] transaction","detail":"{read_only:false; response_revision:25112; number_of_response:1; }","duration":"230.302675ms","start":"2026-10-26T20:07:10.767809Z","end":"2026-10-26T20:07:10.998112Z","steps":["trace[586118147] 'process raft request' (duration: 229.947975ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:08:01.594638Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-000000000000673b.wal"} {"level":"info","ts":"2026-10-26T20:08:08.386708Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22206} {"level":"info","ts":"2026-10-26T20:08:08.515011Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22206,"took":"124.617978ms","hash":1817330805,"current-db-size-bytes":59420672,"current-db-size":"59 MB","current-db-size-in-use-bytes":45539328,"current-db-size-in-use":"46 MB"} {"level":"info","ts":"2026-10-26T20:08:08.515076Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1817330805,"revision":22206,"compact-revision":19959} {"level":"info","ts":"2026-10-26T20:08:12.621843Z","caller":"traceutil/trace.go:171","msg":"trace[950007133] linearizableReadLoop","detail":"{readStateIndex:26506; appliedIndex:26505; }","duration":"313.159058ms","start":"2026-10-26T20:08:12.308661Z","end":"2026-10-26T20:08:12.62182Z","steps":["trace[950007133] 'read index received' (duration: 312.924432ms)","trace[950007133] 'applied index is now lower than readState.Index' (duration: 233.346µs)"],"step_count":2} {"level":"warn","ts":"2026-10-26T20:08:12.622023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.341733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T20:08:12.622046Z","caller":"traceutil/trace.go:171","msg":"trace[534424615] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:25666; }","duration":"313.386644ms","start":"2026-10-26T20:08:12.308652Z","end":"2026-10-26T20:08:12.622038Z","steps":["trace[534424615] 'agreement among raft nodes before linearized reading' (duration: 313.25961ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:08:12.62207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:08:12.308594Z","time spent":"313.470066ms","remote":"127.0.0.1:60906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true "} {"level":"info","ts":"2026-10-26T20:08:12.622321Z","caller":"traceutil/trace.go:171","msg":"trace[1631764621] transaction","detail":"{read_only:false; response_revision:25666; number_of_response:1; }","duration":"333.659647ms","start":"2026-10-26T20:08:12.28862Z","end":"2026-10-26T20:08:12.62228Z","steps":["trace[1631764621] 'process raft request' (duration: 333.053311ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:08:12.622485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:08:12.288595Z","time spent":"333.79802ms","remote":"127.0.0.1:38166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T20:08:12.831926Z","caller":"traceutil/trace.go:171","msg":"trace[620998122] transaction","detail":"{read_only:false; response_revision:25667; number_of_response:1; }","duration":"168.588075ms","start":"2026-10-26T20:08:12.663307Z","end":"2026-10-26T20:08:12.831896Z","steps":["trace[620998122] 'process raft request' (duration: 154.973371ms)","trace[620998122] 'compare' (duration: 13.45075ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:08:13.056353Z","caller":"traceutil/trace.go:171","msg":"trace[1738087582] linearizableReadLoop","detail":"{readStateIndex:26508; appliedIndex:26507; }","duration":"199.099953ms","start":"2026-10-26T20:08:12.857228Z","end":"2026-10-26T20:08:13.056328Z","steps":["trace[1738087582] 'read index received' (duration: 149.710179ms)","trace[1738087582] 'applied index is now lower than readState.Index' (duration: 49.388234ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T20:08:13.05656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.287717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T20:08:13.056525Z","caller":"traceutil/trace.go:171","msg":"trace[393136771] transaction","detail":"{read_only:false; response_revision:25668; number_of_response:1; }","duration":"388.392993ms","start":"2026-10-26T20:08:12.668052Z","end":"2026-10-26T20:08:13.056445Z","steps":["trace[393136771] 'process raft request' (duration: 338.884276ms)","trace[393136771] 'compare' (duration: 49.136207ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:08:13.056602Z","caller":"traceutil/trace.go:171","msg":"trace[1592509842] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:25668; }","duration":"199.372851ms","start":"2026-10-26T20:08:12.857217Z","end":"2026-10-26T20:08:13.05659Z","steps":["trace[1592509842] 'agreement among raft nodes before linearized reading' (duration: 199.205146ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:08:13.056767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:08:12.668032Z","time spent":"388.587168ms","remote":"127.0.0.1:38166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T20:08:13.236985Z","caller":"traceutil/trace.go:171","msg":"trace[1723629903] linearizableReadLoop","detail":"{readStateIndex:26509; appliedIndex:26508; }","duration":"158.607798ms","start":"2026-10-26T20:08:13.078318Z","end":"2026-10-26T20:08:13.236926Z","steps":["trace[1723629903] 'read index received' (duration: 158.32282ms)","trace[1723629903] 'applied index is now lower than readState.Index' (duration: 284.117µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:08:13.237096Z","caller":"traceutil/trace.go:171","msg":"trace[410637084] transaction","detail":"{read_only:false; response_revision:25669; number_of_response:1; }","duration":"173.60524ms","start":"2026-10-26T20:08:13.063447Z","end":"2026-10-26T20:08:13.237052Z","steps":["trace[410637084] 'process raft request' (duration: 173.271531ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:08:13.237181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.815424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs/\" range_end:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T20:08:13.237234Z","caller":"traceutil/trace.go:171","msg":"trace[1703732090] range","detail":"{range_begin:/registry/runtime.cluster.x-k8s.io/extensionconfigs/; range_end:/registry/runtime.cluster.x-k8s.io/extensionconfigs0; response_count:0; response_revision:25669; }","duration":"158.933446ms","start":"2026-10-26T20:08:13.078281Z","end":"2026-10-26T20:08:13.237215Z","steps":["trace[1703732090] 'agreement among raft nodes before linearized reading' (duration: 158.804363ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:08:13.417632Z","caller":"traceutil/trace.go:171","msg":"trace[396285230] transaction","detail":"{read_only:false; response_revision:25670; number_of_response:1; }","duration":"116.177142ms","start":"2026-10-26T20:08:13.301435Z","end":"2026-10-26T20:08:13.417612Z","steps":["trace[396285230] 'process raft request' (duration: 110.548651ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:08:35.319698Z","caller":"traceutil/trace.go:171","msg":"trace[117689440] transaction","detail":"{read_only:false; response_revision:25828; number_of_response:1; }","duration":"173.468486ms","start":"2026-10-26T20:08:35.14621Z","end":"2026-10-26T20:08:35.319678Z","steps":["trace[117689440] 'process raft request' (duration: 173.328602ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:08:35.783496Z","caller":"traceutil/trace.go:171","msg":"trace[784820708] linearizableReadLoop","detail":"{readStateIndex:26677; appliedIndex:26676; }","duration":"230.619347ms","start":"2026-10-26T20:08:35.552857Z","end":"2026-10-26T20:08:35.783477Z","steps":["trace[784820708] 'read index received' (duration: 230.417412ms)","trace[784820708] 'applied index is now lower than readState.Index' (duration: 200.925µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:08:35.783647Z","caller":"traceutil/trace.go:171","msg":"trace[1040404341] transaction","detail":"{read_only:false; response_revision:25833; number_of_response:1; }","duration":"244.437737ms","start":"2026-10-26T20:08:35.539161Z","end":"2026-10-26T20:08:35.783599Z","steps":["trace[1040404341] 'process raft request' (duration: 244.15987ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:08:35.783698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.834313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-26T20:08:35.783745Z","caller":"traceutil/trace.go:171","msg":"trace[1319542603] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25833; }","duration":"230.919595ms","start":"2026-10-26T20:08:35.552812Z","end":"2026-10-26T20:08:35.783731Z","steps":["trace[1319542603] 'agreement among raft nodes before linearized reading' (duration: 230.791542ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:08:35.785714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.468861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-26T20:08:35.78583Z","caller":"traceutil/trace.go:171","msg":"trace[1046671090] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25834; }","duration":"212.630196ms","start":"2026-10-26T20:08:35.573175Z","end":"2026-10-26T20:08:35.785805Z","steps":["trace[1046671090] 'agreement among raft nodes before linearized reading' (duration: 212.301527ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:08:35.786389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.54455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T20:08:35.786436Z","caller":"traceutil/trace.go:171","msg":"trace[2115640911] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25834; }","duration":"160.740615ms","start":"2026-10-26T20:08:35.625682Z","end":"2026-10-26T20:08:35.786423Z","steps":["trace[2115640911] 'agreement among raft nodes before linearized reading' (duration: 160.649573ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:08:35.786542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.534979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-26T20:08:35.786586Z","caller":"traceutil/trace.go:171","msg":"trace[2111328025] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25834; }","duration":"179.619101ms","start":"2026-10-26T20:08:35.606957Z","end":"2026-10-26T20:08:35.786576Z","steps":["trace[2111328025] 'agreement among raft nodes before linearized reading' (duration: 179.442747ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:08:35.786794Z","caller":"traceutil/trace.go:171","msg":"trace[328565480] transaction","detail":"{read_only:false; response_revision:25834; number_of_response:1; }","duration":"144.85234ms","start":"2026-10-26T20:08:35.641926Z","end":"2026-10-26T20:08:35.786778Z","steps":["trace[328565480] 'process raft request' (duration: 143.35406ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:08:35.786841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.985453ms","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-10-26T20:08:35.786881Z","caller":"traceutil/trace.go:171","msg":"trace[270990846] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25834; }","duration":"117.031185ms","start":"2026-10-26T20:08:35.669823Z","end":"2026-10-26T20:08:35.786855Z","steps":["trace[270990846] 'agreement among raft nodes before linearized reading' (duration: 116.975083ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:13:08.397446Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25636} {"level":"info","ts":"2026-10-26T20:13:08.565013Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25636,"took":"163.122071ms","hash":3890437011,"current-db-size-bytes":60264448,"current-db-size":"60 MB","current-db-size-in-use-bytes":35889152,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-26T20:13:08.565079Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3890437011,"revision":25636,"compact-revision":22206} {"level":"info","ts":"2026-10-26T20:14:26.655528Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"638d1b11488f2a2e","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-26T20:14:26.665716Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-26T20:14:26.665826Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-26T20:16:55.517834Z","caller":"traceutil/trace.go:171","msg":"trace[828705398] transaction","detail":"{read_only:false; response_revision:30194; number_of_response:1; }","duration":"135.380626ms","start":"2026-10-26T20:16:55.38243Z","end":"2026-10-26T20:16:55.517811Z","steps":["trace[828705398] 'process raft request' (duration: 135.212341ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:16:55.552043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.303671ms","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-10-26T20:16:55.552132Z","caller":"traceutil/trace.go:171","msg":"trace[2134104251] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:30195; }","duration":"128.403414ms","start":"2026-10-26T20:16:55.423711Z","end":"2026-10-26T20:16:55.552115Z","steps":["trace[2134104251] 'agreement among raft nodes before linearized reading' (duration: 128.181637ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:18:08.40499Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28501} {"level":"info","ts":"2026-10-26T20:18:08.550787Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28501,"took":"142.741251ms","hash":691929664,"current-db-size-bytes":60264448,"current-db-size":"60 MB","current-db-size-in-use-bytes":31797248,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-26T20:18:08.550856Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":691929664,"revision":28501,"compact-revision":25636} {"level":"info","ts":"2026-10-26T20:23:08.417981Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30728} {"level":"info","ts":"2026-10-26T20:23:08.545315Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30728,"took":"123.259129ms","hash":4206206579,"current-db-size-bytes":60264448,"current-db-size":"60 MB","current-db-size-in-use-bytes":32583680,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-26T20:23:08.54538Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4206206579,"revision":30728,"compact-revision":28501} {"level":"info","ts":"2026-10-26T20:28:08.430857Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32987} {"level":"info","ts":"2026-10-26T20:28:08.641878Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32987,"took":"207.69006ms","hash":544818406,"current-db-size-bytes":60264448,"current-db-size":"60 MB","current-db-size-in-use-bytes":31506432,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-26T20:28:08.641953Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":544818406,"revision":32987,"compact-revision":30728} {"level":"info","ts":"2026-10-26T20:33:08.443697Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35220} {"level":"info","ts":"2026-10-26T20:33:08.580315Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35220,"took":"132.125623ms","hash":2218739267,"current-db-size-bytes":60264448,"current-db-size":"60 MB","current-db-size-in-use-bytes":33189888,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-26T20:33:08.580378Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2218739267,"revision":35220,"compact-revision":32987} {"level":"info","ts":"2026-10-26T20:35:53.45701Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"638d1b11488f2a2e","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-26T20:35:53.463769Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-26T20:35:53.463937Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-26T20:38:08.452173Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37512} {"level":"info","ts":"2026-10-26T20:38:08.581343Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37512,"took":"125.616128ms","hash":2733019416,"current-db-size-bytes":60690432,"current-db-size":"61 MB","current-db-size-in-use-bytes":32182272,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-26T20:38:08.581425Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2733019416,"revision":37512,"compact-revision":35220}