{"level":"warn","ts":"2026-09-08T22:55:38.269951Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."}
{"level":"info","ts":"2026-09-08T22:55:38.270076Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.186: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.186:2380","--initial-cluster=instance=https://162.253.55.186:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.186:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.186:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]}
{"level":"warn","ts":"2026-09-08T22:55:38.270163Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."}
{"level":"info","ts":"2026-09-08T22:55:38.270181Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.186:2380"]}
{"level":"info","ts":"2026-09-08T22:55:38.270205Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]}
{"level":"info","ts":"2026-09-08T22:55:38.271194Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.186:2379"]}
{"level":"info","ts":"2026-09-08T22:55:38.271332Z","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.186:2380"],"listen-peer-urls":["https://162.253.55.186:2380"],"advertise-client-urls":["https://162.253.55.186:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.186:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.186:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"}
{"level":"info","ts":"2026-09-08T22:55:38.275083Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.465117ms"}
{"level":"info","ts":"2026-09-08T22:55:38.283856Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"612d5169b08bb96b","cluster-id":"20ec8eafba602517"}
{"level":"info","ts":"2026-09-08T22:55:38.283934Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b switched to configuration voters=()"}
{"level":"info","ts":"2026-09-08T22:55:38.283959Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b became follower at term 0"}
{"level":"info","ts":"2026-09-08T22:55:38.283978Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 612d5169b08bb96b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"}
{"level":"info","ts":"2026-09-08T22:55:38.28399Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b became follower at term 1"}
{"level":"info","ts":"2026-09-08T22:55:38.284033Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b switched to configuration voters=(7002342510006352235)"}
{"level":"warn","ts":"2026-09-08T22:55:38.289308Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"}
{"level":"info","ts":"2026-09-08T22:55:38.290595Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1}
{"level":"info","ts":"2026-09-08T22:55:38.292148Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"}
{"level":"info","ts":"2026-09-08T22:55:38.294449Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"612d5169b08bb96b","local-server-version":"3.5.12","cluster-version":"to_be_decided"}
{"level":"info","ts":"2026-09-08T22:55:38.295069Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"612d5169b08bb96b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"}
{"level":"info","ts":"2026-09-08T22:55:38.295403Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"}
{"level":"info","ts":"2026-09-08T22:55:38.295546Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"}
{"level":"info","ts":"2026-09-08T22:55:38.295578Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"}
{"level":"info","ts":"2026-09-08T22:55:38.296361Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b switched to configuration voters=(7002342510006352235)"}
{"level":"info","ts":"2026-09-08T22:55:38.296583Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"20ec8eafba602517","local-member-id":"612d5169b08bb96b","added-peer-id":"612d5169b08bb96b","added-peer-peer-urls":["https://162.253.55.186:2380"]}
{"level":"info","ts":"2026-09-08T22:55:38.301259Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]}
{"level":"info","ts":"2026-09-08T22:55:38.301423Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.186:2380"}
{"level":"info","ts":"2026-09-08T22:55:38.301497Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.186:2380"}
{"level":"info","ts":"2026-09-08T22:55:38.301685Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"612d5169b08bb96b","initial-advertise-peer-urls":["https://162.253.55.186:2380"],"listen-peer-urls":["https://162.253.55.186:2380"],"advertise-client-urls":["https://162.253.55.186:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.186:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]}
{"level":"info","ts":"2026-09-08T22:55:38.301816Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"}
{"level":"info","ts":"2026-09-08T22:55:38.684653Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b is starting a new election at term 1"}
{"level":"info","ts":"2026-09-08T22:55:38.684735Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b became pre-candidate at term 1"}
{"level":"info","ts":"2026-09-08T22:55:38.684784Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b received MsgPreVoteResp from 612d5169b08bb96b at term 1"}
{"level":"info","ts":"2026-09-08T22:55:38.684807Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b became candidate at term 2"}
{"level":"info","ts":"2026-09-08T22:55:38.684818Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b received MsgVoteResp from 612d5169b08bb96b at term 2"}
{"level":"info","ts":"2026-09-08T22:55:38.684835Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b became leader at term 2"}
{"level":"info","ts":"2026-09-08T22:55:38.684856Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 612d5169b08bb96b elected leader 612d5169b08bb96b at term 2"}
{"level":"info","ts":"2026-09-08T22:55:38.685984Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"612d5169b08bb96b","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.186:2379]}","request-path":"/0/members/612d5169b08bb96b/attributes","cluster-id":"20ec8eafba602517","publish-timeout":"7s"}
{"level":"info","ts":"2026-09-08T22:55:38.689102Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-09-08T22:55:38.689139Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-09-08T22:55:38.689335Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"}
{"level":"info","ts":"2026-09-08T22:55:38.689417Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"}
{"level":"info","ts":"2026-09-08T22:55:38.689542Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"}
{"level":"info","ts":"2026-09-08T22:55:38.691163Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"20ec8eafba602517","local-member-id":"612d5169b08bb96b","cluster-version":"3.5"}
{"level":"info","ts":"2026-09-08T22:55:38.691374Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"}
{"level":"info","ts":"2026-09-08T22:55:38.691437Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"}
{"level":"info","ts":"2026-09-08T22:55:38.693197Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.186:2379"}
{"level":"info","ts":"2026-09-08T22:55:38.696244Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"}
{"level":"info","ts":"2026-09-08T22:55:55.516597Z","caller":"traceutil/trace.go:171","msg":"trace[972252229] linearizableReadLoop","detail":"{readStateIndex:427; appliedIndex:426; }","duration":"191.378141ms","start":"2026-09-08T22:55:55.325183Z","end":"2026-09-08T22:55:55.516561Z","steps":["trace[972252229] 'read index received'  (duration: 191.00475ms)","trace[972252229] 'applied index is now lower than readState.Index'  (duration: 371.851µs)"],"step_count":2}
{"level":"info","ts":"2026-09-08T22:55:55.516651Z","caller":"traceutil/trace.go:171","msg":"trace[1043862009] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"352.025031ms","start":"2026-09-08T22:55:55.164589Z","end":"2026-09-08T22:55:55.516614Z","steps":["trace[1043862009] 'process raft request'  (duration: 351.656621ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T22:55:55.516859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.656879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"}
{"level":"info","ts":"2026-09-08T22:55:55.516991Z","caller":"traceutil/trace.go:171","msg":"trace[213035853] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:414; }","duration":"191.868595ms","start":"2026-09-08T22:55:55.325106Z","end":"2026-09-08T22:55:55.516974Z","steps":["trace[213035853] 'agreement among raft nodes before linearized reading'  (duration: 191.632978ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T22:55:55.517586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:55:55.164551Z","time spent":"352.218197ms","remote":"127.0.0.1:42734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4119,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/pods/kube-system/kube-scheduler-instance\" mod_revision:409 > success:<request_put:<key:\"/registry/pods/kube-system/kube-scheduler-instance\" value_size:4061 >> failure:<request_range:<key:\"/registry/pods/kube-system/kube-scheduler-instance\" > >"}
{"level":"info","ts":"2026-09-08T22:55:55.719124Z","caller":"traceutil/trace.go:171","msg":"trace[1902093191] linearizableReadLoop","detail":"{readStateIndex:428; appliedIndex:427; }","duration":"194.384156ms","start":"2026-09-08T22:55:55.524647Z","end":"2026-09-08T22:55:55.719031Z","steps":["trace[1902093191] 'read index received'  (duration: 163.162062ms)","trace[1902093191] 'applied index is now lower than readState.Index'  (duration: 31.220624ms)"],"step_count":2}
{"level":"info","ts":"2026-09-08T22:55:55.719138Z","caller":"traceutil/trace.go:171","msg":"trace[1982142617] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"197.594517ms","start":"2026-09-08T22:55:55.521501Z","end":"2026-09-08T22:55:55.719095Z","steps":["trace[1982142617] 'process raft request'  (duration: 166.25591ms)","trace[1982142617] 'compare'  (duration: 31.185293ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-08T22:55:55.719341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.673875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"}
{"level":"info","ts":"2026-09-08T22:55:55.719406Z","caller":"traceutil/trace.go:171","msg":"trace[293568954] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:415; }","duration":"194.808718ms","start":"2026-09-08T22:55:55.524583Z","end":"2026-09-08T22:55:55.719392Z","steps":["trace[293568954] 'agreement among raft nodes before linearized reading'  (duration: 194.633753ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T22:55:55.720144Z","caller":"traceutil/trace.go:171","msg":"trace[468875414] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"191.745641ms","start":"2026-09-08T22:55:55.528374Z","end":"2026-09-08T22:55:55.720119Z","steps":["trace[468875414] 'process raft request'  (duration: 191.609148ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T22:56:34.033435Z","caller":"traceutil/trace.go:171","msg":"trace[93966406] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"126.49165ms","start":"2026-09-08T22:56:33.906916Z","end":"2026-09-08T22:56:34.033407Z","steps":["trace[93966406] 'process raft request'  (duration: 119.89048ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T22:56:34.033493Z","caller":"traceutil/trace.go:171","msg":"trace[308783609] linearizableReadLoop","detail":"{readStateIndex:692; appliedIndex:691; }","duration":"121.013932ms","start":"2026-09-08T22:56:33.912448Z","end":"2026-09-08T22:56:34.033462Z","steps":["trace[308783609] 'read index received'  (duration: 114.385582ms)","trace[308783609] 'applied index is now lower than readState.Index'  (duration: 6.62522ms)"],"step_count":2}
{"level":"info","ts":"2026-09-08T22:56:34.033588Z","caller":"traceutil/trace.go:171","msg":"trace[1840179813] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"111.563841ms","start":"2026-09-08T22:56:33.922002Z","end":"2026-09-08T22:56:34.033566Z","steps":["trace[1840179813] 'process raft request'  (duration: 110.921972ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T22:56:34.033707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.838165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"}
{"level":"info","ts":"2026-09-08T22:56:34.033741Z","caller":"traceutil/trace.go:171","msg":"trace[1536777778] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:669; }","duration":"114.915838ms","start":"2026-09-08T22:56:33.918815Z","end":"2026-09-08T22:56:34.033731Z","steps":["trace[1536777778] 'agreement among raft nodes before linearized reading'  (duration: 114.795664ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T22:56:34.034216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.748894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"}
{"level":"info","ts":"2026-09-08T22:56:34.034277Z","caller":"traceutil/trace.go:171","msg":"trace[2056543285] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:669; }","duration":"121.843266ms","start":"2026-09-08T22:56:33.912422Z","end":"2026-09-08T22:56:34.034265Z","steps":["trace[2056543285] 'agreement among raft nodes before linearized reading'  (duration: 121.171557ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T22:56:40.270351Z","caller":"traceutil/trace.go:171","msg":"trace[1680740655] linearizableReadLoop","detail":"{readStateIndex:757; appliedIndex:756; }","duration":"157.401336ms","start":"2026-09-08T22:56:40.112924Z","end":"2026-09-08T22:56:40.270326Z","steps":["trace[1680740655] 'read index received'  (duration: 157.276983ms)","trace[1680740655] 'applied index is now lower than readState.Index'  (duration: 123.313µs)"],"step_count":2}
{"level":"warn","ts":"2026-09-08T22:56:40.270485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.5334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-08T22:56:40.270515Z","caller":"traceutil/trace.go:171","msg":"trace[193302027] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:731; }","duration":"157.604931ms","start":"2026-09-08T22:56:40.112901Z","end":"2026-09-08T22:56:40.270506Z","steps":["trace[193302027] 'agreement among raft nodes before linearized reading'  (duration: 157.531809ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T22:56:40.270684Z","caller":"traceutil/trace.go:171","msg":"trace[399760759] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"203.640672ms","start":"2026-09-08T22:56:40.067012Z","end":"2026-09-08T22:56:40.270652Z","steps":["trace[399760759] 'process raft request'  (duration: 203.163739ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T22:56:41.498449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.467526ms","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-09-08T22:56:41.498476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.290025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-09-08T22:56:41.49854Z","caller":"traceutil/trace.go:171","msg":"trace[981106469] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:734; }","duration":"487.574499ms","start":"2026-09-08T22:56:41.010947Z","end":"2026-09-08T22:56:41.498521Z","steps":["trace[981106469] 'range keys from in-memory index tree'  (duration: 487.23581ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T22:56:41.498549Z","caller":"traceutil/trace.go:171","msg":"trace[600101835] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:734; }","duration":"446.414559ms","start":"2026-09-08T22:56:41.052114Z","end":"2026-09-08T22:56:41.498529Z","steps":["trace[600101835] 'count revisions from in-memory index tree'  (duration: 446.119741ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T22:56:41.49858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:56:41.010862Z","time spent":"487.709263ms","remote":"127.0.0.1:42812","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-09-08T22:56:41.498589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:56:41.052095Z","time spent":"446.483731ms","remote":"127.0.0.1:33706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":29,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "}
{"level":"info","ts":"2026-09-08T22:56:59.193864Z","caller":"traceutil/trace.go:171","msg":"trace[1868928290] linearizableReadLoop","detail":"{readStateIndex:886; appliedIndex:885; }","duration":"302.462698ms","start":"2026-09-08T22:56:58.891372Z","end":"2026-09-08T22:56:59.193834Z","steps":["trace[1868928290] 'read index received'  (duration: 302.17205ms)","trace[1868928290] 'applied index is now lower than readState.Index'  (duration: 289.308µs)"],"step_count":2}
{"level":"info","ts":"2026-09-08T22:56:59.193979Z","caller":"traceutil/trace.go:171","msg":"trace[645699230] transaction","detail":"{read_only:false; response_revision:854; number_of_response:1; }","duration":"318.030115ms","start":"2026-09-08T22:56:58.87591Z","end":"2026-09-08T22:56:59.19394Z","steps":["trace[645699230] 'process raft request'  (duration: 317.738917ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T22:56:59.194056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.671044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"}
{"level":"info","ts":"2026-09-08T22:56:59.194088Z","caller":"traceutil/trace.go:171","msg":"trace[1490480555] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:854; }","duration":"302.749737ms","start":"2026-09-08T22:56:58.891328Z","end":"2026-09-08T22:56:59.194078Z","steps":["trace[1490480555] 'agreement among raft nodes before linearized reading'  (duration: 302.610402ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T22:56:59.194125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:56:58.89131Z","time spent":"302.807749ms","remote":"127.0.0.1:42812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "}
{"level":"warn","ts":"2026-09-08T22:56:59.194141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:56:58.875894Z","time spent":"318.158109ms","remote":"127.0.0.1:42812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" mod_revision:841 > success:<request_put:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" value_size:411 >> failure:<request_range:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" > >"}
{"level":"info","ts":"2026-09-08T22:58:15.963247Z","caller":"traceutil/trace.go:171","msg":"trace[1515414873] linearizableReadLoop","detail":"{readStateIndex:1987; appliedIndex:1986; }","duration":"283.880184ms","start":"2026-09-08T22:58:15.679341Z","end":"2026-09-08T22:58:15.963221Z","steps":["trace[1515414873] 'read index received'  (duration: 283.693758ms)","trace[1515414873] 'applied index is now lower than readState.Index'  (duration: 185.026µs)"],"step_count":2}
{"level":"info","ts":"2026-09-08T22:58:15.963293Z","caller":"traceutil/trace.go:171","msg":"trace[201778202] transaction","detail":"{read_only:false; response_revision:1915; number_of_response:1; }","duration":"322.64424ms","start":"2026-09-08T22:58:15.640625Z","end":"2026-09-08T22:58:15.96327Z","steps":["trace[201778202] 'process raft request'  (duration: 322.435754ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T22:58:15.96351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:58:15.640603Z","time spent":"322.826085ms","remote":"127.0.0.1:42812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" mod_revision:1811 > success:<request_put:<key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" value_size:415 >> failure:<request_range:<key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" > >"}
{"level":"warn","ts":"2026-09-08T22:58:15.96355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.202744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"}
{"level":"info","ts":"2026-09-08T22:58:15.963644Z","caller":"traceutil/trace.go:171","msg":"trace[908355350] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1915; }","duration":"284.309577ms","start":"2026-09-08T22:58:15.679293Z","end":"2026-09-08T22:58:15.963603Z","steps":["trace[908355350] 'agreement among raft nodes before linearized reading'  (duration: 284.111181ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T22:58:16.111437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.517365ms","expected-duration":"100ms","prefix":"","request":"header:<ID:13360949205031402345 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" mod_revision:1898 > success:<request_put:<key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" value_size:459 >> failure:<request_range:<key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" > >>","response":"size:16"}
{"level":"info","ts":"2026-09-08T22:58:16.111691Z","caller":"traceutil/trace.go:171","msg":"trace[422245100] transaction","detail":"{read_only:false; response_revision:1917; number_of_response:1; }","duration":"144.023624ms","start":"2026-09-08T22:58:15.96765Z","end":"2026-09-08T22:58:16.111673Z","steps":["trace[422245100] 'process raft request'  (duration: 143.926581ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T22:58:16.111675Z","caller":"traceutil/trace.go:171","msg":"trace[880029292] transaction","detail":"{read_only:false; response_revision:1916; number_of_response:1; }","duration":"187.797978ms","start":"2026-09-08T22:58:15.923811Z","end":"2026-09-08T22:58:16.111609Z","steps":["trace[880029292] 'process raft request'  (duration: 82.472469ms)","trace[880029292] 'compare'  (duration: 104.275018ms)"],"step_count":2}
{"level":"info","ts":"2026-09-08T22:58:16.111736Z","caller":"traceutil/trace.go:171","msg":"trace[1556330844] linearizableReadLoop","detail":"{readStateIndex:1988; appliedIndex:1987; }","duration":"148.353002ms","start":"2026-09-08T22:58:15.963356Z","end":"2026-09-08T22:58:16.111709Z","steps":["trace[1556330844] 'read index received'  (duration: 42.861148ms)","trace[1556330844] 'applied index is now lower than readState.Index'  (duration: 105.488994ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-08T22:58:16.111967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.556438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"}
{"level":"warn","ts":"2026-09-08T22:58:16.112022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.327835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" ","response":"range_response_count:1 size:10630"}
{"level":"info","ts":"2026-09-08T22:58:16.112007Z","caller":"traceutil/trace.go:171","msg":"trace[1840320048] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1917; }","duration":"312.62949ms","start":"2026-09-08T22:58:15.799365Z","end":"2026-09-08T22:58:16.111994Z","steps":["trace[1840320048] 'agreement among raft nodes before linearized reading'  (duration: 312.454045ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T22:58:16.112057Z","caller":"traceutil/trace.go:171","msg":"trace[2087088813] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-haproxy-0; range_end:; response_count:1; response_revision:1917; }","duration":"339.416728ms","start":"2026-09-08T22:58:15.772629Z","end":"2026-09-08T22:58:16.112046Z","steps":["trace[2087088813] 'agreement among raft nodes before linearized reading'  (duration: 339.219632ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T22:58:16.112085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:58:15.799351Z","time spent":"312.722803ms","remote":"127.0.0.1:50886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5466,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "}
{"level":"warn","ts":"2026-09-08T22:58:16.112096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:58:15.772541Z","time spent":"339.546332ms","remote":"127.0.0.1:42734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":10652,"request content":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" "}
{"level":"warn","ts":"2026-09-08T22:58:16.112159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.513584ms","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-09-08T22:58:16.112212Z","caller":"traceutil/trace.go:171","msg":"trace[774367540] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1917; }","duration":"430.616597ms","start":"2026-09-08T22:58:15.681584Z","end":"2026-09-08T22:58:16.1122Z","steps":["trace[774367540] 'agreement among raft nodes before linearized reading'  (duration: 430.487884ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T22:58:16.112245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:58:15.681563Z","time spent":"430.673279ms","remote":"127.0.0.1:42812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "}
{"level":"warn","ts":"2026-09-08T22:58:16.112434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.335133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"}
{"level":"info","ts":"2026-09-08T22:58:16.112533Z","caller":"traceutil/trace.go:171","msg":"trace[1296632807] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1917; }","duration":"301.523938ms","start":"2026-09-08T22:58:15.81098Z","end":"2026-09-08T22:58:16.112504Z","steps":["trace[1296632807] 'agreement among raft nodes before linearized reading'  (duration: 301.219369ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T22:58:16.112593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:58:15.810959Z","time spent":"301.619702ms","remote":"127.0.0.1:50886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5466,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "}
{"level":"info","ts":"2026-09-08T22:59:05.511561Z","caller":"traceutil/trace.go:171","msg":"trace[393966563] transaction","detail":"{read_only:false; response_revision:2239; number_of_response:1; }","duration":"466.92246ms","start":"2026-09-08T22:59:05.04461Z","end":"2026-09-08T22:59:05.511533Z","steps":["trace[393966563] 'process raft request'  (duration: 466.742945ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T22:59:05.511725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:59:05.044581Z","time spent":"467.069005ms","remote":"127.0.0.1:42812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/08db1feb.percona.com\" mod_revision:2227 > success:<request_put:<key:\"/registry/leases/openstack/08db1feb.percona.com\" value_size:454 >> failure:<request_range:<key:\"/registry/leases/openstack/08db1feb.percona.com\" > >"}
{"level":"info","ts":"2026-09-08T22:59:10.594044Z","caller":"traceutil/trace.go:171","msg":"trace[2126468006] linearizableReadLoop","detail":"{readStateIndex:2350; appliedIndex:2349; }","duration":"108.677379ms","start":"2026-09-08T22:59:10.485337Z","end":"2026-09-08T22:59:10.594014Z","steps":["trace[2126468006] 'read index received'  (duration: 108.401491ms)","trace[2126468006] 'applied index is now lower than readState.Index'  (duration: 274.668µs)"],"step_count":2}
{"level":"info","ts":"2026-09-08T22:59:10.594089Z","caller":"traceutil/trace.go:171","msg":"trace[1409046421] transaction","detail":"{read_only:false; response_revision:2265; number_of_response:1; }","duration":"114.791498ms","start":"2026-09-08T22:59:10.479267Z","end":"2026-09-08T22:59:10.594058Z","steps":["trace[1409046421] 'process raft request'  (duration: 114.570052ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T22:59:10.594287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.416174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"}
{"level":"warn","ts":"2026-09-08T22:59:10.594347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.978018ms","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-09-08T22:59:10.5944Z","caller":"traceutil/trace.go:171","msg":"trace[687032388] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2265; }","duration":"109.06663ms","start":"2026-09-08T22:59:10.485319Z","end":"2026-09-08T22:59:10.594385Z","steps":["trace[687032388] 'agreement among raft nodes before linearized reading'  (duration: 108.872714ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T22:59:10.594352Z","caller":"traceutil/trace.go:171","msg":"trace[1627869464] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2265; }","duration":"104.531867ms","start":"2026-09-08T22:59:10.489808Z","end":"2026-09-08T22:59:10.59434Z","steps":["trace[1627869464] 'agreement among raft nodes before linearized reading'  (duration: 104.377243ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T22:59:10.738673Z","caller":"traceutil/trace.go:171","msg":"trace[1795304945] transaction","detail":"{read_only:false; response_revision:2266; number_of_response:1; }","duration":"140.095051ms","start":"2026-09-08T22:59:10.598558Z","end":"2026-09-08T22:59:10.738653Z","steps":["trace[1795304945] 'process raft request'  (duration: 139.962237ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T22:59:10.746032Z","caller":"traceutil/trace.go:171","msg":"trace[1651655704] linearizableReadLoop","detail":"{readStateIndex:2352; appliedIndex:2350; }","duration":"142.367846ms","start":"2026-09-08T22:59:10.603639Z","end":"2026-09-08T22:59:10.746006Z","steps":["trace[1651655704] 'read index received'  (duration: 134.871976ms)","trace[1651655704] 'applied index is now lower than readState.Index'  (duration: 7.49487ms)"],"step_count":2}
{"level":"info","ts":"2026-09-08T22:59:10.74607Z","caller":"traceutil/trace.go:171","msg":"trace[2021327946] transaction","detail":"{read_only:false; response_revision:2267; number_of_response:1; }","duration":"146.900901ms","start":"2026-09-08T22:59:10.599151Z","end":"2026-09-08T22:59:10.746052Z","steps":["trace[2021327946] 'process raft request'  (duration: 146.715125ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T22:59:10.746204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.556282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"}
{"level":"info","ts":"2026-09-08T22:59:10.746244Z","caller":"traceutil/trace.go:171","msg":"trace[1947545432] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2267; }","duration":"142.629864ms","start":"2026-09-08T22:59:10.603603Z","end":"2026-09-08T22:59:10.746233Z","steps":["trace[1947545432] 'agreement among raft nodes before linearized reading'  (duration: 142.49568ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T22:59:46.318404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.16086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-08T22:59:46.318478Z","caller":"traceutil/trace.go:171","msg":"trace[1305741802] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2635; }","duration":"206.268144ms","start":"2026-09-08T22:59:46.112195Z","end":"2026-09-08T22:59:46.318463Z","steps":["trace[1305741802] 'range keys from in-memory index tree'  (duration: 206.106929ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T23:00:19.824938Z","caller":"traceutil/trace.go:171","msg":"trace[408902592] transaction","detail":"{read_only:false; response_revision:2837; number_of_response:1; }","duration":"108.232902ms","start":"2026-09-08T23:00:19.71668Z","end":"2026-09-08T23:00:19.824913Z","steps":["trace[408902592] 'process raft request'  (duration: 108.073087ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T23:00:20.187462Z","caller":"traceutil/trace.go:171","msg":"trace[80888766] transaction","detail":"{read_only:false; response_revision:2839; number_of_response:1; }","duration":"149.483779ms","start":"2026-09-08T23:00:20.037929Z","end":"2026-09-08T23:00:20.187413Z","steps":["trace[80888766] 'process raft request'  (duration: 149.239342ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T23:00:20.417806Z","caller":"traceutil/trace.go:171","msg":"trace[2085365067] transaction","detail":"{read_only:false; response_revision:2840; number_of_response:1; }","duration":"225.517232ms","start":"2026-09-08T23:00:20.192196Z","end":"2026-09-08T23:00:20.417713Z","steps":["trace[2085365067] 'process raft request'  (duration: 161.444152ms)","trace[2085365067] 'compare'  (duration: 63.923825ms)"],"step_count":2}
{"level":"info","ts":"2026-09-08T23:03:27.469512Z","caller":"traceutil/trace.go:171","msg":"trace[1385944296] linearizableReadLoop","detail":"{readStateIndex:4180; appliedIndex:4179; }","duration":"179.34183ms","start":"2026-09-08T23:03:27.290149Z","end":"2026-09-08T23:03:27.469491Z","steps":["trace[1385944296] 'read index received'  (duration: 179.243287ms)","trace[1385944296] 'applied index is now lower than readState.Index'  (duration: 97.403µs)"],"step_count":2}
{"level":"info","ts":"2026-09-08T23:03:27.469568Z","caller":"traceutil/trace.go:171","msg":"trace[298514209] transaction","detail":"{read_only:false; response_revision:4030; number_of_response:1; }","duration":"252.861479ms","start":"2026-09-08T23:03:27.216682Z","end":"2026-09-08T23:03:27.469543Z","steps":["trace[298514209] 'process raft request'  (duration: 252.672983ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T23:03:27.469744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.568627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-09-08T23:03:27.469867Z","caller":"traceutil/trace.go:171","msg":"trace[1081590520] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:4030; }","duration":"179.750602ms","start":"2026-09-08T23:03:27.290094Z","end":"2026-09-08T23:03:27.469845Z","steps":["trace[1081590520] 'agreement among raft nodes before linearized reading'  (duration: 179.503745ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T23:03:27.549601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.335926ms","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-09-08T23:03:27.549631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.216102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-msp9p\" ","response":"range_response_count:1 size:6524"}
{"level":"info","ts":"2026-09-08T23:03:27.549664Z","caller":"traceutil/trace.go:171","msg":"trace[1441788403] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4030; }","duration":"142.429788ms","start":"2026-09-08T23:03:27.407217Z","end":"2026-09-08T23:03:27.549647Z","steps":["trace[1441788403] 'agreement among raft nodes before linearized reading'  (duration: 142.260694ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T23:03:27.549689Z","caller":"traceutil/trace.go:171","msg":"trace[1368132897] range","detail":"{range_begin:/registry/pods/openstack/keepalived-msp9p; range_end:; response_count:1; response_revision:4030; }","duration":"141.286384ms","start":"2026-09-08T23:03:27.408376Z","end":"2026-09-08T23:03:27.549662Z","steps":["trace[1368132897] 'agreement among raft nodes before linearized reading'  (duration: 141.15998ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T23:04:49.784035Z","caller":"traceutil/trace.go:171","msg":"trace[1688833353] transaction","detail":"{read_only:false; response_revision:4969; number_of_response:1; }","duration":"104.561094ms","start":"2026-09-08T23:04:49.679423Z","end":"2026-09-08T23:04:49.783984Z","steps":["trace[1688833353] 'process raft request'  (duration: 76.553183ms)","trace[1688833353] 'compare'  (duration: 27.871787ms)"],"step_count":2}
{"level":"info","ts":"2026-09-08T23:04:55.137144Z","caller":"traceutil/trace.go:171","msg":"trace[537164856] linearizableReadLoop","detail":"{readStateIndex:5271; appliedIndex:5269; }","duration":"109.19613ms","start":"2026-09-08T23:04:55.027925Z","end":"2026-09-08T23:04:55.137121Z","steps":["trace[537164856] 'read index received'  (duration: 41.60441ms)","trace[537164856] 'applied index is now lower than readState.Index'  (duration: 67.59082ms)"],"step_count":2}
{"level":"info","ts":"2026-09-08T23:04:55.137344Z","caller":"traceutil/trace.go:171","msg":"trace[1946183837] transaction","detail":"{read_only:false; response_revision:5081; number_of_response:1; }","duration":"118.327146ms","start":"2026-09-08T23:04:55.01897Z","end":"2026-09-08T23:04:55.137297Z","steps":["trace[1946183837] 'process raft request'  (duration: 118.001387ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T23:04:55.137426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.483778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-09-08T23:04:55.137483Z","caller":"traceutil/trace.go:171","msg":"trace[267544695] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:5081; }","duration":"109.588471ms","start":"2026-09-08T23:04:55.027882Z","end":"2026-09-08T23:04:55.13747Z","steps":["trace[267544695] 'agreement among raft nodes before linearized reading'  (duration: 109.450787ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T23:04:59.057688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.37831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-08T23:04:59.057824Z","caller":"traceutil/trace.go:171","msg":"trace[615072173] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:5232; }","duration":"163.586416ms","start":"2026-09-08T23:04:58.894217Z","end":"2026-09-08T23:04:59.057804Z","steps":["trace[615072173] 'count revisions from in-memory index tree'  (duration: 163.294757ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T23:05:14.223004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.307305ms","expected-duration":"100ms","prefix":"","request":"header:<ID:13360949205031411132 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/endpointslices/openstack/memcached-metrics-gxng2\" mod_revision:5499 > success:<request_put:<key:\"/registry/endpointslices/openstack/memcached-metrics-gxng2\" value_size:1001 >> failure:<request_range:<key:\"/registry/endpointslices/openstack/memcached-metrics-gxng2\" > >>","response":"size:16"}
{"level":"info","ts":"2026-09-08T23:05:14.223305Z","caller":"traceutil/trace.go:171","msg":"trace[30731177] linearizableReadLoop","detail":"{readStateIndex:5707; appliedIndex:5705; }","duration":"190.771719ms","start":"2026-09-08T23:05:14.032513Z","end":"2026-09-08T23:05:14.223285Z","steps":["trace[30731177] 'read index received'  (duration: 62.062032ms)","trace[30731177] 'applied index is now lower than readState.Index'  (duration: 128.708927ms)"],"step_count":2}
{"level":"info","ts":"2026-09-08T23:05:14.223308Z","caller":"traceutil/trace.go:171","msg":"trace[1080168373] transaction","detail":"{read_only:false; response_revision:5501; number_of_response:1; }","duration":"190.882582ms","start":"2026-09-08T23:05:14.032387Z","end":"2026-09-08T23:05:14.223269Z","steps":["trace[1080168373] 'process raft request'  (duration: 62.233927ms)","trace[1080168373] 'compare'  (duration: 128.215052ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-08T23:05:14.223579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.805554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-08T23:05:14.223617Z","caller":"traceutil/trace.go:171","msg":"trace[923729331] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5504; }","duration":"110.883306ms","start":"2026-09-08T23:05:14.112724Z","end":"2026-09-08T23:05:14.223607Z","steps":["trace[923729331] 'agreement among raft nodes before linearized reading'  (duration: 110.820154ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T23:05:14.223798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.248333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:17252"}
{"level":"info","ts":"2026-09-08T23:05:14.223913Z","caller":"traceutil/trace.go:171","msg":"trace[308399713] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:5504; }","duration":"191.379577ms","start":"2026-09-08T23:05:14.032507Z","end":"2026-09-08T23:05:14.223886Z","steps":["trace[308399713] 'agreement among raft nodes before linearized reading'  (duration: 191.049868ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T23:05:14.223981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.473898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-msp9p\" ","response":"range_response_count:1 size:6524"}
{"level":"info","ts":"2026-09-08T23:05:14.224031Z","caller":"traceutil/trace.go:171","msg":"trace[1741414515] range","detail":"{range_begin:/registry/pods/openstack/keepalived-msp9p; range_end:; response_count:1; response_revision:5504; }","duration":"105.572782ms","start":"2026-09-08T23:05:14.118446Z","end":"2026-09-08T23:05:14.224019Z","steps":["trace[1741414515] 'agreement among raft nodes before linearized reading'  (duration: 105.408767ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T23:05:14.224139Z","caller":"traceutil/trace.go:171","msg":"trace[2135982791] transaction","detail":"{read_only:false; response_revision:5503; number_of_response:1; }","duration":"191.565342ms","start":"2026-09-08T23:05:14.032558Z","end":"2026-09-08T23:05:14.224123Z","steps":["trace[2135982791] 'process raft request'  (duration: 190.634634ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T23:05:14.22424Z","caller":"traceutil/trace.go:171","msg":"trace[540361054] transaction","detail":"{read_only:false; response_revision:5504; number_of_response:1; }","duration":"191.631425ms","start":"2026-09-08T23:05:14.032586Z","end":"2026-09-08T23:05:14.224217Z","steps":["trace[540361054] 'process raft request'  (duration: 190.657566ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T23:05:14.224319Z","caller":"traceutil/trace.go:171","msg":"trace[1026716255] transaction","detail":"{read_only:false; response_revision:5502; number_of_response:1; }","duration":"191.8555ms","start":"2026-09-08T23:05:14.032452Z","end":"2026-09-08T23:05:14.224307Z","steps":["trace[1026716255] 'process raft request'  (duration: 190.679166ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T23:05:14.747209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.388447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"}
{"level":"info","ts":"2026-09-08T23:05:14.747299Z","caller":"traceutil/trace.go:171","msg":"trace[350712056] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5506; }","duration":"116.531353ms","start":"2026-09-08T23:05:14.630748Z","end":"2026-09-08T23:05:14.74728Z","steps":["trace[350712056] 'range keys from in-memory index tree'  (duration: 116.189821ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T23:05:14.926112Z","caller":"traceutil/trace.go:171","msg":"trace[1560426075] transaction","detail":"{read_only:false; response_revision:5507; number_of_response:1; }","duration":"174.947817ms","start":"2026-09-08T23:05:14.751121Z","end":"2026-09-08T23:05:14.926069Z","steps":["trace[1560426075] 'process raft request'  (duration: 174.810583ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T23:05:15.007232Z","caller":"traceutil/trace.go:171","msg":"trace[270145437] linearizableReadLoop","detail":"{readStateIndex:5715; appliedIndex:5711; }","duration":"173.35703ms","start":"2026-09-08T23:05:14.833861Z","end":"2026-09-08T23:05:15.007218Z","steps":["trace[270145437] 'read index received'  (duration: 92.048167ms)","trace[270145437] 'applied index is now lower than readState.Index'  (duration: 81.307993ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-08T23:05:15.007363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.490234ms","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-09-08T23:05:15.007382Z","caller":"traceutil/trace.go:171","msg":"trace[1709915329] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5510; }","duration":"173.557956ms","start":"2026-09-08T23:05:14.833818Z","end":"2026-09-08T23:05:15.007376Z","steps":["trace[1709915329] 'agreement among raft nodes before linearized reading'  (duration: 173.458623ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T23:05:15.007384Z","caller":"traceutil/trace.go:171","msg":"trace[179494998] transaction","detail":"{read_only:false; response_revision:5508; number_of_response:1; }","duration":"255.485288ms","start":"2026-09-08T23:05:14.751872Z","end":"2026-09-08T23:05:15.007357Z","steps":["trace[179494998] 'process raft request'  (duration: 250.0341ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T23:05:15.007494Z","caller":"traceutil/trace.go:171","msg":"trace[828691134] transaction","detail":"{read_only:false; response_revision:5509; number_of_response:1; }","duration":"251.098961ms","start":"2026-09-08T23:05:14.756389Z","end":"2026-09-08T23:05:15.007488Z","steps":["trace[828691134] 'process raft request'  (duration: 250.75897ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T23:05:15.00764Z","caller":"traceutil/trace.go:171","msg":"trace[1991513757] transaction","detail":"{read_only:false; response_revision:5510; number_of_response:1; }","duration":"211.054301ms","start":"2026-09-08T23:05:14.79658Z","end":"2026-09-08T23:05:15.007634Z","steps":["trace[1991513757] 'process raft request'  (duration: 210.602647ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T23:05:22.295699Z","caller":"traceutil/trace.go:171","msg":"trace[664545122] linearizableReadLoop","detail":"{readStateIndex:5877; appliedIndex:5876; }","duration":"165.498372ms","start":"2026-09-08T23:05:22.130171Z","end":"2026-09-08T23:05:22.295669Z","steps":["trace[664545122] 'read index received'  (duration: 165.250654ms)","trace[664545122] 'applied index is now lower than readState.Index'  (duration: 246.728µs)"],"step_count":2}
{"level":"info","ts":"2026-09-08T23:05:22.295894Z","caller":"traceutil/trace.go:171","msg":"trace[1465895840] transaction","detail":"{read_only:false; response_revision:5669; number_of_response:1; }","duration":"179.285744ms","start":"2026-09-08T23:05:22.116563Z","end":"2026-09-08T23:05:22.295848Z","steps":["trace[1465895840] 'process raft request'  (duration: 178.934734ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T23:05:22.296033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.059116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"}
{"level":"info","ts":"2026-09-08T23:05:22.296127Z","caller":"traceutil/trace.go:171","msg":"trace[644592171] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:5669; }","duration":"143.171ms","start":"2026-09-08T23:05:22.152923Z","end":"2026-09-08T23:05:22.296094Z","steps":["trace[644592171] 'agreement among raft nodes before linearized reading'  (duration: 142.961924ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T23:05:22.295947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.762809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2b13db78-c6e1-461c-9e71-0e93fa7872d4\" ","response":"range_response_count:1 size:3646"}
{"level":"info","ts":"2026-09-08T23:05:22.296273Z","caller":"traceutil/trace.go:171","msg":"trace[1823715995] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2b13db78-c6e1-461c-9e71-0e93fa7872d4; range_end:; response_count:1; response_revision:5669; }","duration":"166.17049ms","start":"2026-09-08T23:05:22.130089Z","end":"2026-09-08T23:05:22.296259Z","steps":["trace[1823715995] 'agreement among raft nodes before linearized reading'  (duration: 165.726788ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T23:05:22.535876Z","caller":"traceutil/trace.go:171","msg":"trace[1559766142] transaction","detail":"{read_only:false; response_revision:5670; number_of_response:1; }","duration":"234.105854ms","start":"2026-09-08T23:05:22.301739Z","end":"2026-09-08T23:05:22.535845Z","steps":["trace[1559766142] 'process raft request'  (duration: 182.253661ms)","trace[1559766142] 'compare'  (duration: 51.158194ms)"],"step_count":2}
{"level":"info","ts":"2026-09-08T23:05:22.536393Z","caller":"traceutil/trace.go:171","msg":"trace[189763134] transaction","detail":"{read_only:false; response_revision:5671; number_of_response:1; }","duration":"234.518607ms","start":"2026-09-08T23:05:22.301837Z","end":"2026-09-08T23:05:22.536356Z","steps":["trace[189763134] 'process raft request'  (duration: 233.918949ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T23:05:22.537008Z","caller":"traceutil/trace.go:171","msg":"trace[1250870596] transaction","detail":"{read_only:false; response_revision:5672; number_of_response:1; }","duration":"234.185197ms","start":"2026-09-08T23:05:22.302805Z","end":"2026-09-08T23:05:22.536991Z","steps":["trace[1250870596] 'process raft request'  (duration: 233.486807ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T23:05:22.537265Z","caller":"traceutil/trace.go:171","msg":"trace[681265241] transaction","detail":"{read_only:false; response_revision:5673; number_of_response:1; }","duration":"233.781185ms","start":"2026-09-08T23:05:22.303318Z","end":"2026-09-08T23:05:22.5371Z","steps":["trace[681265241] 'process raft request'  (duration: 233.166098ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T23:05:22.537501Z","caller":"traceutil/trace.go:171","msg":"trace[850444031] transaction","detail":"{read_only:false; response_revision:5674; number_of_response:1; }","duration":"233.903269ms","start":"2026-09-08T23:05:22.303567Z","end":"2026-09-08T23:05:22.53747Z","steps":["trace[850444031] 'process raft request'  (duration: 233.357652ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T23:05:22.537703Z","caller":"traceutil/trace.go:171","msg":"trace[1856735315] transaction","detail":"{read_only:false; response_revision:5675; number_of_response:1; }","duration":"232.804307ms","start":"2026-09-08T23:05:22.304867Z","end":"2026-09-08T23:05:22.537671Z","steps":["trace[1856735315] 'process raft request'  (duration: 232.471457ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T23:05:22.538018Z","caller":"traceutil/trace.go:171","msg":"trace[193616676] linearizableReadLoop","detail":"{readStateIndex:5885; appliedIndex:5878; }","duration":"177.857872ms","start":"2026-09-08T23:05:22.360147Z","end":"2026-09-08T23:05:22.538005Z","steps":["trace[193616676] 'read index received'  (duration: 123.829104ms)","trace[193616676] 'applied index is now lower than readState.Index'  (duration: 54.027828ms)"],"step_count":2}
{"level":"info","ts":"2026-09-08T23:05:22.538302Z","caller":"traceutil/trace.go:171","msg":"trace[660490616] transaction","detail":"{read_only:false; response_revision:5676; number_of_response:1; }","duration":"231.89636ms","start":"2026-09-08T23:05:22.306379Z","end":"2026-09-08T23:05:22.538275Z","steps":["trace[660490616] 'process raft request'  (duration: 231.20904ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T23:05:22.538385Z","caller":"traceutil/trace.go:171","msg":"trace[181221306] transaction","detail":"{read_only:false; response_revision:5677; number_of_response:1; }","duration":"177.367737ms","start":"2026-09-08T23:05:22.361Z","end":"2026-09-08T23:05:22.538368Z","steps":["trace[181221306] 'process raft request'  (duration: 176.921324ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T23:05:22.539548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.452244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:9 size:80093"}
{"level":"info","ts":"2026-09-08T23:05:22.539634Z","caller":"traceutil/trace.go:171","msg":"trace[498190982] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:9; response_revision:5677; }","duration":"235.637479ms","start":"2026-09-08T23:05:22.303977Z","end":"2026-09-08T23:05:22.539614Z","steps":["trace[498190982] 'agreement among raft nodes before linearized reading'  (duration: 234.33002ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T23:05:22.540029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.678868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/loki-0\" ","response":"range_response_count:1 size:5588"}
{"level":"info","ts":"2026-09-08T23:05:22.540101Z","caller":"traceutil/trace.go:171","msg":"trace[811040356] range","detail":"{range_begin:/registry/pods/monitoring/loki-0; range_end:; response_count:1; response_revision:5677; }","duration":"238.784961ms","start":"2026-09-08T23:05:22.301297Z","end":"2026-09-08T23:05:22.540082Z","steps":["trace[811040356] 'agreement among raft nodes before linearized reading'  (duration: 236.908137ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T23:05:38.770372Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2943}
{"level":"info","ts":"2026-09-08T23:05:38.840615Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2943,"took":"69.22959ms","hash":934985849,"current-db-size-bytes":56201216,"current-db-size":"56 MB","current-db-size-in-use-bytes":32374784,"current-db-size-in-use":"32 MB"}
{"level":"info","ts":"2026-09-08T23:05:38.840959Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":934985849,"revision":2943,"compact-revision":-1}
{"level":"info","ts":"2026-09-08T23:10:38.778292Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5859}
{"level":"info","ts":"2026-09-08T23:10:38.864031Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5859,"took":"84.256551ms","hash":4015544169,"current-db-size-bytes":56201216,"current-db-size":"56 MB","current-db-size-in-use-bytes":26570752,"current-db-size-in-use":"27 MB"}
{"level":"info","ts":"2026-09-08T23:10:38.864093Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4015544169,"revision":5859,"compact-revision":2943}
{"level":"info","ts":"2026-09-08T23:11:47.039405Z","caller":"traceutil/trace.go:171","msg":"trace[2083932545] linearizableReadLoop","detail":"{readStateIndex:8889; appliedIndex:8888; }","duration":"329.871783ms","start":"2026-09-08T23:11:46.709487Z","end":"2026-09-08T23:11:47.039359Z","steps":["trace[2083932545] 'read index received'  (duration: 309.873545ms)","trace[2083932545] 'applied index is now lower than readState.Index'  (duration: 19.996938ms)"],"step_count":2}
{"level":"info","ts":"2026-09-08T23:11:47.039451Z","caller":"traceutil/trace.go:171","msg":"trace[29802671] transaction","detail":"{read_only:false; response_revision:8588; number_of_response:1; }","duration":"349.83849ms","start":"2026-09-08T23:11:46.689586Z","end":"2026-09-08T23:11:47.039424Z","steps":["trace[29802671] 'process raft request'  (duration: 329.760919ms)","trace[29802671] 'compare'  (duration: 19.519705ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-08T23:11:47.039625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T23:11:46.689553Z","time spent":"349.954413ms","remote":"127.0.0.1:42812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/08db1feb.percona.com\" mod_revision:8578 > success:<request_put:<key:\"/registry/leases/openstack/08db1feb.percona.com\" value_size:455 >> failure:<request_range:<key:\"/registry/leases/openstack/08db1feb.percona.com\" > >"}
{"level":"warn","ts":"2026-09-08T23:11:47.03966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.174891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"}
{"level":"info","ts":"2026-09-08T23:11:47.039711Z","caller":"traceutil/trace.go:171","msg":"trace[1039510675] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8588; }","duration":"330.274214ms","start":"2026-09-08T23:11:46.709417Z","end":"2026-09-08T23:11:47.039691Z","steps":["trace[1039510675] 'agreement among raft nodes before linearized reading'  (duration: 330.10533ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T23:11:47.03975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T23:11:46.709387Z","time spent":"330.352776ms","remote":"127.0.0.1:42812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "}
{"level":"warn","ts":"2026-09-08T23:11:47.074598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.939747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-msp9p\" ","response":"range_response_count:1 size:6524"}
{"level":"info","ts":"2026-09-08T23:11:47.074834Z","caller":"traceutil/trace.go:171","msg":"trace[1062555441] range","detail":"{range_begin:/registry/pods/openstack/keepalived-msp9p; range_end:; response_count:1; response_revision:8590; }","duration":"206.216325ms","start":"2026-09-08T23:11:46.868575Z","end":"2026-09-08T23:11:47.074791Z","steps":["trace[1062555441] 'agreement among raft nodes before linearized reading'  (duration: 205.868975ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T23:11:47.074616Z","caller":"traceutil/trace.go:171","msg":"trace[236660918] transaction","detail":"{read_only:false; response_revision:8590; number_of_response:1; }","duration":"171.144271ms","start":"2026-09-08T23:11:46.90344Z","end":"2026-09-08T23:11:47.074584Z","steps":["trace[236660918] 'process raft request'  (duration: 170.896034ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T23:11:47.074604Z","caller":"traceutil/trace.go:171","msg":"trace[961655842] transaction","detail":"{read_only:false; response_revision:8589; number_of_response:1; }","duration":"244.590456ms","start":"2026-09-08T23:11:46.82997Z","end":"2026-09-08T23:11:47.07456Z","steps":["trace[961655842] 'process raft request'  (duration: 244.165214ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T23:11:47.074669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.837269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-08T23:11:47.075526Z","caller":"traceutil/trace.go:171","msg":"trace[661441069] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:8590; }","duration":"304.759006ms","start":"2026-09-08T23:11:46.770747Z","end":"2026-09-08T23:11:47.075506Z","steps":["trace[661441069] 'agreement among raft nodes before linearized reading'  (duration: 303.856549ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T23:11:47.075606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T23:11:46.770718Z","time spent":"304.866509ms","remote":"127.0.0.1:54438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "}
{"level":"warn","ts":"2026-09-08T23:11:47.074661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.421618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"}
{"level":"info","ts":"2026-09-08T23:11:47.075725Z","caller":"traceutil/trace.go:171","msg":"trace[1849018965] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8590; }","duration":"145.528899ms","start":"2026-09-08T23:11:46.930174Z","end":"2026-09-08T23:11:47.075703Z","steps":["trace[1849018965] 'agreement among raft nodes before linearized reading'  (duration: 144.281393ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T23:11:47.782249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.201851ms","expected-duration":"100ms","prefix":"","request":"header:<ID:13360949205031419349 > lease_revoke:<id:396ba0833bef7cfd>","response":"size:27"}
{"level":"info","ts":"2026-09-08T23:11:47.782485Z","caller":"traceutil/trace.go:171","msg":"trace[1930180110] linearizableReadLoop","detail":"{readStateIndex:8898; appliedIndex:8897; }","duration":"289.531205ms","start":"2026-09-08T23:11:47.492929Z","end":"2026-09-08T23:11:47.78246Z","steps":["trace[1930180110] 'read index received'  (duration: 15.126898ms)","trace[1930180110] 'applied index is now lower than readState.Index'  (duration: 274.402157ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-08T23:11:47.782709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.760602ms","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-09-08T23:11:47.782788Z","caller":"traceutil/trace.go:171","msg":"trace[25857896] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:8596; }","duration":"289.886725ms","start":"2026-09-08T23:11:47.492883Z","end":"2026-09-08T23:11:47.78277Z","steps":["trace[25857896] 'agreement among raft nodes before linearized reading'  (duration: 289.641478ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T23:12:15.342075Z","caller":"traceutil/trace.go:171","msg":"trace[1268657828] linearizableReadLoop","detail":"{readStateIndex:9104; appliedIndex:9103; }","duration":"152.356397ms","start":"2026-09-08T23:12:15.189696Z","end":"2026-09-08T23:12:15.342052Z","steps":["trace[1268657828] 'read index received'  (duration: 152.241874ms)","trace[1268657828] 'applied index is now lower than readState.Index'  (duration: 113.603µs)"],"step_count":2}
{"level":"info","ts":"2026-09-08T23:12:15.342147Z","caller":"traceutil/trace.go:171","msg":"trace[2070219244] transaction","detail":"{read_only:false; response_revision:8795; number_of_response:1; }","duration":"193.307742ms","start":"2026-09-08T23:12:15.148804Z","end":"2026-09-08T23:12:15.342111Z","steps":["trace[2070219244] 'process raft request'  (duration: 193.065635ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T23:12:15.342357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.640205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1633"}
{"level":"info","ts":"2026-09-08T23:12:15.342584Z","caller":"traceutil/trace.go:171","msg":"trace[446696780] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:8795; }","duration":"152.910553ms","start":"2026-09-08T23:12:15.189657Z","end":"2026-09-08T23:12:15.342567Z","steps":["trace[446696780] 'agreement among raft nodes before linearized reading'  (duration: 152.544492ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T23:12:15.585497Z","caller":"traceutil/trace.go:171","msg":"trace[1612754324] linearizableReadLoop","detail":"{readStateIndex:9105; appliedIndex:9104; }","duration":"243.045321ms","start":"2026-09-08T23:12:15.342423Z","end":"2026-09-08T23:12:15.585468Z","steps":["trace[1612754324] 'read index received'  (duration: 242.860825ms)","trace[1612754324] 'applied index is now lower than readState.Index'  (duration: 183.166µs)"],"step_count":2}
{"level":"info","ts":"2026-09-08T23:12:15.585633Z","caller":"traceutil/trace.go:171","msg":"trace[1726225742] transaction","detail":"{read_only:false; response_revision:8796; number_of_response:1; }","duration":"393.129353ms","start":"2026-09-08T23:12:15.192446Z","end":"2026-09-08T23:12:15.585575Z","steps":["trace[1726225742] 'process raft request'  (duration: 392.833264ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T23:12:15.585705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.079098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"}
{"level":"warn","ts":"2026-09-08T23:12:15.585748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.552071ms","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":"warn","ts":"2026-09-08T23:12:15.585764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.588527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"}
{"level":"info","ts":"2026-09-08T23:12:15.58583Z","caller":"traceutil/trace.go:171","msg":"trace[1958903631] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8796; }","duration":"345.68693ms","start":"2026-09-08T23:12:15.240134Z","end":"2026-09-08T23:12:15.585821Z","steps":["trace[1958903631] 'agreement among raft nodes before linearized reading'  (duration: 345.546216ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T23:12:15.585815Z","caller":"traceutil/trace.go:171","msg":"trace[829770795] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8796; }","duration":"311.227503ms","start":"2026-09-08T23:12:15.274562Z","end":"2026-09-08T23:12:15.58579Z","steps":["trace[829770795] 'agreement among raft nodes before linearized reading'  (duration: 310.934024ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T23:12:15.5858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.239025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-msp9p\" ","response":"range_response_count:1 size:6524"}
{"level":"warn","ts":"2026-09-08T23:12:15.585822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.751707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"}
{"level":"warn","ts":"2026-09-08T23:12:15.585858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T23:12:15.24012Z","time spent":"345.73073ms","remote":"127.0.0.1:42812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}
{"level":"warn","ts":"2026-09-08T23:12:15.58587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T23:12:15.274547Z","time spent":"311.311315ms","remote":"127.0.0.1:42812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "}
{"level":"info","ts":"2026-09-08T23:12:15.585833Z","caller":"traceutil/trace.go:171","msg":"trace[1126495375] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8796; }","duration":"340.666184ms","start":"2026-09-08T23:12:15.245147Z","end":"2026-09-08T23:12:15.585814Z","steps":["trace[1126495375] 'agreement among raft nodes before linearized reading'  (duration: 340.454558ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T23:12:15.585904Z","caller":"traceutil/trace.go:171","msg":"trace[1628715741] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8796; }","duration":"322.90273ms","start":"2026-09-08T23:12:15.26298Z","end":"2026-09-08T23:12:15.585883Z","steps":["trace[1628715741] 'agreement among raft nodes before linearized reading'  (duration: 322.614202ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T23:12:15.585903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T23:12:15.192425Z","time spent":"393.324368ms","remote":"127.0.0.1:42812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" mod_revision:8787 > success:<request_put:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" value_size:477 >> failure:<request_range:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" > >"}
{"level":"warn","ts":"2026-09-08T23:12:15.585947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T23:12:15.245137Z","time spent":"340.797288ms","remote":"127.0.0.1:42812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":577,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "}
{"level":"warn","ts":"2026-09-08T23:12:15.585963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T23:12:15.262967Z","time spent":"322.986893ms","remote":"127.0.0.1:42812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}
{"level":"info","ts":"2026-09-08T23:12:15.585882Z","caller":"traceutil/trace.go:171","msg":"trace[1858154056] range","detail":"{range_begin:/registry/pods/openstack/keepalived-msp9p; range_end:; response_count:1; response_revision:8796; }","duration":"291.379069ms","start":"2026-09-08T23:12:15.294485Z","end":"2026-09-08T23:12:15.585864Z","steps":["trace[1858154056] 'agreement among raft nodes before linearized reading'  (duration: 291.129032ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T23:12:15.58625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.115895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"}
{"level":"info","ts":"2026-09-08T23:12:15.586381Z","caller":"traceutil/trace.go:171","msg":"trace[41552786] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8796; }","duration":"323.311952ms","start":"2026-09-08T23:12:15.263037Z","end":"2026-09-08T23:12:15.586349Z","steps":["trace[41552786] 'agreement among raft nodes before linearized reading'  (duration: 322.798937ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T23:12:15.58645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T23:12:15.26303Z","time spent":"323.408424ms","remote":"127.0.0.1:42812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "}
{"level":"warn","ts":"2026-09-08T23:12:15.586844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.865717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-0a5e8865-88a0-4910-a9a1-295325f98043\" ","response":"range_response_count:1 size:1293"}
{"level":"info","ts":"2026-09-08T23:12:15.586906Z","caller":"traceutil/trace.go:171","msg":"trace[1612145461] range","detail":"{range_begin:/registry/persistentvolumes/pvc-0a5e8865-88a0-4910-a9a1-295325f98043; range_end:; response_count:1; response_revision:8797; }","duration":"240.96702ms","start":"2026-09-08T23:12:15.34592Z","end":"2026-09-08T23:12:15.586887Z","steps":["trace[1612145461] 'agreement among raft nodes before linearized reading'  (duration: 240.818176ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T23:12:17.468382Z","caller":"traceutil/trace.go:171","msg":"trace[1396886890] transaction","detail":"{read_only:false; response_revision:8806; number_of_response:1; }","duration":"120.31793ms","start":"2026-09-08T23:12:17.348038Z","end":"2026-09-08T23:12:17.468356Z","steps":["trace[1396886890] 'process raft request'  (duration: 120.161025ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T23:12:24.19837Z","caller":"traceutil/trace.go:171","msg":"trace[1418497673] linearizableReadLoop","detail":"{readStateIndex:9160; appliedIndex:9159; }","duration":"196.050711ms","start":"2026-09-08T23:12:24.00229Z","end":"2026-09-08T23:12:24.198341Z","steps":["trace[1418497673] 'read index received'  (duration: 195.823665ms)","trace[1418497673] 'applied index is now lower than readState.Index'  (duration: 225.616µs)"],"step_count":2}
{"level":"info","ts":"2026-09-08T23:12:24.198444Z","caller":"traceutil/trace.go:171","msg":"trace[1421933176] transaction","detail":"{read_only:false; response_revision:8848; number_of_response:1; }","duration":"257.616382ms","start":"2026-09-08T23:12:23.940788Z","end":"2026-09-08T23:12:24.198404Z","steps":["trace[1421933176] 'process raft request'  (duration: 257.359965ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T23:12:24.198616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.328849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"}
{"level":"info","ts":"2026-09-08T23:12:24.198657Z","caller":"traceutil/trace.go:171","msg":"trace[1611362080] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8848; }","duration":"196.420022ms","start":"2026-09-08T23:12:24.002226Z","end":"2026-09-08T23:12:24.198646Z","steps":["trace[1611362080] 'agreement among raft nodes before linearized reading'  (duration: 196.261918ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T23:12:24.198736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.931617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-08T23:12:24.199007Z","caller":"traceutil/trace.go:171","msg":"trace[766982567] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:8848; }","duration":"130.251598ms","start":"2026-09-08T23:12:24.068732Z","end":"2026-09-08T23:12:24.198983Z","steps":["trace[766982567] 'agreement among raft nodes before linearized reading'  (duration: 129.864386ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T23:12:29.002782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.38196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"}
{"level":"info","ts":"2026-09-08T23:12:29.002856Z","caller":"traceutil/trace.go:171","msg":"trace[1420726647] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8881; }","duration":"104.502563ms","start":"2026-09-08T23:12:28.898338Z","end":"2026-09-08T23:12:29.00284Z","steps":["trace[1420726647] 'range keys from in-memory index tree'  (duration: 104.252025ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T23:12:29.145582Z","caller":"traceutil/trace.go:171","msg":"trace[576899849] transaction","detail":"{read_only:false; response_revision:8882; number_of_response:1; }","duration":"138.981962ms","start":"2026-09-08T23:12:29.006568Z","end":"2026-09-08T23:12:29.14555Z","steps":["trace[576899849] 'process raft request'  (duration: 138.699473ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T23:12:52.226158Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024d8.wal"}
{"level":"info","ts":"2026-09-08T23:13:46.13487Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"612d5169b08bb96b","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000}
{"level":"info","ts":"2026-09-08T23:13:46.14546Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001}
{"level":"info","ts":"2026-09-08T23:13:46.14557Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001}
{"level":"info","ts":"2026-09-08T23:15:38.784117Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8027}
{"level":"info","ts":"2026-09-08T23:15:38.86766Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8027,"took":"80.728404ms","hash":1886111245,"current-db-size-bytes":56201216,"current-db-size":"56 MB","current-db-size-in-use-bytes":28143616,"current-db-size-in-use":"28 MB"}
{"level":"info","ts":"2026-09-08T23:15:38.867763Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1886111245,"revision":8027,"compact-revision":5859}
{"level":"warn","ts":"2026-09-08T23:17:21.436646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.416491ms","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-09-08T23:17:21.436746Z","caller":"traceutil/trace.go:171","msg":"trace[1824960138] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:11464; }","duration":"139.579165ms","start":"2026-09-08T23:17:21.297145Z","end":"2026-09-08T23:17:21.436724Z","steps":["trace[1824960138] 'range keys from in-memory index tree'  (duration: 139.135923ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T23:17:43.4678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.395691ms","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-09-08T23:17:43.467902Z","caller":"traceutil/trace.go:171","msg":"trace[1186661924] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:11585; }","duration":"110.548116ms","start":"2026-09-08T23:17:43.357328Z","end":"2026-09-08T23:17:43.467876Z","steps":["trace[1186661924] 'range keys from in-memory index tree'  (duration: 110.175565ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T23:17:43.467926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.289356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"}
{"level":"info","ts":"2026-09-08T23:17:43.468015Z","caller":"traceutil/trace.go:171","msg":"trace[610924053] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:11585; }","duration":"103.43036ms","start":"2026-09-08T23:17:43.364566Z","end":"2026-09-08T23:17:43.467996Z","steps":["trace[610924053] 'range keys from in-memory index tree'  (duration: 103.05498ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T23:17:43.491242Z","caller":"traceutil/trace.go:171","msg":"trace[1542204276] transaction","detail":"{read_only:false; response_revision:11586; number_of_response:1; }","duration":"107.781376ms","start":"2026-09-08T23:17:43.383434Z","end":"2026-09-08T23:17:43.491216Z","steps":["trace[1542204276] 'process raft request'  (duration: 107.634721ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T23:20:38.790736Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10467}
{"level":"info","ts":"2026-09-08T23:20:38.87554Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10467,"took":"82.941884ms","hash":4080583709,"current-db-size-bytes":56201216,"current-db-size":"56 MB","current-db-size-in-use-bytes":27799552,"current-db-size-in-use":"28 MB"}
{"level":"info","ts":"2026-09-08T23:20:38.87561Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4080583709,"revision":10467,"compact-revision":8027}
{"level":"info","ts":"2026-09-08T23:23:30.409678Z","caller":"traceutil/trace.go:171","msg":"trace[229282150] transaction","detail":"{read_only:false; response_revision:13961; number_of_response:1; }","duration":"158.655264ms","start":"2026-09-08T23:23:30.250987Z","end":"2026-09-08T23:23:30.409642Z","steps":["trace[229282150] 'process raft request'  (duration: 153.885325ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T23:24:02.936908Z","caller":"traceutil/trace.go:171","msg":"trace[2043290255] transaction","detail":"{read_only:false; response_revision:14137; number_of_response:1; }","duration":"107.299738ms","start":"2026-09-08T23:24:02.829578Z","end":"2026-09-08T23:24:02.936878Z","steps":["trace[2043290255] 'process raft request'  (duration: 107.034629ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T23:24:11.340499Z","caller":"traceutil/trace.go:171","msg":"trace[682636721] transaction","detail":"{read_only:false; response_revision:14194; number_of_response:1; }","duration":"156.120931ms","start":"2026-09-08T23:24:11.184348Z","end":"2026-09-08T23:24:11.340469Z","steps":["trace[682636721] 'process raft request'  (duration: 155.939714ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T23:24:13.310058Z","caller":"traceutil/trace.go:171","msg":"trace[1286629016] transaction","detail":"{read_only:false; response_revision:14210; number_of_response:1; }","duration":"108.819241ms","start":"2026-09-08T23:24:13.201173Z","end":"2026-09-08T23:24:13.309992Z","steps":["trace[1286629016] 'process raft request'  (duration: 108.491222ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T23:24:21.60918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.711335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"}
{"level":"info","ts":"2026-09-08T23:24:21.60933Z","caller":"traceutil/trace.go:171","msg":"trace[547745229] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14359; }","duration":"162.874399ms","start":"2026-09-08T23:24:21.446431Z","end":"2026-09-08T23:24:21.609305Z","steps":["trace[547745229] 'range keys from in-memory index tree'  (duration: 162.389345ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T23:24:21.609563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.926436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-msp9p\" ","response":"range_response_count:1 size:6524"}
{"level":"info","ts":"2026-09-08T23:24:21.609662Z","caller":"traceutil/trace.go:171","msg":"trace[1012975205] range","detail":"{range_begin:/registry/pods/openstack/keepalived-msp9p; range_end:; response_count:1; response_revision:14359; }","duration":"108.075161ms","start":"2026-09-08T23:24:21.501562Z","end":"2026-09-08T23:24:21.609637Z","steps":["trace[1012975205] 'range keys from in-memory index tree'  (duration: 107.70012ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T23:24:21.84103Z","caller":"traceutil/trace.go:171","msg":"trace[1469301256] linearizableReadLoop","detail":"{readStateIndex:14854; appliedIndex:14852; }","duration":"218.719136ms","start":"2026-09-08T23:24:21.622286Z","end":"2026-09-08T23:24:21.841005Z","steps":["trace[1469301256] 'read index received'  (duration: 217.852181ms)","trace[1469301256] 'applied index is now lower than readState.Index'  (duration: 866.005µs)"],"step_count":2}
{"level":"info","ts":"2026-09-08T23:24:21.841146Z","caller":"traceutil/trace.go:171","msg":"trace[562601953] transaction","detail":"{read_only:false; response_revision:14362; number_of_response:1; }","duration":"222.58865ms","start":"2026-09-08T23:24:21.618544Z","end":"2026-09-08T23:24:21.841133Z","steps":["trace[562601953] 'process raft request'  (duration: 222.359423ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T23:24:21.841147Z","caller":"traceutil/trace.go:171","msg":"trace[1492363186] transaction","detail":"{read_only:false; response_revision:14361; number_of_response:1; }","duration":"226.082122ms","start":"2026-09-08T23:24:21.615024Z","end":"2026-09-08T23:24:21.841106Z","steps":["trace[1492363186] 'process raft request'  (duration: 225.125755ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T23:24:21.841949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.472533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:67 size:629932"}
{"level":"info","ts":"2026-09-08T23:24:21.841992Z","caller":"traceutil/trace.go:171","msg":"trace[121264316] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:67; response_revision:14362; }","duration":"227.557986ms","start":"2026-09-08T23:24:21.614424Z","end":"2026-09-08T23:24:21.841982Z","steps":["trace[121264316] 'agreement among raft nodes before linearized reading'  (duration: 226.848995ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T23:24:21.860657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.751952ms","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-09-08T23:24:21.860745Z","caller":"traceutil/trace.go:171","msg":"trace[1208196646] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14363; }","duration":"167.892986ms","start":"2026-09-08T23:24:21.692832Z","end":"2026-09-08T23:24:21.860725Z","steps":["trace[1208196646] 'agreement among raft nodes before linearized reading'  (duration: 167.639668ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T23:24:21.861062Z","caller":"traceutil/trace.go:171","msg":"trace[1775078739] transaction","detail":"{read_only:false; response_revision:14363; number_of_response:1; }","duration":"227.956777ms","start":"2026-09-08T23:24:21.633093Z","end":"2026-09-08T23:24:21.861049Z","steps":["trace[1775078739] 'process raft request'  (duration: 227.238256ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T23:24:21.861245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.239057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"}
{"level":"info","ts":"2026-09-08T23:24:21.861363Z","caller":"traceutil/trace.go:171","msg":"trace[1493278285] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14363; }","duration":"168.401051ms","start":"2026-09-08T23:24:21.692937Z","end":"2026-09-08T23:24:21.861338Z","steps":["trace[1493278285] 'agreement among raft nodes before linearized reading'  (duration: 168.03178ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T23:25:29.020754Z","caller":"traceutil/trace.go:171","msg":"trace[971615599] transaction","detail":"{read_only:false; response_revision:15285; number_of_response:1; }","duration":"133.057287ms","start":"2026-09-08T23:25:28.887671Z","end":"2026-09-08T23:25:29.020728Z","steps":["trace[971615599] 'process raft request'  (duration: 132.861661ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T23:25:38.800565Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12751}
{"level":"info","ts":"2026-09-08T23:25:38.880254Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12751,"took":"77.220924ms","hash":2430880115,"current-db-size-bytes":56201216,"current-db-size":"56 MB","current-db-size-in-use-bytes":31125504,"current-db-size-in-use":"31 MB"}
{"level":"info","ts":"2026-09-08T23:25:38.880337Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2430880115,"revision":12751,"compact-revision":10467}
{"level":"info","ts":"2026-09-08T23:25:52.53818Z","caller":"traceutil/trace.go:171","msg":"trace[897446950] transaction","detail":"{read_only:false; response_revision:15405; number_of_response:1; }","duration":"200.136686ms","start":"2026-09-08T23:25:52.338001Z","end":"2026-09-08T23:25:52.538138Z","steps":["trace[897446950] 'process raft request'  (duration: 101.087067ms)","trace[897446950] 'compare'  (duration: 98.72654ms)"],"step_count":2}
{"level":"info","ts":"2026-09-08T23:25:52.538301Z","caller":"traceutil/trace.go:171","msg":"trace[276317042] linearizableReadLoop","detail":"{readStateIndex:15932; appliedIndex:15930; }","duration":"176.87018ms","start":"2026-09-08T23:25:52.361397Z","end":"2026-09-08T23:25:52.538267Z","steps":["trace[276317042] 'read index received'  (duration: 77.705788ms)","trace[276317042] 'applied index is now lower than readState.Index'  (duration: 99.163402ms)"],"step_count":2}
{"level":"info","ts":"2026-09-08T23:25:52.538592Z","caller":"traceutil/trace.go:171","msg":"trace[1680736322] transaction","detail":"{read_only:false; response_revision:15406; number_of_response:1; }","duration":"196.948953ms","start":"2026-09-08T23:25:52.341616Z","end":"2026-09-08T23:25:52.538565Z","steps":["trace[1680736322] 'process raft request'  (duration: 196.468989ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T23:25:52.538885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.464447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"}
{"level":"info","ts":"2026-09-08T23:25:52.53895Z","caller":"traceutil/trace.go:171","msg":"trace[1295968514] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15406; }","duration":"177.57271ms","start":"2026-09-08T23:25:52.36136Z","end":"2026-09-08T23:25:52.538932Z","steps":["trace[1295968514] 'agreement among raft nodes before linearized reading'  (duration: 177.25106ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T23:25:52.566408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.197935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-08T23:25:52.566493Z","caller":"traceutil/trace.go:171","msg":"trace[655155654] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15407; }","duration":"192.298308ms","start":"2026-09-08T23:25:52.374172Z","end":"2026-09-08T23:25:52.566471Z","steps":["trace[655155654] 'agreement among raft nodes before linearized reading'  (duration: 192.172054ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T23:25:52.566507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.319509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"}
{"level":"warn","ts":"2026-09-08T23:25:52.566641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.554988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"}
{"level":"warn","ts":"2026-09-08T23:25:52.566748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.237276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-09-08T23:25:52.566813Z","caller":"traceutil/trace.go:171","msg":"trace[1694922018] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:15407; }","duration":"138.315489ms","start":"2026-09-08T23:25:52.428474Z","end":"2026-09-08T23:25:52.56679Z","steps":["trace[1694922018] 'agreement among raft nodes before linearized reading'  (duration: 138.237597ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T23:25:52.566857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.463325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"}
{"level":"warn","ts":"2026-09-08T23:25:52.566471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.299583ms","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-09-08T23:25:52.56689Z","caller":"traceutil/trace.go:171","msg":"trace[992931381] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15407; }","duration":"179.532838ms","start":"2026-09-08T23:25:52.387347Z","end":"2026-09-08T23:25:52.56688Z","steps":["trace[992931381] 'agreement among raft nodes before linearized reading'  (duration: 179.435785ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T23:25:52.566584Z","caller":"traceutil/trace.go:171","msg":"trace[2095066782] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:15407; }","duration":"192.429931ms","start":"2026-09-08T23:25:52.374134Z","end":"2026-09-08T23:25:52.566564Z","steps":["trace[2095066782] 'agreement among raft nodes before linearized reading'  (duration: 192.310438ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T23:25:52.566645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.932111ms","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-09-08T23:25:52.566995Z","caller":"traceutil/trace.go:171","msg":"trace[892686003] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:15407; }","duration":"189.334232ms","start":"2026-09-08T23:25:52.377652Z","end":"2026-09-08T23:25:52.566986Z","steps":["trace[892686003] 'agreement among raft nodes before linearized reading'  (duration: 188.840727ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T23:25:52.566829Z","caller":"traceutil/trace.go:171","msg":"trace[748126256] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15407; }","duration":"200.703112ms","start":"2026-09-08T23:25:52.366053Z","end":"2026-09-08T23:25:52.566756Z","steps":["trace[748126256] 'agreement among raft nodes before linearized reading'  (duration: 200.222548ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T23:25:52.56709Z","caller":"traceutil/trace.go:171","msg":"trace[2066080404] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15407; }","duration":"187.767827ms","start":"2026-09-08T23:25:52.379149Z","end":"2026-09-08T23:25:52.566917Z","steps":["trace[2066080404] 'agreement among raft nodes before linearized reading'  (duration: 187.17779ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T23:25:52.567604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.724997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"}
{"level":"info","ts":"2026-09-08T23:25:52.567671Z","caller":"traceutil/trace.go:171","msg":"trace[779698552] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:15407; }","duration":"196.867691ms","start":"2026-09-08T23:25:52.370784Z","end":"2026-09-08T23:25:52.567652Z","steps":["trace[779698552] 'agreement among raft nodes before linearized reading'  (duration: 195.910953ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T23:25:53.680621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.418872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"}
{"level":"info","ts":"2026-09-08T23:25:53.680708Z","caller":"traceutil/trace.go:171","msg":"trace[521349164] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15414; }","duration":"298.541005ms","start":"2026-09-08T23:25:53.382143Z","end":"2026-09-08T23:25:53.680684Z","steps":["trace[521349164] 'range keys from in-memory index tree'  (duration: 298.242467ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T23:25:53.680817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.891762ms","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-09-08T23:25:53.680954Z","caller":"traceutil/trace.go:171","msg":"trace[1598819077] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15414; }","duration":"292.066457ms","start":"2026-09-08T23:25:53.388862Z","end":"2026-09-08T23:25:53.680928Z","steps":["trace[1598819077] 'range keys from in-memory index tree'  (duration: 291.689756ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T23:25:53.681089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.434359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-08T23:25:53.681126Z","caller":"traceutil/trace.go:171","msg":"trace[1457283873] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15414; }","duration":"301.492381ms","start":"2026-09-08T23:25:53.379624Z","end":"2026-09-08T23:25:53.681116Z","steps":["trace[1457283873] 'range keys from in-memory index tree'  (duration: 301.375148ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T23:25:53.681154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T23:25:53.379612Z","time spent":"301.533472ms","remote":"127.0.0.1:42780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/openstack/nova-rabbit-init\" "}
{"level":"warn","ts":"2026-09-08T23:25:53.681164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.822489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"}
{"level":"info","ts":"2026-09-08T23:25:53.681196Z","caller":"traceutil/trace.go:171","msg":"trace[1400621552] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15414; }","duration":"420.88715ms","start":"2026-09-08T23:25:53.260298Z","end":"2026-09-08T23:25:53.681185Z","steps":["trace[1400621552] 'range keys from in-memory index tree'  (duration: 420.722675ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T23:25:53.681246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T23:25:53.260276Z","time spent":"420.962443ms","remote":"127.0.0.1:42812","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-09-08T23:25:53.681262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.334587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-08T23:25:53.681286Z","caller":"traceutil/trace.go:171","msg":"trace[250035302] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15414; }","duration":"300.377148ms","start":"2026-09-08T23:25:53.380901Z","end":"2026-09-08T23:25:53.681278Z","steps":["trace[250035302] 'range keys from in-memory index tree'  (duration: 300.292316ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T23:25:53.681306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T23:25:53.380891Z","time spent":"300.41049ms","remote":"127.0.0.1:42780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/openstack/nova-rabbit-init\" "}
{"level":"warn","ts":"2026-09-08T23:25:53.681374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.875996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-08T23:25:53.681398Z","caller":"traceutil/trace.go:171","msg":"trace[1227074554] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:15414; }","duration":"299.915676ms","start":"2026-09-08T23:25:53.381476Z","end":"2026-09-08T23:25:53.681392Z","steps":["trace[1227074554] 'range keys from in-memory index tree'  (duration: 299.817083ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T23:25:53.681507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.984878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-g8h9v\" ","response":"range_response_count:1 size:22124"}
{"level":"info","ts":"2026-09-08T23:25:53.681536Z","caller":"traceutil/trace.go:171","msg":"trace[1826179802] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-g8h9v; range_end:; response_count:1; response_revision:15414; }","duration":"300.03406ms","start":"2026-09-08T23:25:53.381494Z","end":"2026-09-08T23:25:53.681529Z","steps":["trace[1826179802] 'range keys from in-memory index tree'  (duration: 299.890536ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T23:25:53.681556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T23:25:53.381482Z","time spent":"300.068551ms","remote":"127.0.0.1:42734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22146,"request content":"key:\"/registry/pods/openstack/nova-compute-default-g8h9v\" "}
{"level":"warn","ts":"2026-09-08T23:25:53.681714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.878604ms","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-09-08T23:25:53.681747Z","caller":"traceutil/trace.go:171","msg":"trace[131754722] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:15414; }","duration":"299.932706ms","start":"2026-09-08T23:25:53.381806Z","end":"2026-09-08T23:25:53.681739Z","steps":["trace[131754722] 'range keys from in-memory index tree'  (duration: 299.820793ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T23:25:53.681086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.523367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-g8h9v\" ","response":"range_response_count:1 size:22124"}
{"level":"info","ts":"2026-09-08T23:25:53.6819Z","caller":"traceutil/trace.go:171","msg":"trace[696704471] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-g8h9v; range_end:; response_count:1; response_revision:15414; }","duration":"318.398222ms","start":"2026-09-08T23:25:53.363494Z","end":"2026-09-08T23:25:53.681892Z","steps":["trace[696704471] 'range keys from in-memory index tree'  (duration: 317.383193ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T23:25:53.681923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.673398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"}
{"level":"warn","ts":"2026-09-08T23:25:53.681939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T23:25:53.363474Z","time spent":"318.456304ms","remote":"127.0.0.1:42734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22146,"request content":"key:\"/registry/pods/openstack/nova-compute-default-g8h9v\" "}
{"level":"info","ts":"2026-09-08T23:25:53.681956Z","caller":"traceutil/trace.go:171","msg":"trace[2043536598] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15414; }","duration":"289.75382ms","start":"2026-09-08T23:25:53.392192Z","end":"2026-09-08T23:25:53.681946Z","steps":["trace[2043536598] 'range keys from in-memory index tree'  (duration: 289.567135ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T23:25:53.68216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.105773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-lh2b6\" ","response":"range_response_count:1 size:20653"}
{"level":"info","ts":"2026-09-08T23:25:53.682257Z","caller":"traceutil/trace.go:171","msg":"trace[1766925581] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-lh2b6; range_end:; response_count:1; response_revision:15414; }","duration":"287.227557ms","start":"2026-09-08T23:25:53.395012Z","end":"2026-09-08T23:25:53.682239Z","steps":["trace[1766925581] 'range keys from in-memory index tree'  (duration: 286.963999ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T23:25:53.682268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.620852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-5br4x\" ","response":"range_response_count:1 size:9796"}
{"level":"info","ts":"2026-09-08T23:25:53.68231Z","caller":"traceutil/trace.go:171","msg":"trace[1887790593] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-5br4x; range_end:; response_count:1; response_revision:15414; }","duration":"102.699704ms","start":"2026-09-08T23:25:53.579601Z","end":"2026-09-08T23:25:53.682301Z","steps":["trace[1887790593] 'range keys from in-memory index tree'  (duration: 102.435197ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T23:30:38.806663Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15341}
{"level":"info","ts":"2026-09-08T23:30:38.903478Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15341,"took":"93.897554ms","hash":3272395766,"current-db-size-bytes":56201216,"current-db-size":"56 MB","current-db-size-in-use-bytes":32407552,"current-db-size-in-use":"32 MB"}
{"level":"info","ts":"2026-09-08T23:30:38.903541Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3272395766,"revision":15341,"compact-revision":12751}
{"level":"info","ts":"2026-09-08T23:31:46.164813Z","caller":"traceutil/trace.go:171","msg":"trace[1863550571] linearizableReadLoop","detail":"{readStateIndex:18672; appliedIndex:18671; }","duration":"118.016239ms","start":"2026-09-08T23:31:46.046775Z","end":"2026-09-08T23:31:46.164791Z","steps":["trace[1863550571] 'read index received'  (duration: 117.829103ms)","trace[1863550571] 'applied index is now lower than readState.Index'  (duration: 186.266µs)"],"step_count":2}
{"level":"info","ts":"2026-09-08T23:31:46.165208Z","caller":"traceutil/trace.go:171","msg":"trace[1091558799] transaction","detail":"{read_only:false; response_revision:18059; number_of_response:1; }","duration":"121.311194ms","start":"2026-09-08T23:31:46.043883Z","end":"2026-09-08T23:31:46.165194Z","steps":["trace[1091558799] 'process raft request'  (duration: 120.777709ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T23:31:46.16654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.748599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"}
{"level":"info","ts":"2026-09-08T23:31:46.166891Z","caller":"traceutil/trace.go:171","msg":"trace[643653524] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:18059; }","duration":"120.12169ms","start":"2026-09-08T23:31:46.046749Z","end":"2026-09-08T23:31:46.16687Z","steps":["trace[643653524] 'agreement among raft nodes before linearized reading'  (duration: 119.686257ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T23:31:46.62627Z","caller":"traceutil/trace.go:171","msg":"trace[1256283027] transaction","detail":"{read_only:false; response_revision:18061; number_of_response:1; }","duration":"111.258572ms","start":"2026-09-08T23:31:46.514972Z","end":"2026-09-08T23:31:46.62623Z","steps":["trace[1256283027] 'process raft request'  (duration: 111.038056ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T23:31:46.635535Z","caller":"traceutil/trace.go:171","msg":"trace[1631473757] transaction","detail":"{read_only:false; response_revision:18062; number_of_response:1; }","duration":"117.436423ms","start":"2026-09-08T23:31:46.518071Z","end":"2026-09-08T23:31:46.635508Z","steps":["trace[1631473757] 'process raft request'  (duration: 117.179715ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T23:31:46.749902Z","caller":"traceutil/trace.go:171","msg":"trace[1240679095] transaction","detail":"{read_only:false; response_revision:18063; number_of_response:1; }","duration":"109.019407ms","start":"2026-09-08T23:31:46.64084Z","end":"2026-09-08T23:31:46.749859Z","steps":["trace[1240679095] 'process raft request'  (duration: 79.854ms)","trace[1240679095] 'compare'  (duration: 28.935441ms)"],"step_count":2}
{"level":"info","ts":"2026-09-08T23:31:46.780387Z","caller":"traceutil/trace.go:171","msg":"trace[247628276] transaction","detail":"{read_only:false; response_revision:18064; number_of_response:1; }","duration":"137.979088ms","start":"2026-09-08T23:31:46.642365Z","end":"2026-09-08T23:31:46.780344Z","steps":["trace[247628276] 'process raft request'  (duration: 137.615218ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T23:31:46.78085Z","caller":"traceutil/trace.go:171","msg":"trace[1507841581] linearizableReadLoop","detail":"{readStateIndex:18677; appliedIndex:18675; }","duration":"138.211786ms","start":"2026-09-08T23:31:46.642625Z","end":"2026-09-08T23:31:46.780837Z","steps":["trace[1507841581] 'read index received'  (duration: 78.09129ms)","trace[1507841581] 'applied index is now lower than readState.Index'  (duration: 60.118576ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-08T23:31:46.781268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.333447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"}
{"level":"info","ts":"2026-09-08T23:31:46.781335Z","caller":"traceutil/trace.go:171","msg":"trace[716139639] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:18065; }","duration":"119.613115ms","start":"2026-09-08T23:31:46.661707Z","end":"2026-09-08T23:31:46.78132Z","steps":["trace[716139639] 'agreement among raft nodes before linearized reading'  (duration: 119.42868ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T23:31:46.781438Z","caller":"traceutil/trace.go:171","msg":"trace[307803024] transaction","detail":"{read_only:false; response_revision:18065; number_of_response:1; }","duration":"114.57152ms","start":"2026-09-08T23:31:46.666837Z","end":"2026-09-08T23:31:46.781409Z","steps":["trace[307803024] 'process raft request'  (duration: 113.334994ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T23:31:46.782226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.573986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:95 size:942876"}
{"level":"info","ts":"2026-09-08T23:31:46.782328Z","caller":"traceutil/trace.go:171","msg":"trace[1060603974] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:95; response_revision:18065; }","duration":"139.683308ms","start":"2026-09-08T23:31:46.642602Z","end":"2026-09-08T23:31:46.782285Z","steps":["trace[1060603974] 'agreement among raft nodes before linearized reading'  (duration: 138.454863ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T23:31:47.33444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.246398ms","expected-duration":"100ms","prefix":"","request":"header:<ID:13360949205031449110 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" mod_revision:18052 > success:<request_put:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" value_size:464 >> failure:<request_range:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" > >>","response":"size:18"}
{"level":"info","ts":"2026-09-08T23:31:47.335565Z","caller":"traceutil/trace.go:171","msg":"trace[1612396790] transaction","detail":"{read_only:false; response_revision:18066; number_of_response:1; }","duration":"548.731304ms","start":"2026-09-08T23:31:46.786816Z","end":"2026-09-08T23:31:47.335548Z","steps":["trace[1612396790] 'process raft request'  (duration: 209.208779ms)","trace[1612396790] 'compare'  (duration: 336.664962ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-08T23:31:47.33571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.652836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"}
{"level":"info","ts":"2026-09-08T23:31:47.335796Z","caller":"traceutil/trace.go:171","msg":"trace[851127910] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:18066; }","duration":"405.802471ms","start":"2026-09-08T23:31:46.929973Z","end":"2026-09-08T23:31:47.335776Z","steps":["trace[851127910] 'agreement among raft nodes before linearized reading'  (duration: 405.640537ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T23:31:47.335837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T23:31:46.929949Z","time spent":"405.877283ms","remote":"127.0.0.1:50826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "}
{"level":"warn","ts":"2026-09-08T23:31:47.335744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T23:31:46.786786Z","time spent":"548.895369ms","remote":"127.0.0.1:42812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" mod_revision:18052 > success:<request_put:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" value_size:464 >> failure:<request_range:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" > >"}
{"level":"info","ts":"2026-09-08T23:31:47.336283Z","caller":"traceutil/trace.go:171","msg":"trace[1046429165] linearizableReadLoop","detail":"{readStateIndex:18679; appliedIndex:18678; }","duration":"405.445641ms","start":"2026-09-08T23:31:46.930032Z","end":"2026-09-08T23:31:47.335477Z","steps":["trace[1046429165] 'read index received'  (duration: 66.004699ms)","trace[1046429165] 'applied index is now lower than readState.Index'  (duration: 339.439092ms)"],"step_count":2}
{"level":"info","ts":"2026-09-08T23:32:11.143944Z","caller":"traceutil/trace.go:171","msg":"trace[750002556] transaction","detail":"{read_only:false; response_revision:18434; number_of_response:1; }","duration":"111.989694ms","start":"2026-09-08T23:32:11.031927Z","end":"2026-09-08T23:32:11.143917Z","steps":["trace[750002556] 'process raft request'  (duration: 111.83359ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T23:34:19.054136Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"612d5169b08bb96b","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000}
{"level":"info","ts":"2026-09-08T23:34:19.061394Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002}
{"level":"info","ts":"2026-09-08T23:34:19.061531Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002}
{"level":"info","ts":"2026-09-08T23:35:38.81431Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17531}
{"level":"info","ts":"2026-09-08T23:35:38.924042Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17531,"took":"102.939974ms","hash":1721456302,"current-db-size-bytes":56201216,"current-db-size":"56 MB","current-db-size-in-use-bytes":34934784,"current-db-size-in-use":"35 MB"}
{"level":"info","ts":"2026-09-08T23:35:38.924123Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1721456302,"revision":17531,"compact-revision":15341}
{"level":"info","ts":"2026-09-08T23:36:53.162732Z","caller":"traceutil/trace.go:171","msg":"trace[20641981] transaction","detail":"{read_only:false; response_revision:20451; number_of_response:1; }","duration":"101.227493ms","start":"2026-09-08T23:36:53.06148Z","end":"2026-09-08T23:36:53.162707Z","steps":["trace[20641981] 'process raft request'  (duration: 101.046288ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T23:37:19.536367Z","caller":"traceutil/trace.go:171","msg":"trace[1876689065] transaction","detail":"{read_only:false; response_revision:20586; number_of_response:1; }","duration":"210.719957ms","start":"2026-09-08T23:37:19.325616Z","end":"2026-09-08T23:37:19.536336Z","steps":["trace[1876689065] 'process raft request'  (duration: 143.646773ms)","trace[1876689065] 'compare'  (duration: 66.611631ms)"],"step_count":2}
{"level":"info","ts":"2026-09-08T23:37:19.536467Z","caller":"traceutil/trace.go:171","msg":"trace[1128048854] linearizableReadLoop","detail":"{readStateIndex:21288; appliedIndex:21285; }","duration":"131.731068ms","start":"2026-09-08T23:37:19.404704Z","end":"2026-09-08T23:37:19.536435Z","steps":["trace[1128048854] 'read index received'  (duration: 64.575251ms)","trace[1128048854] 'applied index is now lower than readState.Index'  (duration: 67.154497ms)"],"step_count":2}
{"level":"info","ts":"2026-09-08T23:37:19.536551Z","caller":"traceutil/trace.go:171","msg":"trace[2096824261] transaction","detail":"{read_only:false; response_revision:20587; number_of_response:1; }","duration":"210.629885ms","start":"2026-09-08T23:37:19.325881Z","end":"2026-09-08T23:37:19.536511Z","steps":["trace[2096824261] 'process raft request'  (duration: 210.339207ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T23:37:19.536588Z","caller":"traceutil/trace.go:171","msg":"trace[2050249224] transaction","detail":"{read_only:false; response_revision:20588; number_of_response:1; }","duration":"163.037776ms","start":"2026-09-08T23:37:19.373528Z","end":"2026-09-08T23:37:19.536566Z","steps":["trace[2050249224] 'process raft request'  (duration: 162.811849ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T23:37:19.53671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.973585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-08T23:37:19.536777Z","caller":"traceutil/trace.go:171","msg":"trace[1472575328] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:20588; }","duration":"132.10001ms","start":"2026-09-08T23:37:19.404657Z","end":"2026-09-08T23:37:19.536757Z","steps":["trace[1472575328] 'agreement among raft nodes before linearized reading'  (duration: 131.947116ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T23:37:19.918245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.42845ms","expected-duration":"100ms","prefix":"","request":"header:<ID:13360949205031456442 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-node-lease/instance\" mod_revision:20534 > success:<request_put:<key:\"/registry/leases/kube-node-lease/instance\" value_size:472 >> failure:<request_range:<key:\"/registry/leases/kube-node-lease/instance\" > >>","response":"size:18"}
{"level":"info","ts":"2026-09-08T23:37:19.918353Z","caller":"traceutil/trace.go:171","msg":"trace[319938728] transaction","detail":"{read_only:false; response_revision:20589; number_of_response:1; }","duration":"248.216184ms","start":"2026-09-08T23:37:19.670113Z","end":"2026-09-08T23:37:19.91833Z","steps":["trace[319938728] 'process raft request'  (duration: 97.60232ms)","trace[319938728] 'compare'  (duration: 150.150441ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-08T23:37:20.305885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.937313ms","expected-duration":"100ms","prefix":"","request":"header:<ID:13360949205031456443 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" mod_revision:20577 > success:<request_put:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" value_size:478 >> failure:<request_range:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" > >>","response":"size:18"}
{"level":"info","ts":"2026-09-08T23:37:20.305997Z","caller":"traceutil/trace.go:171","msg":"trace[1927203419] linearizableReadLoop","detail":"{readStateIndex:21290; appliedIndex:21289; }","duration":"304.783352ms","start":"2026-09-08T23:37:20.001197Z","end":"2026-09-08T23:37:20.30598Z","steps":["trace[1927203419] 'read index received'  (duration: 123.555861ms)","trace[1927203419] 'applied index is now lower than readState.Index'  (duration: 181.226061ms)"],"step_count":2}
{"level":"info","ts":"2026-09-08T23:37:20.306048Z","caller":"traceutil/trace.go:171","msg":"trace[868842164] transaction","detail":"{read_only:false; response_revision:20590; number_of_response:1; }","duration":"332.106734ms","start":"2026-09-08T23:37:19.973898Z","end":"2026-09-08T23:37:20.306004Z","steps":["trace[868842164] 'process raft request'  (duration: 150.954255ms)","trace[868842164] 'compare'  (duration: 180.510201ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-08T23:37:20.306214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T23:37:19.973875Z","time spent":"332.253118ms","remote":"127.0.0.1:42812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" mod_revision:20577 > success:<request_put:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" value_size:478 >> failure:<request_range:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" > >"}
{"level":"warn","ts":"2026-09-08T23:37:20.306193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.992858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"}
{"level":"warn","ts":"2026-09-08T23:37:20.306204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.909911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-08T23:37:20.306264Z","caller":"traceutil/trace.go:171","msg":"trace[126540634] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20590; }","duration":"305.103562ms","start":"2026-09-08T23:37:20.001149Z","end":"2026-09-08T23:37:20.306253Z","steps":["trace[126540634] 'agreement among raft nodes before linearized reading'  (duration: 304.916817ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T23:37:20.306293Z","caller":"traceutil/trace.go:171","msg":"trace[407292658] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20590; }","duration":"193.044215ms","start":"2026-09-08T23:37:20.113228Z","end":"2026-09-08T23:37:20.306272Z","steps":["trace[407292658] 'agreement among raft nodes before linearized reading'  (duration: 192.8855ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T23:37:20.3063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T23:37:20.001129Z","time spent":"305.161603ms","remote":"127.0.0.1:42812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}
{"level":"warn","ts":"2026-09-08T23:37:20.306305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1782"}
{"level":"info","ts":"2026-09-08T23:37:20.306379Z","caller":"traceutil/trace.go:171","msg":"trace[1918106357] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:20590; }","duration":"190.262803ms","start":"2026-09-08T23:37:20.116095Z","end":"2026-09-08T23:37:20.306358Z","steps":["trace[1918106357] 'agreement among raft nodes before linearized reading'  (duration: 190.047207ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T23:37:20.515759Z","caller":"traceutil/trace.go:171","msg":"trace[786004031] transaction","detail":"{read_only:false; response_revision:20591; number_of_response:1; }","duration":"203.882785ms","start":"2026-09-08T23:37:20.31185Z","end":"2026-09-08T23:37:20.515733Z","steps":["trace[786004031] 'process raft request'  (duration: 198.367535ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T23:37:20.946862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.333915ms","expected-duration":"100ms","prefix":"","request":"header:<ID:13360949205031456451 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:20581 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-cp-lock\" value_size:369 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-cp-lock\" > >>","response":"size:18"}
{"level":"info","ts":"2026-09-08T23:37:20.947108Z","caller":"traceutil/trace.go:171","msg":"trace[1296697109] transaction","detail":"{read_only:false; response_revision:20594; number_of_response:1; }","duration":"426.013737ms","start":"2026-09-08T23:37:20.521077Z","end":"2026-09-08T23:37:20.947091Z","steps":["trace[1296697109] 'process raft request'  (duration: 425.973706ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T23:37:20.94718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T23:37:20.521053Z","time spent":"426.091389ms","remote":"127.0.0.1:42812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" mod_revision:20583 > success:<request_put:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" value_size:411 >> failure:<request_range:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" > >"}
{"level":"info","ts":"2026-09-08T23:37:20.947368Z","caller":"traceutil/trace.go:171","msg":"trace[2090479614] transaction","detail":"{read_only:false; response_revision:20592; number_of_response:1; }","duration":"426.663647ms","start":"2026-09-08T23:37:20.520692Z","end":"2026-09-08T23:37:20.947356Z","steps":["trace[2090479614] 'process raft request'  (duration: 170.511228ms)","trace[2090479614] 'compare'  (duration: 255.052956ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-08T23:37:20.947444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T23:37:20.520676Z","time spent":"426.719598ms","remote":"127.0.0.1:42812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:20581 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-cp-lock\" value_size:369 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-cp-lock\" > >"}
{"level":"info","ts":"2026-09-08T23:37:20.947686Z","caller":"traceutil/trace.go:171","msg":"trace[1123063520] transaction","detail":"{read_only:false; response_revision:20593; number_of_response:1; }","duration":"426.679456ms","start":"2026-09-08T23:37:20.520996Z","end":"2026-09-08T23:37:20.947676Z","steps":["trace[1123063520] 'process raft request'  (duration: 425.963955ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T23:37:20.94775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T23:37:20.520977Z","time spent":"426.742868ms","remote":"127.0.0.1:42812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:20582 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" value_size:371 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" > >"}
{"level":"info","ts":"2026-09-08T23:37:21.155601Z","caller":"traceutil/trace.go:171","msg":"trace[445636021] transaction","detail":"{read_only:false; response_revision:20595; number_of_response:1; }","duration":"106.581387ms","start":"2026-09-08T23:37:21.048993Z","end":"2026-09-08T23:37:21.155574Z","steps":["trace[445636021] 'process raft request'  (duration: 103.742394ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T23:40:38.820731Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20008}
{"level":"info","ts":"2026-09-08T23:40:38.941377Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20008,"took":"117.236746ms","hash":4036848188,"current-db-size-bytes":56201216,"current-db-size":"56 MB","current-db-size-in-use-bytes":29990912,"current-db-size-in-use":"30 MB"}
{"level":"info","ts":"2026-09-08T23:40:38.941421Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4036848188,"revision":20008,"compact-revision":17531}
{"level":"info","ts":"2026-09-08T23:41:08.555796Z","caller":"traceutil/trace.go:171","msg":"trace[1085764620] linearizableReadLoop","detail":"{readStateIndex:23601; appliedIndex:23600; }","duration":"134.941454ms","start":"2026-09-08T23:41:08.420827Z","end":"2026-09-08T23:41:08.555769Z","steps":["trace[1085764620] 'read index received'  (duration: 134.845501ms)","trace[1085764620] 'applied index is now lower than readState.Index'  (duration: 94.803µs)"],"step_count":2}
{"level":"info","ts":"2026-09-08T23:41:08.555842Z","caller":"traceutil/trace.go:171","msg":"trace[143225379] transaction","detail":"{read_only:false; response_revision:22828; number_of_response:1; }","duration":"167.651304ms","start":"2026-09-08T23:41:08.388155Z","end":"2026-09-08T23:41:08.555807Z","steps":["trace[143225379] 'process raft request'  (duration: 167.457738ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T23:41:08.556719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.872542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2225409"}
{"level":"info","ts":"2026-09-08T23:41:08.556767Z","caller":"traceutil/trace.go:171","msg":"trace[2119321131] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:22828; }","duration":"135.977364ms","start":"2026-09-08T23:41:08.420777Z","end":"2026-09-08T23:41:08.556754Z","steps":["trace[2119321131] 'agreement among raft nodes before linearized reading'  (duration: 135.11951ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T23:41:08.556782Z","caller":"traceutil/trace.go:171","msg":"trace[443997613] transaction","detail":"{read_only:false; response_revision:22829; number_of_response:1; }","duration":"129.821609ms","start":"2026-09-08T23:41:08.426946Z","end":"2026-09-08T23:41:08.556768Z","steps":["trace[443997613] 'process raft request'  (duration: 129.646354ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T23:41:15.853663Z","caller":"traceutil/trace.go:171","msg":"trace[1590696283] transaction","detail":"{read_only:false; response_revision:23021; number_of_response:1; }","duration":"173.615735ms","start":"2026-09-08T23:41:15.68001Z","end":"2026-09-08T23:41:15.853625Z","steps":["trace[1590696283] 'process raft request'  (duration: 173.288385ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T23:41:16.028893Z","caller":"traceutil/trace.go:171","msg":"trace[146391703] transaction","detail":"{read_only:false; response_revision:23022; number_of_response:1; }","duration":"170.739742ms","start":"2026-09-08T23:41:15.858117Z","end":"2026-09-08T23:41:16.028856Z","steps":["trace[146391703] 'process raft request'  (duration: 123.30383ms)","trace[146391703] 'compare'  (duration: 47.151104ms)"],"step_count":2}
{"level":"info","ts":"2026-09-08T23:45:38.826816Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22204}
{"level":"info","ts":"2026-09-08T23:45:38.946788Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22204,"took":"116.186758ms","hash":2920570380,"current-db-size-bytes":56201216,"current-db-size":"56 MB","current-db-size-in-use-bytes":42196992,"current-db-size-in-use":"42 MB"}
{"level":"info","ts":"2026-09-08T23:45:38.946861Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2920570380,"revision":22204,"compact-revision":20008}
{"level":"info","ts":"2026-09-08T23:50:38.834448Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25318}
{"level":"info","ts":"2026-09-08T23:50:38.983497Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25318,"took":"145.531154ms","hash":1342576162,"current-db-size-bytes":56201216,"current-db-size":"56 MB","current-db-size-in-use-bytes":29663232,"current-db-size-in-use":"30 MB"}
{"level":"info","ts":"2026-09-08T23:50:38.983579Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1342576162,"revision":25318,"compact-revision":22204}