{"level":"warn","ts":"2026-10-07T05:43:11.592456Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-07T05:43:11.592578Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.31: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.31:2380","--initial-cluster=instance=https://162.253.55.31:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.31:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.31:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-10-07T05:43:11.592674Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-07T05:43:11.59269Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.31:2380"]} {"level":"info","ts":"2026-10-07T05:43:11.592728Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-07T05:43:11.593524Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.31:2379"]} {"level":"info","ts":"2026-10-07T05:43:11.59363Z","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.31:2380"],"listen-peer-urls":["https://162.253.55.31:2380"],"advertise-client-urls":["https://162.253.55.31:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.31:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.31:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-10-07T05:43:11.597209Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.23675ms"} {"level":"info","ts":"2026-10-07T05:43:11.606967Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"de05d1e64231de42","cluster-id":"4f2999739a68ff64"} {"level":"info","ts":"2026-10-07T05:43:11.607059Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de05d1e64231de42 switched to configuration voters=()"} {"level":"info","ts":"2026-10-07T05:43:11.607079Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de05d1e64231de42 became follower at term 0"} {"level":"info","ts":"2026-10-07T05:43:11.607093Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft de05d1e64231de42 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-07T05:43:11.607111Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de05d1e64231de42 became follower at term 1"} {"level":"info","ts":"2026-10-07T05:43:11.607148Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de05d1e64231de42 switched to configuration voters=(15998424038186802754)"} {"level":"warn","ts":"2026-10-07T05:43:11.616455Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-07T05:43:11.61876Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-07T05:43:11.62168Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-10-07T05:43:11.624857Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"de05d1e64231de42","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-07T05:43:11.625116Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"de05d1e64231de42","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-07T05:43:11.625316Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-07T05:43:11.625385Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-07T05:43:11.625399Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-07T05:43:11.628Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de05d1e64231de42 switched to configuration voters=(15998424038186802754)"} {"level":"info","ts":"2026-10-07T05:43:11.630924Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4f2999739a68ff64","local-member-id":"de05d1e64231de42","added-peer-id":"de05d1e64231de42","added-peer-peer-urls":["https://162.253.55.31:2380"]} {"level":"info","ts":"2026-10-07T05:43:11.631009Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-07T05:43:11.631168Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.31:2380"} {"level":"info","ts":"2026-10-07T05:43:11.631252Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.31:2380"} {"level":"info","ts":"2026-10-07T05:43:11.631377Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"de05d1e64231de42","initial-advertise-peer-urls":["https://162.253.55.31:2380"],"listen-peer-urls":["https://162.253.55.31:2380"],"advertise-client-urls":["https://162.253.55.31:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.31:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-07T05:43:11.631439Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-07T05:43:11.707702Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de05d1e64231de42 is starting a new election at term 1"} {"level":"info","ts":"2026-10-07T05:43:11.707851Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de05d1e64231de42 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-07T05:43:11.707883Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de05d1e64231de42 received MsgPreVoteResp from de05d1e64231de42 at term 1"} {"level":"info","ts":"2026-10-07T05:43:11.707928Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de05d1e64231de42 became candidate at term 2"} {"level":"info","ts":"2026-10-07T05:43:11.708146Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de05d1e64231de42 received MsgVoteResp from de05d1e64231de42 at term 2"} {"level":"info","ts":"2026-10-07T05:43:11.708164Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de05d1e64231de42 became leader at term 2"} {"level":"info","ts":"2026-10-07T05:43:11.708174Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: de05d1e64231de42 elected leader de05d1e64231de42 at term 2"} {"level":"info","ts":"2026-10-07T05:43:11.709369Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-07T05:43:11.712506Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-07T05:43:11.712512Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"de05d1e64231de42","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.31:2379]}","request-path":"/0/members/de05d1e64231de42/attributes","cluster-id":"4f2999739a68ff64","publish-timeout":"7s"} {"level":"info","ts":"2026-10-07T05:43:11.712608Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-07T05:43:11.712939Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-07T05:43:11.71313Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-07T05:43:11.713277Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4f2999739a68ff64","local-member-id":"de05d1e64231de42","cluster-version":"3.5"} {"level":"info","ts":"2026-10-07T05:43:11.713403Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-07T05:43:11.71344Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-07T05:43:11.715548Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.31:2379"} {"level":"info","ts":"2026-10-07T05:43:11.715963Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-07T05:43:31.322393Z","caller":"traceutil/trace.go:171","msg":"trace[1228649114] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"159.956643ms","start":"2026-10-07T05:43:31.162399Z","end":"2026-10-07T05:43:31.322356Z","steps":["trace[1228649114] 'process raft request' (duration: 159.718257ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T05:44:01.851905Z","caller":"traceutil/trace.go:171","msg":"trace[186762369] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"310.093034ms","start":"2026-10-07T05:44:01.541773Z","end":"2026-10-07T05:44:01.851866Z","steps":["trace[186762369] 'process raft request' (duration: 309.757025ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T05:44:01.8528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T05:44:01.541703Z","time spent":"310.31288ms","remote":"127.0.0.1:54612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-07T05:44:02.152457Z","caller":"traceutil/trace.go:171","msg":"trace[610935027] linearizableReadLoop","detail":"{readStateIndex:628; appliedIndex:626; }","duration":"450.378931ms","start":"2026-10-07T05:44:01.70203Z","end":"2026-10-07T05:44:02.152409Z","steps":["trace[610935027] 'read index received' (duration: 149.608936ms)","trace[610935027] 'applied index is now lower than readState.Index' (duration: 300.768415ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T05:44:02.152564Z","caller":"traceutil/trace.go:171","msg":"trace[1654087486] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"585.028011ms","start":"2026-10-07T05:44:01.567501Z","end":"2026-10-07T05:44:02.152529Z","steps":["trace[1654087486] 'process raft request' (duration: 547.819357ms)","trace[1654087486] 'compare' (duration: 36.386001ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T05:44:02.152777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T05:44:01.567459Z","time spent":"585.180545ms","remote":"127.0.0.1:54508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036265,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-07T05:44:02.152687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.640008ms","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-10-07T05:44:02.152868Z","caller":"traceutil/trace.go:171","msg":"trace[1632570651] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:604; }","duration":"450.868455ms","start":"2026-10-07T05:44:01.701971Z","end":"2026-10-07T05:44:02.15284Z","steps":["trace[1632570651] 'agreement among raft nodes before linearized reading' (duration: 450.585247ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T05:44:02.152924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T05:44:01.701947Z","time spent":"450.963827ms","remote":"127.0.0.1:54696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-07T05:44:02.318153Z","caller":"traceutil/trace.go:171","msg":"trace[448189241] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"159.819389ms","start":"2026-10-07T05:44:02.15828Z","end":"2026-10-07T05:44:02.3181Z","steps":["trace[448189241] 'process raft request' (duration: 145.426909ms)","trace[448189241] 'compare' (duration: 14.100352ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T05:44:03.799382Z","caller":"traceutil/trace.go:171","msg":"trace[1824558841] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"100.43632ms","start":"2026-10-07T05:44:03.698904Z","end":"2026-10-07T05:44:03.799341Z","steps":["trace[1824558841] 'process raft request' (duration: 100.264556ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T05:44:47.897116Z","caller":"traceutil/trace.go:171","msg":"trace[177609592] transaction","detail":"{read_only:false; response_revision:1175; number_of_response:1; }","duration":"135.531845ms","start":"2026-10-07T05:44:47.761561Z","end":"2026-10-07T05:44:47.897092Z","steps":["trace[177609592] 'process raft request' (duration: 135.35967ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T05:45:38.93649Z","caller":"traceutil/trace.go:171","msg":"trace[294694206] transaction","detail":"{read_only:false; response_revision:1871; number_of_response:1; }","duration":"105.590696ms","start":"2026-10-07T05:45:38.830871Z","end":"2026-10-07T05:45:38.936461Z","steps":["trace[294694206] 'process raft request' (duration: 105.37564ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T05:45:38.936528Z","caller":"traceutil/trace.go:171","msg":"trace[1570566341] transaction","detail":"{read_only:false; response_revision:1872; number_of_response:1; }","duration":"105.149034ms","start":"2026-10-07T05:45:38.831331Z","end":"2026-10-07T05:45:38.93648Z","steps":["trace[1570566341] 'process raft request' (duration: 105.056832ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T05:45:39.228668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.881368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9a4fcfde-d9e0-4300-8d66-01c4788ee074\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-10-07T05:45:39.228778Z","caller":"traceutil/trace.go:171","msg":"trace[465240322] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9a4fcfde-d9e0-4300-8d66-01c4788ee074; range_end:; response_count:1; response_revision:1873; }","duration":"183.058603ms","start":"2026-10-07T05:45:39.045688Z","end":"2026-10-07T05:45:39.228747Z","steps":["trace[465240322] 'range keys from in-memory index tree' (duration: 182.637221ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T05:45:51.168024Z","caller":"traceutil/trace.go:171","msg":"trace[126835971] linearizableReadLoop","detail":"{readStateIndex:2051; appliedIndex:2050; }","duration":"104.698372ms","start":"2026-10-07T05:45:51.063304Z","end":"2026-10-07T05:45:51.168002Z","steps":["trace[126835971] 'read index received' (duration: 104.506216ms)","trace[126835971] 'applied index is now lower than readState.Index' (duration: 191.466µs)"],"step_count":2} {"level":"info","ts":"2026-10-07T05:45:51.168094Z","caller":"traceutil/trace.go:171","msg":"trace[194922488] transaction","detail":"{read_only:false; response_revision:1972; number_of_response:1; }","duration":"220.185561ms","start":"2026-10-07T05:45:50.947881Z","end":"2026-10-07T05:45:51.168066Z","steps":["trace[194922488] 'process raft request' (duration: 219.967235ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T05:45:51.168215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.365732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-10-07T05:45:51.168249Z","caller":"traceutil/trace.go:171","msg":"trace[1902539721] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1972; }","duration":"104.445024ms","start":"2026-10-07T05:45:51.063795Z","end":"2026-10-07T05:45:51.16824Z","steps":["trace[1902539721] 'agreement among raft nodes before linearized reading' (duration: 104.319701ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T05:45:51.168291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.951098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-10-07T05:45:51.168375Z","caller":"traceutil/trace.go:171","msg":"trace[525973904] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1972; }","duration":"105.091322ms","start":"2026-10-07T05:45:51.063265Z","end":"2026-10-07T05:45:51.168356Z","steps":["trace[525973904] 'agreement among raft nodes before linearized reading' (duration: 104.833595ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T05:46:29.524767Z","caller":"traceutil/trace.go:171","msg":"trace[1821320240] linearizableReadLoop","detail":"{readStateIndex:2282; appliedIndex:2281; }","duration":"103.554002ms","start":"2026-10-07T05:46:29.421189Z","end":"2026-10-07T05:46:29.524743Z","steps":["trace[1821320240] 'read index received' (duration: 103.390187ms)","trace[1821320240] 'applied index is now lower than readState.Index' (duration: 162.505µs)"],"step_count":2} {"level":"info","ts":"2026-10-07T05:46:29.524815Z","caller":"traceutil/trace.go:171","msg":"trace[1585388126] transaction","detail":"{read_only:false; response_revision:2195; number_of_response:1; }","duration":"117.769155ms","start":"2026-10-07T05:46:29.407019Z","end":"2026-10-07T05:46:29.524788Z","steps":["trace[1585388126] 'process raft request' (duration: 117.565269ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T05:46:29.524995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.793608ms","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-10-07T05:46:29.525031Z","caller":"traceutil/trace.go:171","msg":"trace[1724783699] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2195; }","duration":"103.882101ms","start":"2026-10-07T05:46:29.421139Z","end":"2026-10-07T05:46:29.525021Z","steps":["trace[1724783699] 'agreement among raft nodes before linearized reading' (duration: 103.737417ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T05:46:29.742395Z","caller":"traceutil/trace.go:171","msg":"trace[200088686] linearizableReadLoop","detail":"{readStateIndex:2284; appliedIndex:2283; }","duration":"198.258867ms","start":"2026-10-07T05:46:29.544111Z","end":"2026-10-07T05:46:29.74237Z","steps":["trace[200088686] 'read index received' (duration: 194.624606ms)","trace[200088686] 'applied index is now lower than readState.Index' (duration: 3.633201ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T05:46:29.742425Z","caller":"traceutil/trace.go:171","msg":"trace[1360638751] transaction","detail":"{read_only:false; response_revision:2197; number_of_response:1; }","duration":"213.227792ms","start":"2026-10-07T05:46:29.529154Z","end":"2026-10-07T05:46:29.742382Z","steps":["trace[1360638751] 'process raft request' (duration: 209.54098ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T05:46:29.742607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.468313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-07T05:46:29.74261Z","caller":"traceutil/trace.go:171","msg":"trace[826870893] transaction","detail":"{read_only:false; response_revision:2198; number_of_response:1; }","duration":"195.896312ms","start":"2026-10-07T05:46:29.546685Z","end":"2026-10-07T05:46:29.742581Z","steps":["trace[826870893] 'process raft request' (duration: 195.654475ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T05:46:29.742649Z","caller":"traceutil/trace.go:171","msg":"trace[1030336823] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2198; }","duration":"198.556165ms","start":"2026-10-07T05:46:29.544082Z","end":"2026-10-07T05:46:29.742638Z","steps":["trace[1030336823] 'agreement among raft nodes before linearized reading' (duration: 198.426242ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T05:46:29.74281Z","caller":"traceutil/trace.go:171","msg":"trace[53157420] transaction","detail":"{read_only:false; response_revision:2199; number_of_response:1; }","duration":"123.136254ms","start":"2026-10-07T05:46:29.619638Z","end":"2026-10-07T05:46:29.742774Z","steps":["trace[53157420] 'process raft request' (duration: 122.858306ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T05:47:55.137506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.684529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-10-07T05:47:55.138359Z","caller":"traceutil/trace.go:171","msg":"trace[433378914] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2852; }","duration":"102.685857ms","start":"2026-10-07T05:47:55.035647Z","end":"2026-10-07T05:47:55.138333Z","steps":["trace[433378914] 'range keys from in-memory index tree' (duration: 101.380771ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T05:47:55.520469Z","caller":"traceutil/trace.go:171","msg":"trace[1662250362] transaction","detail":"{read_only:false; response_revision:2854; number_of_response:1; }","duration":"116.097636ms","start":"2026-10-07T05:47:55.404346Z","end":"2026-10-07T05:47:55.520444Z","steps":["trace[1662250362] 'process raft request' (duration: 115.923971ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T05:52:43.876658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.211739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-07T05:52:43.876788Z","caller":"traceutil/trace.go:171","msg":"trace[1487586884] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5286; }","duration":"136.412835ms","start":"2026-10-07T05:52:43.740355Z","end":"2026-10-07T05:52:43.876768Z","steps":["trace[1487586884] 'range keys from in-memory index tree' (duration: 136.033135ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T05:52:43.8771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.83345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-07T05:52:43.877206Z","caller":"traceutil/trace.go:171","msg":"trace[269507366] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5286; }","duration":"111.989485ms","start":"2026-10-07T05:52:43.765194Z","end":"2026-10-07T05:52:43.877183Z","steps":["trace[269507366] 'range keys from in-memory index tree' (duration: 111.585843ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T05:53:00.290855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.116276ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-07T05:53:00.290996Z","caller":"traceutil/trace.go:171","msg":"trace[1803012523] linearizableReadLoop","detail":"{readStateIndex:5819; appliedIndex:5818; }","duration":"251.754131ms","start":"2026-10-07T05:53:00.039202Z","end":"2026-10-07T05:53:00.290956Z","steps":["trace[1803012523] 'read index received' (duration: 50.361µs)","trace[1803012523] 'applied index is now lower than readState.Index' (duration: 251.70185ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T05:53:00.291098Z","caller":"traceutil/trace.go:171","msg":"trace[669093834] transaction","detail":"{read_only:false; response_revision:5603; number_of_response:1; }","duration":"299.42725ms","start":"2026-10-07T05:52:59.99166Z","end":"2026-10-07T05:53:00.291087Z","steps":["trace[669093834] 'process raft request' (duration: 44.789909ms)","trace[669093834] 'compare' (duration: 253.88256ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T05:53:00.291344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.030589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-10-07T05:53:00.291421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.227904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-10-07T05:53:00.29145Z","caller":"traceutil/trace.go:171","msg":"trace[1991457426] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5603; }","duration":"252.293436ms","start":"2026-10-07T05:53:00.039147Z","end":"2026-10-07T05:53:00.29144Z","steps":["trace[1991457426] 'agreement among raft nodes before linearized reading' (duration: 252.110521ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T05:53:00.291464Z","caller":"traceutil/trace.go:171","msg":"trace[1720826507] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:5603; }","duration":"185.215424ms","start":"2026-10-07T05:53:00.10622Z","end":"2026-10-07T05:53:00.291435Z","steps":["trace[1720826507] 'agreement among raft nodes before linearized reading' (duration: 185.016109ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T05:53:00.551894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.76793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5651"} {"level":"info","ts":"2026-10-07T05:53:00.552014Z","caller":"traceutil/trace.go:171","msg":"trace[1213349609] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:5603; }","duration":"161.893314ms","start":"2026-10-07T05:53:00.390075Z","end":"2026-10-07T05:53:00.551968Z","steps":["trace[1213349609] 'range keys from in-memory index tree' (duration: 161.621197ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T05:53:12.022895Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2944} {"level":"info","ts":"2026-10-07T05:53:12.107149Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2944,"took":"80.650884ms","hash":2383965765,"current-db-size-bytes":56152064,"current-db-size":"56 MB","current-db-size-in-use-bytes":32116736,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-07T05:53:12.107229Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2383965765,"revision":2944,"compact-revision":-1} {"level":"info","ts":"2026-10-07T05:58:12.030266Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5821} {"level":"info","ts":"2026-10-07T05:58:12.123084Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5821,"took":"89.890423ms","hash":1229533336,"current-db-size-bytes":56152064,"current-db-size":"56 MB","current-db-size-in-use-bytes":26288128,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-07T05:58:12.123252Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1229533336,"revision":5821,"compact-revision":2944} {"level":"warn","ts":"2026-10-07T05:59:33.188868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.037692ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-07T05:59:33.189105Z","caller":"traceutil/trace.go:171","msg":"trace[1050518105] transaction","detail":"{read_only:false; response_revision:8655; number_of_response:1; }","duration":"341.677148ms","start":"2026-10-07T05:59:32.847361Z","end":"2026-10-07T05:59:33.189039Z","steps":["trace[1050518105] 'process raft request' (duration: 194.301428ms)","trace[1050518105] 'compare' (duration: 146.64627ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T05:59:33.189209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T05:59:32.847339Z","time spent":"341.819471ms","remote":"127.0.0.1:54696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-07T05:59:33.190039Z","caller":"traceutil/trace.go:171","msg":"trace[1583748473] linearizableReadLoop","detail":"{readStateIndex:8969; appliedIndex:8967; }","duration":"295.075131ms","start":"2026-10-07T05:59:32.894933Z","end":"2026-10-07T05:59:33.190008Z","steps":["trace[1583748473] 'read index received' (duration: 146.660962ms)","trace[1583748473] 'applied index is now lower than readState.Index' (duration: 148.413029ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T05:59:33.190159Z","caller":"traceutil/trace.go:171","msg":"trace[452373874] transaction","detail":"{read_only:false; response_revision:8657; number_of_response:1; }","duration":"276.410085ms","start":"2026-10-07T05:59:32.913713Z","end":"2026-10-07T05:59:33.190123Z","steps":["trace[452373874] 'process raft request' (duration: 276.23676ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T05:59:33.190163Z","caller":"traceutil/trace.go:171","msg":"trace[148941433] transaction","detail":"{read_only:false; response_revision:8656; number_of_response:1; }","duration":"315.509335ms","start":"2026-10-07T05:59:32.874582Z","end":"2026-10-07T05:59:33.190091Z","steps":["trace[148941433] 'process raft request' (duration: 315.179566ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T05:59:33.190361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T05:59:32.874565Z","time spent":"315.69347ms","remote":"127.0.0.1:54696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-07T05:59:33.190344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.390289ms","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-10-07T05:59:33.190455Z","caller":"traceutil/trace.go:171","msg":"trace[2039710176] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8657; }","duration":"295.513303ms","start":"2026-10-07T05:59:32.894914Z","end":"2026-10-07T05:59:33.190428Z","steps":["trace[2039710176] 'agreement among raft nodes before linearized reading' (duration: 295.221425ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T05:59:45.567244Z","caller":"traceutil/trace.go:171","msg":"trace[795895977] linearizableReadLoop","detail":"{readStateIndex:9036; appliedIndex:9033; }","duration":"311.052372ms","start":"2026-10-07T05:59:45.256124Z","end":"2026-10-07T05:59:45.567176Z","steps":["trace[795895977] 'read index received' (duration: 6.52695ms)","trace[795895977] 'applied index is now lower than readState.Index' (duration: 304.524422ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T05:59:45.567572Z","caller":"traceutil/trace.go:171","msg":"trace[22720535] transaction","detail":"{read_only:false; response_revision:8720; number_of_response:1; }","duration":"323.726232ms","start":"2026-10-07T05:59:45.243832Z","end":"2026-10-07T05:59:45.567558Z","steps":["trace[22720535] 'process raft request' (duration: 323.158877ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T05:59:45.567705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T05:59:45.243801Z","time spent":"323.811115ms","remote":"127.0.0.1:54696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-07T05:59:45.567932Z","caller":"traceutil/trace.go:171","msg":"trace[1723258301] transaction","detail":"{read_only:false; response_revision:8721; number_of_response:1; }","duration":"317.425748ms","start":"2026-10-07T05:59:45.250495Z","end":"2026-10-07T05:59:45.567921Z","steps":["trace[1723258301] 'process raft request' (duration: 316.630906ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T05:59:45.568049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T05:59:45.250471Z","time spent":"317.535901ms","remote":"127.0.0.1:54696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-07T05:59:45.568274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.156973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-07T05:59:45.568315Z","caller":"traceutil/trace.go:171","msg":"trace[2279954] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8721; }","duration":"312.197544ms","start":"2026-10-07T05:59:45.256107Z","end":"2026-10-07T05:59:45.568305Z","steps":["trace[2279954] 'agreement among raft nodes before linearized reading' (duration: 312.06727ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T05:59:45.568342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T05:59:45.25606Z","time spent":"312.275966ms","remote":"127.0.0.1:54696","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":"warn","ts":"2026-10-07T05:59:45.568527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.48707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-10-07T05:59:45.568568Z","caller":"traceutil/trace.go:171","msg":"trace[1419028966] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8721; }","duration":"300.569772ms","start":"2026-10-07T05:59:45.267989Z","end":"2026-10-07T05:59:45.568559Z","steps":["trace[1419028966] 'agreement among raft nodes before linearized reading' (duration: 300.467299ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T05:59:45.568593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T05:59:45.267973Z","time spent":"300.614444ms","remote":"127.0.0.1:54696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-10-07T05:59:49.453098Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002366.wal"} {"level":"info","ts":"2026-10-07T06:00:16.539305Z","caller":"traceutil/trace.go:171","msg":"trace[2025337385] linearizableReadLoop","detail":"{readStateIndex:9263; appliedIndex:9262; }","duration":"129.754234ms","start":"2026-10-07T06:00:16.409527Z","end":"2026-10-07T06:00:16.539282Z","steps":["trace[2025337385] 'read index received' (duration: 129.525768ms)","trace[2025337385] 'applied index is now lower than readState.Index' (duration: 227.306µs)"],"step_count":2} {"level":"info","ts":"2026-10-07T06:00:16.53945Z","caller":"traceutil/trace.go:171","msg":"trace[2107764631] transaction","detail":"{read_only:false; response_revision:8938; number_of_response:1; }","duration":"146.611339ms","start":"2026-10-07T06:00:16.392824Z","end":"2026-10-07T06:00:16.539435Z","steps":["trace[2107764631] 'process raft request' (duration: 146.29893ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T06:00:16.539993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.448104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-07T06:00:16.540089Z","caller":"traceutil/trace.go:171","msg":"trace[1027320596] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:8938; }","duration":"130.595518ms","start":"2026-10-07T06:00:16.409476Z","end":"2026-10-07T06:00:16.540072Z","steps":["trace[1027320596] 'agreement among raft nodes before linearized reading' (duration: 130.458244ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:00:19.95727Z","caller":"traceutil/trace.go:171","msg":"trace[191761429] linearizableReadLoop","detail":"{readStateIndex:9280; appliedIndex:9278; }","duration":"162.020782ms","start":"2026-10-07T06:00:19.795218Z","end":"2026-10-07T06:00:19.957239Z","steps":["trace[191761429] 'read index received' (duration: 17.894863ms)","trace[191761429] 'applied index is now lower than readState.Index' (duration: 144.124229ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T06:00:19.957417Z","caller":"traceutil/trace.go:171","msg":"trace[1336501880] transaction","detail":"{read_only:false; response_revision:8955; number_of_response:1; }","duration":"230.960731ms","start":"2026-10-07T06:00:19.72644Z","end":"2026-10-07T06:00:19.957401Z","steps":["trace[1336501880] 'process raft request' (duration: 138.510075ms)","trace[1336501880] 'compare' (duration: 91.88369ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T06:00:19.95779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.670363ms","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":"warn","ts":"2026-10-07T06:00:19.957876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.664781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-07T06:00:19.957902Z","caller":"traceutil/trace.go:171","msg":"trace[1099352007] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8955; }","duration":"161.839218ms","start":"2026-10-07T06:00:19.79604Z","end":"2026-10-07T06:00:19.957879Z","steps":["trace[1099352007] 'agreement among raft nodes before linearized reading' (duration: 161.598121ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:00:19.957921Z","caller":"traceutil/trace.go:171","msg":"trace[64459163] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:8955; }","duration":"125.785844ms","start":"2026-10-07T06:00:19.832123Z","end":"2026-10-07T06:00:19.957909Z","steps":["trace[64459163] 'agreement among raft nodes before linearized reading' (duration: 125.66348ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T06:00:19.958024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.746913ms","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-10-07T06:00:19.958112Z","caller":"traceutil/trace.go:171","msg":"trace[394373289] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8955; }","duration":"162.921948ms","start":"2026-10-07T06:00:19.795166Z","end":"2026-10-07T06:00:19.958088Z","steps":["trace[394373289] 'agreement among raft nodes before linearized reading' (duration: 162.612549ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:00:28.163501Z","caller":"traceutil/trace.go:171","msg":"trace[64660226] linearizableReadLoop","detail":"{readStateIndex:9328; appliedIndex:9327; }","duration":"101.494975ms","start":"2026-10-07T06:00:28.061976Z","end":"2026-10-07T06:00:28.163471Z","steps":["trace[64660226] 'read index received' (duration: 57.682099ms)","trace[64660226] 'applied index is now lower than readState.Index' (duration: 43.811836ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T06:00:28.163623Z","caller":"traceutil/trace.go:171","msg":"trace[989275670] transaction","detail":"{read_only:false; response_revision:9001; number_of_response:1; }","duration":"174.378953ms","start":"2026-10-07T06:00:27.989207Z","end":"2026-10-07T06:00:28.163586Z","steps":["trace[989275670] 'process raft request' (duration: 130.569146ms)","trace[989275670] 'compare' (duration: 43.5543ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T06:00:28.163856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.860255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-07T06:00:28.163944Z","caller":"traceutil/trace.go:171","msg":"trace[1897782370] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9001; }","duration":"102.01566ms","start":"2026-10-07T06:00:28.061909Z","end":"2026-10-07T06:00:28.163925Z","steps":["trace[1897782370] 'agreement among raft nodes before linearized reading' (duration: 101.714952ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:01:31.897785Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"de05d1e64231de42","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-07T06:01:31.911903Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-07T06:01:31.912189Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-07T06:03:12.039256Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7998} {"level":"info","ts":"2026-10-07T06:03:12.122786Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7998,"took":"80.595106ms","hash":2280366151,"current-db-size-bytes":56152064,"current-db-size":"56 MB","current-db-size-in-use-bytes":27897856,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-07T06:03:12.123202Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2280366151,"revision":7998,"compact-revision":5821} {"level":"info","ts":"2026-10-07T06:07:39.775549Z","caller":"traceutil/trace.go:171","msg":"trace[1106447080] transaction","detail":"{read_only:false; response_revision:12306; number_of_response:1; }","duration":"128.51962ms","start":"2026-10-07T06:07:39.646993Z","end":"2026-10-07T06:07:39.775513Z","steps":["trace[1106447080] 'process raft request' (duration: 128.261633ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:08:12.047542Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10362} {"level":"info","ts":"2026-10-07T06:08:12.139382Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10362,"took":"88.635859ms","hash":2557798029,"current-db-size-bytes":56152064,"current-db-size":"56 MB","current-db-size-in-use-bytes":25382912,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-10-07T06:08:12.13948Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2557798029,"revision":10362,"compact-revision":7998} {"level":"info","ts":"2026-10-07T06:13:12.055439Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12530} {"level":"info","ts":"2026-10-07T06:13:12.161414Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12530,"took":"102.67265ms","hash":2049522985,"current-db-size-bytes":56152064,"current-db-size":"56 MB","current-db-size-in-use-bytes":27652096,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-07T06:13:12.161514Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2049522985,"revision":12530,"compact-revision":10362} {"level":"info","ts":"2026-10-07T06:14:17.714933Z","caller":"traceutil/trace.go:171","msg":"trace[1739062057] transaction","detail":"{read_only:false; response_revision:14946; number_of_response:1; }","duration":"137.108204ms","start":"2026-10-07T06:14:17.577792Z","end":"2026-10-07T06:14:17.7149Z","steps":["trace[1739062057] 'process raft request' (duration: 136.95526ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:14:17.728075Z","caller":"traceutil/trace.go:171","msg":"trace[1185954113] linearizableReadLoop","detail":"{readStateIndex:15475; appliedIndex:15472; }","duration":"134.490971ms","start":"2026-10-07T06:14:17.593566Z","end":"2026-10-07T06:14:17.728057Z","steps":["trace[1185954113] 'read index received' (duration: 121.175613ms)","trace[1185954113] 'applied index is now lower than readState.Index' (duration: 13.314018ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T06:14:17.728104Z","caller":"traceutil/trace.go:171","msg":"trace[74271668] transaction","detail":"{read_only:false; response_revision:14947; number_of_response:1; }","duration":"140.788545ms","start":"2026-10-07T06:14:17.587283Z","end":"2026-10-07T06:14:17.728072Z","steps":["trace[74271668] 'process raft request' (duration: 140.612551ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:14:17.728187Z","caller":"traceutil/trace.go:171","msg":"trace[1423175530] transaction","detail":"{read_only:false; response_revision:14948; number_of_response:1; }","duration":"139.558501ms","start":"2026-10-07T06:14:17.588617Z","end":"2026-10-07T06:14:17.728175Z","steps":["trace[1423175530] 'process raft request' (duration: 139.384506ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T06:14:17.728459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.13574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-07T06:14:17.728504Z","caller":"traceutil/trace.go:171","msg":"trace[19084246] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14948; }","duration":"130.197172ms","start":"2026-10-07T06:14:17.598296Z","end":"2026-10-07T06:14:17.728493Z","steps":["trace[19084246] 'agreement among raft nodes before linearized reading' (duration: 130.004007ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T06:14:17.728667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.107448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:356"} {"level":"info","ts":"2026-10-07T06:14:17.728701Z","caller":"traceutil/trace.go:171","msg":"trace[609362693] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:14948; }","duration":"135.17043ms","start":"2026-10-07T06:14:17.593522Z","end":"2026-10-07T06:14:17.728692Z","steps":["trace[609362693] 'agreement among raft nodes before linearized reading' (duration: 135.072257ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:14:35.882887Z","caller":"traceutil/trace.go:171","msg":"trace[1372263097] linearizableReadLoop","detail":"{readStateIndex:15586; appliedIndex:15584; }","duration":"179.940539ms","start":"2026-10-07T06:14:35.702917Z","end":"2026-10-07T06:14:35.882858Z","steps":["trace[1372263097] 'read index received' (duration: 152.441188ms)","trace[1372263097] 'applied index is now lower than readState.Index' (duration: 27.498551ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T06:14:35.883014Z","caller":"traceutil/trace.go:171","msg":"trace[1926826461] transaction","detail":"{read_only:false; response_revision:15053; number_of_response:1; }","duration":"250.309966ms","start":"2026-10-07T06:14:35.632654Z","end":"2026-10-07T06:14:35.882964Z","steps":["trace[1926826461] 'process raft request' (duration: 249.974577ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T06:14:35.883336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.35739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-07T06:14:35.883456Z","caller":"traceutil/trace.go:171","msg":"trace[1255929732] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:15055; }","duration":"180.584897ms","start":"2026-10-07T06:14:35.702841Z","end":"2026-10-07T06:14:35.883426Z","steps":["trace[1255929732] 'agreement among raft nodes before linearized reading' (duration: 180.311089ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:14:46.305076Z","caller":"traceutil/trace.go:171","msg":"trace[505444573] transaction","detail":"{read_only:false; response_revision:15229; number_of_response:1; }","duration":"138.212234ms","start":"2026-10-07T06:14:46.166779Z","end":"2026-10-07T06:14:46.304991Z","steps":["trace[505444573] 'process raft request' (duration: 96.295094ms)","trace[505444573] 'compare' (duration: 41.261802ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T06:14:46.502554Z","caller":"traceutil/trace.go:171","msg":"trace[490786596] transaction","detail":"{read_only:false; response_revision:15231; number_of_response:1; }","duration":"191.91319ms","start":"2026-10-07T06:14:46.310603Z","end":"2026-10-07T06:14:46.502516Z","steps":["trace[490786596] 'process raft request' (duration: 98.142286ms)","trace[490786596] 'compare' (duration: 93.446705ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T06:16:30.676611Z","caller":"traceutil/trace.go:171","msg":"trace[411147692] linearizableReadLoop","detail":"{readStateIndex:16911; appliedIndex:16910; }","duration":"116.553056ms","start":"2026-10-07T06:16:30.560007Z","end":"2026-10-07T06:16:30.67656Z","steps":["trace[411147692] 'read index received' (duration: 104.547764ms)","trace[411147692] 'applied index is now lower than readState.Index' (duration: 12.003802ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T06:16:30.67678Z","caller":"traceutil/trace.go:171","msg":"trace[1300804335] transaction","detail":"{read_only:false; response_revision:16345; number_of_response:1; }","duration":"131.753437ms","start":"2026-10-07T06:16:30.545001Z","end":"2026-10-07T06:16:30.676754Z","steps":["trace[1300804335] 'process raft request' (duration: 119.638051ms)","trace[1300804335] 'compare' (duration: 11.55784ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T06:16:30.677029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.996988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:682"} {"level":"info","ts":"2026-10-07T06:16:30.677157Z","caller":"traceutil/trace.go:171","msg":"trace[1544452306] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:16345; }","duration":"117.163412ms","start":"2026-10-07T06:16:30.55998Z","end":"2026-10-07T06:16:30.677144Z","steps":["trace[1544452306] 'agreement among raft nodes before linearized reading' (duration: 116.834323ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T06:16:30.677197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.948807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-07T06:16:30.677245Z","caller":"traceutil/trace.go:171","msg":"trace[1534713099] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16345; }","duration":"117.038119ms","start":"2026-10-07T06:16:30.560197Z","end":"2026-10-07T06:16:30.677235Z","steps":["trace[1534713099] 'agreement among raft nodes before linearized reading' (duration: 116.959947ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T06:16:30.677492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.985428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-07T06:16:30.677529Z","caller":"traceutil/trace.go:171","msg":"trace[342208774] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:16345; }","duration":"117.040179ms","start":"2026-10-07T06:16:30.56048Z","end":"2026-10-07T06:16:30.67752Z","steps":["trace[342208774] 'agreement among raft nodes before linearized reading' (duration: 116.979137ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T06:16:30.67766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.162592ms","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-10-07T06:16:30.677698Z","caller":"traceutil/trace.go:171","msg":"trace[1555813112] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16345; }","duration":"117.218034ms","start":"2026-10-07T06:16:30.560469Z","end":"2026-10-07T06:16:30.677687Z","steps":["trace[1555813112] 'agreement among raft nodes before linearized reading' (duration: 117.118752ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T06:16:30.67791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.917752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-10-07T06:16:30.677946Z","caller":"traceutil/trace.go:171","msg":"trace[266701337] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16345; }","duration":"109.977214ms","start":"2026-10-07T06:16:30.56796Z","end":"2026-10-07T06:16:30.677937Z","steps":["trace[266701337] 'agreement among raft nodes before linearized reading' (duration: 109.883072ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T06:16:31.246223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.076511ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-10-07T06:16:31.246845Z","caller":"traceutil/trace.go:171","msg":"trace[417997916] linearizableReadLoop","detail":"{readStateIndex:16913; appliedIndex:16911; }","duration":"134.351558ms","start":"2026-10-07T06:16:31.112452Z","end":"2026-10-07T06:16:31.246803Z","steps":["trace[417997916] 'read index received' (duration: 16.835575ms)","trace[417997916] 'applied index is now lower than readState.Index' (duration: 117.514203ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T06:16:31.246867Z","caller":"traceutil/trace.go:171","msg":"trace[654842386] transaction","detail":"{read_only:false; response_revision:16346; number_of_response:1; }","duration":"140.943431ms","start":"2026-10-07T06:16:31.105873Z","end":"2026-10-07T06:16:31.246817Z","steps":["trace[654842386] 'process raft request' (duration: 140.499749ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T06:16:31.257829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.352163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-10-07T06:16:31.257928Z","caller":"traceutil/trace.go:171","msg":"trace[371452012] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:16346; }","duration":"145.519957ms","start":"2026-10-07T06:16:31.112386Z","end":"2026-10-07T06:16:31.257906Z","steps":["trace[371452012] 'agreement among raft nodes before linearized reading' (duration: 134.614945ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T06:16:31.258741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.636749ms","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-10-07T06:16:31.258807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.801432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-07T06:16:31.258831Z","caller":"traceutil/trace.go:171","msg":"trace[422265655] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:16347; }","duration":"124.761953ms","start":"2026-10-07T06:16:31.134048Z","end":"2026-10-07T06:16:31.25881Z","steps":["trace[422265655] 'agreement among raft nodes before linearized reading' (duration: 124.614869ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:16:31.258851Z","caller":"traceutil/trace.go:171","msg":"trace[831058150] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:16347; }","duration":"125.868174ms","start":"2026-10-07T06:16:31.13297Z","end":"2026-10-07T06:16:31.258838Z","steps":["trace[831058150] 'agreement among raft nodes before linearized reading' (duration: 125.792742ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T06:16:31.259152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.733707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-10-07T06:16:31.259188Z","caller":"traceutil/trace.go:171","msg":"trace[1112952146] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16347; }","duration":"126.801369ms","start":"2026-10-07T06:16:31.132377Z","end":"2026-10-07T06:16:31.259178Z","steps":["trace[1112952146] 'agreement among raft nodes before linearized reading' (duration: 126.676706ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T06:16:31.259283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.351105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-07T06:16:31.259311Z","caller":"traceutil/trace.go:171","msg":"trace[1299656608] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16347; }","duration":"127.379286ms","start":"2026-10-07T06:16:31.131925Z","end":"2026-10-07T06:16:31.259304Z","steps":["trace[1299656608] 'agreement among raft nodes before linearized reading' (duration: 127.337305ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T06:16:31.259821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.012896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-4sqb9\" ","response":"range_response_count:1 size:22089"} {"level":"info","ts":"2026-10-07T06:16:31.259871Z","caller":"traceutil/trace.go:171","msg":"trace[1502236557] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-4sqb9; range_end:; response_count:1; response_revision:16347; }","duration":"101.078937ms","start":"2026-10-07T06:16:31.15878Z","end":"2026-10-07T06:16:31.259859Z","steps":["trace[1502236557] 'agreement among raft nodes before linearized reading' (duration: 101.004235ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:18:12.063609Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14529} {"level":"info","ts":"2026-10-07T06:18:12.149522Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14529,"took":"82.434592ms","hash":2721616732,"current-db-size-bytes":56152064,"current-db-size":"56 MB","current-db-size-in-use-bytes":31854592,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-07T06:18:12.1496Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2721616732,"revision":14529,"compact-revision":12530} {"level":"info","ts":"2026-10-07T06:22:11.563539Z","caller":"traceutil/trace.go:171","msg":"trace[2013079814] linearizableReadLoop","detail":"{readStateIndex:19425; appliedIndex:19424; }","duration":"120.312822ms","start":"2026-10-07T06:22:11.44319Z","end":"2026-10-07T06:22:11.563503Z","steps":["trace[2013079814] 'read index received' (duration: 120.014293ms)","trace[2013079814] 'applied index is now lower than readState.Index' (duration: 297.209µs)"],"step_count":2} {"level":"info","ts":"2026-10-07T06:22:11.563662Z","caller":"traceutil/trace.go:171","msg":"trace[896427545] transaction","detail":"{read_only:false; response_revision:18776; number_of_response:1; }","duration":"235.796018ms","start":"2026-10-07T06:22:11.327836Z","end":"2026-10-07T06:22:11.563632Z","steps":["trace[896427545] 'process raft request' (duration: 235.380027ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T06:22:11.56395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.729854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:703"} {"level":"info","ts":"2026-10-07T06:22:11.564095Z","caller":"traceutil/trace.go:171","msg":"trace[1632902333] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18776; }","duration":"120.952129ms","start":"2026-10-07T06:22:11.443108Z","end":"2026-10-07T06:22:11.56406Z","steps":["trace[1632902333] 'agreement among raft nodes before linearized reading' (duration: 120.549568ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T06:22:11.564093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.073364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:703"} {"level":"info","ts":"2026-10-07T06:22:11.564323Z","caller":"traceutil/trace.go:171","msg":"trace[1397215468] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18776; }","duration":"111.427053ms","start":"2026-10-07T06:22:11.452881Z","end":"2026-10-07T06:22:11.564308Z","steps":["trace[1397215468] 'agreement among raft nodes before linearized reading' (duration: 110.813596ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:22:12.02547Z","caller":"traceutil/trace.go:171","msg":"trace[1049504101] transaction","detail":"{read_only:false; response_revision:18777; number_of_response:1; }","duration":"354.220307ms","start":"2026-10-07T06:22:11.671221Z","end":"2026-10-07T06:22:12.025442Z","steps":["trace[1049504101] 'process raft request' (duration: 354.025441ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:22:12.025584Z","caller":"traceutil/trace.go:171","msg":"trace[1171107995] linearizableReadLoop","detail":"{readStateIndex:19426; appliedIndex:19426; }","duration":"311.270957ms","start":"2026-10-07T06:22:11.714285Z","end":"2026-10-07T06:22:12.025556Z","steps":["trace[1171107995] 'read index received' (duration: 311.254176ms)","trace[1171107995] 'applied index is now lower than readState.Index' (duration: 13.601µs)"],"step_count":2} {"level":"warn","ts":"2026-10-07T06:22:12.025633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T06:22:11.671169Z","time spent":"354.385991ms","remote":"127.0.0.1:54502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":916,"response count":0,"response size":41,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-07T06:22:12.025932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.614536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-07T06:22:12.026043Z","caller":"traceutil/trace.go:171","msg":"trace[413836009] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18777; }","duration":"311.796241ms","start":"2026-10-07T06:22:11.714223Z","end":"2026-10-07T06:22:12.02602Z","steps":["trace[413836009] 'agreement among raft nodes before linearized reading' (duration: 311.425381ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T06:22:12.026089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T06:22:11.714195Z","time spent":"311.885514ms","remote":"127.0.0.1:54696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-07T06:22:12.152044Z","caller":"traceutil/trace.go:171","msg":"trace[1337182747] transaction","detail":"{read_only:false; response_revision:18778; number_of_response:1; }","duration":"334.778453ms","start":"2026-10-07T06:22:11.817226Z","end":"2026-10-07T06:22:12.152004Z","steps":["trace[1337182747] 'process raft request' (duration: 316.866663ms)","trace[1337182747] 'compare' (duration: 17.5635ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T06:22:12.152104Z","caller":"traceutil/trace.go:171","msg":"trace[1694143693] transaction","detail":"{read_only:false; response_revision:18779; number_of_response:1; }","duration":"320.602578ms","start":"2026-10-07T06:22:11.831473Z","end":"2026-10-07T06:22:12.152075Z","steps":["trace[1694143693] 'process raft request' (duration: 320.390552ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:22:12.152166Z","caller":"traceutil/trace.go:171","msg":"trace[1250368714] linearizableReadLoop","detail":"{readStateIndex:19430; appliedIndex:19426; }","duration":"126.487014ms","start":"2026-10-07T06:22:12.025636Z","end":"2026-10-07T06:22:12.152123Z","steps":["trace[1250368714] 'read index received' (duration: 108.474051ms)","trace[1250368714] 'applied index is now lower than readState.Index' (duration: 18.011263ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T06:22:12.152231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T06:22:11.817207Z","time spent":"334.923838ms","remote":"127.0.0.1:54696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-07T06:22:12.152266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T06:22:11.831456Z","time spent":"320.711741ms","remote":"127.0.0.1:54696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-07T06:22:12.152499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.785292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-07T06:22:12.152578Z","caller":"traceutil/trace.go:171","msg":"trace[1157100238] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18781; }","duration":"408.941276ms","start":"2026-10-07T06:22:11.743618Z","end":"2026-10-07T06:22:12.15256Z","steps":["trace[1157100238] 'agreement among raft nodes before linearized reading' (duration: 408.641027ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T06:22:12.152631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T06:22:11.74358Z","time spent":"409.037898ms","remote":"127.0.0.1:54696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-07T06:22:12.152605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.257183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-ks-user\" ","response":"range_response_count:1 size:7382"} {"level":"info","ts":"2026-10-07T06:22:12.15216Z","caller":"traceutil/trace.go:171","msg":"trace[1601680555] transaction","detail":"{read_only:false; response_revision:18780; number_of_response:1; }","duration":"263.884873ms","start":"2026-10-07T06:22:11.888247Z","end":"2026-10-07T06:22:12.152131Z","steps":["trace[1601680555] 'process raft request' (duration: 263.668417ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:22:12.152724Z","caller":"traceutil/trace.go:171","msg":"trace[563315143] range","detail":"{range_begin:/registry/jobs/openstack/neutron-ks-user; range_end:; response_count:1; response_revision:18781; }","duration":"191.425789ms","start":"2026-10-07T06:22:11.961272Z","end":"2026-10-07T06:22:12.152697Z","steps":["trace[563315143] 'agreement among raft nodes before linearized reading' (duration: 190.972496ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T06:22:12.152726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.694728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-ks-user\" ","response":"range_response_count:1 size:7382"} {"level":"info","ts":"2026-10-07T06:22:12.152821Z","caller":"traceutil/trace.go:171","msg":"trace[1435126522] range","detail":"{range_begin:/registry/jobs/openstack/neutron-ks-user; range_end:; response_count:1; response_revision:18781; }","duration":"195.828471ms","start":"2026-10-07T06:22:11.956973Z","end":"2026-10-07T06:22:12.152802Z","steps":["trace[1435126522] 'agreement among raft nodes before linearized reading' (duration: 195.331087ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:22:12.154009Z","caller":"traceutil/trace.go:171","msg":"trace[1739868969] transaction","detail":"{read_only:false; response_revision:18781; number_of_response:1; }","duration":"251.676761ms","start":"2026-10-07T06:22:11.900766Z","end":"2026-10-07T06:22:12.152443Z","steps":["trace[1739868969] 'process raft request' (duration: 251.245359ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:22:12.163154Z","caller":"traceutil/trace.go:171","msg":"trace[1715913102] transaction","detail":"{read_only:false; response_revision:18782; number_of_response:1; }","duration":"123.436979ms","start":"2026-10-07T06:22:12.0397Z","end":"2026-10-07T06:22:12.163137Z","steps":["trace[1715913102] 'process raft request' (duration: 123.238613ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:22:18.422253Z","caller":"traceutil/trace.go:171","msg":"trace[374159371] linearizableReadLoop","detail":"{readStateIndex:19497; appliedIndex:19495; }","duration":"114.737486ms","start":"2026-10-07T06:22:18.307481Z","end":"2026-10-07T06:22:18.422219Z","steps":["trace[374159371] 'read index received' (duration: 57.99963ms)","trace[374159371] 'applied index is now lower than readState.Index' (duration: 56.736786ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T06:22:18.422544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.041994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:3959"} {"level":"info","ts":"2026-10-07T06:22:18.42258Z","caller":"traceutil/trace.go:171","msg":"trace[1612862168] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:18846; }","duration":"115.119186ms","start":"2026-10-07T06:22:18.307447Z","end":"2026-10-07T06:22:18.422567Z","steps":["trace[1612862168] 'agreement among raft nodes before linearized reading' (duration: 114.89302ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:22:18.422916Z","caller":"traceutil/trace.go:171","msg":"trace[671405432] transaction","detail":"{read_only:false; response_revision:18846; number_of_response:1; }","duration":"116.967298ms","start":"2026-10-07T06:22:18.305937Z","end":"2026-10-07T06:22:18.422904Z","steps":["trace[671405432] 'process raft request' (duration: 116.143585ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:23:05.215058Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"de05d1e64231de42","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-07T06:23:05.221807Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-07T06:23:05.221946Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-07T06:23:12.071816Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16998} {"level":"info","ts":"2026-10-07T06:23:12.186779Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16998,"took":"107.509524ms","hash":1373032077,"current-db-size-bytes":56152064,"current-db-size":"56 MB","current-db-size-in-use-bytes":32546816,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-07T06:23:12.187083Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1373032077,"revision":16998,"compact-revision":14529} {"level":"info","ts":"2026-10-07T06:28:12.081297Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19393} {"level":"info","ts":"2026-10-07T06:28:12.208679Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19393,"took":"123.217488ms","hash":3407454085,"current-db-size-bytes":56152064,"current-db-size":"56 MB","current-db-size-in-use-bytes":30146560,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-07T06:28:12.208791Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3407454085,"revision":19393,"compact-revision":16998} {"level":"info","ts":"2026-10-07T06:28:42.595352Z","caller":"traceutil/trace.go:171","msg":"trace[1120869916] transaction","detail":"{read_only:false; response_revision:21783; number_of_response:1; }","duration":"149.211622ms","start":"2026-10-07T06:28:42.446101Z","end":"2026-10-07T06:28:42.595312Z","steps":["trace[1120869916] 'process raft request' (duration: 138.754462ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:28:58.366781Z","caller":"traceutil/trace.go:171","msg":"trace[2049312001] transaction","detail":"{read_only:false; response_revision:21863; number_of_response:1; }","duration":"405.991852ms","start":"2026-10-07T06:28:57.960726Z","end":"2026-10-07T06:28:58.366718Z","steps":["trace[2049312001] 'process raft request' (duration: 349.136858ms)","trace[2049312001] 'compare' (duration: 56.649368ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T06:28:58.367001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T06:28:57.960697Z","time spent":"406.193508ms","remote":"127.0.0.1:54696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-07T06:28:58.670405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.28291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T06:28:58.670557Z","caller":"traceutil/trace.go:171","msg":"trace[1419153634] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21863; }","duration":"198.521257ms","start":"2026-10-07T06:28:58.472005Z","end":"2026-10-07T06:28:58.670526Z","steps":["trace[1419153634] 'range keys from in-memory index tree' (duration: 198.128106ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:28:58.890028Z","caller":"traceutil/trace.go:171","msg":"trace[1885334129] linearizableReadLoop","detail":"{readStateIndex:22617; appliedIndex:22616; }","duration":"167.468557ms","start":"2026-10-07T06:28:58.722515Z","end":"2026-10-07T06:28:58.889983Z","steps":["trace[1885334129] 'read index received' (duration: 75.394328ms)","trace[1885334129] 'applied index is now lower than readState.Index' (duration: 92.072299ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T06:28:58.8906Z","caller":"traceutil/trace.go:171","msg":"trace[911136422] transaction","detail":"{read_only:false; response_revision:21865; number_of_response:1; }","duration":"183.09303ms","start":"2026-10-07T06:28:58.707488Z","end":"2026-10-07T06:28:58.890581Z","steps":["trace[911136422] 'process raft request' (duration: 90.614699ms)","trace[911136422] 'compare' (duration: 91.507444ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T06:28:58.890897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.354652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-10-07T06:28:58.890915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.725246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-07T06:28:58.891004Z","caller":"traceutil/trace.go:171","msg":"trace[1505974533] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21865; }","duration":"168.497626ms","start":"2026-10-07T06:28:58.722478Z","end":"2026-10-07T06:28:58.890975Z","steps":["trace[1505974533] 'agreement among raft nodes before linearized reading' (duration: 168.205788ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:28:58.891023Z","caller":"traceutil/trace.go:171","msg":"trace[1328287784] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21865; }","duration":"162.88245ms","start":"2026-10-07T06:28:58.72811Z","end":"2026-10-07T06:28:58.890993Z","steps":["trace[1328287784] 'agreement among raft nodes before linearized reading' (duration: 162.549951ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:28:59.158982Z","caller":"traceutil/trace.go:171","msg":"trace[994800622] linearizableReadLoop","detail":"{readStateIndex:22620; appliedIndex:22619; }","duration":"182.900655ms","start":"2026-10-07T06:28:58.976045Z","end":"2026-10-07T06:28:59.158946Z","steps":["trace[994800622] 'read index received' (duration: 155.565248ms)","trace[994800622] 'applied index is now lower than readState.Index' (duration: 27.324987ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T06:28:59.159275Z","caller":"traceutil/trace.go:171","msg":"trace[944459257] transaction","detail":"{read_only:false; response_revision:21868; number_of_response:1; }","duration":"185.164477ms","start":"2026-10-07T06:28:58.974078Z","end":"2026-10-07T06:28:59.159243Z","steps":["trace[944459257] 'process raft request' (duration: 157.619035ms)","trace[944459257] 'compare' (duration: 26.714659ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T06:28:59.159314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.252764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-07T06:28:59.159368Z","caller":"traceutil/trace.go:171","msg":"trace[98148718] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21868; }","duration":"183.369877ms","start":"2026-10-07T06:28:58.975985Z","end":"2026-10-07T06:28:59.159355Z","steps":["trace[98148718] 'agreement among raft nodes before linearized reading' (duration: 183.083379ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:33:12.109568Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21626} {"level":"info","ts":"2026-10-07T06:33:12.242629Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21626,"took":"128.276452ms","hash":1408799633,"current-db-size-bytes":56152064,"current-db-size":"56 MB","current-db-size-in-use-bytes":33837056,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-07T06:33:12.242689Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1408799633,"revision":21626,"compact-revision":19393} {"level":"info","ts":"2026-10-07T06:33:21.690378Z","caller":"traceutil/trace.go:171","msg":"trace[1888683489] transaction","detail":"{read_only:false; response_revision:24476; number_of_response:1; }","duration":"132.687394ms","start":"2026-10-07T06:33:21.557664Z","end":"2026-10-07T06:33:21.690351Z","steps":["trace[1888683489] 'process raft request' (duration: 132.506969ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T06:33:21.937513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.580392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T06:33:21.937654Z","caller":"traceutil/trace.go:171","msg":"trace[2010056555] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:24477; }","duration":"140.799749ms","start":"2026-10-07T06:33:21.796829Z","end":"2026-10-07T06:33:21.937628Z","steps":["trace[2010056555] 'count revisions from in-memory index tree' (duration: 140.45767ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:36:01.167649Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006940.wal"} {"level":"info","ts":"2026-10-07T06:36:45.940047Z","caller":"traceutil/trace.go:171","msg":"trace[1957104136] linearizableReadLoop","detail":"{readStateIndex:27431; appliedIndex:27429; }","duration":"142.26397ms","start":"2026-10-07T06:36:45.797754Z","end":"2026-10-07T06:36:45.940018Z","steps":["trace[1957104136] 'read index received' (duration: 9.353079ms)","trace[1957104136] 'applied index is now lower than readState.Index' (duration: 132.909431ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T06:36:45.940195Z","caller":"traceutil/trace.go:171","msg":"trace[822072680] transaction","detail":"{read_only:false; response_revision:26546; number_of_response:1; }","duration":"180.761545ms","start":"2026-10-07T06:36:45.759375Z","end":"2026-10-07T06:36:45.940136Z","steps":["trace[822072680] 'process raft request' (duration: 180.407595ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T06:36:45.940287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.509276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-07T06:36:45.940335Z","caller":"traceutil/trace.go:171","msg":"trace[688021216] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:26546; }","duration":"142.627649ms","start":"2026-10-07T06:36:45.797692Z","end":"2026-10-07T06:36:45.94032Z","steps":["trace[688021216] 'agreement among raft nodes before linearized reading' (duration: 142.517576ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T06:36:45.971972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.427613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-07T06:36:45.972071Z","caller":"traceutil/trace.go:171","msg":"trace[1919186313] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26547; }","duration":"121.590267ms","start":"2026-10-07T06:36:45.850459Z","end":"2026-10-07T06:36:45.972049Z","steps":["trace[1919186313] 'agreement among raft nodes before linearized reading' (duration: 121.306629ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:36:45.972321Z","caller":"traceutil/trace.go:171","msg":"trace[513263024] transaction","detail":"{read_only:false; response_revision:26547; number_of_response:1; }","duration":"163.910749ms","start":"2026-10-07T06:36:45.808399Z","end":"2026-10-07T06:36:45.972309Z","steps":["trace[513263024] 'process raft request' (duration: 163.190409ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T06:36:45.972855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.520263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-07T06:36:45.972897Z","caller":"traceutil/trace.go:171","msg":"trace[2127894535] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26547; }","duration":"113.609986ms","start":"2026-10-07T06:36:45.859277Z","end":"2026-10-07T06:36:45.972887Z","steps":["trace[2127894535] 'agreement among raft nodes before linearized reading' (duration: 113.499553ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T06:36:45.973121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.868925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-07T06:36:45.973167Z","caller":"traceutil/trace.go:171","msg":"trace[399352935] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26547; }","duration":"121.936736ms","start":"2026-10-07T06:36:45.851221Z","end":"2026-10-07T06:36:45.973158Z","steps":["trace[399352935] 'agreement among raft nodes before linearized reading' (duration: 121.791242ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:36:46.126395Z","caller":"traceutil/trace.go:171","msg":"trace[302547096] transaction","detail":"{read_only:false; response_revision:26548; number_of_response:1; }","duration":"149.892101ms","start":"2026-10-07T06:36:45.976469Z","end":"2026-10-07T06:36:46.126361Z","steps":["trace[302547096] 'process raft request' (duration: 130.153164ms)","trace[302547096] 'compare' (duration: 19.463299ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T06:36:46.336719Z","caller":"traceutil/trace.go:171","msg":"trace[377476069] transaction","detail":"{read_only:false; response_revision:26549; number_of_response:1; }","duration":"358.774204ms","start":"2026-10-07T06:36:45.977911Z","end":"2026-10-07T06:36:46.336685Z","steps":["trace[377476069] 'process raft request' (duration: 358.470446ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T06:36:46.337029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T06:36:45.977882Z","time spent":"359.033002ms","remote":"127.0.0.1:54696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-07T06:36:46.337276Z","caller":"traceutil/trace.go:171","msg":"trace[739432670] transaction","detail":"{read_only:false; response_revision:26550; number_of_response:1; }","duration":"358.801875ms","start":"2026-10-07T06:36:45.978456Z","end":"2026-10-07T06:36:46.337258Z","steps":["trace[739432670] 'process raft request' (duration: 358.135677ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T06:36:46.33739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T06:36:45.978443Z","time spent":"358.910529ms","remote":"127.0.0.1:54696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-07T06:36:46.33747Z","caller":"traceutil/trace.go:171","msg":"trace[964606670] transaction","detail":"{read_only:false; response_revision:26553; number_of_response:1; }","duration":"265.462111ms","start":"2026-10-07T06:36:46.07196Z","end":"2026-10-07T06:36:46.337422Z","steps":["trace[964606670] 'process raft request' (duration: 265.325947ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:36:46.337383Z","caller":"traceutil/trace.go:171","msg":"trace[771185071] linearizableReadLoop","detail":"{readStateIndex:27434; appliedIndex:27432; }","duration":"358.985271ms","start":"2026-10-07T06:36:45.97836Z","end":"2026-10-07T06:36:46.337345Z","steps":["trace[771185071] 'read index received' (duration: 128.254632ms)","trace[771185071] 'applied index is now lower than readState.Index' (duration: 230.729249ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T06:36:46.337626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.222697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-10-07T06:36:46.33768Z","caller":"traceutil/trace.go:171","msg":"trace[1714647696] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:26553; }","duration":"359.358801ms","start":"2026-10-07T06:36:45.978309Z","end":"2026-10-07T06:36:46.337668Z","steps":["trace[1714647696] 'agreement among raft nodes before linearized reading' (duration: 359.137264ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T06:36:46.337724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T06:36:45.978256Z","time spent":"359.458124ms","remote":"127.0.0.1:54472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":160,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"info","ts":"2026-10-07T06:36:46.337404Z","caller":"traceutil/trace.go:171","msg":"trace[1421237192] transaction","detail":"{read_only:false; response_revision:26552; number_of_response:1; }","duration":"358.505997ms","start":"2026-10-07T06:36:45.978856Z","end":"2026-10-07T06:36:46.337362Z","steps":["trace[1421237192] 'process raft request' (duration: 358.386484ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:36:46.337497Z","caller":"traceutil/trace.go:171","msg":"trace[143660792] transaction","detail":"{read_only:false; response_revision:26551; number_of_response:1; }","duration":"358.656771ms","start":"2026-10-07T06:36:45.978827Z","end":"2026-10-07T06:36:46.337483Z","steps":["trace[143660792] 'process raft request' (duration: 358.297982ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T06:36:46.337896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T06:36:45.97882Z","time spent":"358.951439ms","remote":"127.0.0.1:54696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-07T06:36:46.338001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T06:36:45.9788Z","time spent":"359.121935ms","remote":"127.0.0.1:54696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-07T06:36:46.711976Z","caller":"traceutil/trace.go:171","msg":"trace[1978472197] transaction","detail":"{read_only:false; response_revision:26555; number_of_response:1; }","duration":"105.426869ms","start":"2026-10-07T06:36:46.60652Z","end":"2026-10-07T06:36:46.711946Z","steps":["trace[1978472197] 'process raft request' (duration: 103.519427ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:36:51.717073Z","caller":"traceutil/trace.go:171","msg":"trace[763290176] linearizableReadLoop","detail":"{readStateIndex:27474; appliedIndex:27473; }","duration":"100.968016ms","start":"2026-10-07T06:36:51.615954Z","end":"2026-10-07T06:36:51.716922Z","steps":["trace[763290176] 'read index received' (duration: 87.36751ms)","trace[763290176] 'applied index is now lower than readState.Index' (duration: 13.545515ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T06:36:51.717413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.429169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T06:36:51.717522Z","caller":"traceutil/trace.go:171","msg":"trace[330598298] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:26588; }","duration":"101.556092ms","start":"2026-10-07T06:36:51.615946Z","end":"2026-10-07T06:36:51.717503Z","steps":["trace[330598298] 'agreement among raft nodes before linearized reading' (duration: 101.386148ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:36:51.717376Z","caller":"traceutil/trace.go:171","msg":"trace[218566764] transaction","detail":"{read_only:false; response_revision:26588; number_of_response:1; }","duration":"226.006548ms","start":"2026-10-07T06:36:51.491317Z","end":"2026-10-07T06:36:51.717324Z","steps":["trace[218566764] 'process raft request' (duration: 212.070292ms)","trace[218566764] 'compare' (duration: 13.072332ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T06:36:52.191352Z","caller":"traceutil/trace.go:171","msg":"trace[1013352488] transaction","detail":"{read_only:false; response_revision:26589; number_of_response:1; }","duration":"232.566348ms","start":"2026-10-07T06:36:51.958748Z","end":"2026-10-07T06:36:52.191314Z","steps":["trace[1013352488] 'process raft request' (duration: 232.330262ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T06:36:52.47351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.149346ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-07T06:36:52.473672Z","caller":"traceutil/trace.go:171","msg":"trace[1125345677] linearizableReadLoop","detail":"{readStateIndex:27476; appliedIndex:27474; }","duration":"329.409131ms","start":"2026-10-07T06:36:52.144249Z","end":"2026-10-07T06:36:52.473658Z","steps":["trace[1125345677] 'read index received' (duration: 46.811216ms)","trace[1125345677] 'applied index is now lower than readState.Index' (duration: 282.597085ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T06:36:52.473691Z","caller":"traceutil/trace.go:171","msg":"trace[267165025] transaction","detail":"{read_only:false; response_revision:26590; number_of_response:1; }","duration":"476.799252ms","start":"2026-10-07T06:36:51.996856Z","end":"2026-10-07T06:36:52.473656Z","steps":["trace[267165025] 'process raft request' (duration: 312.415471ms)","trace[267165025] 'compare' (duration: 164.011021ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T06:36:52.473725Z","caller":"traceutil/trace.go:171","msg":"trace[547642463] transaction","detail":"{read_only:false; response_revision:26591; number_of_response:1; }","duration":"325.612696ms","start":"2026-10-07T06:36:52.148079Z","end":"2026-10-07T06:36:52.473692Z","steps":["trace[547642463] 'process raft request' (duration: 325.532374ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T06:36:52.473793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.558706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T06:36:52.473817Z","caller":"traceutil/trace.go:171","msg":"trace[761324735] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:26591; }","duration":"329.585937ms","start":"2026-10-07T06:36:52.144222Z","end":"2026-10-07T06:36:52.473808Z","steps":["trace[761324735] 'agreement among raft nodes before linearized reading' (duration: 329.517285ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T06:36:52.473854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T06:36:52.144107Z","time spent":"329.740101ms","remote":"127.0.0.1:60322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true "} {"level":"warn","ts":"2026-10-07T06:36:52.473905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T06:36:52.148055Z","time spent":"325.73991ms","remote":"127.0.0.1:54696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-07T06:36:52.473912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T06:36:51.996809Z","time spent":"476.958336ms","remote":"127.0.0.1:54696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-07T06:36:52.474379Z","caller":"traceutil/trace.go:171","msg":"trace[1392377369] transaction","detail":"{read_only:false; response_revision:26592; number_of_response:1; }","duration":"105.198413ms","start":"2026-10-07T06:36:52.36917Z","end":"2026-10-07T06:36:52.474369Z","steps":["trace[1392377369] 'process raft request' (duration: 105.149072ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T06:36:52.474499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.998482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/roles/\" range_end:\"/registry/openstack.k-orc.cloud/roles0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T06:36:52.474539Z","caller":"traceutil/trace.go:171","msg":"trace[1821768829] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/roles/; range_end:/registry/openstack.k-orc.cloud/roles0; response_count:0; response_revision:26592; }","duration":"272.085955ms","start":"2026-10-07T06:36:52.202442Z","end":"2026-10-07T06:36:52.474528Z","steps":["trace[1821768829] 'agreement among raft nodes before linearized reading' (duration: 271.93002ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T06:36:52.474681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.190912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-07T06:36:52.474705Z","caller":"traceutil/trace.go:171","msg":"trace[993218144] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26592; }","duration":"101.240173ms","start":"2026-10-07T06:36:52.373458Z","end":"2026-10-07T06:36:52.474698Z","steps":["trace[993218144] 'agreement among raft nodes before linearized reading' (duration: 101.12783ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T06:36:52.474767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.901852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-07T06:36:52.474788Z","caller":"traceutil/trace.go:171","msg":"trace[1823275259] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26592; }","duration":"101.981954ms","start":"2026-10-07T06:36:52.372799Z","end":"2026-10-07T06:36:52.474781Z","steps":["trace[1823275259] 'agreement among raft nodes before linearized reading' (duration: 101.86777ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T06:36:52.474884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.857759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T06:36:52.474914Z","caller":"traceutil/trace.go:171","msg":"trace[328143693] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddressclaims/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddressclaims0; response_count:0; response_revision:26592; }","duration":"184.913931ms","start":"2026-10-07T06:36:52.289988Z","end":"2026-10-07T06:36:52.474902Z","steps":["trace[328143693] 'agreement among raft nodes before linearized reading' (duration: 184.858909ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T06:36:52.474916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.993602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T06:36:52.474943Z","caller":"traceutil/trace.go:171","msg":"trace[1089177300] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:26592; }","duration":"211.071925ms","start":"2026-10-07T06:36:52.263861Z","end":"2026-10-07T06:36:52.474933Z","steps":["trace[1089177300] 'agreement among raft nodes before linearized reading' (duration: 211.020393ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T06:36:52.475091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.473334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-07T06:36:52.475112Z","caller":"traceutil/trace.go:171","msg":"trace[1604232695] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26592; }","duration":"108.493274ms","start":"2026-10-07T06:36:52.366611Z","end":"2026-10-07T06:36:52.475105Z","steps":["trace[1604232695] 'agreement among raft nodes before linearized reading' (duration: 108.409322ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:36:52.646381Z","caller":"traceutil/trace.go:171","msg":"trace[1826574915] transaction","detail":"{read_only:false; response_revision:26593; number_of_response:1; }","duration":"165.322628ms","start":"2026-10-07T06:36:52.481026Z","end":"2026-10-07T06:36:52.646348Z","steps":["trace[1826574915] 'process raft request' (duration: 134.188632ms)","trace[1826574915] 'compare' (duration: 30.9421ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T06:36:52.687978Z","caller":"traceutil/trace.go:171","msg":"trace[1056512639] linearizableReadLoop","detail":"{readStateIndex:27481; appliedIndex:27478; }","duration":"205.087664ms","start":"2026-10-07T06:36:52.48286Z","end":"2026-10-07T06:36:52.687948Z","steps":["trace[1056512639] 'read index received' (duration: 132.350441ms)","trace[1056512639] 'applied index is now lower than readState.Index' (duration: 72.735702ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T06:36:52.68801Z","caller":"traceutil/trace.go:171","msg":"trace[720452509] transaction","detail":"{read_only:false; response_revision:26594; number_of_response:1; }","duration":"206.305527ms","start":"2026-10-07T06:36:52.481665Z","end":"2026-10-07T06:36:52.687971Z","steps":["trace[720452509] 'process raft request' (duration: 205.983618ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:36:52.688122Z","caller":"traceutil/trace.go:171","msg":"trace[1648598517] transaction","detail":"{read_only:false; response_revision:26595; number_of_response:1; }","duration":"206.291787ms","start":"2026-10-07T06:36:52.481817Z","end":"2026-10-07T06:36:52.688109Z","steps":["trace[1648598517] 'process raft request' (duration: 205.981958ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:36:52.688272Z","caller":"traceutil/trace.go:171","msg":"trace[291225292] transaction","detail":"{read_only:false; response_revision:26596; number_of_response:1; }","duration":"204.936029ms","start":"2026-10-07T06:36:52.483329Z","end":"2026-10-07T06:36:52.688265Z","steps":["trace[291225292] 'process raft request' (duration: 204.516148ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T06:36:52.688403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.522345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T06:36:52.68843Z","caller":"traceutil/trace.go:171","msg":"trace[1624657084] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26596; }","duration":"205.624778ms","start":"2026-10-07T06:36:52.482798Z","end":"2026-10-07T06:36:52.688423Z","steps":["trace[1624657084] 'agreement among raft nodes before linearized reading' (duration: 205.525145ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T06:36:52.688656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.878959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-07T06:36:52.688675Z","caller":"traceutil/trace.go:171","msg":"trace[237474372] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26596; }","duration":"121.934751ms","start":"2026-10-07T06:36:52.566735Z","end":"2026-10-07T06:36:52.68867Z","steps":["trace[237474372] 'agreement among raft nodes before linearized reading' (duration: 121.833698ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T06:36:52.947081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.800493ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-07T06:36:52.947312Z","caller":"traceutil/trace.go:171","msg":"trace[640328948] transaction","detail":"{read_only:false; response_revision:26598; number_of_response:1; }","duration":"216.792049ms","start":"2026-10-07T06:36:52.730495Z","end":"2026-10-07T06:36:52.947287Z","steps":["trace[640328948] 'process raft request' (duration: 216.695956ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:36:52.947403Z","caller":"traceutil/trace.go:171","msg":"trace[1406719278] transaction","detail":"{read_only:false; response_revision:26597; number_of_response:1; }","duration":"253.746896ms","start":"2026-10-07T06:36:52.693619Z","end":"2026-10-07T06:36:52.947366Z","steps":["trace[1406719278] 'process raft request' (duration: 120.561142ms)","trace[1406719278] 'compare' (duration: 132.480584ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T06:36:52.979397Z","caller":"traceutil/trace.go:171","msg":"trace[1353772723] linearizableReadLoop","detail":"{readStateIndex:27485; appliedIndex:27484; }","duration":"102.48485ms","start":"2026-10-07T06:36:52.876876Z","end":"2026-10-07T06:36:52.97936Z","steps":["trace[1353772723] 'read index received' (duration: 102.269304ms)","trace[1353772723] 'applied index is now lower than readState.Index' (duration: 214.025µs)"],"step_count":2} {"level":"info","ts":"2026-10-07T06:36:52.979456Z","caller":"traceutil/trace.go:171","msg":"trace[786549132] transaction","detail":"{read_only:false; response_revision:26599; number_of_response:1; }","duration":"106.915543ms","start":"2026-10-07T06:36:52.8725Z","end":"2026-10-07T06:36:52.979416Z","steps":["trace[786549132] 'process raft request' (duration: 106.651236ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T06:36:52.979587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.670645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/keypairs/\" range_end:\"/registry/openstack.k-orc.cloud/keypairs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T06:36:52.979628Z","caller":"traceutil/trace.go:171","msg":"trace[995034960] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/keypairs/; range_end:/registry/openstack.k-orc.cloud/keypairs0; response_count:0; response_revision:26599; }","duration":"102.797279ms","start":"2026-10-07T06:36:52.876817Z","end":"2026-10-07T06:36:52.979614Z","steps":["trace[995034960] 'agreement among raft nodes before linearized reading' (duration: 102.677595ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T06:36:53.307991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.980595ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-07T06:36:53.308303Z","caller":"traceutil/trace.go:171","msg":"trace[1955914831] transaction","detail":"{read_only:false; response_revision:26601; number_of_response:1; }","duration":"155.402632ms","start":"2026-10-07T06:36:53.152875Z","end":"2026-10-07T06:36:53.308278Z","steps":["trace[1955914831] 'process raft request' (duration: 155.309529ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:36:53.308404Z","caller":"traceutil/trace.go:171","msg":"trace[1175129698] linearizableReadLoop","detail":"{readStateIndex:27486; appliedIndex:27485; }","duration":"200.99999ms","start":"2026-10-07T06:36:53.107376Z","end":"2026-10-07T06:36:53.308376Z","steps":["trace[1175129698] 'read index received' (duration: 34.544451ms)","trace[1175129698] 'applied index is now lower than readState.Index' (duration: 166.452649ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T06:36:53.308576Z","caller":"traceutil/trace.go:171","msg":"trace[1579817653] transaction","detail":"{read_only:false; response_revision:26600; number_of_response:1; }","duration":"411.73671ms","start":"2026-10-07T06:36:52.896819Z","end":"2026-10-07T06:36:53.308556Z","steps":["trace[1579817653] 'process raft request' (duration: 245.086246ms)","trace[1579817653] 'compare' (duration: 165.571914ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T06:36:53.308712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T06:36:52.896794Z","time spent":"411.821953ms","remote":"127.0.0.1:54696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-07T06:36:53.309139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.73346ms","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-10-07T06:36:53.309261Z","caller":"traceutil/trace.go:171","msg":"trace[2011253469] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26601; }","duration":"201.880084ms","start":"2026-10-07T06:36:53.107352Z","end":"2026-10-07T06:36:53.309232Z","steps":["trace[2011253469] 'agreement among raft nodes before linearized reading' (duration: 201.406011ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:36:53.85731Z","caller":"traceutil/trace.go:171","msg":"trace[646020918] transaction","detail":"{read_only:false; response_revision:26603; number_of_response:1; }","duration":"133.812701ms","start":"2026-10-07T06:36:53.72345Z","end":"2026-10-07T06:36:53.857262Z","steps":["trace[646020918] 'process raft request' (duration: 117.802406ms)","trace[646020918] 'compare' (duration: 15.683156ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T06:36:54.304704Z","caller":"traceutil/trace.go:171","msg":"trace[219886878] transaction","detail":"{read_only:false; response_revision:26604; number_of_response:1; }","duration":"106.717878ms","start":"2026-10-07T06:36:54.197961Z","end":"2026-10-07T06:36:54.304679Z","steps":["trace[219886878] 'process raft request' (duration: 106.570503ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:36:54.657075Z","caller":"traceutil/trace.go:171","msg":"trace[1809170842] transaction","detail":"{read_only:false; response_revision:26605; number_of_response:1; }","duration":"175.220322ms","start":"2026-10-07T06:36:54.481788Z","end":"2026-10-07T06:36:54.657008Z","steps":["trace[1809170842] 'process raft request' (duration: 174.930754ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:36:54.677877Z","caller":"traceutil/trace.go:171","msg":"trace[1204329593] transaction","detail":"{read_only:false; response_revision:26606; number_of_response:1; }","duration":"195.108766ms","start":"2026-10-07T06:36:54.482701Z","end":"2026-10-07T06:36:54.67781Z","steps":["trace[1204329593] 'process raft request' (duration: 194.861869ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:36:54.852673Z","caller":"traceutil/trace.go:171","msg":"trace[412921641] linearizableReadLoop","detail":"{readStateIndex:27496; appliedIndex:27494; }","duration":"121.974082ms","start":"2026-10-07T06:36:54.730677Z","end":"2026-10-07T06:36:54.852651Z","steps":["trace[412921641] 'read index received' (duration: 121.764226ms)","trace[412921641] 'applied index is now lower than readState.Index' (duration: 208.856µs)"],"step_count":2} {"level":"info","ts":"2026-10-07T06:36:54.852981Z","caller":"traceutil/trace.go:171","msg":"trace[2120646704] transaction","detail":"{read_only:false; response_revision:26609; number_of_response:1; }","duration":"154.098716ms","start":"2026-10-07T06:36:54.698868Z","end":"2026-10-07T06:36:54.852967Z","steps":["trace[2120646704] 'process raft request' (duration: 153.622503ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:36:54.853215Z","caller":"traceutil/trace.go:171","msg":"trace[1984051532] transaction","detail":"{read_only:false; response_revision:26610; number_of_response:1; }","duration":"153.235501ms","start":"2026-10-07T06:36:54.699968Z","end":"2026-10-07T06:36:54.853203Z","steps":["trace[1984051532] 'process raft request' (duration: 152.641285ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T06:36:54.853426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.715762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T06:36:54.853519Z","caller":"traceutil/trace.go:171","msg":"trace[315601130] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:26610; }","duration":"122.876717ms","start":"2026-10-07T06:36:54.730618Z","end":"2026-10-07T06:36:54.853495Z","steps":["trace[315601130] 'agreement among raft nodes before linearized reading' (duration: 122.709593ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T06:36:55.010645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.920451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T06:36:55.010753Z","caller":"traceutil/trace.go:171","msg":"trace[1166321056] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:26610; }","duration":"140.092896ms","start":"2026-10-07T06:36:54.870636Z","end":"2026-10-07T06:36:55.010729Z","steps":["trace[1166321056] 'range keys from in-memory index tree' (duration: 139.797597ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:36:55.168011Z","caller":"traceutil/trace.go:171","msg":"trace[33122742] transaction","detail":"{read_only:false; response_revision:26611; number_of_response:1; }","duration":"213.802556ms","start":"2026-10-07T06:36:54.954187Z","end":"2026-10-07T06:36:55.16799Z","steps":["trace[33122742] 'process raft request' (duration: 213.61188ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:36:55.189101Z","caller":"traceutil/trace.go:171","msg":"trace[1499801525] linearizableReadLoop","detail":"{readStateIndex:27499; appliedIndex:27496; }","duration":"169.232126ms","start":"2026-10-07T06:36:55.019855Z","end":"2026-10-07T06:36:55.189088Z","steps":["trace[1499801525] 'read index received' (duration: 148.029237ms)","trace[1499801525] 'applied index is now lower than readState.Index' (duration: 21.201229ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T06:36:55.189077Z","caller":"traceutil/trace.go:171","msg":"trace[1154820038] transaction","detail":"{read_only:false; response_revision:26612; number_of_response:1; }","duration":"204.269441ms","start":"2026-10-07T06:36:54.984734Z","end":"2026-10-07T06:36:55.189003Z","steps":["trace[1154820038] 'process raft request' (duration: 203.945272ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T06:36:55.189206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.407871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T06:36:55.189235Z","caller":"traceutil/trace.go:171","msg":"trace[1697929183] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:26613; }","duration":"169.644938ms","start":"2026-10-07T06:36:55.019579Z","end":"2026-10-07T06:36:55.189224Z","steps":["trace[1697929183] 'agreement among raft nodes before linearized reading' (duration: 169.547125ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:36:55.189308Z","caller":"traceutil/trace.go:171","msg":"trace[363113206] transaction","detail":"{read_only:false; response_revision:26613; number_of_response:1; }","duration":"173.396332ms","start":"2026-10-07T06:36:55.015761Z","end":"2026-10-07T06:36:55.189157Z","steps":["trace[363113206] 'process raft request' (duration: 173.185676ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T06:36:55.69253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.271303ms","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-10-07T06:36:55.692735Z","caller":"traceutil/trace.go:171","msg":"trace[198305239] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26614; }","duration":"139.51687ms","start":"2026-10-07T06:36:55.553189Z","end":"2026-10-07T06:36:55.692706Z","steps":["trace[198305239] 'count revisions from in-memory index tree' (duration: 139.112698ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:36:55.925253Z","caller":"traceutil/trace.go:171","msg":"trace[860533631] linearizableReadLoop","detail":"{readStateIndex:27503; appliedIndex:27502; }","duration":"116.89725ms","start":"2026-10-07T06:36:55.808326Z","end":"2026-10-07T06:36:55.925223Z","steps":["trace[860533631] 'read index received' (duration: 64.598586ms)","trace[860533631] 'applied index is now lower than readState.Index' (duration: 52.296944ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T06:36:55.925259Z","caller":"traceutil/trace.go:171","msg":"trace[1978553715] transaction","detail":"{read_only:false; response_revision:26616; number_of_response:1; }","duration":"177.912637ms","start":"2026-10-07T06:36:55.747309Z","end":"2026-10-07T06:36:55.925221Z","steps":["trace[1978553715] 'process raft request' (duration: 125.601933ms)","trace[1978553715] 'compare' (duration: 52.096378ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T06:36:55.925441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.082765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T06:36:55.925512Z","caller":"traceutil/trace.go:171","msg":"trace[1536365542] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:26616; }","duration":"117.174148ms","start":"2026-10-07T06:36:55.80832Z","end":"2026-10-07T06:36:55.925494Z","steps":["trace[1536365542] 'agreement among raft nodes before linearized reading' (duration: 116.976952ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T06:37:06.574965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.627574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T06:37:06.57505Z","caller":"traceutil/trace.go:171","msg":"trace[1548994163] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26692; }","duration":"102.751297ms","start":"2026-10-07T06:37:06.472278Z","end":"2026-10-07T06:37:06.575029Z","steps":["trace[1548994163] 'range keys from in-memory index tree' (duration: 102.555002ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T06:37:06.575123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.887331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1936"} {"level":"info","ts":"2026-10-07T06:37:06.575286Z","caller":"traceutil/trace.go:171","msg":"trace[1214494014] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:26692; }","duration":"162.084727ms","start":"2026-10-07T06:37:06.413132Z","end":"2026-10-07T06:37:06.575217Z","steps":["trace[1214494014] 'range keys from in-memory index tree' (duration: 161.695346ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:37:09.926394Z","caller":"traceutil/trace.go:171","msg":"trace[1723495480] transaction","detail":"{read_only:false; response_revision:26774; number_of_response:1; }","duration":"180.073338ms","start":"2026-10-07T06:37:09.746291Z","end":"2026-10-07T06:37:09.926365Z","steps":["trace[1723495480] 'process raft request' (duration: 179.899823ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:37:10.209157Z","caller":"traceutil/trace.go:171","msg":"trace[231040751] transaction","detail":"{read_only:false; response_revision:26775; number_of_response:1; }","duration":"432.842427ms","start":"2026-10-07T06:37:09.776286Z","end":"2026-10-07T06:37:10.209129Z","steps":["trace[231040751] 'process raft request' (duration: 432.637901ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T06:37:10.209322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T06:37:09.776257Z","time spent":"432.973419ms","remote":"127.0.0.1:54696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-07T06:37:10.23102Z","caller":"traceutil/trace.go:171","msg":"trace[2029028434] transaction","detail":"{read_only:false; response_revision:26776; number_of_response:1; }","duration":"214.555736ms","start":"2026-10-07T06:37:10.016449Z","end":"2026-10-07T06:37:10.231005Z","steps":["trace[2029028434] 'process raft request' (duration: 212.950171ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:37:10.231465Z","caller":"traceutil/trace.go:171","msg":"trace[1331293980] transaction","detail":"{read_only:false; response_revision:26777; number_of_response:1; }","duration":"129.340577ms","start":"2026-10-07T06:37:10.102093Z","end":"2026-10-07T06:37:10.231433Z","steps":["trace[1331293980] 'process raft request' (duration: 128.862753ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T06:37:10.571171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.331899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-10-07T06:37:10.571252Z","caller":"traceutil/trace.go:171","msg":"trace[1324025423] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:26777; }","duration":"233.463702ms","start":"2026-10-07T06:37:10.33777Z","end":"2026-10-07T06:37:10.571233Z","steps":["trace[1324025423] 'range keys from in-memory index tree' (duration: 233.117903ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T06:37:10.571892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.742358ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-07T06:37:10.57204Z","caller":"traceutil/trace.go:171","msg":"trace[1542570777] transaction","detail":"{read_only:false; response_revision:26778; number_of_response:1; }","duration":"192.453232ms","start":"2026-10-07T06:37:10.37957Z","end":"2026-10-07T06:37:10.572023Z","steps":["trace[1542570777] 'process raft request' (duration: 30.450457ms)","trace[1542570777] 'compare' (duration: 161.45599ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T06:37:10.572254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.108914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T06:37:10.572405Z","caller":"traceutil/trace.go:171","msg":"trace[214516490] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26778; }","duration":"100.254948ms","start":"2026-10-07T06:37:10.472095Z","end":"2026-10-07T06:37:10.57235Z","steps":["trace[214516490] 'agreement among raft nodes before linearized reading' (duration: 100.042722ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:37:10.889268Z","caller":"traceutil/trace.go:171","msg":"trace[457194391] transaction","detail":"{read_only:false; response_revision:26779; number_of_response:1; }","duration":"154.189128ms","start":"2026-10-07T06:37:10.735039Z","end":"2026-10-07T06:37:10.889228Z","steps":["trace[457194391] 'process raft request' (duration: 153.817988ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:37:10.95133Z","caller":"traceutil/trace.go:171","msg":"trace[740569476] linearizableReadLoop","detail":"{readStateIndex:27670; appliedIndex:27668; }","duration":"164.056812ms","start":"2026-10-07T06:37:10.787237Z","end":"2026-10-07T06:37:10.951294Z","steps":["trace[740569476] 'read index received' (duration: 101.834952ms)","trace[740569476] 'applied index is now lower than readState.Index' (duration: 62.22021ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T06:37:10.951487Z","caller":"traceutil/trace.go:171","msg":"trace[1182202062] transaction","detail":"{read_only:false; response_revision:26780; number_of_response:1; }","duration":"214.098204ms","start":"2026-10-07T06:37:10.737327Z","end":"2026-10-07T06:37:10.951425Z","steps":["trace[1182202062] 'process raft request' (duration: 213.743614ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T06:37:10.951645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.393641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-07T06:37:10.9517Z","caller":"traceutil/trace.go:171","msg":"trace[1687732713] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26780; }","duration":"164.507495ms","start":"2026-10-07T06:37:10.787175Z","end":"2026-10-07T06:37:10.951682Z","steps":["trace[1687732713] 'agreement among raft nodes before linearized reading' (duration: 164.30315ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T06:37:10.951715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.33383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T06:37:10.951798Z","caller":"traceutil/trace.go:171","msg":"trace[1113776708] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:26780; }","duration":"123.462564ms","start":"2026-10-07T06:37:10.828313Z","end":"2026-10-07T06:37:10.951776Z","steps":["trace[1113776708] 'agreement among raft nodes before linearized reading' (duration: 123.30626ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T06:37:10.952145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.045878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-07T06:37:10.952205Z","caller":"traceutil/trace.go:171","msg":"trace[2009951369] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26780; }","duration":"102.12636ms","start":"2026-10-07T06:37:10.850063Z","end":"2026-10-07T06:37:10.95219Z","steps":["trace[2009951369] 'agreement among raft nodes before linearized reading' (duration: 101.977456ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:37:11.166203Z","caller":"traceutil/trace.go:171","msg":"trace[2076406139] transaction","detail":"{read_only:false; response_revision:26782; number_of_response:1; }","duration":"208.396966ms","start":"2026-10-07T06:37:10.957768Z","end":"2026-10-07T06:37:11.166164Z","steps":["trace[2076406139] 'process raft request' (duration: 143.647975ms)","trace[2076406139] 'compare' (duration: 64.34945ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T06:37:11.166493Z","caller":"traceutil/trace.go:171","msg":"trace[1946425828] transaction","detail":"{read_only:false; response_revision:26784; number_of_response:1; }","duration":"207.244733ms","start":"2026-10-07T06:37:10.959221Z","end":"2026-10-07T06:37:11.166466Z","steps":["trace[1946425828] 'process raft request' (duration: 207.15042ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:37:11.166728Z","caller":"traceutil/trace.go:171","msg":"trace[1742648777] transaction","detail":"{read_only:false; response_revision:26783; number_of_response:1; }","duration":"208.707284ms","start":"2026-10-07T06:37:10.95799Z","end":"2026-10-07T06:37:11.166697Z","steps":["trace[1742648777] 'process raft request' (duration: 208.054976ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T06:37:11.651377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.52736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-10-07T06:37:11.651481Z","caller":"traceutil/trace.go:171","msg":"trace[865433678] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0; range_end:; response_count:1; response_revision:26788; }","duration":"235.672354ms","start":"2026-10-07T06:37:11.415769Z","end":"2026-10-07T06:37:11.651441Z","steps":["trace[865433678] 'range keys from in-memory index tree' (duration: 235.294863ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:37:11.651952Z","caller":"traceutil/trace.go:171","msg":"trace[775300509] transaction","detail":"{read_only:false; response_revision:26789; number_of_response:1; }","duration":"208.787267ms","start":"2026-10-07T06:37:11.443112Z","end":"2026-10-07T06:37:11.651899Z","steps":["trace[775300509] 'process raft request' (duration: 184.339567ms)","trace[775300509] 'compare' (duration: 24.08787ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T06:37:12.091942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.429767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T06:37:12.09205Z","caller":"traceutil/trace.go:171","msg":"trace[2035340231] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:26789; }","duration":"162.604512ms","start":"2026-10-07T06:37:11.929416Z","end":"2026-10-07T06:37:12.092021Z","steps":["trace[2035340231] 'count revisions from in-memory index tree' (duration: 162.337285ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:37:12.598356Z","caller":"traceutil/trace.go:171","msg":"trace[71960406] transaction","detail":"{read_only:false; response_revision:26791; number_of_response:1; }","duration":"362.101419ms","start":"2026-10-07T06:37:12.236203Z","end":"2026-10-07T06:37:12.598304Z","steps":["trace[71960406] 'process raft request' (duration: 337.29733ms)","trace[71960406] 'compare' (duration: 24.241164ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T06:37:12.598367Z","caller":"traceutil/trace.go:171","msg":"trace[1872147493] linearizableReadLoop","detail":"{readStateIndex:27682; appliedIndex:27681; }","duration":"124.256005ms","start":"2026-10-07T06:37:12.474072Z","end":"2026-10-07T06:37:12.598328Z","steps":["trace[1872147493] 'read index received' (duration: 99.473086ms)","trace[1872147493] 'applied index is now lower than readState.Index' (duration: 24.779739ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T06:37:12.59854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T06:37:12.23618Z","time spent":"362.264264ms","remote":"127.0.0.1:54696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-07T06:37:12.598595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.502282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T06:37:12.598649Z","caller":"traceutil/trace.go:171","msg":"trace[1557398638] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26792; }","duration":"124.614926ms","start":"2026-10-07T06:37:12.474018Z","end":"2026-10-07T06:37:12.598633Z","steps":["trace[1557398638] 'agreement among raft nodes before linearized reading' (duration: 124.463851ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T06:37:12.947758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.025431ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-07T06:37:12.948152Z","caller":"traceutil/trace.go:171","msg":"trace[1791809323] linearizableReadLoop","detail":"{readStateIndex:27685; appliedIndex:27684; }","duration":"282.742283ms","start":"2026-10-07T06:37:12.665387Z","end":"2026-10-07T06:37:12.94813Z","steps":["trace[1791809323] 'read index received' (duration: 159.28994ms)","trace[1791809323] 'applied index is now lower than readState.Index' (duration: 123.450173ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T06:37:12.948391Z","caller":"traceutil/trace.go:171","msg":"trace[738541779] transaction","detail":"{read_only:false; response_revision:26794; number_of_response:1; }","duration":"330.09841ms","start":"2026-10-07T06:37:12.618048Z","end":"2026-10-07T06:37:12.948146Z","steps":["trace[738541779] 'process raft request' (duration: 206.550414ms)","trace[738541779] 'compare' (duration: 122.883318ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T06:37:12.948615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T06:37:12.618016Z","time spent":"330.479881ms","remote":"127.0.0.1:54696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-07T06:37:12.948446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.049841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b1436944-4026-46b9-a1d1-82793dee604c\" ","response":"range_response_count:1 size:3760"} {"level":"info","ts":"2026-10-07T06:37:12.94877Z","caller":"traceutil/trace.go:171","msg":"trace[1297278624] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b1436944-4026-46b9-a1d1-82793dee604c; range_end:; response_count:1; response_revision:26794; }","duration":"283.397111ms","start":"2026-10-07T06:37:12.665355Z","end":"2026-10-07T06:37:12.948752Z","steps":["trace[1297278624] 'agreement among raft nodes before linearized reading' (duration: 282.950369ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:37:13.00871Z","caller":"traceutil/trace.go:171","msg":"trace[1919513170] transaction","detail":"{read_only:false; response_revision:26795; number_of_response:1; }","duration":"226.860899ms","start":"2026-10-07T06:37:12.781809Z","end":"2026-10-07T06:37:13.00867Z","steps":["trace[1919513170] 'process raft request' (duration: 226.622883ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:37:13.119806Z","caller":"traceutil/trace.go:171","msg":"trace[561900799] linearizableReadLoop","detail":"{readStateIndex:27687; appliedIndex:27685; }","duration":"171.512489ms","start":"2026-10-07T06:37:12.94827Z","end":"2026-10-07T06:37:13.119783Z","steps":["trace[561900799] 'read index received' (duration: 60.117301ms)","trace[561900799] 'applied index is now lower than readState.Index' (duration: 111.394008ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T06:37:13.119954Z","caller":"traceutil/trace.go:171","msg":"trace[1394685960] transaction","detail":"{read_only:false; response_revision:26797; number_of_response:1; }","duration":"160.919945ms","start":"2026-10-07T06:37:12.959023Z","end":"2026-10-07T06:37:13.119943Z","steps":["trace[1394685960] 'process raft request' (duration: 160.713189ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:37:13.119909Z","caller":"traceutil/trace.go:171","msg":"trace[1590406110] transaction","detail":"{read_only:false; response_revision:26796; number_of_response:1; }","duration":"224.642297ms","start":"2026-10-07T06:37:12.895201Z","end":"2026-10-07T06:37:13.119843Z","steps":["trace[1590406110] 'process raft request' (duration: 224.371379ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T06:37:13.120232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.868103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/domains/\" range_end:\"/registry/openstack.k-orc.cloud/domains0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T06:37:13.120335Z","caller":"traceutil/trace.go:171","msg":"trace[1009933992] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/domains/; range_end:/registry/openstack.k-orc.cloud/domains0; response_count:0; response_revision:26797; }","duration":"362.064179ms","start":"2026-10-07T06:37:12.758239Z","end":"2026-10-07T06:37:13.120304Z","steps":["trace[1009933992] 'agreement among raft nodes before linearized reading' (duration: 361.865083ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T06:37:13.120317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.728381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-10-07T06:37:13.120406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T06:37:12.758187Z","time spent":"362.205543ms","remote":"127.0.0.1:52612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/domains/\" range_end:\"/registry/openstack.k-orc.cloud/domains0\" count_only:true "} {"level":"info","ts":"2026-10-07T06:37:13.120419Z","caller":"traceutil/trace.go:171","msg":"trace[257981325] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26797; }","duration":"141.881875ms","start":"2026-10-07T06:37:12.97851Z","end":"2026-10-07T06:37:13.120392Z","steps":["trace[257981325] 'agreement among raft nodes before linearized reading' (duration: 141.560736ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:37:13.392187Z","caller":"traceutil/trace.go:171","msg":"trace[1125717896] transaction","detail":"{read_only:false; response_revision:26799; number_of_response:1; }","duration":"203.003476ms","start":"2026-10-07T06:37:13.189145Z","end":"2026-10-07T06:37:13.392149Z","steps":["trace[1125717896] 'process raft request' (duration: 202.755619ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:37:13.463203Z","caller":"traceutil/trace.go:171","msg":"trace[2139023307] transaction","detail":"{read_only:false; response_revision:26800; number_of_response:1; }","duration":"269.231657ms","start":"2026-10-07T06:37:13.193936Z","end":"2026-10-07T06:37:13.463168Z","steps":["trace[2139023307] 'process raft request' (duration: 268.931259ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:37:13.463673Z","caller":"traceutil/trace.go:171","msg":"trace[86297911] linearizableReadLoop","detail":"{readStateIndex:27693; appliedIndex:27689; }","duration":"262.288434ms","start":"2026-10-07T06:37:13.201369Z","end":"2026-10-07T06:37:13.463657Z","steps":["trace[86297911] 'read index received' (duration: 190.383184ms)","trace[86297911] 'applied index is now lower than readState.Index' (duration: 71.90384ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T06:37:13.463751Z","caller":"traceutil/trace.go:171","msg":"trace[1865262397] transaction","detail":"{read_only:false; response_revision:26802; number_of_response:1; }","duration":"269.725231ms","start":"2026-10-07T06:37:13.19399Z","end":"2026-10-07T06:37:13.463715Z","steps":["trace[1865262397] 'process raft request' (duration: 269.508565ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:37:13.463887Z","caller":"traceutil/trace.go:171","msg":"trace[147003181] transaction","detail":"{read_only:false; response_revision:26801; number_of_response:1; }","duration":"269.910656ms","start":"2026-10-07T06:37:13.193959Z","end":"2026-10-07T06:37:13.46387Z","steps":["trace[147003181] 'process raft request' (duration: 269.136714ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:37:13.46377Z","caller":"traceutil/trace.go:171","msg":"trace[811662002] transaction","detail":"{read_only:false; response_revision:26803; number_of_response:1; }","duration":"151.526214ms","start":"2026-10-07T06:37:13.312207Z","end":"2026-10-07T06:37:13.463733Z","steps":["trace[811662002] 'process raft request' (duration: 151.346329ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T06:37:13.464044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.646794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T06:37:13.463778Z","caller":"traceutil/trace.go:171","msg":"trace[1959116667] transaction","detail":"{read_only:false; response_revision:26804; number_of_response:1; }","duration":"151.269276ms","start":"2026-10-07T06:37:13.312469Z","end":"2026-10-07T06:37:13.463738Z","steps":["trace[1959116667] 'process raft request' (duration: 151.139863ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:37:13.464131Z","caller":"traceutil/trace.go:171","msg":"trace[1157671851] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:26804; }","duration":"262.779908ms","start":"2026-10-07T06:37:13.201329Z","end":"2026-10-07T06:37:13.464109Z","steps":["trace[1157671851] 'agreement among raft nodes before linearized reading' (duration: 262.620713ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T06:37:13.464495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.743906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3309"} {"level":"info","ts":"2026-10-07T06:37:13.464561Z","caller":"traceutil/trace.go:171","msg":"trace[1758188108] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:26804; }","duration":"244.828539ms","start":"2026-10-07T06:37:13.219716Z","end":"2026-10-07T06:37:13.464544Z","steps":["trace[1758188108] 'agreement among raft nodes before linearized reading' (duration: 244.557071ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T06:37:13.464843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.002801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-07T06:37:13.464899Z","caller":"traceutil/trace.go:171","msg":"trace[930011636] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26804; }","duration":"132.196936ms","start":"2026-10-07T06:37:13.332687Z","end":"2026-10-07T06:37:13.464884Z","steps":["trace[930011636] 'agreement among raft nodes before linearized reading' (duration: 131.646001ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T06:37:19.331431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.461507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T06:37:19.331542Z","caller":"traceutil/trace.go:171","msg":"trace[916695653] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26866; }","duration":"231.608761ms","start":"2026-10-07T06:37:19.099908Z","end":"2026-10-07T06:37:19.331517Z","steps":["trace[916695653] 'range keys from in-memory index tree' (duration: 231.343143ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:37:19.332167Z","caller":"traceutil/trace.go:171","msg":"trace[751342047] transaction","detail":"{read_only:false; response_revision:26867; number_of_response:1; }","duration":"136.128686ms","start":"2026-10-07T06:37:19.195998Z","end":"2026-10-07T06:37:19.332126Z","steps":["trace[751342047] 'process raft request' (duration: 59.222247ms)","trace[751342047] 'compare' (duration: 76.717854ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T06:37:19.336419Z","caller":"traceutil/trace.go:171","msg":"trace[1071025203] transaction","detail":"{read_only:false; response_revision:26868; number_of_response:1; }","duration":"126.663862ms","start":"2026-10-07T06:37:19.209722Z","end":"2026-10-07T06:37:19.336386Z","steps":["trace[1071025203] 'process raft request' (duration: 126.457547ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:37:19.336308Z","caller":"traceutil/trace.go:171","msg":"trace[1819887922] linearizableReadLoop","detail":"{readStateIndex:27760; appliedIndex:27758; }","duration":"118.005762ms","start":"2026-10-07T06:37:19.218282Z","end":"2026-10-07T06:37:19.336288Z","steps":["trace[1819887922] 'read index received' (duration: 36.974698ms)","trace[1819887922] 'applied index is now lower than readState.Index' (duration: 81.029694ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T06:37:19.336718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.416443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-10-07T06:37:19.336817Z","caller":"traceutil/trace.go:171","msg":"trace[176538420] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26868; }","duration":"118.530946ms","start":"2026-10-07T06:37:19.21826Z","end":"2026-10-07T06:37:19.336791Z","steps":["trace[176538420] 'agreement among raft nodes before linearized reading' (duration: 118.215327ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T06:37:19.659198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.29382ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-07T06:37:19.659349Z","caller":"traceutil/trace.go:171","msg":"trace[480316184] linearizableReadLoop","detail":"{readStateIndex:27761; appliedIndex:27760; }","duration":"317.123389ms","start":"2026-10-07T06:37:19.342201Z","end":"2026-10-07T06:37:19.659324Z","steps":["trace[480316184] 'read index received' (duration: 139.605123ms)","trace[480316184] 'applied index is now lower than readState.Index' (duration: 177.516016ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T06:37:19.659452Z","caller":"traceutil/trace.go:171","msg":"trace[2052040796] transaction","detail":"{read_only:false; response_revision:26869; number_of_response:1; }","duration":"318.006603ms","start":"2026-10-07T06:37:19.341431Z","end":"2026-10-07T06:37:19.659438Z","steps":["trace[2052040796] 'process raft request' (duration: 140.305272ms)","trace[2052040796] 'compare' (duration: 177.031643ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T06:37:19.659525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T06:37:19.341407Z","time spent":"318.082516ms","remote":"127.0.0.1:54696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-07T06:37:19.659764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.544721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19230"} {"level":"info","ts":"2026-10-07T06:37:19.659863Z","caller":"traceutil/trace.go:171","msg":"trace[919867373] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:26869; }","duration":"317.653654ms","start":"2026-10-07T06:37:19.342166Z","end":"2026-10-07T06:37:19.65982Z","steps":["trace[919867373] 'agreement among raft nodes before linearized reading' (duration: 317.414827ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T06:37:19.659912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T06:37:19.342153Z","time spent":"317.746756ms","remote":"127.0.0.1:54604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":19253,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-10-07T06:37:19.681339Z","caller":"traceutil/trace.go:171","msg":"trace[1514627294] transaction","detail":"{read_only:false; response_revision:26870; number_of_response:1; }","duration":"189.079529ms","start":"2026-10-07T06:37:19.492231Z","end":"2026-10-07T06:37:19.68131Z","steps":["trace[1514627294] 'process raft request' (duration: 188.906464ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:37:19.69893Z","caller":"traceutil/trace.go:171","msg":"trace[37402038] transaction","detail":"{read_only:false; response_revision:26871; number_of_response:1; }","duration":"206.210294ms","start":"2026-10-07T06:37:19.492682Z","end":"2026-10-07T06:37:19.698892Z","steps":["trace[37402038] 'process raft request' (duration: 205.821334ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T06:37:19.699066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.091327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-07T06:37:19.699122Z","caller":"traceutil/trace.go:171","msg":"trace[1852361406] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26871; }","duration":"194.157279ms","start":"2026-10-07T06:37:19.504946Z","end":"2026-10-07T06:37:19.699104Z","steps":["trace[1852361406] 'agreement among raft nodes before linearized reading' (duration: 194.056657ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T06:37:19.699288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.789177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-07T06:37:19.69938Z","caller":"traceutil/trace.go:171","msg":"trace[1245986665] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26871; }","duration":"208.927831ms","start":"2026-10-07T06:37:19.490438Z","end":"2026-10-07T06:37:19.699365Z","steps":["trace[1245986665] 'agreement among raft nodes before linearized reading' (duration: 208.590751ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T06:37:19.698958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.800462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-10-07T06:37:19.699515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.581366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-07T06:37:19.699574Z","caller":"traceutil/trace.go:171","msg":"trace[676919626] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26871; }","duration":"210.694889ms","start":"2026-10-07T06:37:19.488863Z","end":"2026-10-07T06:37:19.699557Z","steps":["trace[676919626] 'agreement among raft nodes before linearized reading' (duration: 210.538785ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:37:19.699665Z","caller":"traceutil/trace.go:171","msg":"trace[507076339] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26871; }","duration":"202.47086ms","start":"2026-10-07T06:37:19.497082Z","end":"2026-10-07T06:37:19.699553Z","steps":["trace[507076339] 'agreement among raft nodes before linearized reading' (duration: 201.685718ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T06:37:27.846907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.882167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-07T06:37:27.846996Z","caller":"traceutil/trace.go:171","msg":"trace[1108318194] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26935; }","duration":"146.041862ms","start":"2026-10-07T06:37:27.700936Z","end":"2026-10-07T06:37:27.846977Z","steps":["trace[1108318194] 'range keys from in-memory index tree' (duration: 145.660621ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:37:27.847761Z","caller":"traceutil/trace.go:171","msg":"trace[1014657212] transaction","detail":"{read_only:false; response_revision:26936; number_of_response:1; }","duration":"135.085556ms","start":"2026-10-07T06:37:27.712663Z","end":"2026-10-07T06:37:27.847749Z","steps":["trace[1014657212] 'process raft request' (duration: 132.419302ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:37:28.05248Z","caller":"traceutil/trace.go:171","msg":"trace[144289551] transaction","detail":"{read_only:false; response_revision:26937; number_of_response:1; }","duration":"322.524628ms","start":"2026-10-07T06:37:27.729907Z","end":"2026-10-07T06:37:28.052431Z","steps":["trace[144289551] 'process raft request' (duration: 266.432468ms)","trace[144289551] 'compare' (duration: 55.892665ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T06:37:28.052668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T06:37:27.729893Z","time spent":"322.674462ms","remote":"127.0.0.1:54696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-07T06:37:28.052864Z","caller":"traceutil/trace.go:171","msg":"trace[699014539] linearizableReadLoop","detail":"{readStateIndex:27832; appliedIndex:27829; }","duration":"267.078866ms","start":"2026-10-07T06:37:27.78573Z","end":"2026-10-07T06:37:28.052809Z","steps":["trace[699014539] 'read index received' (duration: 59.36955ms)","trace[699014539] 'applied index is now lower than readState.Index' (duration: 207.705836ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T06:37:28.053021Z","caller":"traceutil/trace.go:171","msg":"trace[2140965841] transaction","detail":"{read_only:false; response_revision:26939; number_of_response:1; }","duration":"201.276586ms","start":"2026-10-07T06:37:27.851716Z","end":"2026-10-07T06:37:28.052993Z","steps":["trace[2140965841] 'process raft request' (duration: 200.982758ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:37:28.05304Z","caller":"traceutil/trace.go:171","msg":"trace[1812203330] transaction","detail":"{read_only:false; response_revision:26938; number_of_response:1; }","duration":"322.858567ms","start":"2026-10-07T06:37:27.730151Z","end":"2026-10-07T06:37:28.053009Z","steps":["trace[1812203330] 'process raft request' (duration: 322.217379ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T06:37:28.053251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.525639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-10-07T06:37:28.053272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.25221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-10-07T06:37:28.053257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T06:37:27.73014Z","time spent":"322.995281ms","remote":"127.0.0.1:54696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-07T06:37:28.053294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.695516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-07T06:37:28.05331Z","caller":"traceutil/trace.go:171","msg":"trace[2108193261] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26941; }","duration":"267.624491ms","start":"2026-10-07T06:37:27.785669Z","end":"2026-10-07T06:37:28.053293Z","steps":["trace[2108193261] 'agreement among raft nodes before linearized reading' (duration: 267.482097ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:37:28.053319Z","caller":"traceutil/trace.go:171","msg":"trace[1255723672] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26941; }","duration":"263.330902ms","start":"2026-10-07T06:37:27.789975Z","end":"2026-10-07T06:37:28.053305Z","steps":["trace[1255723672] 'agreement among raft nodes before linearized reading' (duration: 263.179647ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:37:28.053337Z","caller":"traceutil/trace.go:171","msg":"trace[935045581] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:26941; }","duration":"229.802689ms","start":"2026-10-07T06:37:27.823521Z","end":"2026-10-07T06:37:28.053324Z","steps":["trace[935045581] 'agreement among raft nodes before linearized reading' (duration: 229.701166ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T06:37:28.053388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.760006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-07T06:37:28.053431Z","caller":"traceutil/trace.go:171","msg":"trace[690099050] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26941; }","duration":"257.826349ms","start":"2026-10-07T06:37:27.795594Z","end":"2026-10-07T06:37:28.05342Z","steps":["trace[690099050] 'agreement among raft nodes before linearized reading' (duration: 257.631983ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T06:37:28.053414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.715168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-10-07T06:37:28.053449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.979366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-07T06:37:28.053475Z","caller":"traceutil/trace.go:171","msg":"trace[872574210] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26941; }","duration":"261.057589ms","start":"2026-10-07T06:37:27.792409Z","end":"2026-10-07T06:37:28.053467Z","steps":["trace[872574210] 'agreement among raft nodes before linearized reading' (duration: 260.982657ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:37:28.053478Z","caller":"traceutil/trace.go:171","msg":"trace[1018913514] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:26941; }","duration":"133.836181ms","start":"2026-10-07T06:37:27.919626Z","end":"2026-10-07T06:37:28.053463Z","steps":["trace[1018913514] 'agreement among raft nodes before linearized reading' (duration: 133.665206ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T06:37:28.34171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.33535ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-07T06:37:28.341873Z","caller":"traceutil/trace.go:171","msg":"trace[942718553] transaction","detail":"{read_only:false; response_revision:26942; number_of_response:1; }","duration":"284.421119ms","start":"2026-10-07T06:37:28.057395Z","end":"2026-10-07T06:37:28.341816Z","steps":["trace[942718553] 'process raft request' (duration: 137.875764ms)","trace[942718553] 'compare' (duration: 146.164564ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T06:37:28.343897Z","caller":"traceutil/trace.go:171","msg":"trace[1073849350] transaction","detail":"{read_only:false; response_revision:26943; number_of_response:1; }","duration":"284.952084ms","start":"2026-10-07T06:37:28.058917Z","end":"2026-10-07T06:37:28.34387Z","steps":["trace[1073849350] 'process raft request' (duration: 284.700757ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:37:28.343967Z","caller":"traceutil/trace.go:171","msg":"trace[2144920377] transaction","detail":"{read_only:false; response_revision:26945; number_of_response:1; }","duration":"284.335287ms","start":"2026-10-07T06:37:28.059624Z","end":"2026-10-07T06:37:28.343959Z","steps":["trace[2144920377] 'process raft request' (duration: 284.132942ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:37:28.343883Z","caller":"traceutil/trace.go:171","msg":"trace[1939388039] transaction","detail":"{read_only:false; response_revision:26944; number_of_response:1; }","duration":"284.832041ms","start":"2026-10-07T06:37:28.059014Z","end":"2026-10-07T06:37:28.343846Z","steps":["trace[1939388039] 'process raft request' (duration: 284.701558ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T06:37:33.695429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.731041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3309"} {"level":"info","ts":"2026-10-07T06:37:33.69555Z","caller":"traceutil/trace.go:171","msg":"trace[273039967] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:26978; }","duration":"155.919796ms","start":"2026-10-07T06:37:33.5396Z","end":"2026-10-07T06:37:33.69552Z","steps":["trace[273039967] 'range keys from in-memory index tree' (duration: 155.522255ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:37:34.048466Z","caller":"traceutil/trace.go:171","msg":"trace[344697971] transaction","detail":"{read_only:false; response_revision:26980; number_of_response:1; }","duration":"160.654408ms","start":"2026-10-07T06:37:33.887773Z","end":"2026-10-07T06:37:34.048427Z","steps":["trace[344697971] 'process raft request' (duration: 137.144344ms)","trace[344697971] 'compare' (duration: 23.273387ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T06:37:34.048715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.451456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T06:37:34.048891Z","caller":"traceutil/trace.go:171","msg":"trace[1799794282] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:26980; }","duration":"131.687112ms","start":"2026-10-07T06:37:33.917185Z","end":"2026-10-07T06:37:34.048872Z","steps":["trace[1799794282] 'agreement among raft nodes before linearized reading' (duration: 131.464506ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:37:34.048608Z","caller":"traceutil/trace.go:171","msg":"trace[829924422] linearizableReadLoop","detail":"{readStateIndex:27875; appliedIndex:27873; }","duration":"131.096886ms","start":"2026-10-07T06:37:33.917248Z","end":"2026-10-07T06:37:34.048345Z","steps":["trace[829924422] 'read index received' (duration: 32.396851ms)","trace[829924422] 'applied index is now lower than readState.Index' (duration: 98.698695ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T06:37:34.049465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.632057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs/\" range_end:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T06:37:34.049495Z","caller":"traceutil/trace.go:171","msg":"trace[1205044575] range","detail":"{range_begin:/registry/runtime.cluster.x-k8s.io/extensionconfigs/; range_end:/registry/runtime.cluster.x-k8s.io/extensionconfigs0; response_count:0; response_revision:26980; }","duration":"105.73202ms","start":"2026-10-07T06:37:33.943752Z","end":"2026-10-07T06:37:34.049484Z","steps":["trace[1205044575] 'agreement among raft nodes before linearized reading' (duration: 105.669359ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:37:34.24124Z","caller":"traceutil/trace.go:171","msg":"trace[1119982559] transaction","detail":"{read_only:false; response_revision:26981; number_of_response:1; }","duration":"167.878999ms","start":"2026-10-07T06:37:34.073338Z","end":"2026-10-07T06:37:34.241217Z","steps":["trace[1119982559] 'process raft request' (duration: 156.963845ms)","trace[1119982559] 'compare' (duration: 10.79291ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T06:37:34.245303Z","caller":"traceutil/trace.go:171","msg":"trace[1454009819] linearizableReadLoop","detail":"{readStateIndex:27877; appliedIndex:27875; }","duration":"165.891293ms","start":"2026-10-07T06:37:34.079392Z","end":"2026-10-07T06:37:34.245283Z","steps":["trace[1454009819] 'read index received' (duration: 150.912106ms)","trace[1454009819] 'applied index is now lower than readState.Index' (duration: 14.978247ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T06:37:34.245394Z","caller":"traceutil/trace.go:171","msg":"trace[1948312783] transaction","detail":"{read_only:false; response_revision:26982; number_of_response:1; }","duration":"169.047431ms","start":"2026-10-07T06:37:34.076336Z","end":"2026-10-07T06:37:34.245384Z","steps":["trace[1948312783] 'process raft request' (duration: 168.866686ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T06:37:34.245815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.386957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-10-07T06:37:34.245947Z","caller":"traceutil/trace.go:171","msg":"trace[778325212] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26982; }","duration":"166.553022ms","start":"2026-10-07T06:37:34.079369Z","end":"2026-10-07T06:37:34.245922Z","steps":["trace[778325212] 'agreement among raft nodes before linearized reading' (duration: 166.242743ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:37:34.842688Z","caller":"traceutil/trace.go:171","msg":"trace[77992515] transaction","detail":"{read_only:false; response_revision:26989; number_of_response:1; }","duration":"147.293417ms","start":"2026-10-07T06:37:34.695362Z","end":"2026-10-07T06:37:34.842655Z","steps":["trace[77992515] 'process raft request' (duration: 146.965277ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:37:34.864123Z","caller":"traceutil/trace.go:171","msg":"trace[710689965] transaction","detail":"{read_only:false; response_revision:26990; number_of_response:1; }","duration":"147.589965ms","start":"2026-10-07T06:37:34.716498Z","end":"2026-10-07T06:37:34.864088Z","steps":["trace[710689965] 'process raft request' (duration: 143.46126ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:37:41.661696Z","caller":"traceutil/trace.go:171","msg":"trace[315267751] transaction","detail":"{read_only:false; response_revision:27052; number_of_response:1; }","duration":"165.935224ms","start":"2026-10-07T06:37:41.495721Z","end":"2026-10-07T06:37:41.661656Z","steps":["trace[315267751] 'process raft request' (duration: 86.67665ms)","trace[315267751] 'compare' (duration: 78.929325ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T06:37:42.130376Z","caller":"traceutil/trace.go:171","msg":"trace[530346366] transaction","detail":"{read_only:false; response_revision:27053; number_of_response:1; }","duration":"127.818935ms","start":"2026-10-07T06:37:42.002528Z","end":"2026-10-07T06:37:42.130347Z","steps":["trace[530346366] 'process raft request' (duration: 127.6407ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:37:42.402866Z","caller":"traceutil/trace.go:171","msg":"trace[1641551855] linearizableReadLoop","detail":"{readStateIndex:27952; appliedIndex:27951; }","duration":"111.179612ms","start":"2026-10-07T06:37:42.291635Z","end":"2026-10-07T06:37:42.402815Z","steps":["trace[1641551855] 'read index received' (duration: 55.300928ms)","trace[1641551855] 'applied index is now lower than readState.Index' (duration: 55.876984ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T06:37:42.402991Z","caller":"traceutil/trace.go:171","msg":"trace[1091856589] transaction","detail":"{read_only:false; response_revision:27055; number_of_response:1; }","duration":"113.651431ms","start":"2026-10-07T06:37:42.289295Z","end":"2026-10-07T06:37:42.402947Z","steps":["trace[1091856589] 'process raft request' (duration: 57.745116ms)","trace[1091856589] 'compare' (duration: 55.589166ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T06:37:42.403172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.47628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T06:37:42.403303Z","caller":"traceutil/trace.go:171","msg":"trace[1346468979] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters0; response_count:0; response_revision:27055; }","duration":"111.656215ms","start":"2026-10-07T06:37:42.291615Z","end":"2026-10-07T06:37:42.403271Z","steps":["trace[1346468979] 'agreement among raft nodes before linearized reading' (duration: 111.364067ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T06:37:42.458756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.167852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T06:37:42.45887Z","caller":"traceutil/trace.go:171","msg":"trace[378101658] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:27056; }","duration":"111.319525ms","start":"2026-10-07T06:37:42.347526Z","end":"2026-10-07T06:37:42.458846Z","steps":["trace[378101658] 'agreement among raft nodes before linearized reading' (duration: 111.087619ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T06:37:42.45877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.666666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-07T06:37:42.459095Z","caller":"traceutil/trace.go:171","msg":"trace[424791625] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:27056; }","duration":"162.017066ms","start":"2026-10-07T06:37:42.297008Z","end":"2026-10-07T06:37:42.459025Z","steps":["trace[424791625] 'agreement among raft nodes before linearized reading' (duration: 161.602245ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:37:43.195214Z","caller":"traceutil/trace.go:171","msg":"trace[1713625639] transaction","detail":"{read_only:false; response_revision:27065; number_of_response:1; }","duration":"135.22717ms","start":"2026-10-07T06:37:43.05996Z","end":"2026-10-07T06:37:43.195187Z","steps":["trace[1713625639] 'process raft request' (duration: 135.067726ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:37:43.202689Z","caller":"traceutil/trace.go:171","msg":"trace[1470578196] transaction","detail":"{read_only:false; response_revision:27066; number_of_response:1; }","duration":"137.669898ms","start":"2026-10-07T06:37:43.064968Z","end":"2026-10-07T06:37:43.202638Z","steps":["trace[1470578196] 'process raft request' (duration: 137.442482ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T06:37:49.784357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.121247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/\" range_end:\"/registry/monitoring.coreos.com/prometheuses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-07T06:37:49.784455Z","caller":"traceutil/trace.go:171","msg":"trace[925794013] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/; range_end:/registry/monitoring.coreos.com/prometheuses0; response_count:0; response_revision:27109; }","duration":"126.278742ms","start":"2026-10-07T06:37:49.658153Z","end":"2026-10-07T06:37:49.784432Z","steps":["trace[925794013] 'count revisions from in-memory index tree' (duration: 125.928672ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:37:55.95746Z","caller":"traceutil/trace.go:171","msg":"trace[1880757606] linearizableReadLoop","detail":"{readStateIndex:28054; appliedIndex:28053; }","duration":"129.542143ms","start":"2026-10-07T06:37:55.827877Z","end":"2026-10-07T06:37:55.957419Z","steps":["trace[1880757606] 'read index received' (duration: 50.429293ms)","trace[1880757606] 'applied index is now lower than readState.Index' (duration: 79.11144ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T06:37:55.957654Z","caller":"traceutil/trace.go:171","msg":"trace[944539448] transaction","detail":"{read_only:false; response_revision:27154; number_of_response:1; }","duration":"145.593969ms","start":"2026-10-07T06:37:55.812008Z","end":"2026-10-07T06:37:55.957602Z","steps":["trace[944539448] 'process raft request' (duration: 66.316364ms)","trace[944539448] 'compare' (duration: 78.916795ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T06:37:55.957753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.84054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-10-07T06:37:55.957793Z","caller":"traceutil/trace.go:171","msg":"trace[1322230783] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:27154; }","duration":"129.923053ms","start":"2026-10-07T06:37:55.827859Z","end":"2026-10-07T06:37:55.957782Z","steps":["trace[1322230783] 'agreement among raft nodes before linearized reading' (duration: 129.698126ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:37:59.860697Z","caller":"traceutil/trace.go:171","msg":"trace[1325671488] transaction","detail":"{read_only:false; response_revision:27183; number_of_response:1; }","duration":"264.114704ms","start":"2026-10-07T06:37:59.596541Z","end":"2026-10-07T06:37:59.860655Z","steps":["trace[1325671488] 'process raft request' (duration: 263.857547ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:38:05.110411Z","caller":"traceutil/trace.go:171","msg":"trace[545810660] transaction","detail":"{read_only:false; response_revision:27224; number_of_response:1; }","duration":"126.729764ms","start":"2026-10-07T06:38:04.983646Z","end":"2026-10-07T06:38:05.110376Z","steps":["trace[545810660] 'process raft request' (duration: 126.529449ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:38:05.920772Z","caller":"traceutil/trace.go:171","msg":"trace[287885119] linearizableReadLoop","detail":"{readStateIndex:28127; appliedIndex:28125; }","duration":"172.191738ms","start":"2026-10-07T06:38:05.748558Z","end":"2026-10-07T06:38:05.920749Z","steps":["trace[287885119] 'read index received' (duration: 35.288281ms)","trace[287885119] 'applied index is now lower than readState.Index' (duration: 136.902407ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T06:38:05.920969Z","caller":"traceutil/trace.go:171","msg":"trace[1792659015] transaction","detail":"{read_only:false; response_revision:27225; number_of_response:1; }","duration":"197.256156ms","start":"2026-10-07T06:38:05.723655Z","end":"2026-10-07T06:38:05.920911Z","steps":["trace[1792659015] 'process raft request' (duration: 196.913306ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T06:38:05.921168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.519108ms","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-10-07T06:38:05.921216Z","caller":"traceutil/trace.go:171","msg":"trace[1851087893] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:27225; }","duration":"172.698633ms","start":"2026-10-07T06:38:05.748505Z","end":"2026-10-07T06:38:05.921204Z","steps":["trace[1851087893] 'agreement among raft nodes before linearized reading' (duration: 172.357474ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:38:05.939137Z","caller":"traceutil/trace.go:171","msg":"trace[1105060355] transaction","detail":"{read_only:false; response_revision:27226; number_of_response:1; }","duration":"153.923311ms","start":"2026-10-07T06:38:05.78518Z","end":"2026-10-07T06:38:05.939103Z","steps":["trace[1105060355] 'process raft request' (duration: 153.266342ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:38:06.54963Z","caller":"traceutil/trace.go:171","msg":"trace[102371786] transaction","detail":"{read_only:false; response_revision:27230; number_of_response:1; }","duration":"158.983821ms","start":"2026-10-07T06:38:06.390618Z","end":"2026-10-07T06:38:06.549602Z","steps":["trace[102371786] 'process raft request' (duration: 113.691171ms)","trace[102371786] 'compare' (duration: 45.161017ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T06:38:06.609549Z","caller":"traceutil/trace.go:171","msg":"trace[2118978116] transaction","detail":"{read_only:false; response_revision:27231; number_of_response:1; }","duration":"111.025107ms","start":"2026-10-07T06:38:06.498482Z","end":"2026-10-07T06:38:06.609507Z","steps":["trace[2118978116] 'process raft request' (duration: 110.696378ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:38:06.698147Z","caller":"traceutil/trace.go:171","msg":"trace[87376478] transaction","detail":"{read_only:false; response_revision:27232; number_of_response:1; }","duration":"142.342688ms","start":"2026-10-07T06:38:06.555772Z","end":"2026-10-07T06:38:06.698114Z","steps":["trace[87376478] 'process raft request' (duration: 130.472068ms)","trace[87376478] 'compare' (duration: 11.49609ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T06:38:06.895981Z","caller":"traceutil/trace.go:171","msg":"trace[1271273260] linearizableReadLoop","detail":"{readStateIndex:28139; appliedIndex:28135; }","duration":"126.076327ms","start":"2026-10-07T06:38:06.769869Z","end":"2026-10-07T06:38:06.895946Z","steps":["trace[1271273260] 'read index received' (duration: 88.45335ms)","trace[1271273260] 'applied index is now lower than readState.Index' (duration: 37.621187ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T06:38:06.89622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.312673ms","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-10-07T06:38:06.896269Z","caller":"traceutil/trace.go:171","msg":"trace[1671703422] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:27237; }","duration":"126.471947ms","start":"2026-10-07T06:38:06.769781Z","end":"2026-10-07T06:38:06.896253Z","steps":["trace[1671703422] 'agreement among raft nodes before linearized reading' (duration: 126.299253ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:38:06.896646Z","caller":"traceutil/trace.go:171","msg":"trace[175935021] transaction","detail":"{read_only:false; response_revision:27234; number_of_response:1; }","duration":"192.918965ms","start":"2026-10-07T06:38:06.703711Z","end":"2026-10-07T06:38:06.89663Z","steps":["trace[175935021] 'process raft request' (duration: 154.709902ms)","trace[175935021] 'compare' (duration: 37.172334ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T06:38:06.8968Z","caller":"traceutil/trace.go:171","msg":"trace[1397046615] transaction","detail":"{read_only:false; response_revision:27235; number_of_response:1; }","duration":"192.196075ms","start":"2026-10-07T06:38:06.704593Z","end":"2026-10-07T06:38:06.896789Z","steps":["trace[1397046615] 'process raft request' (duration: 191.187437ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:38:06.896959Z","caller":"traceutil/trace.go:171","msg":"trace[1224314869] transaction","detail":"{read_only:false; response_revision:27236; number_of_response:1; }","duration":"191.853536ms","start":"2026-10-07T06:38:06.705095Z","end":"2026-10-07T06:38:06.896949Z","steps":["trace[1224314869] 'process raft request' (duration: 190.758945ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:38:06.897119Z","caller":"traceutil/trace.go:171","msg":"trace[552245001] transaction","detail":"{read_only:false; response_revision:27237; number_of_response:1; }","duration":"191.925377ms","start":"2026-10-07T06:38:06.705183Z","end":"2026-10-07T06:38:06.897109Z","steps":["trace[552245001] 'process raft request' (duration: 190.710113ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:38:12.123273Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23999} {"level":"info","ts":"2026-10-07T06:38:12.281251Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23999,"took":"152.924023ms","hash":2537692995,"current-db-size-bytes":57004032,"current-db-size":"57 MB","current-db-size-in-use-bytes":45776896,"current-db-size-in-use":"46 MB"} {"level":"info","ts":"2026-10-07T06:38:12.281537Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2537692995,"revision":23999,"compact-revision":21626} {"level":"info","ts":"2026-10-07T06:38:17.487673Z","caller":"traceutil/trace.go:171","msg":"trace[694771699] transaction","detail":"{read_only:false; response_revision:27319; number_of_response:1; }","duration":"146.551546ms","start":"2026-10-07T06:38:17.341092Z","end":"2026-10-07T06:38:17.487643Z","steps":["trace[694771699] 'process raft request' (duration: 135.321073ms)","trace[694771699] 'compare' (duration: 10.730839ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T06:38:17.487938Z","caller":"traceutil/trace.go:171","msg":"trace[1285107726] transaction","detail":"{read_only:false; response_revision:27320; number_of_response:1; }","duration":"146.394911ms","start":"2026-10-07T06:38:17.34141Z","end":"2026-10-07T06:38:17.487805Z","steps":["trace[1285107726] 'process raft request' (duration: 146.118943ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:38:22.821948Z","caller":"traceutil/trace.go:171","msg":"trace[849458457] transaction","detail":"{read_only:false; response_revision:27359; number_of_response:1; }","duration":"149.027165ms","start":"2026-10-07T06:38:22.672855Z","end":"2026-10-07T06:38:22.821882Z","steps":["trace[849458457] 'process raft request' (duration: 123.905046ms)","trace[849458457] 'compare' (duration: 24.881192ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T06:38:23.025909Z","caller":"traceutil/trace.go:171","msg":"trace[431348682] linearizableReadLoop","detail":"{readStateIndex:28268; appliedIndex:28266; }","duration":"179.903223ms","start":"2026-10-07T06:38:22.845976Z","end":"2026-10-07T06:38:23.025879Z","steps":["trace[431348682] 'read index received' (duration: 179.535352ms)","trace[431348682] 'applied index is now lower than readState.Index' (duration: 366.511µs)"],"step_count":2} {"level":"info","ts":"2026-10-07T06:38:23.026017Z","caller":"traceutil/trace.go:171","msg":"trace[509073936] transaction","detail":"{read_only:false; response_revision:27361; number_of_response:1; }","duration":"262.52475ms","start":"2026-10-07T06:38:22.763455Z","end":"2026-10-07T06:38:23.02598Z","steps":["trace[509073936] 'process raft request' (duration: 262.331805ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:38:23.026004Z","caller":"traceutil/trace.go:171","msg":"trace[484624640] transaction","detail":"{read_only:false; response_revision:27360; number_of_response:1; }","duration":"268.186748ms","start":"2026-10-07T06:38:22.757755Z","end":"2026-10-07T06:38:23.025941Z","steps":["trace[484624640] 'process raft request' (duration: 267.826548ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T06:38:23.026547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.4974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-10-07T06:38:23.026636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.604032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T06:38:23.026737Z","caller":"traceutil/trace.go:171","msg":"trace[50802581] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:27361; }","duration":"172.22737ms","start":"2026-10-07T06:38:22.854484Z","end":"2026-10-07T06:38:23.026711Z","steps":["trace[50802581] 'agreement among raft nodes before linearized reading' (duration: 171.581972ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:38:23.026711Z","caller":"traceutil/trace.go:171","msg":"trace[1929882246] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:27361; }","duration":"180.751887ms","start":"2026-10-07T06:38:22.845922Z","end":"2026-10-07T06:38:23.026674Z","steps":["trace[1929882246] 'agreement among raft nodes before linearized reading' (duration: 180.109539ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:38:23.176171Z","caller":"traceutil/trace.go:171","msg":"trace[1922682071] transaction","detail":"{read_only:false; response_revision:27363; number_of_response:1; }","duration":"143.536361ms","start":"2026-10-07T06:38:23.032594Z","end":"2026-10-07T06:38:23.176131Z","steps":["trace[1922682071] 'process raft request' (duration: 143.046797ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:38:23.176608Z","caller":"traceutil/trace.go:171","msg":"trace[1323746243] transaction","detail":"{read_only:false; response_revision:27365; number_of_response:1; }","duration":"143.823539ms","start":"2026-10-07T06:38:23.032745Z","end":"2026-10-07T06:38:23.176569Z","steps":["trace[1323746243] 'process raft request' (duration: 143.620174ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:38:23.176704Z","caller":"traceutil/trace.go:171","msg":"trace[273495140] linearizableReadLoop","detail":"{readStateIndex:28273; appliedIndex:28268; }","duration":"141.027582ms","start":"2026-10-07T06:38:23.035646Z","end":"2026-10-07T06:38:23.176673Z","steps":["trace[273495140] 'read index received' (duration: 15.361087ms)","trace[273495140] 'applied index is now lower than readState.Index' (duration: 125.665105ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T06:38:23.17692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.240548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-07T06:38:23.176968Z","caller":"traceutil/trace.go:171","msg":"trace[461640281] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:27366; }","duration":"141.341901ms","start":"2026-10-07T06:38:23.035609Z","end":"2026-10-07T06:38:23.176951Z","steps":["trace[461640281] 'agreement among raft nodes before linearized reading' (duration: 141.168746ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:38:23.177001Z","caller":"traceutil/trace.go:171","msg":"trace[1111936006] transaction","detail":"{read_only:false; response_revision:27364; number_of_response:1; }","duration":"144.265072ms","start":"2026-10-07T06:38:23.032717Z","end":"2026-10-07T06:38:23.176982Z","steps":["trace[1111936006] 'process raft request' (duration: 143.347876ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:38:23.177097Z","caller":"traceutil/trace.go:171","msg":"trace[554911661] transaction","detail":"{read_only:false; response_revision:27366; number_of_response:1; }","duration":"144.2163ms","start":"2026-10-07T06:38:23.032853Z","end":"2026-10-07T06:38:23.177069Z","steps":["trace[554911661] 'process raft request' (duration: 143.654835ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:38:23.355897Z","caller":"traceutil/trace.go:171","msg":"trace[632296042] transaction","detail":"{read_only:false; response_revision:27368; number_of_response:1; }","duration":"169.73438ms","start":"2026-10-07T06:38:23.18613Z","end":"2026-10-07T06:38:23.355864Z","steps":["trace[632296042] 'process raft request' (duration: 145.195778ms)","trace[632296042] 'compare' (duration: 24.381358ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T06:38:24.382772Z","caller":"traceutil/trace.go:171","msg":"trace[2070611675] linearizableReadLoop","detail":"{readStateIndex:28277; appliedIndex:28276; }","duration":"136.726242ms","start":"2026-10-07T06:38:24.245988Z","end":"2026-10-07T06:38:24.382714Z","steps":["trace[2070611675] 'read index received' (duration: 56.978204ms)","trace[2070611675] 'applied index is now lower than readState.Index' (duration: 79.746398ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T06:38:24.382927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.926058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T06:38:24.382995Z","caller":"traceutil/trace.go:171","msg":"trace[1886807929] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0; response_count:0; response_revision:27370; }","duration":"137.054381ms","start":"2026-10-07T06:38:24.245929Z","end":"2026-10-07T06:38:24.382983Z","steps":["trace[1886807929] 'agreement among raft nodes before linearized reading' (duration: 136.923238ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:38:24.382796Z","caller":"traceutil/trace.go:171","msg":"trace[299934316] transaction","detail":"{read_only:false; response_revision:27370; number_of_response:1; }","duration":"206.982626ms","start":"2026-10-07T06:38:24.175751Z","end":"2026-10-07T06:38:24.382734Z","steps":["trace[299934316] 'process raft request' (duration: 127.176807ms)","trace[299934316] 'compare' (duration: 79.332166ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T06:38:24.559955Z","caller":"traceutil/trace.go:171","msg":"trace[1998602726] linearizableReadLoop","detail":"{readStateIndex:28278; appliedIndex:28277; }","duration":"111.83296ms","start":"2026-10-07T06:38:24.448095Z","end":"2026-10-07T06:38:24.559928Z","steps":["trace[1998602726] 'read index received' (duration: 111.606364ms)","trace[1998602726] 'applied index is now lower than readState.Index' (duration: 225.046µs)"],"step_count":2} {"level":"info","ts":"2026-10-07T06:38:24.560047Z","caller":"traceutil/trace.go:171","msg":"trace[1718992572] transaction","detail":"{read_only:false; response_revision:27371; number_of_response:1; }","duration":"175.623464ms","start":"2026-10-07T06:38:24.384373Z","end":"2026-10-07T06:38:24.559997Z","steps":["trace[1718992572] 'process raft request' (duration: 175.350207ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T06:38:24.560375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.242871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2083"} {"level":"info","ts":"2026-10-07T06:38:24.560509Z","caller":"traceutil/trace.go:171","msg":"trace[1436599004] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:27371; }","duration":"112.461477ms","start":"2026-10-07T06:38:24.448013Z","end":"2026-10-07T06:38:24.560474Z","steps":["trace[1436599004] 'agreement among raft nodes before linearized reading' (duration: 112.083927ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T06:41:18.125365Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"de05d1e64231de42","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-07T06:41:18.131699Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-07T06:41:18.131779Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-07T06:43:12.131095Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27270} {"level":"info","ts":"2026-10-07T06:43:12.307847Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27270,"took":"171.051417ms","hash":171938425,"current-db-size-bytes":58691584,"current-db-size":"59 MB","current-db-size-in-use-bytes":36159488,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-07T06:43:12.307954Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":171938425,"revision":27270,"compact-revision":23999} {"level":"info","ts":"2026-10-07T06:48:12.141896Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30152} {"level":"info","ts":"2026-10-07T06:48:12.297313Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30152,"took":"150.157524ms","hash":3468140940,"current-db-size-bytes":58691584,"current-db-size":"59 MB","current-db-size-in-use-bytes":31346688,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-07T06:48:12.297403Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3468140940,"revision":30152,"compact-revision":27270} {"level":"info","ts":"2026-10-07T06:53:12.150518Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32407} {"level":"info","ts":"2026-10-07T06:53:12.319025Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32407,"took":"161.834188ms","hash":1572265373,"current-db-size-bytes":59736064,"current-db-size":"60 MB","current-db-size-in-use-bytes":30339072,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-07T06:53:12.319107Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1572265373,"revision":32407,"compact-revision":30152} {"level":"info","ts":"2026-10-07T06:58:12.15817Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34628} {"level":"info","ts":"2026-10-07T06:58:12.300252Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34628,"took":"135.16085ms","hash":601360484,"current-db-size-bytes":59736064,"current-db-size":"60 MB","current-db-size-in-use-bytes":30486528,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-07T06:58:12.30034Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":601360484,"revision":34628,"compact-revision":32407} {"level":"info","ts":"2026-10-07T07:02:06.389642Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"de05d1e64231de42","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-07T07:02:06.395783Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-07T07:02:06.395935Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-07T07:03:12.167592Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":36860} {"level":"info","ts":"2026-10-07T07:03:12.306211Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":36860,"took":"132.065883ms","hash":2827825412,"current-db-size-bytes":59736064,"current-db-size":"60 MB","current-db-size-in-use-bytes":32067584,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-07T07:03:12.306277Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2827825412,"revision":36860,"compact-revision":34628} {"level":"info","ts":"2026-10-07T07:08:12.1758Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39261} {"level":"info","ts":"2026-10-07T07:08:12.316318Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39261,"took":"135.430616ms","hash":2650577400,"current-db-size-bytes":60157952,"current-db-size":"60 MB","current-db-size-in-use-bytes":31420416,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-07T07:08:12.316381Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2650577400,"revision":39261,"compact-revision":36860} {"level":"info","ts":"2026-10-07T07:13:12.183433Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":41520} {"level":"info","ts":"2026-10-07T07:13:12.317727Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":41520,"took":"128.456514ms","hash":1528423110,"current-db-size-bytes":60157952,"current-db-size":"60 MB","current-db-size-in-use-bytes":29605888,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-07T07:13:12.317835Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1528423110,"revision":41520,"compact-revision":39261} {"level":"info","ts":"2026-10-07T07:18:12.192822Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":43745} {"level":"info","ts":"2026-10-07T07:18:12.32892Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":43745,"took":"131.426706ms","hash":1066474432,"current-db-size-bytes":60157952,"current-db-size":"60 MB","current-db-size-in-use-bytes":28962816,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-07T07:18:12.328996Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1066474432,"revision":43745,"compact-revision":41520} {"level":"info","ts":"2026-10-07T07:23:12.200376Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":46019} {"level":"info","ts":"2026-10-07T07:23:12.347589Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":46019,"took":"141.628266ms","hash":3384475617,"current-db-size-bytes":60157952,"current-db-size":"60 MB","current-db-size-in-use-bytes":28774400,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-07T07:23:12.347712Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3384475617,"revision":46019,"compact-revision":43745} {"level":"info","ts":"2026-10-07T07:23:46.251898Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"de05d1e64231de42","local-member-applied-index":50005,"local-member-snapshot-index":40004,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-07T07:23:46.259679Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":50005} {"level":"info","ts":"2026-10-07T07:23:46.259861Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":45005}