{"level":"warn","ts":"2026-10-09T02:21:00.7401Z","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-09T02:21:00.740244Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.212: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.212:2380","--initial-cluster=instance=https://162.253.55.212:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.212:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.212: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-09T02:21:00.740335Z","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-09T02:21:00.740349Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.212:2380"]} {"level":"info","ts":"2026-10-09T02:21:00.740367Z","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-09T02:21:00.741057Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.212:2379"]} {"level":"info","ts":"2026-10-09T02:21:00.741157Z","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.212:2380"],"listen-peer-urls":["https://162.253.55.212:2380"],"advertise-client-urls":["https://162.253.55.212:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.212:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.212: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-09T02:21:00.74533Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.769116ms"} {"level":"info","ts":"2026-10-09T02:21:00.750131Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d078b1e39c21939c","cluster-id":"cdc9ba8de57e3589"} {"level":"info","ts":"2026-10-09T02:21:00.750212Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c switched to configuration voters=()"} {"level":"info","ts":"2026-10-09T02:21:00.750233Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c became follower at term 0"} {"level":"info","ts":"2026-10-09T02:21:00.750245Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d078b1e39c21939c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-09T02:21:00.750253Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c became follower at term 1"} {"level":"info","ts":"2026-10-09T02:21:00.75029Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c switched to configuration voters=(15021952148229428124)"} {"level":"warn","ts":"2026-10-09T02:21:00.757142Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-09T02:21:00.758425Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-09T02:21:00.759697Z","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-09T02:21:00.76173Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d078b1e39c21939c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-09T02:21:00.761944Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d078b1e39c21939c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-09T02:21:00.762022Z","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-09T02:21:00.762171Z","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-09T02:21:00.762202Z","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-09T02:21:00.763857Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c switched to configuration voters=(15021952148229428124)"} {"level":"info","ts":"2026-10-09T02:21:00.764068Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cdc9ba8de57e3589","local-member-id":"d078b1e39c21939c","added-peer-id":"d078b1e39c21939c","added-peer-peer-urls":["https://162.253.55.212:2380"]} {"level":"info","ts":"2026-10-09T02:21:00.766993Z","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-09T02:21:00.76717Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.212:2380"} {"level":"info","ts":"2026-10-09T02:21:00.767248Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.212:2380"} {"level":"info","ts":"2026-10-09T02:21:00.767317Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d078b1e39c21939c","initial-advertise-peer-urls":["https://162.253.55.212:2380"],"listen-peer-urls":["https://162.253.55.212:2380"],"advertise-client-urls":["https://162.253.55.212:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.212:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-09T02:21:00.767406Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-09T02:21:01.75134Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c is starting a new election at term 1"} {"level":"info","ts":"2026-10-09T02:21:01.751417Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c became pre-candidate at term 1"} {"level":"info","ts":"2026-10-09T02:21:01.75144Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c received MsgPreVoteResp from d078b1e39c21939c at term 1"} {"level":"info","ts":"2026-10-09T02:21:01.751459Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c became candidate at term 2"} {"level":"info","ts":"2026-10-09T02:21:01.751469Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c received MsgVoteResp from d078b1e39c21939c at term 2"} {"level":"info","ts":"2026-10-09T02:21:01.751485Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c became leader at term 2"} {"level":"info","ts":"2026-10-09T02:21:01.751498Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d078b1e39c21939c elected leader d078b1e39c21939c at term 2"} {"level":"info","ts":"2026-10-09T02:21:01.758955Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d078b1e39c21939c","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.212:2379]}","request-path":"/0/members/d078b1e39c21939c/attributes","cluster-id":"cdc9ba8de57e3589","publish-timeout":"7s"} {"level":"info","ts":"2026-10-09T02:21:01.759003Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-09T02:21:01.759129Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-09T02:21:01.759129Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-09T02:21:01.759344Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-09T02:21:01.759407Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-09T02:21:01.761292Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cdc9ba8de57e3589","local-member-id":"d078b1e39c21939c","cluster-version":"3.5"} {"level":"info","ts":"2026-10-09T02:21:01.761608Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-09T02:21:01.761915Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-09T02:21:01.765252Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.212:2379"} {"level":"info","ts":"2026-10-09T02:21:01.765617Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-09T02:21:54.791334Z","caller":"traceutil/trace.go:171","msg":"trace[1005520760] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"320.078618ms","start":"2026-10-09T02:21:54.471179Z","end":"2026-10-09T02:21:54.791257Z","steps":["trace[1005520760] 'process raft request' (duration: 319.189213ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:21:54.793873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:21:54.47115Z","time spent":"320.282964ms","remote":"127.0.0.1:56740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036261,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T02:21:54.980619Z","caller":"traceutil/trace.go:171","msg":"trace[1889043126] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"377.708737ms","start":"2026-10-09T02:21:54.602848Z","end":"2026-10-09T02:21:54.980556Z","steps":["trace[1889043126] 'process raft request' (duration: 327.970528ms)","trace[1889043126] 'compare' (duration: 49.540344ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:21:54.980647Z","caller":"traceutil/trace.go:171","msg":"trace[15865787] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"109.676283ms","start":"2026-10-09T02:21:54.870948Z","end":"2026-10-09T02:21:54.980624Z","steps":["trace[15865787] 'process raft request' (duration: 109.518789ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:21:54.980655Z","caller":"traceutil/trace.go:171","msg":"trace[1118076515] linearizableReadLoop","detail":"{readStateIndex:640; appliedIndex:638; }","duration":"354.345315ms","start":"2026-10-09T02:21:54.626243Z","end":"2026-10-09T02:21:54.980588Z","steps":["trace[1118076515] 'read index received' (duration: 164.02143ms)","trace[1118076515] 'applied index is now lower than readState.Index' (duration: 190.317965ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T02:21:54.980901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:21:54.602821Z","time spent":"377.888392ms","remote":"127.0.0.1:56916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T02:21:54.981104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.843449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-67swb\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-10-09T02:21:54.981236Z","caller":"traceutil/trace.go:171","msg":"trace[1132013475] range","detail":"{range_begin:/registry/pods/kube-system/cilium-67swb; range_end:; response_count:1; response_revision:611; }","duration":"355.029514ms","start":"2026-10-09T02:21:54.626184Z","end":"2026-10-09T02:21:54.981213Z","steps":["trace[1132013475] 'agreement among raft nodes before linearized reading' (duration: 354.495439ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:21:54.98141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:21:54.626161Z","time spent":"355.23559ms","remote":"127.0.0.1:56824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16989,"request content":"key:\"/registry/pods/kube-system/cilium-67swb\" "} {"level":"info","ts":"2026-10-09T02:21:55.541846Z","caller":"traceutil/trace.go:171","msg":"trace[1778345632] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"537.278823ms","start":"2026-10-09T02:21:55.004527Z","end":"2026-10-09T02:21:55.541806Z","steps":["trace[1778345632] 'process raft request' (duration: 470.045445ms)","trace[1778345632] 'compare' (duration: 67.046033ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:21:55.54194Z","caller":"traceutil/trace.go:171","msg":"trace[1751599198] linearizableReadLoop","detail":"{readStateIndex:642; appliedIndex:641; }","duration":"452.784284ms","start":"2026-10-09T02:21:55.089119Z","end":"2026-10-09T02:21:55.541903Z","steps":["trace[1751599198] 'read index received' (duration: 385.456773ms)","trace[1751599198] 'applied index is now lower than readState.Index' (duration: 67.324771ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T02:21:55.542026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:21:55.004506Z","time spent":"537.418577ms","remote":"127.0.0.1:56824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T02:21:55.542142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.016241ms","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-09T02:21:55.542227Z","caller":"traceutil/trace.go:171","msg":"trace[380488359] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:612; }","duration":"453.130174ms","start":"2026-10-09T02:21:55.089063Z","end":"2026-10-09T02:21:55.542193Z","steps":["trace[380488359] 'agreement among raft nodes before linearized reading' (duration: 452.945229ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:21:55.542262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:21:55.08904Z","time spent":"453.214115ms","remote":"127.0.0.1:56916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-09T02:21:55.542684Z","caller":"traceutil/trace.go:171","msg":"trace[1060791627] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"101.618748ms","start":"2026-10-09T02:21:55.441033Z","end":"2026-10-09T02:21:55.542652Z","steps":["trace[1060791627] 'process raft request' (duration: 101.006921ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:22:10.425408Z","caller":"traceutil/trace.go:171","msg":"trace[860515682] linearizableReadLoop","detail":"{readStateIndex:814; appliedIndex:813; }","duration":"256.74433ms","start":"2026-10-09T02:22:10.168618Z","end":"2026-10-09T02:22:10.425363Z","steps":["trace[860515682] 'read index received' (duration: 256.567315ms)","trace[860515682] 'applied index is now lower than readState.Index' (duration: 175.815µs)"],"step_count":2} {"level":"warn","ts":"2026-10-09T02:22:10.425524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.895334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T02:22:10.425487Z","caller":"traceutil/trace.go:171","msg":"trace[646625997] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"343.78216ms","start":"2026-10-09T02:22:10.081648Z","end":"2026-10-09T02:22:10.425431Z","steps":["trace[646625997] 'process raft request' (duration: 343.548353ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:22:10.425558Z","caller":"traceutil/trace.go:171","msg":"trace[1121257272] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:779; }","duration":"256.969676ms","start":"2026-10-09T02:22:10.168577Z","end":"2026-10-09T02:22:10.425547Z","steps":["trace[1121257272] 'agreement among raft nodes before linearized reading' (duration: 256.902274ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:22:10.425659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:22:10.081625Z","time spent":"343.953614ms","remote":"127.0.0.1:56916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T02:23:02.259141Z","caller":"traceutil/trace.go:171","msg":"trace[1925997312] transaction","detail":"{read_only:false; response_revision:1437; number_of_response:1; }","duration":"133.61914ms","start":"2026-10-09T02:23:02.125497Z","end":"2026-10-09T02:23:02.259116Z","steps":["trace[1925997312] 'process raft request' (duration: 133.431444ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:23:02.259473Z","caller":"traceutil/trace.go:171","msg":"trace[1569002609] transaction","detail":"{read_only:false; response_revision:1438; number_of_response:1; }","duration":"120.797015ms","start":"2026-10-09T02:23:02.138641Z","end":"2026-10-09T02:23:02.259438Z","steps":["trace[1569002609] 'process raft request' (duration: 120.429475ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:23:28.220369Z","caller":"traceutil/trace.go:171","msg":"trace[2027715702] transaction","detail":"{read_only:false; response_revision:1847; number_of_response:1; }","duration":"116.105766ms","start":"2026-10-09T02:23:28.104234Z","end":"2026-10-09T02:23:28.220339Z","steps":["trace[2027715702] 'process raft request' (duration: 115.938611ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:23:28.348246Z","caller":"traceutil/trace.go:171","msg":"trace[616770839] linearizableReadLoop","detail":"{readStateIndex:1926; appliedIndex:1925; }","duration":"113.73063ms","start":"2026-10-09T02:23:28.234489Z","end":"2026-10-09T02:23:28.348219Z","steps":["trace[616770839] 'read index received' (duration: 73.49688ms)","trace[616770839] 'applied index is now lower than readState.Index' (duration: 40.23262ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:23:28.348345Z","caller":"traceutil/trace.go:171","msg":"trace[1233667719] transaction","detail":"{read_only:false; response_revision:1849; number_of_response:1; }","duration":"122.335298ms","start":"2026-10-09T02:23:28.225979Z","end":"2026-10-09T02:23:28.348314Z","steps":["trace[1233667719] 'process raft request' (duration: 82.064456ms)","trace[1233667719] 'compare' (duration: 40.040916ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T02:23:28.348399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.887715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T02:23:28.348462Z","caller":"traceutil/trace.go:171","msg":"trace[1238651921] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1849; }","duration":"113.983857ms","start":"2026-10-09T02:23:28.234448Z","end":"2026-10-09T02:23:28.348432Z","steps":["trace[1238651921] 'agreement among raft nodes before linearized reading' (duration: 113.878244ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:24:13.647444Z","caller":"traceutil/trace.go:171","msg":"trace[5102185] transaction","detail":"{read_only:false; response_revision:2151; number_of_response:1; }","duration":"174.92831ms","start":"2026-10-09T02:24:13.472442Z","end":"2026-10-09T02:24:13.64737Z","steps":["trace[5102185] 'process raft request' (duration: 173.105651ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:25:42.421185Z","caller":"traceutil/trace.go:171","msg":"trace[1238642648] transaction","detail":"{read_only:false; response_revision:2824; number_of_response:1; }","duration":"125.176498ms","start":"2026-10-09T02:25:42.295981Z","end":"2026-10-09T02:25:42.421158Z","steps":["trace[1238642648] 'process raft request' (duration: 124.581401ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:28:31.995715Z","caller":"traceutil/trace.go:171","msg":"trace[1127248451] transaction","detail":"{read_only:false; response_revision:3841; number_of_response:1; }","duration":"103.242331ms","start":"2026-10-09T02:28:31.892449Z","end":"2026-10-09T02:28:31.995691Z","steps":["trace[1127248451] 'process raft request' (duration: 101.706899ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:29:04.953697Z","caller":"traceutil/trace.go:171","msg":"trace[171094229] transaction","detail":"{read_only:false; response_revision:4092; number_of_response:1; }","duration":"111.600683ms","start":"2026-10-09T02:29:04.842061Z","end":"2026-10-09T02:29:04.953662Z","steps":["trace[171094229] 'process raft request' (duration: 89.321786ms)","trace[171094229] 'compare' (duration: 22.111633ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:30:39.187545Z","caller":"traceutil/trace.go:171","msg":"trace[357783979] transaction","detail":"{read_only:false; response_revision:5294; number_of_response:1; }","duration":"153.978053ms","start":"2026-10-09T02:30:39.033523Z","end":"2026-10-09T02:30:39.1875Z","steps":["trace[357783979] 'process raft request' (duration: 153.765758ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:30:55.189376Z","caller":"traceutil/trace.go:171","msg":"trace[2141903878] transaction","detail":"{read_only:false; response_revision:5571; number_of_response:1; }","duration":"366.059928ms","start":"2026-10-09T02:30:54.823282Z","end":"2026-10-09T02:30:55.189342Z","steps":["trace[2141903878] 'process raft request' (duration: 329.378319ms)","trace[2141903878] 'compare' (duration: 36.489794ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T02:30:55.189634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:30:54.823257Z","time spent":"366.227763ms","remote":"127.0.0.1:56916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T02:30:55.190169Z","caller":"traceutil/trace.go:171","msg":"trace[2109077661] linearizableReadLoop","detail":"{readStateIndex:5789; appliedIndex:5784; }","duration":"210.165453ms","start":"2026-10-09T02:30:54.979971Z","end":"2026-10-09T02:30:55.190137Z","steps":["trace[2109077661] 'read index received' (duration: 172.734494ms)","trace[2109077661] 'applied index is now lower than readState.Index' (duration: 37.429629ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:30:55.190316Z","caller":"traceutil/trace.go:171","msg":"trace[794510734] transaction","detail":"{read_only:false; response_revision:5574; number_of_response:1; }","duration":"304.306247ms","start":"2026-10-09T02:30:54.885996Z","end":"2026-10-09T02:30:55.190303Z","steps":["trace[794510734] 'process raft request' (duration: 304.0267ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:30:55.190346Z","caller":"traceutil/trace.go:171","msg":"trace[956081936] transaction","detail":"{read_only:false; response_revision:5572; number_of_response:1; }","duration":"304.42635ms","start":"2026-10-09T02:30:54.8859Z","end":"2026-10-09T02:30:55.190326Z","steps":["trace[956081936] 'process raft request' (duration: 303.910466ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:30:55.190457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:30:54.885858Z","time spent":"304.545783ms","remote":"127.0.0.1:56922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1068,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T02:30:55.190463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:30:54.885859Z","time spent":"304.491722ms","remote":"127.0.0.1:56818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":530,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T02:30:55.190634Z","caller":"traceutil/trace.go:171","msg":"trace[1978277723] transaction","detail":"{read_only:false; response_revision:5573; number_of_response:1; }","duration":"304.634816ms","start":"2026-10-09T02:30:54.885942Z","end":"2026-10-09T02:30:55.190577Z","steps":["trace[1978277723] 'process raft request' (duration: 304.0352ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:30:55.190897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:30:54.885908Z","time spent":"304.841121ms","remote":"127.0.0.1:56922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1194,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T02:30:55.190914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.953805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-09T02:30:55.190972Z","caller":"traceutil/trace.go:171","msg":"trace[721085415] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5575; }","duration":"211.073498ms","start":"2026-10-09T02:30:54.979885Z","end":"2026-10-09T02:30:55.190958Z","steps":["trace[721085415] 'agreement among raft nodes before linearized reading' (duration: 210.901253ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:30:55.19099Z","caller":"traceutil/trace.go:171","msg":"trace[233839983] transaction","detail":"{read_only:false; response_revision:5575; number_of_response:1; }","duration":"304.171223ms","start":"2026-10-09T02:30:54.886786Z","end":"2026-10-09T02:30:55.190957Z","steps":["trace[233839983] 'process raft request' (duration: 303.297469ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:30:55.191113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:30:54.886771Z","time spent":"304.274587ms","remote":"127.0.0.1:56818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":682,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T02:30:55.191348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.819011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-09T02:30:55.191395Z","caller":"traceutil/trace.go:171","msg":"trace[981028011] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5575; }","duration":"134.893244ms","start":"2026-10-09T02:30:55.056487Z","end":"2026-10-09T02:30:55.19138Z","steps":["trace[981028011] 'agreement among raft nodes before linearized reading' (duration: 134.812721ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:31:01.794793Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2933} {"level":"info","ts":"2026-10-09T02:31:01.878444Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2933,"took":"80.835922ms","hash":735605789,"current-db-size-bytes":56209408,"current-db-size":"56 MB","current-db-size-in-use-bytes":31592448,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-09T02:31:01.87853Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":735605789,"revision":2933,"compact-revision":-1} {"level":"info","ts":"2026-10-09T02:31:03.507407Z","caller":"traceutil/trace.go:171","msg":"trace[2098479803] transaction","detail":"{read_only:false; response_revision:5765; number_of_response:1; }","duration":"254.17583ms","start":"2026-10-09T02:31:03.253201Z","end":"2026-10-09T02:31:03.507377Z","steps":["trace[2098479803] 'process raft request' (duration: 253.958405ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:31:03.507898Z","caller":"traceutil/trace.go:171","msg":"trace[1123381444] linearizableReadLoop","detail":"{readStateIndex:5986; appliedIndex:5984; }","duration":"192.07536ms","start":"2026-10-09T02:31:03.31577Z","end":"2026-10-09T02:31:03.507846Z","steps":["trace[1123381444] 'read index received' (duration: 191.465724ms)","trace[1123381444] 'applied index is now lower than readState.Index' (duration: 606.806µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:31:03.507984Z","caller":"traceutil/trace.go:171","msg":"trace[908586497] transaction","detail":"{read_only:false; response_revision:5766; number_of_response:1; }","duration":"252.738622ms","start":"2026-10-09T02:31:03.255219Z","end":"2026-10-09T02:31:03.507958Z","steps":["trace[908586497] 'process raft request' (duration: 252.117415ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:31:03.508172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.140924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-09T02:31:03.508219Z","caller":"traceutil/trace.go:171","msg":"trace[1358955638] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:5766; }","duration":"224.232466ms","start":"2026-10-09T02:31:03.28397Z","end":"2026-10-09T02:31:03.508203Z","steps":["trace[1358955638] 'agreement among raft nodes before linearized reading' (duration: 224.082192ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:31:03.681937Z","caller":"traceutil/trace.go:171","msg":"trace[340049233] transaction","detail":"{read_only:false; response_revision:5769; number_of_response:1; }","duration":"118.758414ms","start":"2026-10-09T02:31:03.563154Z","end":"2026-10-09T02:31:03.681912Z","steps":["trace[340049233] 'process raft request' (duration: 64.811765ms)","trace[340049233] 'compare' (duration: 53.646841ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:36:01.80568Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5678} {"level":"info","ts":"2026-10-09T02:36:01.901771Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5678,"took":"93.531095ms","hash":3611331086,"current-db-size-bytes":56209408,"current-db-size":"56 MB","current-db-size-in-use-bytes":26300416,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-09T02:36:01.901868Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3611331086,"revision":5678,"compact-revision":2933} {"level":"info","ts":"2026-10-09T02:37:31.253493Z","caller":"traceutil/trace.go:171","msg":"trace[1092949112] linearizableReadLoop","detail":"{readStateIndex:8995; appliedIndex:8994; }","duration":"314.693069ms","start":"2026-10-09T02:37:30.938743Z","end":"2026-10-09T02:37:31.253436Z","steps":["trace[1092949112] 'read index received' (duration: 314.459003ms)","trace[1092949112] 'applied index is now lower than readState.Index' (duration: 232.306µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:37:31.253564Z","caller":"traceutil/trace.go:171","msg":"trace[207477486] transaction","detail":"{read_only:false; response_revision:8682; number_of_response:1; }","duration":"318.314979ms","start":"2026-10-09T02:37:30.935196Z","end":"2026-10-09T02:37:31.253511Z","steps":["trace[207477486] 'process raft request' (duration: 317.948809ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:37:31.253874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:37:30.935178Z","time spent":"318.499795ms","remote":"127.0.0.1:56916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T02:37:31.25387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.105381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-10-09T02:37:31.253979Z","caller":"traceutil/trace.go:171","msg":"trace[1751938321] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8682; }","duration":"315.280536ms","start":"2026-10-09T02:37:30.938658Z","end":"2026-10-09T02:37:31.253938Z","steps":["trace[1751938321] 'agreement among raft nodes before linearized reading' (duration: 314.955837ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:37:31.254046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:37:30.938626Z","time spent":"315.41245ms","remote":"127.0.0.1:56916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-10-09T02:37:32.009757Z","caller":"traceutil/trace.go:171","msg":"trace[1389121183] transaction","detail":"{read_only:false; response_revision:8687; number_of_response:1; }","duration":"339.781352ms","start":"2026-10-09T02:37:31.669947Z","end":"2026-10-09T02:37:32.009728Z","steps":["trace[1389121183] 'process raft request' (duration: 339.610917ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:37:32.009923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:37:31.669923Z","time spent":"339.911065ms","remote":"127.0.0.1:56916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T02:38:14.566926Z","caller":"traceutil/trace.go:171","msg":"trace[1672336710] linearizableReadLoop","detail":"{readStateIndex:9306; appliedIndex:9305; }","duration":"117.455709ms","start":"2026-10-09T02:38:14.449432Z","end":"2026-10-09T02:38:14.566888Z","steps":["trace[1672336710] 'read index received' (duration: 71.331007ms)","trace[1672336710] 'applied index is now lower than readState.Index' (duration: 46.123052ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:38:14.567115Z","caller":"traceutil/trace.go:171","msg":"trace[2009061961] transaction","detail":"{read_only:false; response_revision:8981; number_of_response:1; }","duration":"120.188825ms","start":"2026-10-09T02:38:14.446907Z","end":"2026-10-09T02:38:14.567096Z","steps":["trace[2009061961] 'process raft request' (duration: 73.946959ms)","trace[2009061961] 'compare' (duration: 45.855675ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T02:38:14.567776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.330423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-8rpv9\" ","response":"range_response_count:1 size:2803"} {"level":"info","ts":"2026-10-09T02:38:14.567912Z","caller":"traceutil/trace.go:171","msg":"trace[632203133] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-8rpv9; range_end:; response_count:1; response_revision:8981; }","duration":"118.537159ms","start":"2026-10-09T02:38:14.449352Z","end":"2026-10-09T02:38:14.567889Z","steps":["trace[632203133] 'agreement among raft nodes before linearized reading' (duration: 118.042326ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:38:14.742273Z","caller":"traceutil/trace.go:171","msg":"trace[2009177819] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8982; }","duration":"170.503453ms","start":"2026-10-09T02:38:14.571725Z","end":"2026-10-09T02:38:14.742228Z","steps":["trace[2009177819] 'process raft request' (duration: 148.692701ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:38:14.742347Z","caller":"traceutil/trace.go:171","msg":"trace[472322190] transaction","detail":"{read_only:false; response_revision:8983; number_of_response:1; }","duration":"169.927907ms","start":"2026-10-09T02:38:14.572397Z","end":"2026-10-09T02:38:14.742325Z","steps":["trace[472322190] 'process raft request' (duration: 169.729811ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:38:14.742618Z","caller":"traceutil/trace.go:171","msg":"trace[2048510967] transaction","detail":"{read_only:false; response_revision:8984; number_of_response:1; }","duration":"168.476617ms","start":"2026-10-09T02:38:14.574107Z","end":"2026-10-09T02:38:14.742584Z","steps":["trace[2048510967] 'process raft request' (duration: 168.107557ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:38:14.742762Z","caller":"traceutil/trace.go:171","msg":"trace[2103303873] transaction","detail":"{read_only:false; response_revision:8985; number_of_response:1; }","duration":"166.744799ms","start":"2026-10-09T02:38:14.575958Z","end":"2026-10-09T02:38:14.742703Z","steps":["trace[2103303873] 'process raft request' (duration: 166.311237ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:38:14.743283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.085321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-8rpv9\" ","response":"range_response_count:1 size:4653"} {"level":"info","ts":"2026-10-09T02:38:14.743361Z","caller":"traceutil/trace.go:171","msg":"trace[1479941067] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-8rpv9; range_end:; response_count:1; response_revision:8985; }","duration":"170.210345ms","start":"2026-10-09T02:38:14.573131Z","end":"2026-10-09T02:38:14.743341Z","steps":["trace[1479941067] 'agreement among raft nodes before linearized reading' (duration: 169.910266ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:38:14.742362Z","caller":"traceutil/trace.go:171","msg":"trace[194767175] linearizableReadLoop","detail":"{readStateIndex:9308; appliedIndex:9306; }","duration":"169.164656ms","start":"2026-10-09T02:38:14.573159Z","end":"2026-10-09T02:38:14.742324Z","steps":["trace[194767175] 'read index received' (duration: 147.330203ms)","trace[194767175] 'applied index is now lower than readState.Index' (duration: 21.832773ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:38:15.754478Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000247f.wal"} {"level":"info","ts":"2026-10-09T02:39:18.238279Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d078b1e39c21939c","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-09T02:39:18.25402Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-09T02:39:18.254155Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-09T02:41:01.817133Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7986} {"level":"info","ts":"2026-10-09T02:41:01.903929Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7986,"took":"83.614384ms","hash":635181654,"current-db-size-bytes":56209408,"current-db-size":"56 MB","current-db-size-in-use-bytes":24944640,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-10-09T02:41:01.90403Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":635181654,"revision":7986,"compact-revision":5678} {"level":"warn","ts":"2026-10-09T02:45:18.329829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.763693ms","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-09T02:45:18.329956Z","caller":"traceutil/trace.go:171","msg":"trace[1038022173] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:12242; }","duration":"110.924668ms","start":"2026-10-09T02:45:18.219004Z","end":"2026-10-09T02:45:18.329928Z","steps":["trace[1038022173] 'range keys from in-memory index tree' (duration: 110.444125ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:46:01.824618Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10379} {"level":"info","ts":"2026-10-09T02:46:01.926958Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10379,"took":"99.12102ms","hash":4194894197,"current-db-size-bytes":56209408,"current-db-size":"56 MB","current-db-size-in-use-bytes":25772032,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-09T02:46:01.927033Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4194894197,"revision":10379,"compact-revision":7986} {"level":"info","ts":"2026-10-09T02:51:01.832674Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12557} {"level":"info","ts":"2026-10-09T02:51:01.912291Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12557,"took":"76.999661ms","hash":373654255,"current-db-size-bytes":56209408,"current-db-size":"56 MB","current-db-size-in-use-bytes":27303936,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-10-09T02:51:01.912401Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":373654255,"revision":12557,"compact-revision":10379} {"level":"info","ts":"2026-10-09T02:51:35.17932Z","caller":"traceutil/trace.go:171","msg":"trace[1556347856] transaction","detail":"{read_only:false; response_revision:14776; number_of_response:1; }","duration":"114.355171ms","start":"2026-10-09T02:51:35.064937Z","end":"2026-10-09T02:51:35.179292Z","steps":["trace[1556347856] 'process raft request' (duration: 114.184447ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:51:53.445804Z","caller":"traceutil/trace.go:171","msg":"trace[1528381340] transaction","detail":"{read_only:false; response_revision:14883; number_of_response:1; }","duration":"161.938043ms","start":"2026-10-09T02:51:53.283836Z","end":"2026-10-09T02:51:53.445774Z","steps":["trace[1528381340] 'process raft request' (duration: 161.687166ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:51:53.563604Z","caller":"traceutil/trace.go:171","msg":"trace[453623337] transaction","detail":"{read_only:false; response_revision:14884; number_of_response:1; }","duration":"201.868713ms","start":"2026-10-09T02:51:53.361705Z","end":"2026-10-09T02:51:53.563573Z","steps":["trace[453623337] 'process raft request' (duration: 156.678968ms)","trace[453623337] 'compare' (duration: 44.838396ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:51:53.563871Z","caller":"traceutil/trace.go:171","msg":"trace[1515614655] transaction","detail":"{read_only:false; response_revision:14885; number_of_response:1; }","duration":"187.880418ms","start":"2026-10-09T02:51:53.375939Z","end":"2026-10-09T02:51:53.563819Z","steps":["trace[1515614655] 'process raft request' (duration: 187.522688ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:51:53.952867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.164132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T02:51:53.952979Z","caller":"traceutil/trace.go:171","msg":"trace[266783577] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:14885; }","duration":"173.411279ms","start":"2026-10-09T02:51:53.779542Z","end":"2026-10-09T02:51:53.952954Z","steps":["trace[266783577] 'count revisions from in-memory index tree' (duration: 172.956577ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:51:54.013768Z","caller":"traceutil/trace.go:171","msg":"trace[956284943] transaction","detail":"{read_only:false; response_revision:14886; number_of_response:1; }","duration":"113.113147ms","start":"2026-10-09T02:51:53.900606Z","end":"2026-10-09T02:51:54.013719Z","steps":["trace[956284943] 'process raft request' (duration: 112.248373ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:51:54.200822Z","caller":"traceutil/trace.go:171","msg":"trace[764075585] transaction","detail":"{read_only:false; response_revision:14887; number_of_response:1; }","duration":"181.275446ms","start":"2026-10-09T02:51:54.019489Z","end":"2026-10-09T02:51:54.200764Z","steps":["trace[764075585] 'process raft request' (duration: 154.125278ms)","trace[764075585] 'compare' (duration: 26.969793ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:51:54.862981Z","caller":"traceutil/trace.go:171","msg":"trace[1379829249] transaction","detail":"{read_only:false; response_revision:14888; number_of_response:1; }","duration":"843.099736ms","start":"2026-10-09T02:51:54.019846Z","end":"2026-10-09T02:51:54.862946Z","steps":["trace[1379829249] 'process raft request' (duration: 842.794817ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:51:54.863384Z","caller":"traceutil/trace.go:171","msg":"trace[1475651147] transaction","detail":"{read_only:false; response_revision:14889; number_of_response:1; }","duration":"843.400933ms","start":"2026-10-09T02:51:54.01994Z","end":"2026-10-09T02:51:54.863341Z","steps":["trace[1475651147] 'process raft request' (duration: 842.953241ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:51:54.863414Z","caller":"traceutil/trace.go:171","msg":"trace[870992058] linearizableReadLoop","detail":"{readStateIndex:15414; appliedIndex:15410; }","duration":"819.860935ms","start":"2026-10-09T02:51:54.043512Z","end":"2026-10-09T02:51:54.863373Z","steps":["trace[870992058] 'read index received' (duration: 129.424417ms)","trace[870992058] 'applied index is now lower than readState.Index' (duration: 690.435128ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T02:51:54.863579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:51:54.019587Z","time spent":"843.900448ms","remote":"127.0.0.1:56916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T02:51:54.863548Z","caller":"traceutil/trace.go:171","msg":"trace[2132960189] transaction","detail":"{read_only:false; response_revision:14890; number_of_response:1; }","duration":"834.082797ms","start":"2026-10-09T02:51:54.029386Z","end":"2026-10-09T02:51:54.863468Z","steps":["trace[2132960189] 'process raft request' (duration: 833.81214ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:51:54.863681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"820.173313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-grfj2\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-10-09T02:51:54.86378Z","caller":"traceutil/trace.go:171","msg":"trace[1198686918] range","detail":"{range_begin:/registry/pods/openstack/keepalived-grfj2; range_end:; response_count:1; response_revision:14890; }","duration":"820.309908ms","start":"2026-10-09T02:51:54.043451Z","end":"2026-10-09T02:51:54.863761Z","steps":["trace[1198686918] 'agreement among raft nodes before linearized reading' (duration: 820.074652ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:51:54.863814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:51:54.029367Z","time spent":"834.286122ms","remote":"127.0.0.1:56824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12483,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T02:51:54.863831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:51:54.043427Z","time spent":"820.38937ms","remote":"127.0.0.1:56824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6548,"request content":"key:\"/registry/pods/openstack/keepalived-grfj2\" "} {"level":"warn","ts":"2026-10-09T02:51:54.863991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:51:54.019833Z","time spent":"843.242449ms","remote":"127.0.0.1:56916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T02:51:54.887077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.958585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/openstack/ovn-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T02:51:54.887087Z","caller":"traceutil/trace.go:171","msg":"trace[381830628] transaction","detail":"{read_only:false; response_revision:14891; number_of_response:1; }","duration":"353.626166ms","start":"2026-10-09T02:51:54.533436Z","end":"2026-10-09T02:51:54.887062Z","steps":["trace[381830628] 'process raft request' (duration: 353.40101ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:51:54.887141Z","caller":"traceutil/trace.go:171","msg":"trace[638338248] range","detail":"{range_begin:/registry/daemonsets/openstack/ovn-controller; range_end:; response_count:0; response_revision:14891; }","duration":"264.064248ms","start":"2026-10-09T02:51:54.623059Z","end":"2026-10-09T02:51:54.887124Z","steps":["trace[638338248] 'agreement among raft nodes before linearized reading' (duration: 263.961505ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:51:54.887215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:51:54.533368Z","time spent":"353.770909ms","remote":"127.0.0.1:56916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T02:51:57.067343Z","caller":"traceutil/trace.go:171","msg":"trace[1338655763] transaction","detail":"{read_only:false; response_revision:14904; number_of_response:1; }","duration":"193.532654ms","start":"2026-10-09T02:51:56.873781Z","end":"2026-10-09T02:51:57.067314Z","steps":["trace[1338655763] 'process raft request' (duration: 193.349139ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:51:57.086745Z","caller":"traceutil/trace.go:171","msg":"trace[1914565355] linearizableReadLoop","detail":"{readStateIndex:15430; appliedIndex:15428; }","duration":"186.380666ms","start":"2026-10-09T02:51:56.90032Z","end":"2026-10-09T02:51:57.086701Z","steps":["trace[1914565355] 'read index received' (duration: 166.801257ms)","trace[1914565355] 'applied index is now lower than readState.Index' (duration: 19.578069ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:51:57.086747Z","caller":"traceutil/trace.go:171","msg":"trace[1587424294] transaction","detail":"{read_only:false; response_revision:14905; number_of_response:1; }","duration":"212.895527ms","start":"2026-10-09T02:51:56.873808Z","end":"2026-10-09T02:51:57.086704Z","steps":["trace[1587424294] 'process raft request' (duration: 212.649561ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:51:57.087141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.794838ms","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-09T02:51:57.087249Z","caller":"traceutil/trace.go:171","msg":"trace[657239655] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14906; }","duration":"186.953293ms","start":"2026-10-09T02:51:56.900274Z","end":"2026-10-09T02:51:57.087227Z","steps":["trace[657239655] 'agreement among raft nodes before linearized reading' (duration: 186.668395ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:51:57.08743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.469693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-09T02:51:57.087389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.349788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-grfj2\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-10-09T02:51:57.087478Z","caller":"traceutil/trace.go:171","msg":"trace[1381273763] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:14906; }","duration":"123.543094ms","start":"2026-10-09T02:51:56.963922Z","end":"2026-10-09T02:51:57.087465Z","steps":["trace[1381273763] 'agreement among raft nodes before linearized reading' (duration: 123.460802ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:51:57.087567Z","caller":"traceutil/trace.go:171","msg":"trace[7393641] range","detail":"{range_begin:/registry/pods/openstack/keepalived-grfj2; range_end:; response_count:1; response_revision:14906; }","duration":"185.563844ms","start":"2026-10-09T02:51:56.901926Z","end":"2026-10-09T02:51:57.08749Z","steps":["trace[7393641] 'agreement among raft nodes before linearized reading' (duration: 185.148163ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:53:50.075884Z","caller":"traceutil/trace.go:171","msg":"trace[609648732] transaction","detail":"{read_only:false; response_revision:16168; number_of_response:1; }","duration":"197.626157ms","start":"2026-10-09T02:53:49.87823Z","end":"2026-10-09T02:53:50.075856Z","steps":["trace[609648732] 'process raft request' (duration: 190.940173ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:53:50.076194Z","caller":"traceutil/trace.go:171","msg":"trace[23496039] linearizableReadLoop","detail":"{readStateIndex:16734; appliedIndex:16732; }","duration":"169.81936ms","start":"2026-10-09T02:53:49.906339Z","end":"2026-10-09T02:53:50.076158Z","steps":["trace[23496039] 'read index received' (duration: 162.808797ms)","trace[23496039] 'applied index is now lower than readState.Index' (duration: 7.008803ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:53:50.076474Z","caller":"traceutil/trace.go:171","msg":"trace[978267786] transaction","detail":"{read_only:false; response_revision:16169; number_of_response:1; }","duration":"197.35461ms","start":"2026-10-09T02:53:49.879077Z","end":"2026-10-09T02:53:50.076431Z","steps":["trace[978267786] 'process raft request' (duration: 196.734143ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:53:50.076819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.460458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-10-09T02:53:50.076879Z","caller":"traceutil/trace.go:171","msg":"trace[398965541] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:16169; }","duration":"170.56599ms","start":"2026-10-09T02:53:49.906293Z","end":"2026-10-09T02:53:50.076859Z","steps":["trace[398965541] 'agreement among raft nodes before linearized reading' (duration: 170.416316ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:53:51.062344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.508502ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-10-09T02:53:51.062523Z","caller":"traceutil/trace.go:171","msg":"trace[1133620578] linearizableReadLoop","detail":"{readStateIndex:16740; appliedIndex:16739; }","duration":"154.57645ms","start":"2026-10-09T02:53:50.907921Z","end":"2026-10-09T02:53:51.062497Z","steps":["trace[1133620578] 'read index received' (duration: 57.142µs)","trace[1133620578] 'applied index is now lower than readState.Index' (duration: 154.516788ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T02:53:51.062886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.93717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-10-09T02:53:51.062927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.861906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-kz5ds\" ","response":"range_response_count:1 size:20653"} {"level":"info","ts":"2026-10-09T02:53:51.063004Z","caller":"traceutil/trace.go:171","msg":"trace[24341506] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:16174; }","duration":"155.112024ms","start":"2026-10-09T02:53:50.907864Z","end":"2026-10-09T02:53:51.062976Z","steps":["trace[24341506] 'agreement among raft nodes before linearized reading' (duration: 154.757295ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:53:51.063058Z","caller":"traceutil/trace.go:171","msg":"trace[945319718] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-kz5ds; range_end:; response_count:1; response_revision:16174; }","duration":"119.052141ms","start":"2026-10-09T02:53:50.943976Z","end":"2026-10-09T02:53:51.063029Z","steps":["trace[945319718] 'agreement among raft nodes before linearized reading' (duration: 118.66211ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:53:51.173382Z","caller":"traceutil/trace.go:171","msg":"trace[736663290] linearizableReadLoop","detail":"{readStateIndex:16741; appliedIndex:16740; }","duration":"102.70078ms","start":"2026-10-09T02:53:51.070654Z","end":"2026-10-09T02:53:51.173355Z","steps":["trace[736663290] 'read index received' (duration: 102.397152ms)","trace[736663290] 'applied index is now lower than readState.Index' (duration: 302.418µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:53:51.173529Z","caller":"traceutil/trace.go:171","msg":"trace[1795650135] transaction","detail":"{read_only:false; response_revision:16175; number_of_response:1; }","duration":"105.20881ms","start":"2026-10-09T02:53:51.068262Z","end":"2026-10-09T02:53:51.173471Z","steps":["trace[1795650135] 'process raft request' (duration: 104.86775ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:53:51.174949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.273243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:79 size:769827"} {"level":"info","ts":"2026-10-09T02:53:51.175047Z","caller":"traceutil/trace.go:171","msg":"trace[642357553] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:79; response_revision:16175; }","duration":"104.423638ms","start":"2026-10-09T02:53:51.070608Z","end":"2026-10-09T02:53:51.175032Z","steps":["trace[642357553] 'agreement among raft nodes before linearized reading' (duration: 102.893906ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:56:01.840854Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14565} {"level":"info","ts":"2026-10-09T02:56:01.927319Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14565,"took":"82.988688ms","hash":2165295426,"current-db-size-bytes":56209408,"current-db-size":"56 MB","current-db-size-in-use-bytes":31928320,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-09T02:56:01.927413Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2165295426,"revision":14565,"compact-revision":12557} {"level":"info","ts":"2026-10-09T02:59:30.099338Z","caller":"traceutil/trace.go:171","msg":"trace[665778851] transaction","detail":"{read_only:false; response_revision:18628; number_of_response:1; }","duration":"100.573525ms","start":"2026-10-09T02:59:29.998722Z","end":"2026-10-09T02:59:30.099296Z","steps":["trace[665778851] 'process raft request' (duration: 100.296817ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:59:30.446749Z","caller":"traceutil/trace.go:171","msg":"trace[554397100] linearizableReadLoop","detail":"{readStateIndex:19276; appliedIndex:19275; }","duration":"347.495113ms","start":"2026-10-09T02:59:30.09921Z","end":"2026-10-09T02:59:30.446705Z","steps":["trace[554397100] 'read index received' (duration: 294.193727ms)","trace[554397100] 'applied index is now lower than readState.Index' (duration: 53.299826ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:59:30.44679Z","caller":"traceutil/trace.go:171","msg":"trace[1641290410] transaction","detail":"{read_only:false; response_revision:18629; number_of_response:1; }","duration":"362.832217ms","start":"2026-10-09T02:59:30.083909Z","end":"2026-10-09T02:59:30.446742Z","steps":["trace[1641290410] 'process raft request' (duration: 309.593602ms)","trace[1641290410] 'compare' (duration: 53.026109ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T02:59:30.44707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:59:30.083882Z","time spent":"363.030942ms","remote":"127.0.0.1:56916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T02:59:30.44705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.673203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-kz5ds\" ","response":"range_response_count:1 size:20756"} {"level":"warn","ts":"2026-10-09T02:59:30.447094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.860305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:703"} {"level":"warn","ts":"2026-10-09T02:59:30.447157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.161063ms","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-09T02:59:30.44718Z","caller":"traceutil/trace.go:171","msg":"trace[1442167501] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-kz5ds; range_end:; response_count:1; response_revision:18629; }","duration":"405.89731ms","start":"2026-10-09T02:59:30.041245Z","end":"2026-10-09T02:59:30.447142Z","steps":["trace[1442167501] 'agreement among raft nodes before linearized reading' (duration: 405.497089ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:59:30.447206Z","caller":"traceutil/trace.go:171","msg":"trace[95138375] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18629; }","duration":"429.044831ms","start":"2026-10-09T02:59:30.018137Z","end":"2026-10-09T02:59:30.447182Z","steps":["trace[95138375] 'agreement among raft nodes before linearized reading' (duration: 428.721082ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:59:30.447222Z","caller":"traceutil/trace.go:171","msg":"trace[980132488] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:18629; }","duration":"295.304097ms","start":"2026-10-09T02:59:30.151896Z","end":"2026-10-09T02:59:30.4472Z","steps":["trace[980132488] 'agreement among raft nodes before linearized reading' (duration: 295.074201ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:59:30.44726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:59:30.018118Z","time spent":"429.128653ms","remote":"127.0.0.1:56818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":727,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"warn","ts":"2026-10-09T02:59:30.447259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:59:30.041214Z","time spent":"406.027322ms","remote":"127.0.0.1:56824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":20780,"request content":"key:\"/registry/pods/openstack/libvirt-libvirt-default-kz5ds\" "} {"level":"warn","ts":"2026-10-09T02:59:30.44764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.799774ms","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-09T02:59:30.447701Z","caller":"traceutil/trace.go:171","msg":"trace[615403071] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18629; }","duration":"259.863456ms","start":"2026-10-09T02:59:30.187824Z","end":"2026-10-09T02:59:30.447687Z","steps":["trace[615403071] 'agreement among raft nodes before linearized reading' (duration: 259.713492ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:59:30.447832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.681119ms","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-09T02:59:30.447819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.741621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-s4rjq\" ","response":"range_response_count:1 size:9800"} {"level":"info","ts":"2026-10-09T02:59:30.447887Z","caller":"traceutil/trace.go:171","msg":"trace[1552500549] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18629; }","duration":"290.756901ms","start":"2026-10-09T02:59:30.157118Z","end":"2026-10-09T02:59:30.447875Z","steps":["trace[1552500549] 'agreement among raft nodes before linearized reading' (duration: 290.627307ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:59:30.447884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.745595ms","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-09T02:59:30.448009Z","caller":"traceutil/trace.go:171","msg":"trace[353698123] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18629; }","duration":"271.93003ms","start":"2026-10-09T02:59:30.176056Z","end":"2026-10-09T02:59:30.447986Z","steps":["trace[353698123] 'agreement among raft nodes before linearized reading' (duration: 271.656382ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:59:30.447912Z","caller":"traceutil/trace.go:171","msg":"trace[641604589] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-s4rjq; range_end:; response_count:1; response_revision:18629; }","duration":"290.895754ms","start":"2026-10-09T02:59:30.156997Z","end":"2026-10-09T02:59:30.447893Z","steps":["trace[641604589] 'agreement among raft nodes before linearized reading' (duration: 290.684669ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:59:30.903995Z","caller":"traceutil/trace.go:171","msg":"trace[356559916] transaction","detail":"{read_only:false; response_revision:18631; number_of_response:1; }","duration":"451.319886ms","start":"2026-10-09T02:59:30.452636Z","end":"2026-10-09T02:59:30.903956Z","steps":["trace[356559916] 'process raft request' (duration: 406.118114ms)","trace[356559916] 'compare' (duration: 44.588515ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T02:59:30.904214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:59:30.452605Z","time spent":"451.48023ms","remote":"127.0.0.1:56916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T02:59:30.904383Z","caller":"traceutil/trace.go:171","msg":"trace[1614640480] transaction","detail":"{read_only:false; response_revision:18632; number_of_response:1; }","duration":"451.257864ms","start":"2026-10-09T02:59:30.453087Z","end":"2026-10-09T02:59:30.904345Z","steps":["trace[1614640480] 'process raft request' (duration: 450.807322ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:59:30.904592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:59:30.453053Z","time spent":"451.434409ms","remote":"127.0.0.1:56916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T02:59:30.904615Z","caller":"traceutil/trace.go:171","msg":"trace[1766760581] linearizableReadLoop","detail":"{readStateIndex:19279; appliedIndex:19276; }","duration":"451.089729ms","start":"2026-10-09T02:59:30.453479Z","end":"2026-10-09T02:59:30.904568Z","steps":["trace[1766760581] 'read index received' (duration: 46.485597ms)","trace[1766760581] 'applied index is now lower than readState.Index' (duration: 404.599692ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:59:30.904648Z","caller":"traceutil/trace.go:171","msg":"trace[1074067218] transaction","detail":"{read_only:false; response_revision:18633; number_of_response:1; }","duration":"434.929612ms","start":"2026-10-09T02:59:30.469687Z","end":"2026-10-09T02:59:30.904617Z","steps":["trace[1074067218] 'process raft request' (duration: 434.51852ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:59:30.904802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:59:30.469641Z","time spent":"435.102247ms","remote":"127.0.0.1:56824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20871,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T02:59:30.906025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.55094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:94 size:931055"} {"level":"info","ts":"2026-10-09T02:59:30.906087Z","caller":"traceutil/trace.go:171","msg":"trace[767551515] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:94; response_revision:18633; }","duration":"452.644592ms","start":"2026-10-09T02:59:30.453428Z","end":"2026-10-09T02:59:30.906072Z","steps":["trace[767551515] 'agreement among raft nodes before linearized reading' (duration: 451.265044ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:59:30.906123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:59:30.453414Z","time spent":"452.697584ms","remote":"127.0.0.1:56824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":94,"response size":931079,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"warn","ts":"2026-10-09T02:59:30.97532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.995572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-09T02:59:30.975337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.528372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-09T02:59:30.975443Z","caller":"traceutil/trace.go:171","msg":"trace[1822107838] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:18633; }","duration":"505.190887ms","start":"2026-10-09T02:59:30.470226Z","end":"2026-10-09T02:59:30.975416Z","steps":["trace[1822107838] 'agreement among raft nodes before linearized reading' (duration: 504.91749ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:59:30.975466Z","caller":"traceutil/trace.go:171","msg":"trace[1190786162] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:18633; }","duration":"416.715998ms","start":"2026-10-09T02:59:30.558718Z","end":"2026-10-09T02:59:30.975434Z","steps":["trace[1190786162] 'agreement among raft nodes before linearized reading' (duration: 416.382768ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:59:30.975493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:59:30.470211Z","time spent":"505.2712ms","remote":"127.0.0.1:56518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"warn","ts":"2026-10-09T02:59:30.975524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:59:30.558694Z","time spent":"416.81398ms","remote":"127.0.0.1:56916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":582,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-10-09T03:00:23.358991Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d078b1e39c21939c","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-09T03:00:23.366189Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-09T03:00:23.366318Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-09T03:01:01.852438Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17069} {"level":"info","ts":"2026-10-09T03:01:01.961201Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17069,"took":"104.623767ms","hash":494499880,"current-db-size-bytes":56209408,"current-db-size":"56 MB","current-db-size-in-use-bytes":33329152,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-09T03:01:01.961329Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":494499880,"revision":17069,"compact-revision":14565} {"level":"warn","ts":"2026-10-09T03:05:27.250639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.30817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-10-09T03:05:27.250736Z","caller":"traceutil/trace.go:171","msg":"trace[203456536] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:21491; }","duration":"106.421463ms","start":"2026-10-09T03:05:27.144293Z","end":"2026-10-09T03:05:27.250715Z","steps":["trace[203456536] 'count revisions from in-memory index tree' (duration: 106.169027ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:05:27.404324Z","caller":"traceutil/trace.go:171","msg":"trace[1862058045] transaction","detail":"{read_only:false; response_revision:21492; number_of_response:1; }","duration":"209.086244ms","start":"2026-10-09T03:05:27.195217Z","end":"2026-10-09T03:05:27.404303Z","steps":["trace[1862058045] 'process raft request' (duration: 208.978341ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:05:27.404394Z","caller":"traceutil/trace.go:171","msg":"trace[453236408] linearizableReadLoop","detail":"{readStateIndex:22229; appliedIndex:22229; }","duration":"141.203422ms","start":"2026-10-09T03:05:27.263165Z","end":"2026-10-09T03:05:27.404368Z","steps":["trace[453236408] 'read index received' (duration: 141.194052ms)","trace[453236408] 'applied index is now lower than readState.Index' (duration: 7.57µs)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:05:27.404657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.49079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T03:05:27.404711Z","caller":"traceutil/trace.go:171","msg":"trace[369578752] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21492; }","duration":"141.668304ms","start":"2026-10-09T03:05:27.26303Z","end":"2026-10-09T03:05:27.404698Z","steps":["trace[369578752] 'agreement among raft nodes before linearized reading' (duration: 141.481739ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:05:27.448734Z","caller":"traceutil/trace.go:171","msg":"trace[652502771] transaction","detail":"{read_only:false; response_revision:21493; number_of_response:1; }","duration":"181.644897ms","start":"2026-10-09T03:05:27.267071Z","end":"2026-10-09T03:05:27.448716Z","steps":["trace[652502771] 'process raft request' (duration: 181.41121ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:05:27.448942Z","caller":"traceutil/trace.go:171","msg":"trace[1518972287] transaction","detail":"{read_only:false; response_revision:21494; number_of_response:1; }","duration":"128.657366ms","start":"2026-10-09T03:05:27.320258Z","end":"2026-10-09T03:05:27.448915Z","steps":["trace[1518972287] 'process raft request' (duration: 128.41236ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:05:27.449119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.011455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-10-09T03:05:27.449207Z","caller":"traceutil/trace.go:171","msg":"trace[1135488770] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:21494; }","duration":"183.158479ms","start":"2026-10-09T03:05:27.266026Z","end":"2026-10-09T03:05:27.449184Z","steps":["trace[1135488770] 'agreement among raft nodes before linearized reading' (duration: 182.872751ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:05:27.449195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.304463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-09T03:05:27.449345Z","caller":"traceutil/trace.go:171","msg":"trace[331846879] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21494; }","duration":"109.470828ms","start":"2026-10-09T03:05:27.339814Z","end":"2026-10-09T03:05:27.449284Z","steps":["trace[331846879] 'agreement among raft nodes before linearized reading' (duration: 109.124778ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:05:27.614776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.846156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19263"} {"level":"warn","ts":"2026-10-09T03:05:27.614927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.54222ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T03:05:27.615054Z","caller":"traceutil/trace.go:171","msg":"trace[1575796205] transaction","detail":"{read_only:false; response_revision:21495; number_of_response:1; }","duration":"161.4382ms","start":"2026-10-09T03:05:27.453593Z","end":"2026-10-09T03:05:27.615031Z","steps":["trace[1575796205] 'process raft request' (duration: 43.678304ms)","trace[1575796205] 'compare' (duration: 117.373755ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:05:27.615066Z","caller":"traceutil/trace.go:171","msg":"trace[1146394607] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:21494; }","duration":"164.116623ms","start":"2026-10-09T03:05:27.450857Z","end":"2026-10-09T03:05:27.614973Z","steps":["trace[1146394607] 'range keys from in-memory index tree' (duration: 163.584469ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:06:01.859537Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19636} {"level":"info","ts":"2026-10-09T03:06:01.980337Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19636,"took":"115.03729ms","hash":175502481,"current-db-size-bytes":56209408,"current-db-size":"56 MB","current-db-size-in-use-bytes":29945856,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-09T03:06:01.980431Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":175502481,"revision":19636,"compact-revision":17069} {"level":"info","ts":"2026-10-09T03:09:22.076847Z","caller":"traceutil/trace.go:171","msg":"trace[1681610990] transaction","detail":"{read_only:false; response_revision:23929; number_of_response:1; }","duration":"176.290643ms","start":"2026-10-09T03:09:21.900525Z","end":"2026-10-09T03:09:22.076816Z","steps":["trace[1681610990] 'process raft request' (duration: 176.096908ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:11:01.879958Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21758} {"level":"info","ts":"2026-10-09T03:11:02.027868Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21758,"took":"143.896272ms","hash":753068970,"current-db-size-bytes":56209408,"current-db-size":"56 MB","current-db-size-in-use-bytes":43720704,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-10-09T03:11:02.028019Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":753068970,"revision":21758,"compact-revision":19636} {"level":"warn","ts":"2026-10-09T03:12:59.336792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.295763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T03:12:59.336955Z","caller":"traceutil/trace.go:171","msg":"trace[1792682012] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26095; }","duration":"168.504049ms","start":"2026-10-09T03:12:59.168427Z","end":"2026-10-09T03:12:59.336931Z","steps":["trace[1792682012] 'range keys from in-memory index tree' (duration: 168.205341ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:12:59.577995Z","caller":"traceutil/trace.go:171","msg":"trace[421372632] transaction","detail":"{read_only:false; response_revision:26096; number_of_response:1; }","duration":"381.622667ms","start":"2026-10-09T03:12:59.196344Z","end":"2026-10-09T03:12:59.577966Z","steps":["trace[421372632] 'process raft request' (duration: 381.467573ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:12:59.578177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:12:59.196324Z","time spent":"381.74865ms","remote":"127.0.0.1:56916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T03:12:59.629166Z","caller":"traceutil/trace.go:171","msg":"trace[410804730] linearizableReadLoop","detail":"{readStateIndex:26957; appliedIndex:26956; }","duration":"291.247848ms","start":"2026-10-09T03:12:59.337868Z","end":"2026-10-09T03:12:59.629116Z","steps":["trace[410804730] 'read index received' (duration: 240.078419ms)","trace[410804730] 'applied index is now lower than readState.Index' (duration: 51.168479ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:12:59.629239Z","caller":"traceutil/trace.go:171","msg":"trace[1583137781] transaction","detail":"{read_only:false; response_revision:26097; number_of_response:1; }","duration":"307.942348ms","start":"2026-10-09T03:12:59.321269Z","end":"2026-10-09T03:12:59.629211Z","steps":["trace[1583137781] 'process raft request' (duration: 307.726942ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:12:59.629375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.493555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-09T03:12:59.629413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:12:59.32124Z","time spent":"308.092192ms","remote":"127.0.0.1:56916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T03:12:59.629445Z","caller":"traceutil/trace.go:171","msg":"trace[384854271] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26097; }","duration":"291.608758ms","start":"2026-10-09T03:12:59.337814Z","end":"2026-10-09T03:12:59.629422Z","steps":["trace[384854271] 'agreement among raft nodes before linearized reading' (duration: 291.468505ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:12:59.630315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.410897ms","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-09T03:12:59.630495Z","caller":"traceutil/trace.go:171","msg":"trace[1944019258] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26098; }","duration":"285.631733ms","start":"2026-10-09T03:12:59.344839Z","end":"2026-10-09T03:12:59.63047Z","steps":["trace[1944019258] 'agreement among raft nodes before linearized reading' (duration: 285.197781ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:12:59.844893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.759688ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T03:12:59.845108Z","caller":"traceutil/trace.go:171","msg":"trace[2081464515] linearizableReadLoop","detail":"{readStateIndex:26960; appliedIndex:26958; }","duration":"205.99054ms","start":"2026-10-09T03:12:59.639099Z","end":"2026-10-09T03:12:59.845089Z","steps":["trace[2081464515] 'read index received' (duration: 41.931304ms)","trace[2081464515] 'applied index is now lower than readState.Index' (duration: 164.058126ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:12:59.84526Z","caller":"traceutil/trace.go:171","msg":"trace[83798479] transaction","detail":"{read_only:false; response_revision:26099; number_of_response:1; }","duration":"209.874217ms","start":"2026-10-09T03:12:59.635364Z","end":"2026-10-09T03:12:59.845238Z","steps":["trace[83798479] 'process raft request' (duration: 45.655316ms)","trace[83798479] 'compare' (duration: 163.590924ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:12:59.845325Z","caller":"traceutil/trace.go:171","msg":"trace[975344733] transaction","detail":"{read_only:false; response_revision:26100; number_of_response:1; }","duration":"209.284451ms","start":"2026-10-09T03:12:59.635985Z","end":"2026-10-09T03:12:59.84527Z","steps":["trace[975344733] 'process raft request' (duration: 209.022064ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:12:59.845713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.600627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-09T03:12:59.845788Z","caller":"traceutil/trace.go:171","msg":"trace[1196555500] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26100; }","duration":"206.70844ms","start":"2026-10-09T03:12:59.639061Z","end":"2026-10-09T03:12:59.84577Z","steps":["trace[1196555500] 'agreement among raft nodes before linearized reading' (duration: 206.223327ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:12:59.84986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.789109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-09T03:12:59.849975Z","caller":"traceutil/trace.go:171","msg":"trace[596500255] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:26101; }","duration":"143.975964ms","start":"2026-10-09T03:12:59.705971Z","end":"2026-10-09T03:12:59.849947Z","steps":["trace[596500255] 'agreement among raft nodes before linearized reading' (duration: 143.734117ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:13:05.151957Z","caller":"traceutil/trace.go:171","msg":"trace[442635904] transaction","detail":"{read_only:false; response_revision:26139; number_of_response:1; }","duration":"110.519203ms","start":"2026-10-09T03:13:05.041371Z","end":"2026-10-09T03:13:05.15189Z","steps":["trace[442635904] 'process raft request' (duration: 110.275386ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:13:05.39781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.607218ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T03:13:05.397945Z","caller":"traceutil/trace.go:171","msg":"trace[1273283605] linearizableReadLoop","detail":"{readStateIndex:27001; appliedIndex:27000; }","duration":"118.514812ms","start":"2026-10-09T03:13:05.279408Z","end":"2026-10-09T03:13:05.397923Z","steps":["trace[1273283605] 'read index received' (duration: 641.937µs)","trace[1273283605] 'applied index is now lower than readState.Index' (duration: 117.871235ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:13:05.397999Z","caller":"traceutil/trace.go:171","msg":"trace[434044632] transaction","detail":"{read_only:false; response_revision:26140; number_of_response:1; }","duration":"238.584189ms","start":"2026-10-09T03:13:05.159384Z","end":"2026-10-09T03:13:05.397968Z","steps":["trace[434044632] 'process raft request' (duration: 120.718214ms)","trace[434044632] 'compare' (duration: 117.299679ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:13:05.398117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.631406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T03:13:05.3982Z","caller":"traceutil/trace.go:171","msg":"trace[2038319644] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:26140; }","duration":"118.836331ms","start":"2026-10-09T03:13:05.279342Z","end":"2026-10-09T03:13:05.398179Z","steps":["trace[2038319644] 'agreement among raft nodes before linearized reading' (duration: 118.640146ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:13:05.709925Z","caller":"traceutil/trace.go:171","msg":"trace[743565722] transaction","detail":"{read_only:false; response_revision:26141; number_of_response:1; }","duration":"112.407805ms","start":"2026-10-09T03:13:05.597466Z","end":"2026-10-09T03:13:05.709873Z","steps":["trace[743565722] 'process raft request' (duration: 112.198369ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:13:05.93929Z","caller":"traceutil/trace.go:171","msg":"trace[1670133508] linearizableReadLoop","detail":"{readStateIndex:27005; appliedIndex:27004; }","duration":"223.806622ms","start":"2026-10-09T03:13:05.715442Z","end":"2026-10-09T03:13:05.939249Z","steps":["trace[1670133508] 'read index received' (duration: 191.097841ms)","trace[1670133508] 'applied index is now lower than readState.Index' (duration: 32.707291ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:13:05.939813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.348427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/openstack/container-infra\" ","response":"range_response_count:1 size:729"} {"level":"info","ts":"2026-10-09T03:13:05.93991Z","caller":"traceutil/trace.go:171","msg":"trace[1660827056] range","detail":"{range_begin:/registry/ingress/openstack/container-infra; range_end:; response_count:1; response_revision:26143; }","duration":"224.4835ms","start":"2026-10-09T03:13:05.715404Z","end":"2026-10-09T03:13:05.939888Z","steps":["trace[1660827056] 'agreement among raft nodes before linearized reading' (duration: 224.224213ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:13:05.960896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.353985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/openstack/container-infra-registry\" ","response":"range_response_count:1 size:784"} {"level":"info","ts":"2026-10-09T03:13:05.960974Z","caller":"traceutil/trace.go:171","msg":"trace[552721304] range","detail":"{range_begin:/registry/ingress/openstack/container-infra-registry; range_end:; response_count:1; response_revision:26144; }","duration":"245.484068ms","start":"2026-10-09T03:13:05.715472Z","end":"2026-10-09T03:13:05.960956Z","steps":["trace[552721304] 'agreement among raft nodes before linearized reading' (duration: 245.253342ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:13:05.961005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.084116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusterclasses/\" range_end:\"/registry/cluster.x-k8s.io/clusterclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T03:13:05.961079Z","caller":"traceutil/trace.go:171","msg":"trace[546766132] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusterclasses/; range_end:/registry/cluster.x-k8s.io/clusterclasses0; response_count:0; response_revision:26144; }","duration":"108.213649ms","start":"2026-10-09T03:13:05.852845Z","end":"2026-10-09T03:13:05.961059Z","steps":["trace[546766132] 'agreement among raft nodes before linearized reading' (duration: 108.098486ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:13:06.191858Z","caller":"traceutil/trace.go:171","msg":"trace[948200889] transaction","detail":"{read_only:false; response_revision:26148; number_of_response:1; }","duration":"226.116426ms","start":"2026-10-09T03:13:05.965717Z","end":"2026-10-09T03:13:06.191834Z","steps":["trace[948200889] 'process raft request' (duration: 192.944452ms)","trace[948200889] 'compare' (duration: 33.007639ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:13:06.192131Z","caller":"traceutil/trace.go:171","msg":"trace[86019617] transaction","detail":"{read_only:false; response_revision:26149; number_of_response:1; }","duration":"226.27106ms","start":"2026-10-09T03:13:05.96582Z","end":"2026-10-09T03:13:06.192091Z","steps":["trace[86019617] 'process raft request' (duration: 225.975532ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:13:06.192938Z","caller":"traceutil/trace.go:171","msg":"trace[122970342] linearizableReadLoop","detail":"{readStateIndex:27012; appliedIndex:27009; }","duration":"215.105402ms","start":"2026-10-09T03:13:05.977791Z","end":"2026-10-09T03:13:06.192896Z","steps":["trace[122970342] 'read index received' (duration: 181.031974ms)","trace[122970342] 'applied index is now lower than readState.Index' (duration: 34.070988ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:13:06.192984Z","caller":"traceutil/trace.go:171","msg":"trace[938509137] transaction","detail":"{read_only:false; response_revision:26150; number_of_response:1; }","duration":"227.110983ms","start":"2026-10-09T03:13:05.965854Z","end":"2026-10-09T03:13:06.192965Z","steps":["trace[938509137] 'process raft request' (duration: 226.098635ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:13:06.193252Z","caller":"traceutil/trace.go:171","msg":"trace[1236181651] transaction","detail":"{read_only:false; response_revision:26152; number_of_response:1; }","duration":"152.405286ms","start":"2026-10-09T03:13:06.040828Z","end":"2026-10-09T03:13:06.193233Z","steps":["trace[1236181651] 'process raft request' (duration: 152.16833ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:13:06.193269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.788034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-10-09T03:13:06.194225Z","caller":"traceutil/trace.go:171","msg":"trace[473287820] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:26151; }","duration":"227.780191ms","start":"2026-10-09T03:13:05.966424Z","end":"2026-10-09T03:13:06.194204Z","steps":["trace[473287820] 'agreement among raft nodes before linearized reading' (duration: 226.6605ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:13:06.193233Z","caller":"traceutil/trace.go:171","msg":"trace[1678651574] transaction","detail":"{read_only:false; response_revision:26151; number_of_response:1; }","duration":"212.611444ms","start":"2026-10-09T03:13:05.980596Z","end":"2026-10-09T03:13:06.193208Z","steps":["trace[1678651574] 'process raft request' (duration: 212.160021ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:13:06.413114Z","caller":"traceutil/trace.go:171","msg":"trace[1306822700] linearizableReadLoop","detail":"{readStateIndex:27016; appliedIndex:27015; }","duration":"207.467162ms","start":"2026-10-09T03:13:06.205628Z","end":"2026-10-09T03:13:06.413095Z","steps":["trace[1306822700] 'read index received' (duration: 207.327778ms)","trace[1306822700] 'applied index is now lower than readState.Index' (duration: 138.354µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:13:06.413232Z","caller":"traceutil/trace.go:171","msg":"trace[418694040] transaction","detail":"{read_only:false; response_revision:26154; number_of_response:1; }","duration":"213.57038ms","start":"2026-10-09T03:13:06.19964Z","end":"2026-10-09T03:13:06.413211Z","steps":["trace[418694040] 'process raft request' (duration: 213.317573ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:13:06.413349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.707139ms","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-09T03:13:06.413431Z","caller":"traceutil/trace.go:171","msg":"trace[2061810287] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26154; }","duration":"207.820822ms","start":"2026-10-09T03:13:06.2056Z","end":"2026-10-09T03:13:06.41342Z","steps":["trace[2061810287] 'agreement among raft nodes before linearized reading' (duration: 207.631407ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:13:06.656346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.187614ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T03:13:06.656555Z","caller":"traceutil/trace.go:171","msg":"trace[814219206] transaction","detail":"{read_only:false; response_revision:26156; number_of_response:1; }","duration":"238.882506ms","start":"2026-10-09T03:13:06.417655Z","end":"2026-10-09T03:13:06.656537Z","steps":["trace[814219206] 'process raft request' (duration: 238.809784ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:13:06.656645Z","caller":"traceutil/trace.go:171","msg":"trace[341360906] transaction","detail":"{read_only:false; response_revision:26155; number_of_response:1; }","duration":"347.203899ms","start":"2026-10-09T03:13:06.309409Z","end":"2026-10-09T03:13:06.656612Z","steps":["trace[341360906] 'process raft request' (duration: 208.617374ms)","trace[341360906] 'compare' (duration: 138.0391ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:13:06.656773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.822393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-09T03:13:06.656842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.852741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-10-09T03:13:06.656872Z","caller":"traceutil/trace.go:171","msg":"trace[1927028523] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:26156; }","duration":"243.941466ms","start":"2026-10-09T03:13:06.412908Z","end":"2026-10-09T03:13:06.656849Z","steps":["trace[1927028523] 'agreement among raft nodes before linearized reading' (duration: 243.74796ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:13:06.656898Z","caller":"traceutil/trace.go:171","msg":"trace[1317291476] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26156; }","duration":"406.947434ms","start":"2026-10-09T03:13:06.249931Z","end":"2026-10-09T03:13:06.656878Z","steps":["trace[1317291476] 'agreement among raft nodes before linearized reading' (duration: 406.771569ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:13:06.656815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:13:06.309282Z","time spent":"347.434845ms","remote":"127.0.0.1:56916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T03:13:06.656946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:13:06.24991Z","time spent":"407.022076ms","remote":"127.0.0.1:56916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-10-09T03:13:06.656665Z","caller":"traceutil/trace.go:171","msg":"trace[289379936] linearizableReadLoop","detail":"{readStateIndex:27017; appliedIndex:27016; }","duration":"243.434112ms","start":"2026-10-09T03:13:06.413208Z","end":"2026-10-09T03:13:06.656642Z","steps":["trace[289379936] 'read index received' (duration: 104.97407ms)","trace[289379936] 'applied index is now lower than readState.Index' (duration: 138.456952ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:13:07.046565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.566169ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T03:13:07.046698Z","caller":"traceutil/trace.go:171","msg":"trace[769030781] transaction","detail":"{read_only:false; response_revision:26157; number_of_response:1; }","duration":"384.882565ms","start":"2026-10-09T03:13:06.661793Z","end":"2026-10-09T03:13:07.046676Z","steps":["trace[769030781] 'process raft request' (duration: 207.108911ms)","trace[769030781] 'compare' (duration: 177.218629ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:13:07.046762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:13:06.661768Z","time spent":"384.958897ms","remote":"127.0.0.1:56916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T03:13:07.698099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.26937ms","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-09T03:13:07.698202Z","caller":"traceutil/trace.go:171","msg":"trace[707163325] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:26158; }","duration":"165.402524ms","start":"2026-10-09T03:13:07.532778Z","end":"2026-10-09T03:13:07.69818Z","steps":["trace[707163325] 'range keys from in-memory index tree' (duration: 165.200058ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:13:07.875592Z","caller":"traceutil/trace.go:171","msg":"trace[335848341] linearizableReadLoop","detail":"{readStateIndex:27021; appliedIndex:27020; }","duration":"165.149877ms","start":"2026-10-09T03:13:07.710406Z","end":"2026-10-09T03:13:07.875555Z","steps":["trace[335848341] 'read index received' (duration: 164.984312ms)","trace[335848341] 'applied index is now lower than readState.Index' (duration: 163.185µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:13:07.875645Z","caller":"traceutil/trace.go:171","msg":"trace[1497777304] transaction","detail":"{read_only:false; response_revision:26159; number_of_response:1; }","duration":"231.502963ms","start":"2026-10-09T03:13:07.644117Z","end":"2026-10-09T03:13:07.87562Z","steps":["trace[1497777304] 'process raft request' (duration: 231.177494ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:13:07.875778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.347092ms","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-09T03:13:07.875838Z","caller":"traceutil/trace.go:171","msg":"trace[1949534751] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:26159; }","duration":"165.464625ms","start":"2026-10-09T03:13:07.710355Z","end":"2026-10-09T03:13:07.87582Z","steps":["trace[1949534751] 'agreement among raft nodes before linearized reading' (duration: 165.317841ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:13:08.058244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.395656ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T03:13:08.058418Z","caller":"traceutil/trace.go:171","msg":"trace[867156121] transaction","detail":"{read_only:false; response_revision:26160; number_of_response:1; }","duration":"342.929101ms","start":"2026-10-09T03:13:07.715456Z","end":"2026-10-09T03:13:08.058385Z","steps":["trace[867156121] 'process raft request' (duration: 190.31086ms)","trace[867156121] 'compare' (duration: 152.156848ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:13:08.05848Z","caller":"traceutil/trace.go:171","msg":"trace[1854037822] transaction","detail":"{read_only:false; response_revision:26161; number_of_response:1; }","duration":"340.450053ms","start":"2026-10-09T03:13:07.717992Z","end":"2026-10-09T03:13:08.058442Z","steps":["trace[1854037822] 'process raft request' (duration: 340.332779ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:13:08.058441Z","caller":"traceutil/trace.go:171","msg":"trace[362889185] transaction","detail":"{read_only:false; response_revision:26162; number_of_response:1; }","duration":"339.487226ms","start":"2026-10-09T03:13:07.718937Z","end":"2026-10-09T03:13:08.058425Z","steps":["trace[362889185] 'process raft request' (duration: 339.441505ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:13:08.058591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:13:07.715429Z","time spent":"343.077415ms","remote":"127.0.0.1:56916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T03:13:08.058616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:13:07.717957Z","time spent":"340.580926ms","remote":"127.0.0.1:56916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T03:13:08.058744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:13:07.718927Z","time spent":"339.685631ms","remote":"127.0.0.1:56916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T03:13:08.38285Z","caller":"traceutil/trace.go:171","msg":"trace[1342750188] linearizableReadLoop","detail":"{readStateIndex:27026; appliedIndex:27025; }","duration":"185.731163ms","start":"2026-10-09T03:13:08.197097Z","end":"2026-10-09T03:13:08.382828Z","steps":["trace[1342750188] 'read index received' (duration: 185.60465ms)","trace[1342750188] 'applied index is now lower than readState.Index' (duration: 125.353µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:13:08.383082Z","caller":"traceutil/trace.go:171","msg":"trace[1263264747] transaction","detail":"{read_only:false; response_revision:26164; number_of_response:1; }","duration":"186.024251ms","start":"2026-10-09T03:13:08.197043Z","end":"2026-10-09T03:13:08.383068Z","steps":["trace[1263264747] 'process raft request' (duration: 185.64375ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:13:08.383495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.362091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-09T03:13:08.383596Z","caller":"traceutil/trace.go:171","msg":"trace[399776395] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26164; }","duration":"186.503944ms","start":"2026-10-09T03:13:08.197069Z","end":"2026-10-09T03:13:08.383573Z","steps":["trace[399776395] 'agreement among raft nodes before linearized reading' (duration: 186.190945ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:13:08.697748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.316479ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T03:13:08.697904Z","caller":"traceutil/trace.go:171","msg":"trace[553228568] transaction","detail":"{read_only:false; response_revision:26165; number_of_response:1; }","duration":"497.731313ms","start":"2026-10-09T03:13:08.200153Z","end":"2026-10-09T03:13:08.697884Z","steps":["trace[553228568] 'process raft request' (duration: 258.201849ms)","trace[553228568] 'compare' (duration: 239.201065ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:13:08.697984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:13:08.200125Z","time spent":"497.828135ms","remote":"127.0.0.1:56916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T03:13:08.698457Z","caller":"traceutil/trace.go:171","msg":"trace[862720885] linearizableReadLoop","detail":"{readStateIndex:27028; appliedIndex:27026; }","duration":"315.528108ms","start":"2026-10-09T03:13:08.382904Z","end":"2026-10-09T03:13:08.698432Z","steps":["trace[862720885] 'read index received' (duration: 75.53724ms)","trace[862720885] 'applied index is now lower than readState.Index' (duration: 239.989987ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:13:08.698594Z","caller":"traceutil/trace.go:171","msg":"trace[2076572580] transaction","detail":"{read_only:false; response_revision:26166; number_of_response:1; }","duration":"460.978851ms","start":"2026-10-09T03:13:08.237581Z","end":"2026-10-09T03:13:08.69856Z","steps":["trace[2076572580] 'process raft request' (duration: 460.256611ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:13:08.698634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.081606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T03:13:08.698671Z","caller":"traceutil/trace.go:171","msg":"trace[999025297] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26166; }","duration":"464.158599ms","start":"2026-10-09T03:13:08.2345Z","end":"2026-10-09T03:13:08.698658Z","steps":["trace[999025297] 'agreement among raft nodes before linearized reading' (duration: 464.061346ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:13:08.698711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:13:08.234473Z","time spent":"464.228201ms","remote":"127.0.0.1:56646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-09T03:13:08.698731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:13:08.23756Z","time spent":"461.101005ms","remote":"127.0.0.1:56916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T03:13:08.698783Z","caller":"traceutil/trace.go:171","msg":"trace[1542912405] transaction","detail":"{read_only:false; response_revision:26167; number_of_response:1; }","duration":"310.724545ms","start":"2026-10-09T03:13:08.388025Z","end":"2026-10-09T03:13:08.698749Z","steps":["trace[1542912405] 'process raft request' (duration: 310.305344ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:13:08.698881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.183668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-10-09T03:13:08.698896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:13:08.388Z","time spent":"310.831658ms","remote":"127.0.0.1:56916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T03:13:08.698938Z","caller":"traceutil/trace.go:171","msg":"trace[1309796747] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26167; }","duration":"501.26002ms","start":"2026-10-09T03:13:08.197662Z","end":"2026-10-09T03:13:08.698922Z","steps":["trace[1309796747] 'agreement among raft nodes before linearized reading' (duration: 500.943251ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:13:08.698981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:13:08.197655Z","time spent":"501.314701ms","remote":"127.0.0.1:56916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-09T03:13:08.725034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.827433ms","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-09T03:13:08.725097Z","caller":"traceutil/trace.go:171","msg":"trace[454990939] 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:26168; }","duration":"194.921326ms","start":"2026-10-09T03:13:08.530158Z","end":"2026-10-09T03:13:08.72508Z","steps":["trace[454990939] 'agreement among raft nodes before linearized reading' (duration: 194.72769ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:13:08.892431Z","caller":"traceutil/trace.go:171","msg":"trace[871923271] linearizableReadLoop","detail":"{readStateIndex:27032; appliedIndex:27031; }","duration":"143.188412ms","start":"2026-10-09T03:13:08.749213Z","end":"2026-10-09T03:13:08.892402Z","steps":["trace[871923271] 'read index received' (duration: 143.036658ms)","trace[871923271] 'applied index is now lower than readState.Index' (duration: 150.594µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:13:08.892498Z","caller":"traceutil/trace.go:171","msg":"trace[439190914] transaction","detail":"{read_only:false; response_revision:26170; number_of_response:1; }","duration":"163.273965ms","start":"2026-10-09T03:13:08.729168Z","end":"2026-10-09T03:13:08.892442Z","steps":["trace[439190914] 'process raft request' (duration: 163.094621ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:13:08.892668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.224641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-10-09T03:13:08.892707Z","caller":"traceutil/trace.go:171","msg":"trace[1011991544] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26170; }","duration":"164.301143ms","start":"2026-10-09T03:13:08.728395Z","end":"2026-10-09T03:13:08.892697Z","steps":["trace[1011991544] 'agreement among raft nodes before linearized reading' (duration: 164.142659ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:13:09.230931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.340838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"warn","ts":"2026-10-09T03:13:09.230981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.193042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5684"} {"level":"info","ts":"2026-10-09T03:13:09.231041Z","caller":"traceutil/trace.go:171","msg":"trace[319201939] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:26170; }","duration":"236.271765ms","start":"2026-10-09T03:13:08.994748Z","end":"2026-10-09T03:13:09.23102Z","steps":["trace[319201939] 'range keys from in-memory index tree' (duration: 236.083849ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:13:09.231002Z","caller":"traceutil/trace.go:171","msg":"trace[1982277327] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26170; }","duration":"179.43571ms","start":"2026-10-09T03:13:09.051542Z","end":"2026-10-09T03:13:09.230978Z","steps":["trace[1982277327] 'range keys from in-memory index tree' (duration: 179.226915ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:13:09.479829Z","caller":"traceutil/trace.go:171","msg":"trace[941311003] transaction","detail":"{read_only:false; response_revision:26171; number_of_response:1; }","duration":"244.539902ms","start":"2026-10-09T03:13:09.235271Z","end":"2026-10-09T03:13:09.479811Z","steps":["trace[941311003] 'process raft request' (duration: 244.412149ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:13:22.112806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.074457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9b58f955-258d-49eb-a4cb-b37b0a072bb0\" ","response":"range_response_count:1 size:3644"} {"level":"warn","ts":"2026-10-09T03:13:22.112861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.965406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9b58f955-258d-49eb-a4cb-b37b0a072bb0\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-10-09T03:13:22.112902Z","caller":"traceutil/trace.go:171","msg":"trace[770262178] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9b58f955-258d-49eb-a4cb-b37b0a072bb0; range_end:; response_count:1; response_revision:26320; }","duration":"399.221951ms","start":"2026-10-09T03:13:21.713652Z","end":"2026-10-09T03:13:22.112874Z","steps":["trace[770262178] 'range keys from in-memory index tree' (duration: 398.881811ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:13:22.112956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:13:21.713629Z","time spent":"399.313684ms","remote":"127.0.0.1:56824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3668,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9b58f955-258d-49eb-a4cb-b37b0a072bb0\" "} {"level":"info","ts":"2026-10-09T03:13:22.112962Z","caller":"traceutil/trace.go:171","msg":"trace[430116238] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9b58f955-258d-49eb-a4cb-b37b0a072bb0; range_end:; response_count:1; response_revision:26320; }","duration":"135.074759ms","start":"2026-10-09T03:13:21.977836Z","end":"2026-10-09T03:13:22.112911Z","steps":["trace[430116238] 'range keys from in-memory index tree' (duration: 134.815282ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:13:22.679684Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10636553572155584069,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-09T03:13:22.81968Z","caller":"traceutil/trace.go:171","msg":"trace[1908803265] transaction","detail":"{read_only:false; response_revision:26321; number_of_response:1; }","duration":"708.694981ms","start":"2026-10-09T03:13:22.110958Z","end":"2026-10-09T03:13:22.819653Z","steps":["trace[1908803265] 'process raft request' (duration: 708.538156ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:13:22.819861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:13:22.11094Z","time spent":"708.832425ms","remote":"127.0.0.1:56916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T03:13:23.270411Z","caller":"traceutil/trace.go:171","msg":"trace[1532762894] transaction","detail":"{read_only:false; response_revision:26325; number_of_response:1; }","duration":"1.142152255s","start":"2026-10-09T03:13:22.128221Z","end":"2026-10-09T03:13:23.270373Z","steps":["trace[1532762894] 'process raft request' (duration: 1.142043402s)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:13:23.270426Z","caller":"traceutil/trace.go:171","msg":"trace[1380501815] linearizableReadLoop","detail":"{readStateIndex:27192; appliedIndex:27187; }","duration":"1.091657804s","start":"2026-10-09T03:13:22.178746Z","end":"2026-10-09T03:13:23.270404Z","steps":["trace[1380501815] 'read index received' (duration: 641.637395ms)","trace[1380501815] 'applied index is now lower than readState.Index' (duration: 450.019539ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:13:23.270418Z","caller":"traceutil/trace.go:171","msg":"trace[980748891] transaction","detail":"{read_only:false; response_revision:26323; number_of_response:1; }","duration":"1.148337705s","start":"2026-10-09T03:13:22.122044Z","end":"2026-10-09T03:13:23.270381Z","steps":["trace[980748891] 'process raft request' (duration: 1.148070508s)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:13:23.27055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.091785468s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-10-09T03:13:23.271193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:13:22.122034Z","time spent":"1.149027034s","remote":"127.0.0.1:56916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T03:13:23.271217Z","caller":"traceutil/trace.go:171","msg":"trace[92060032] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/; range_end:/registry/ceph.rook.io/cephclusters0; response_count:0; response_revision:26328; }","duration":"1.092497277s","start":"2026-10-09T03:13:22.17869Z","end":"2026-10-09T03:13:23.271187Z","steps":["trace[92060032] 'agreement among raft nodes before linearized reading' (duration: 1.091785957s)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:13:23.271415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:13:22.178661Z","time spent":"1.092728554s","remote":"127.0.0.1:56500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":32,"request content":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" count_only:true "} {"level":"info","ts":"2026-10-09T03:13:23.270538Z","caller":"traceutil/trace.go:171","msg":"trace[1652264245] transaction","detail":"{read_only:false; response_revision:26322; number_of_response:1; }","duration":"1.149222279s","start":"2026-10-09T03:13:22.121284Z","end":"2026-10-09T03:13:23.270507Z","steps":["trace[1652264245] 'process raft request' (duration: 1.131869601s)","trace[1652264245] 'compare' (duration: 16.538826ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:13:23.270589Z","caller":"traceutil/trace.go:171","msg":"trace[2048463491] transaction","detail":"{read_only:false; response_revision:26326; number_of_response:1; }","duration":"838.507705ms","start":"2026-10-09T03:13:22.432071Z","end":"2026-10-09T03:13:23.270579Z","steps":["trace[2048463491] 'process raft request' (duration: 838.230138ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:13:23.271682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:13:22.121259Z","time spent":"1.150253437s","remote":"127.0.0.1:56824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3746,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T03:13:23.271727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:13:22.432048Z","time spent":"839.630416ms","remote":"127.0.0.1:56916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T03:13:23.270622Z","caller":"traceutil/trace.go:171","msg":"trace[1416581153] transaction","detail":"{read_only:false; response_revision:26327; number_of_response:1; }","duration":"514.3445ms","start":"2026-10-09T03:13:22.756267Z","end":"2026-10-09T03:13:23.270611Z","steps":["trace[1416581153] 'process raft request' (duration: 514.069493ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:13:23.270617Z","caller":"traceutil/trace.go:171","msg":"trace[1508284729] transaction","detail":"{read_only:false; response_revision:26324; number_of_response:1; }","duration":"1.142455173s","start":"2026-10-09T03:13:22.128148Z","end":"2026-10-09T03:13:23.270603Z","steps":["trace[1508284729] 'process raft request' (duration: 1.142081533s)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:13:23.271936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:13:22.756243Z","time spent":"515.553564ms","remote":"127.0.0.1:56916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T03:13:23.272041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:13:22.128117Z","time spent":"1.143845681s","remote":"127.0.0.1:56916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T03:13:23.270649Z","caller":"traceutil/trace.go:171","msg":"trace[38780650] transaction","detail":"{read_only:false; response_revision:26328; number_of_response:1; }","duration":"497.351672ms","start":"2026-10-09T03:13:22.773291Z","end":"2026-10-09T03:13:23.270642Z","steps":["trace[38780650] 'process raft request' (duration: 497.075344ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:13:23.272273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:13:22.773266Z","time spent":"498.917905ms","remote":"127.0.0.1:56916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T03:13:23.270918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:13:22.128189Z","time spent":"1.142651068s","remote":"127.0.0.1:56916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T03:13:23.291322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"761.189927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T03:13:23.291397Z","caller":"traceutil/trace.go:171","msg":"trace[1185609782] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:26329; }","duration":"761.346761ms","start":"2026-10-09T03:13:22.530033Z","end":"2026-10-09T03:13:23.29138Z","steps":["trace[1185609782] 'agreement among raft nodes before linearized reading' (duration: 761.203747ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:13:23.291441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:13:22.530002Z","time spent":"761.427282ms","remote":"127.0.0.1:56848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":30,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-10-09T03:13:23.291481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.484283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-10-09T03:13:23.291544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.648243ms","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-09T03:13:23.291567Z","caller":"traceutil/trace.go:171","msg":"trace[2122085365] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26329; }","duration":"312.618537ms","start":"2026-10-09T03:13:22.978926Z","end":"2026-10-09T03:13:23.291545Z","steps":["trace[2122085365] 'agreement among raft nodes before linearized reading' (duration: 312.355179ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:13:23.291595Z","caller":"traceutil/trace.go:171","msg":"trace[596006540] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26329; }","duration":"508.722566ms","start":"2026-10-09T03:13:22.782859Z","end":"2026-10-09T03:13:23.291581Z","steps":["trace[596006540] 'agreement among raft nodes before linearized reading' (duration: 508.53016ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:13:23.291612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:13:22.978905Z","time spent":"312.697659ms","remote":"127.0.0.1:56916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":506,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-09T03:13:23.291625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:13:22.78285Z","time spent":"508.767507ms","remote":"127.0.0.1:56916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-09T03:13:23.291617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.131805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T03:13:23.291676Z","caller":"traceutil/trace.go:171","msg":"trace[334973201] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26329; }","duration":"125.269569ms","start":"2026-10-09T03:13:23.16639Z","end":"2026-10-09T03:13:23.291659Z","steps":["trace[334973201] 'agreement among raft nodes before linearized reading' (duration: 125.067393ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:13:23.291747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.358743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9b58f955-258d-49eb-a4cb-b37b0a072bb0\" ","response":"range_response_count:1 size:3764"} {"level":"warn","ts":"2026-10-09T03:13:23.291749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.887725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-10-09T03:13:23.291771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.000155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-09T03:13:23.29178Z","caller":"traceutil/trace.go:171","msg":"trace[903397479] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9b58f955-258d-49eb-a4cb-b37b0a072bb0; range_end:; response_count:1; response_revision:26329; }","duration":"173.426475ms","start":"2026-10-09T03:13:23.118341Z","end":"2026-10-09T03:13:23.291768Z","steps":["trace[903397479] 'agreement among raft nodes before linearized reading' (duration: 173.367563ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:13:23.291796Z","caller":"traceutil/trace.go:171","msg":"trace[1056828187] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26329; }","duration":"468.061646ms","start":"2026-10-09T03:13:22.823725Z","end":"2026-10-09T03:13:23.291787Z","steps":["trace[1056828187] 'agreement among raft nodes before linearized reading' (duration: 467.987194ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:13:23.291795Z","caller":"traceutil/trace.go:171","msg":"trace[1966631522] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26329; }","duration":"510.961517ms","start":"2026-10-09T03:13:22.780817Z","end":"2026-10-09T03:13:23.291778Z","steps":["trace[1966631522] 'agreement among raft nodes before linearized reading' (duration: 510.836044ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:13:23.291819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:13:22.823705Z","time spent":"468.107818ms","remote":"127.0.0.1:56916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-09T03:13:23.291848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:13:22.780805Z","time spent":"511.014589ms","remote":"127.0.0.1:56916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":465,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-09T03:13:23.291918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.887817ms","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":"info","ts":"2026-10-09T03:13:23.291942Z","caller":"traceutil/trace.go:171","msg":"trace[1511063869] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:26329; }","duration":"489.967329ms","start":"2026-10-09T03:13:22.801967Z","end":"2026-10-09T03:13:23.291934Z","steps":["trace[1511063869] 'agreement among raft nodes before linearized reading' (duration: 489.907888ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:13:23.291966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.574077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T03:13:23.291947Z","caller":"traceutil/trace.go:171","msg":"trace[133760857] transaction","detail":"{read_only:false; response_revision:26329; number_of_response:1; }","duration":"395.987212ms","start":"2026-10-09T03:13:22.895945Z","end":"2026-10-09T03:13:23.291932Z","steps":["trace[133760857] 'process raft request' (duration: 395.098858ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:13:23.292002Z","caller":"traceutil/trace.go:171","msg":"trace[821907334] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:26329; }","duration":"591.630978ms","start":"2026-10-09T03:13:22.700357Z","end":"2026-10-09T03:13:23.291988Z","steps":["trace[821907334] 'agreement among raft nodes before linearized reading' (duration: 591.572176ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:13:23.292012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.391086ms","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-09T03:13:23.29204Z","caller":"traceutil/trace.go:171","msg":"trace[1248784478] 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:26329; }","duration":"508.467229ms","start":"2026-10-09T03:13:22.783562Z","end":"2026-10-09T03:13:23.292029Z","steps":["trace[1248784478] 'agreement among raft nodes before linearized reading' (duration: 508.423788ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:13:23.292034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:13:22.700347Z","time spent":"591.67837ms","remote":"127.0.0.1:56864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":30,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-10-09T03:13:23.292065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.791259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T03:13:23.2921Z","caller":"traceutil/trace.go:171","msg":"trace[340507841] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:26329; }","duration":"131.851451ms","start":"2026-10-09T03:13:23.160238Z","end":"2026-10-09T03:13:23.29209Z","steps":["trace[340507841] 'agreement among raft nodes before linearized reading' (duration: 131.792189ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:13:23.292067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:13:22.895918Z","time spent":"396.084195ms","remote":"127.0.0.1:56916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T03:13:23.292376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.8848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/images/\" range_end:\"/registry/openstack.k-orc.cloud/images0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-09T03:13:23.291969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:13:22.801937Z","time spent":"490.024731ms","remote":"127.0.0.1:57096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":22,"response size":32,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"info","ts":"2026-10-09T03:13:23.292417Z","caller":"traceutil/trace.go:171","msg":"trace[1054112363] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/images/; range_end:/registry/openstack.k-orc.cloud/images0; response_count:0; response_revision:26329; }","duration":"601.963712ms","start":"2026-10-09T03:13:22.690439Z","end":"2026-10-09T03:13:23.292402Z","steps":["trace[1054112363] 'agreement among raft nodes before linearized reading' (duration: 601.89241ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:13:23.292445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:13:22.690421Z","time spent":"602.016004ms","remote":"127.0.0.1:57914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":30,"request content":"key:\"/registry/openstack.k-orc.cloud/images/\" range_end:\"/registry/openstack.k-orc.cloud/images0\" count_only:true "} {"level":"warn","ts":"2026-10-09T03:13:23.292064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:13:22.783535Z","time spent":"508.5213ms","remote":"127.0.0.1:52848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":30,"request content":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true "} {"level":"warn","ts":"2026-10-09T03:13:23.293036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.990232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T03:13:23.293153Z","caller":"traceutil/trace.go:171","msg":"trace[694104431] range","detail":"{range_begin:/registry/monitoring.coreos.com/scrapeconfigs/; range_end:/registry/monitoring.coreos.com/scrapeconfigs0; response_count:0; response_revision:26329; }","duration":"157.077485ms","start":"2026-10-09T03:13:23.135993Z","end":"2026-10-09T03:13:23.29307Z","steps":["trace[694104431] 'agreement among raft nodes before linearized reading' (duration: 157.000223ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:13:23.679813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.055576ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T03:13:23.679985Z","caller":"traceutil/trace.go:171","msg":"trace[2033187880] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:26333; }","duration":"233.234181ms","start":"2026-10-09T03:13:23.446719Z","end":"2026-10-09T03:13:23.679953Z","steps":["trace[2033187880] 'range keys from in-memory index tree' (duration: 233.034205ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:13:23.680073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.852064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T03:13:23.680205Z","caller":"traceutil/trace.go:171","msg":"trace[4130365] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:26333; }","duration":"118.073731ms","start":"2026-10-09T03:13:23.562101Z","end":"2026-10-09T03:13:23.680175Z","steps":["trace[4130365] 'count revisions from in-memory index tree' (duration: 117.711781ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:13:23.680531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.992805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-09T03:13:23.680646Z","caller":"traceutil/trace.go:171","msg":"trace[199565104] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26333; }","duration":"103.52485ms","start":"2026-10-09T03:13:23.577097Z","end":"2026-10-09T03:13:23.680622Z","steps":["trace[199565104] 'range keys from in-memory index tree' (duration: 102.688297ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:13:23.893339Z","caller":"traceutil/trace.go:171","msg":"trace[514365020] transaction","detail":"{read_only:false; response_revision:26335; number_of_response:1; }","duration":"205.97199ms","start":"2026-10-09T03:13:23.687297Z","end":"2026-10-09T03:13:23.893269Z","steps":["trace[514365020] 'process raft request' (duration: 180.50916ms)","trace[514365020] 'compare' (duration: 25.063269ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:13:24.223485Z","caller":"traceutil/trace.go:171","msg":"trace[830617604] transaction","detail":"{read_only:false; response_revision:26336; number_of_response:1; }","duration":"179.944853ms","start":"2026-10-09T03:13:24.043498Z","end":"2026-10-09T03:13:24.223443Z","steps":["trace[830617604] 'process raft request' (duration: 94.044939ms)","trace[830617604] 'compare' (duration: 85.689809ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:13:25.287428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.298277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T03:13:25.287525Z","caller":"traceutil/trace.go:171","msg":"trace[1517126985] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26338; }","duration":"118.441161ms","start":"2026-10-09T03:13:25.16905Z","end":"2026-10-09T03:13:25.287491Z","steps":["trace[1517126985] 'range keys from in-memory index tree' (duration: 118.184844ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:13:25.287522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.273907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-09T03:13:25.287679Z","caller":"traceutil/trace.go:171","msg":"trace[2033252123] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:26338; }","duration":"292.522774ms","start":"2026-10-09T03:13:24.995115Z","end":"2026-10-09T03:13:25.287638Z","steps":["trace[2033252123] 'count revisions from in-memory index tree' (duration: 292.164884ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:13:30.747414Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006a87.wal"} {"level":"info","ts":"2026-10-09T03:13:54.821465Z","caller":"traceutil/trace.go:171","msg":"trace[861990730] linearizableReadLoop","detail":"{readStateIndex:27471; appliedIndex:27470; }","duration":"259.396861ms","start":"2026-10-09T03:13:54.562045Z","end":"2026-10-09T03:13:54.821442Z","steps":["trace[861990730] 'read index received' (duration: 259.114553ms)","trace[861990730] 'applied index is now lower than readState.Index' (duration: 281.438µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:13:54.821537Z","caller":"traceutil/trace.go:171","msg":"trace[402586239] transaction","detail":"{read_only:false; response_revision:26599; number_of_response:1; }","duration":"289.820389ms","start":"2026-10-09T03:13:54.531694Z","end":"2026-10-09T03:13:54.821514Z","steps":["trace[402586239] 'process raft request' (duration: 289.532001ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:13:54.821718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.645458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0\" ","response":"range_response_count:1 size:1864"} {"level":"info","ts":"2026-10-09T03:13:54.821807Z","caller":"traceutil/trace.go:171","msg":"trace[2006035109] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0; range_end:; response_count:1; response_revision:26599; }","duration":"259.779652ms","start":"2026-10-09T03:13:54.562006Z","end":"2026-10-09T03:13:54.821786Z","steps":["trace[2006035109] 'agreement among raft nodes before linearized reading' (duration: 259.529885ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:13:55.17761Z","caller":"traceutil/trace.go:171","msg":"trace[1981834363] transaction","detail":"{read_only:false; response_revision:26601; number_of_response:1; }","duration":"130.435591ms","start":"2026-10-09T03:13:55.04714Z","end":"2026-10-09T03:13:55.177576Z","steps":["trace[1981834363] 'process raft request' (duration: 50.698676ms)","trace[1981834363] 'compare' (duration: 79.336694ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:13:55.567641Z","caller":"traceutil/trace.go:171","msg":"trace[1909893513] transaction","detail":"{read_only:false; response_revision:26605; number_of_response:1; }","duration":"156.782646ms","start":"2026-10-09T03:13:55.410835Z","end":"2026-10-09T03:13:55.567618Z","steps":["trace[1909893513] 'process raft request' (duration: 156.716654ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:13:55.567679Z","caller":"traceutil/trace.go:171","msg":"trace[377505087] transaction","detail":"{read_only:false; response_revision:26604; number_of_response:1; }","duration":"157.629009ms","start":"2026-10-09T03:13:55.410031Z","end":"2026-10-09T03:13:55.56766Z","steps":["trace[377505087] 'process raft request' (duration: 157.475625ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:13:55.56767Z","caller":"traceutil/trace.go:171","msg":"trace[1067897275] transaction","detail":"{read_only:false; response_revision:26603; number_of_response:1; }","duration":"157.967719ms","start":"2026-10-09T03:13:55.409664Z","end":"2026-10-09T03:13:55.567632Z","steps":["trace[1067897275] 'process raft request' (duration: 157.696172ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:13:55.567923Z","caller":"traceutil/trace.go:171","msg":"trace[740797173] transaction","detail":"{read_only:false; response_revision:26606; number_of_response:1; }","duration":"156.971951ms","start":"2026-10-09T03:13:55.41094Z","end":"2026-10-09T03:13:55.567912Z","steps":["trace[740797173] 'process raft request' (duration: 156.644702ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:13:55.567955Z","caller":"traceutil/trace.go:171","msg":"trace[1450521754] linearizableReadLoop","detail":"{readStateIndex:27479; appliedIndex:27474; }","duration":"114.74767ms","start":"2026-10-09T03:13:55.453184Z","end":"2026-10-09T03:13:55.567931Z","steps":["trace[1450521754] 'read index received' (duration: 114.133133ms)","trace[1450521754] 'applied index is now lower than readState.Index' (duration: 613.417µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:13:55.568073Z","caller":"traceutil/trace.go:171","msg":"trace[2019506027] transaction","detail":"{read_only:false; response_revision:26607; number_of_response:1; }","duration":"153.416793ms","start":"2026-10-09T03:13:55.414645Z","end":"2026-10-09T03:13:55.568062Z","steps":["trace[2019506027] 'process raft request' (duration: 153.186227ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:13:55.568149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.929184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T03:13:55.568201Z","caller":"traceutil/trace.go:171","msg":"trace[625624512] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26607; }","duration":"115.054558ms","start":"2026-10-09T03:13:55.453133Z","end":"2026-10-09T03:13:55.568187Z","steps":["trace[625624512] 'agreement among raft nodes before linearized reading' (duration: 114.938905ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:13:55.835913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.485144ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T03:13:55.83613Z","caller":"traceutil/trace.go:171","msg":"trace[587794356] transaction","detail":"{read_only:false; response_revision:26610; number_of_response:1; }","duration":"263.390011ms","start":"2026-10-09T03:13:55.572698Z","end":"2026-10-09T03:13:55.836088Z","steps":["trace[587794356] 'process raft request' (duration: 263.300449ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:13:55.83614Z","caller":"traceutil/trace.go:171","msg":"trace[881255546] transaction","detail":"{read_only:false; response_revision:26609; number_of_response:1; }","duration":"263.449263ms","start":"2026-10-09T03:13:55.572643Z","end":"2026-10-09T03:13:55.836093Z","steps":["trace[881255546] 'process raft request' (duration: 141.708162ms)","trace[881255546] 'compare' (duration: 121.136165ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:13:55.836316Z","caller":"traceutil/trace.go:171","msg":"trace[272202466] linearizableReadLoop","detail":"{readStateIndex:27482; appliedIndex:27479; }","duration":"263.355781ms","start":"2026-10-09T03:13:55.572925Z","end":"2026-10-09T03:13:55.836281Z","steps":["trace[272202466] 'read index received' (duration: 41.372029ms)","trace[272202466] 'applied index is now lower than readState.Index' (duration: 221.981542ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:13:55.836438Z","caller":"traceutil/trace.go:171","msg":"trace[1096404840] transaction","detail":"{read_only:false; response_revision:26611; number_of_response:1; }","duration":"263.016911ms","start":"2026-10-09T03:13:55.573396Z","end":"2026-10-09T03:13:55.836413Z","steps":["trace[1096404840] 'process raft request' (duration: 262.655361ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:13:55.836574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.866705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.212\" ","response":"range_response_count:1 size:140"} {"level":"warn","ts":"2026-10-09T03:13:55.836583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.632128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19232"} {"level":"info","ts":"2026-10-09T03:13:55.836619Z","caller":"traceutil/trace.go:171","msg":"trace[1583640874] range","detail":"{range_begin:/registry/masterleases/162.253.55.212; range_end:; response_count:1; response_revision:26611; }","duration":"121.965358ms","start":"2026-10-09T03:13:55.714644Z","end":"2026-10-09T03:13:55.836609Z","steps":["trace[1583640874] 'agreement among raft nodes before linearized reading' (duration: 121.823474ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:13:55.836626Z","caller":"traceutil/trace.go:171","msg":"trace[2010245430] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:26611; }","duration":"263.71351ms","start":"2026-10-09T03:13:55.5729Z","end":"2026-10-09T03:13:55.836614Z","steps":["trace[2010245430] 'agreement among raft nodes before linearized reading' (duration: 263.523145ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:13:56.023182Z","caller":"traceutil/trace.go:171","msg":"trace[321975847] linearizableReadLoop","detail":"{readStateIndex:27485; appliedIndex:27484; }","duration":"120.179148ms","start":"2026-10-09T03:13:55.902971Z","end":"2026-10-09T03:13:56.02315Z","steps":["trace[321975847] 'read index received' (duration: 40.800983ms)","trace[321975847] 'applied index is now lower than readState.Index' (duration: 79.376775ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:13:56.023208Z","caller":"traceutil/trace.go:171","msg":"trace[1735995187] transaction","detail":"{read_only:false; response_revision:26612; number_of_response:1; }","duration":"144.251761ms","start":"2026-10-09T03:13:55.878905Z","end":"2026-10-09T03:13:56.023157Z","steps":["trace[1735995187] 'process raft request' (duration: 64.928547ms)","trace[1735995187] 'compare' (duration: 79.122139ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:13:56.023549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.554119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-09T03:13:56.023596Z","caller":"traceutil/trace.go:171","msg":"trace[296042060] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26612; }","duration":"120.680542ms","start":"2026-10-09T03:13:55.902901Z","end":"2026-10-09T03:13:56.023581Z","steps":["trace[296042060] 'agreement among raft nodes before linearized reading' (duration: 120.438825ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:13:56.403606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.055492ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T03:13:56.403723Z","caller":"traceutil/trace.go:171","msg":"trace[1649666245] linearizableReadLoop","detail":"{readStateIndex:27487; appliedIndex:27486; }","duration":"157.545627ms","start":"2026-10-09T03:13:56.246156Z","end":"2026-10-09T03:13:56.403701Z","steps":["trace[1649666245] 'read index received' (duration: 43.241µs)","trace[1649666245] 'applied index is now lower than readState.Index' (duration: 157.500656ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:13:56.403797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.64538ms","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:8"} {"level":"info","ts":"2026-10-09T03:13:56.403829Z","caller":"traceutil/trace.go:171","msg":"trace[789161186] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:26614; }","duration":"157.736562ms","start":"2026-10-09T03:13:56.246082Z","end":"2026-10-09T03:13:56.403819Z","steps":["trace[789161186] 'agreement among raft nodes before linearized reading' (duration: 157.669391ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:13:56.403869Z","caller":"traceutil/trace.go:171","msg":"trace[1718784499] transaction","detail":"{read_only:false; response_revision:26614; number_of_response:1; }","duration":"346.596442ms","start":"2026-10-09T03:13:56.057234Z","end":"2026-10-09T03:13:56.403831Z","steps":["trace[1718784499] 'process raft request' (duration: 156.2118ms)","trace[1718784499] 'compare' (duration: 189.673942ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:13:56.404045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:13:56.057205Z","time spent":"346.743846ms","remote":"127.0.0.1:56916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T03:13:57.336668Z","caller":"traceutil/trace.go:171","msg":"trace[5487625] linearizableReadLoop","detail":"{readStateIndex:27488; appliedIndex:27487; }","duration":"123.186692ms","start":"2026-10-09T03:13:57.213464Z","end":"2026-10-09T03:13:57.336651Z","steps":["trace[5487625] 'read index received' (duration: 123.133311ms)","trace[5487625] 'applied index is now lower than readState.Index' (duration: 52.801µs)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:13:57.33676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.278165ms","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-09T03:13:57.336779Z","caller":"traceutil/trace.go:171","msg":"trace[1025500862] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/domains/; range_end:/registry/openstack.k-orc.cloud/domains0; response_count:0; response_revision:26615; }","duration":"123.313675ms","start":"2026-10-09T03:13:57.213458Z","end":"2026-10-09T03:13:57.336771Z","steps":["trace[1025500862] 'agreement among raft nodes before linearized reading' (duration: 123.262624ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:13:57.336895Z","caller":"traceutil/trace.go:171","msg":"trace[1317542543] transaction","detail":"{read_only:false; response_revision:26615; number_of_response:1; }","duration":"153.63677ms","start":"2026-10-09T03:13:57.183233Z","end":"2026-10-09T03:13:57.336869Z","steps":["trace[1317542543] 'process raft request' (duration: 153.305561ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:13:57.747181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.296897ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T03:13:57.747319Z","caller":"traceutil/trace.go:171","msg":"trace[1366607915] transaction","detail":"{read_only:false; response_revision:26617; number_of_response:1; }","duration":"174.126163ms","start":"2026-10-09T03:13:57.573165Z","end":"2026-10-09T03:13:57.747291Z","steps":["trace[1366607915] 'process raft request' (duration: 67.658812ms)","trace[1366607915] 'compare' (duration: 105.995088ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:13:57.779575Z","caller":"traceutil/trace.go:171","msg":"trace[1602897589] transaction","detail":"{read_only:false; response_revision:26619; number_of_response:1; }","duration":"205.97745ms","start":"2026-10-09T03:13:57.573564Z","end":"2026-10-09T03:13:57.779541Z","steps":["trace[1602897589] 'process raft request' (duration: 205.876857ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:13:57.779588Z","caller":"traceutil/trace.go:171","msg":"trace[1999687795] transaction","detail":"{read_only:false; response_revision:26618; number_of_response:1; }","duration":"206.071563ms","start":"2026-10-09T03:13:57.573484Z","end":"2026-10-09T03:13:57.779555Z","steps":["trace[1999687795] 'process raft request' (duration: 205.781185ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:13:57.779885Z","caller":"traceutil/trace.go:171","msg":"trace[42106982] linearizableReadLoop","detail":"{readStateIndex:27494; appliedIndex:27489; }","duration":"160.790397ms","start":"2026-10-09T03:13:57.619082Z","end":"2026-10-09T03:13:57.779872Z","steps":["trace[42106982] 'read index received' (duration: 21.725409ms)","trace[42106982] 'applied index is now lower than readState.Index' (duration: 139.064108ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:13:57.77997Z","caller":"traceutil/trace.go:171","msg":"trace[474269482] transaction","detail":"{read_only:false; response_revision:26620; number_of_response:1; }","duration":"205.678213ms","start":"2026-10-09T03:13:57.574283Z","end":"2026-10-09T03:13:57.779961Z","steps":["trace[474269482] 'process raft request' (duration: 205.21665ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:13:57.780087Z","caller":"traceutil/trace.go:171","msg":"trace[24586852] transaction","detail":"{read_only:false; response_revision:26621; number_of_response:1; }","duration":"205.787175ms","start":"2026-10-09T03:13:57.574292Z","end":"2026-10-09T03:13:57.78008Z","steps":["trace[24586852] 'process raft request' (duration: 205.472436ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:13:57.780467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.368813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-09T03:13:57.780514Z","caller":"traceutil/trace.go:171","msg":"trace[562515322] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26621; }","duration":"161.464505ms","start":"2026-10-09T03:13:57.619038Z","end":"2026-10-09T03:13:57.780503Z","steps":["trace[562515322] 'agreement among raft nodes before linearized reading' (duration: 161.323432ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:13:57.988587Z","caller":"traceutil/trace.go:171","msg":"trace[836468347] linearizableReadLoop","detail":"{readStateIndex:27495; appliedIndex:27494; }","duration":"148.609232ms","start":"2026-10-09T03:13:57.839951Z","end":"2026-10-09T03:13:57.98856Z","steps":["trace[836468347] 'read index received' (duration: 94.537643ms)","trace[836468347] 'applied index is now lower than readState.Index' (duration: 54.070609ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:13:57.98868Z","caller":"traceutil/trace.go:171","msg":"trace[571769223] transaction","detail":"{read_only:false; response_revision:26622; number_of_response:1; }","duration":"203.895533ms","start":"2026-10-09T03:13:57.784758Z","end":"2026-10-09T03:13:57.988654Z","steps":["trace[571769223] 'process raft request' (duration: 149.732142ms)","trace[571769223] 'compare' (duration: 53.879493ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:13:57.988785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.829947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-09T03:13:57.988813Z","caller":"traceutil/trace.go:171","msg":"trace[103397996] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26622; }","duration":"148.915029ms","start":"2026-10-09T03:13:57.839891Z","end":"2026-10-09T03:13:57.988806Z","steps":["trace[103397996] 'agreement among raft nodes before linearized reading' (duration: 148.784326ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:13:57.989218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.787497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-09T03:13:57.989272Z","caller":"traceutil/trace.go:171","msg":"trace[958369493] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26622; }","duration":"148.853348ms","start":"2026-10-09T03:13:57.840404Z","end":"2026-10-09T03:13:57.989257Z","steps":["trace[958369493] 'agreement among raft nodes before linearized reading' (duration: 148.742265ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:13:57.988884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.443707ms","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-09T03:13:57.989545Z","caller":"traceutil/trace.go:171","msg":"trace[1179691238] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26622; }","duration":"149.144476ms","start":"2026-10-09T03:13:57.84039Z","end":"2026-10-09T03:13:57.989534Z","steps":["trace[1179691238] 'agreement among raft nodes before linearized reading' (duration: 148.372804ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:13:58.244386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.86217ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T03:13:58.244518Z","caller":"traceutil/trace.go:171","msg":"trace[1523891839] linearizableReadLoop","detail":"{readStateIndex:27498; appliedIndex:27496; }","duration":"245.287323ms","start":"2026-10-09T03:13:57.999218Z","end":"2026-10-09T03:13:58.244505Z","steps":["trace[1523891839] 'read index received' (duration: 46.414888ms)","trace[1523891839] 'applied index is now lower than readState.Index' (duration: 198.871555ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:13:58.244579Z","caller":"traceutil/trace.go:171","msg":"trace[1427582385] transaction","detail":"{read_only:false; response_revision:26625; number_of_response:1; }","duration":"250.185438ms","start":"2026-10-09T03:13:57.994386Z","end":"2026-10-09T03:13:58.244572Z","steps":["trace[1427582385] 'process raft request' (duration: 250.076625ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:13:58.244565Z","caller":"traceutil/trace.go:171","msg":"trace[252932394] transaction","detail":"{read_only:false; response_revision:26624; number_of_response:1; }","duration":"250.203388ms","start":"2026-10-09T03:13:57.994327Z","end":"2026-10-09T03:13:58.244531Z","steps":["trace[252932394] 'process raft request' (duration: 117.827513ms)","trace[252932394] 'compare' (duration: 131.759828ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:13:58.244685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.462368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T03:13:58.24471Z","caller":"traceutil/trace.go:171","msg":"trace[1468411878] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:26625; }","duration":"245.507859ms","start":"2026-10-09T03:13:57.999195Z","end":"2026-10-09T03:13:58.244702Z","steps":["trace[1468411878] 'agreement among raft nodes before linearized reading' (duration: 245.462367ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:13:58.244787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.827886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-09T03:13:58.244835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.974757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:7914"} {"level":"info","ts":"2026-10-09T03:13:58.244838Z","caller":"traceutil/trace.go:171","msg":"trace[897643183] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:26625; }","duration":"108.928049ms","start":"2026-10-09T03:13:58.135896Z","end":"2026-10-09T03:13:58.244824Z","steps":["trace[897643183] 'agreement among raft nodes before linearized reading' (duration: 108.827416ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:13:58.244867Z","caller":"traceutil/trace.go:171","msg":"trace[1825105969] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:26625; }","duration":"155.032048ms","start":"2026-10-09T03:13:58.089824Z","end":"2026-10-09T03:13:58.244856Z","steps":["trace[1825105969] 'agreement among raft nodes before linearized reading' (duration: 154.900864ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:13:58.244964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.574662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-09T03:13:58.24506Z","caller":"traceutil/trace.go:171","msg":"trace[1582382487] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26625; }","duration":"188.718066ms","start":"2026-10-09T03:13:58.056324Z","end":"2026-10-09T03:13:58.245042Z","steps":["trace[1582382487] 'agreement among raft nodes before linearized reading' (duration: 188.403737ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:13:58.508857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.780139ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T03:13:58.509066Z","caller":"traceutil/trace.go:171","msg":"trace[636478667] transaction","detail":"{read_only:false; response_revision:26626; number_of_response:1; }","duration":"260.618975ms","start":"2026-10-09T03:13:58.248412Z","end":"2026-10-09T03:13:58.509031Z","steps":["trace[636478667] 'process raft request' (duration: 108.570208ms)","trace[636478667] 'compare' (duration: 151.512282ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:13:58.509129Z","caller":"traceutil/trace.go:171","msg":"trace[523896177] transaction","detail":"{read_only:false; response_revision:26627; number_of_response:1; }","duration":"163.316796ms","start":"2026-10-09T03:13:58.345762Z","end":"2026-10-09T03:13:58.509079Z","steps":["trace[523896177] 'process raft request' (duration: 163.207993ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:13:58.50929Z","caller":"traceutil/trace.go:171","msg":"trace[428611358] linearizableReadLoop","detail":"{readStateIndex:27499; appliedIndex:27498; }","duration":"259.904876ms","start":"2026-10-09T03:13:58.249371Z","end":"2026-10-09T03:13:58.509276Z","steps":["trace[428611358] 'read index received' (duration: 107.635193ms)","trace[428611358] 'applied index is now lower than readState.Index' (duration: 152.267913ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:13:58.509731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.346078ms","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-09T03:13:58.509786Z","caller":"traceutil/trace.go:171","msg":"trace[848983128] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:26627; }","duration":"260.42115ms","start":"2026-10-09T03:13:58.249348Z","end":"2026-10-09T03:13:58.509769Z","steps":["trace[848983128] 'agreement among raft nodes before linearized reading' (duration: 260.335898ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:13:58.509848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.035722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-10-09T03:13:58.509908Z","caller":"traceutil/trace.go:171","msg":"trace[2137762158] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26627; }","duration":"101.151605ms","start":"2026-10-09T03:13:58.408741Z","end":"2026-10-09T03:13:58.509893Z","steps":["trace[2137762158] 'agreement among raft nodes before linearized reading' (duration: 100.967549ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:13:58.702408Z","caller":"traceutil/trace.go:171","msg":"trace[407767095] linearizableReadLoop","detail":"{readStateIndex:27501; appliedIndex:27500; }","duration":"185.375774ms","start":"2026-10-09T03:13:58.517006Z","end":"2026-10-09T03:13:58.702382Z","steps":["trace[407767095] 'read index received' (duration: 153.676741ms)","trace[407767095] 'applied index is now lower than readState.Index' (duration: 31.697693ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:13:58.702649Z","caller":"traceutil/trace.go:171","msg":"trace[1756230402] transaction","detail":"{read_only:false; response_revision:26628; number_of_response:1; }","duration":"188.648374ms","start":"2026-10-09T03:13:58.513986Z","end":"2026-10-09T03:13:58.702634Z","steps":["trace[1756230402] 'process raft request' (duration: 156.767056ms)","trace[1756230402] 'compare' (duration: 31.272891ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:13:58.702898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.872767ms","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-09T03:13:58.702939Z","caller":"traceutil/trace.go:171","msg":"trace[1864451593] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:26628; }","duration":"185.96635ms","start":"2026-10-09T03:13:58.516961Z","end":"2026-10-09T03:13:58.702928Z","steps":["trace[1864451593] 'agreement among raft nodes before linearized reading' (duration: 185.880827ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:13:58.703187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.678081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-10-09T03:13:58.703221Z","caller":"traceutil/trace.go:171","msg":"trace[1237487824] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0; range_end:; response_count:1; response_revision:26628; }","duration":"169.749154ms","start":"2026-10-09T03:13:58.533463Z","end":"2026-10-09T03:13:58.703213Z","steps":["trace[1237487824] 'agreement among raft nodes before linearized reading' (duration: 169.60889ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:13:58.943503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.049362ms","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-09T03:13:58.943569Z","caller":"traceutil/trace.go:171","msg":"trace[877658229] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26628; }","duration":"117.150895ms","start":"2026-10-09T03:13:58.826402Z","end":"2026-10-09T03:13:58.943553Z","steps":["trace[877658229] 'count revisions from in-memory index tree' (duration: 116.96264ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:13:59.91594Z","caller":"traceutil/trace.go:171","msg":"trace[1950461686] transaction","detail":"{read_only:false; response_revision:26632; number_of_response:1; }","duration":"164.662203ms","start":"2026-10-09T03:13:59.751246Z","end":"2026-10-09T03:13:59.915909Z","steps":["trace[1950461686] 'process raft request' (duration: 84.190658ms)","trace[1950461686] 'compare' (duration: 80.346582ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:13:59.969514Z","caller":"traceutil/trace.go:171","msg":"trace[844513049] transaction","detail":"{read_only:false; response_revision:26634; number_of_response:1; }","duration":"183.609445ms","start":"2026-10-09T03:13:59.785879Z","end":"2026-10-09T03:13:59.969488Z","steps":["trace[844513049] 'process raft request' (duration: 183.516313ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:13:59.969575Z","caller":"traceutil/trace.go:171","msg":"trace[264982709] transaction","detail":"{read_only:false; response_revision:26633; number_of_response:1; }","duration":"183.774879ms","start":"2026-10-09T03:13:59.785751Z","end":"2026-10-09T03:13:59.969526Z","steps":["trace[264982709] 'process raft request' (duration: 183.460801ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:13:59.969735Z","caller":"traceutil/trace.go:171","msg":"trace[1921798136] transaction","detail":"{read_only:false; response_revision:26635; number_of_response:1; }","duration":"183.770609ms","start":"2026-10-09T03:13:59.785942Z","end":"2026-10-09T03:13:59.969713Z","steps":["trace[1921798136] 'process raft request' (duration: 183.505502ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:13:59.969745Z","caller":"traceutil/trace.go:171","msg":"trace[776392538] transaction","detail":"{read_only:false; response_revision:26636; number_of_response:1; }","duration":"183.688547ms","start":"2026-10-09T03:13:59.786036Z","end":"2026-10-09T03:13:59.969725Z","steps":["trace[776392538] 'process raft request' (duration: 183.615025ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:14:00.268142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.668876ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T03:14:00.268286Z","caller":"traceutil/trace.go:171","msg":"trace[325544900] linearizableReadLoop","detail":"{readStateIndex:27510; appliedIndex:27509; }","duration":"218.186887ms","start":"2026-10-09T03:14:00.050078Z","end":"2026-10-09T03:14:00.268264Z","steps":["trace[325544900] 'read index received' (duration: 86.311966ms)","trace[325544900] 'applied index is now lower than readState.Index' (duration: 131.872571ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:14:00.268397Z","caller":"traceutil/trace.go:171","msg":"trace[967212657] transaction","detail":"{read_only:false; response_revision:26637; number_of_response:1; }","duration":"270.327052ms","start":"2026-10-09T03:13:59.998053Z","end":"2026-10-09T03:14:00.26838Z","steps":["trace[967212657] 'process raft request' (duration: 138.312988ms)","trace[967212657] 'compare' (duration: 131.48588ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:14:00.268943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.858646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-09T03:14:00.268999Z","caller":"traceutil/trace.go:171","msg":"trace[1442822916] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26637; }","duration":"218.959388ms","start":"2026-10-09T03:14:00.050026Z","end":"2026-10-09T03:14:00.268985Z","steps":["trace[1442822916] 'agreement among raft nodes before linearized reading' (duration: 218.784693ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:14:00.491211Z","caller":"traceutil/trace.go:171","msg":"trace[1198338615] transaction","detail":"{read_only:false; response_revision:26638; number_of_response:1; }","duration":"218.29067ms","start":"2026-10-09T03:14:00.27288Z","end":"2026-10-09T03:14:00.491171Z","steps":["trace[1198338615] 'process raft request' (duration: 188.395567ms)","trace[1198338615] 'compare' (duration: 29.648446ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:14:00.514379Z","caller":"traceutil/trace.go:171","msg":"trace[1596997269] transaction","detail":"{read_only:false; response_revision:26639; number_of_response:1; }","duration":"240.765749ms","start":"2026-10-09T03:14:00.273589Z","end":"2026-10-09T03:14:00.514355Z","steps":["trace[1596997269] 'process raft request' (duration: 240.553693ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:14:00.514533Z","caller":"traceutil/trace.go:171","msg":"trace[1685300047] linearizableReadLoop","detail":"{readStateIndex:27513; appliedIndex:27510; }","duration":"150.272517ms","start":"2026-10-09T03:14:00.364229Z","end":"2026-10-09T03:14:00.514501Z","steps":["trace[1685300047] 'read index received' (duration: 97.053982ms)","trace[1685300047] 'applied index is now lower than readState.Index' (duration: 53.216525ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:14:00.5147Z","caller":"traceutil/trace.go:171","msg":"trace[1424795923] transaction","detail":"{read_only:false; response_revision:26640; number_of_response:1; }","duration":"240.897892ms","start":"2026-10-09T03:14:00.27372Z","end":"2026-10-09T03:14:00.514618Z","steps":["trace[1424795923] 'process raft request' (duration: 240.591534ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:14:00.514743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.484883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-09T03:14:00.514783Z","caller":"traceutil/trace.go:171","msg":"trace[890014492] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:26640; }","duration":"150.567556ms","start":"2026-10-09T03:14:00.3642Z","end":"2026-10-09T03:14:00.514767Z","steps":["trace[890014492] 'agreement among raft nodes before linearized reading' (duration: 150.440292ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:14:00.705602Z","caller":"traceutil/trace.go:171","msg":"trace[1884682656] transaction","detail":"{read_only:false; response_revision:26641; number_of_response:1; }","duration":"185.009952ms","start":"2026-10-09T03:14:00.520564Z","end":"2026-10-09T03:14:00.705574Z","steps":["trace[1884682656] 'process raft request' (duration: 160.613441ms)","trace[1884682656] 'compare' (duration: 23.874017ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:14:18.167362Z","caller":"traceutil/trace.go:171","msg":"trace[1957436539] transaction","detail":"{read_only:false; response_revision:26768; number_of_response:1; }","duration":"107.771228ms","start":"2026-10-09T03:14:18.059553Z","end":"2026-10-09T03:14:18.167325Z","steps":["trace[1957436539] 'process raft request' (duration: 27.633521ms)","trace[1957436539] 'compare' (duration: 79.840608ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:14:38.810197Z","caller":"traceutil/trace.go:171","msg":"trace[1604732280] transaction","detail":"{read_only:false; response_revision:27068; number_of_response:1; }","duration":"176.185131ms","start":"2026-10-09T03:14:38.633987Z","end":"2026-10-09T03:14:38.810172Z","steps":["trace[1604732280] 'process raft request' (duration: 135.712176ms)","trace[1604732280] 'compare' (duration: 40.117775ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:14:38.987292Z","caller":"traceutil/trace.go:171","msg":"trace[1772208706] linearizableReadLoop","detail":"{readStateIndex:27954; appliedIndex:27951; }","duration":"168.653263ms","start":"2026-10-09T03:14:38.818612Z","end":"2026-10-09T03:14:38.987265Z","steps":["trace[1772208706] 'read index received' (duration: 62.11749ms)","trace[1772208706] 'applied index is now lower than readState.Index' (duration: 106.534573ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:14:38.987316Z","caller":"traceutil/trace.go:171","msg":"trace[515266532] transaction","detail":"{read_only:false; response_revision:27070; number_of_response:1; }","duration":"170.281628ms","start":"2026-10-09T03:14:38.817009Z","end":"2026-10-09T03:14:38.987291Z","steps":["trace[515266532] 'process raft request' (duration: 139.823499ms)","trace[515266532] 'compare' (duration: 30.044427ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:14:38.987489Z","caller":"traceutil/trace.go:171","msg":"trace[598210592] transaction","detail":"{read_only:false; response_revision:27071; number_of_response:1; }","duration":"169.63461ms","start":"2026-10-09T03:14:38.817817Z","end":"2026-10-09T03:14:38.987451Z","steps":["trace[598210592] 'process raft request' (duration: 169.343312ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:14:38.987525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.873589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T03:14:38.987604Z","caller":"traceutil/trace.go:171","msg":"trace[975344138] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:27071; }","duration":"168.978642ms","start":"2026-10-09T03:14:38.818605Z","end":"2026-10-09T03:14:38.987584Z","steps":["trace[975344138] 'agreement among raft nodes before linearized reading' (duration: 168.835938ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:14:39.162181Z","caller":"traceutil/trace.go:171","msg":"trace[1723993011] linearizableReadLoop","detail":"{readStateIndex:27956; appliedIndex:27954; }","duration":"169.492646ms","start":"2026-10-09T03:14:38.992658Z","end":"2026-10-09T03:14:39.162151Z","steps":["trace[1723993011] 'read index received' (duration: 71.731815ms)","trace[1723993011] 'applied index is now lower than readState.Index' (duration: 97.759551ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:14:39.162547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.410552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-09T03:14:39.162595Z","caller":"traceutil/trace.go:171","msg":"trace[1727077462] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:27073; }","duration":"158.512744ms","start":"2026-10-09T03:14:39.00407Z","end":"2026-10-09T03:14:39.162583Z","steps":["trace[1727077462] 'agreement among raft nodes before linearized reading' (duration: 158.36132ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:14:39.16261Z","caller":"traceutil/trace.go:171","msg":"trace[132320195] transaction","detail":"{read_only:false; response_revision:27073; number_of_response:1; }","duration":"170.065552ms","start":"2026-10-09T03:14:38.992512Z","end":"2026-10-09T03:14:39.162578Z","steps":["trace[132320195] 'process raft request' (duration: 128.049356ms)","trace[132320195] 'compare' (duration: 41.201304ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:14:39.162836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.125054ms","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-09T03:14:39.162964Z","caller":"traceutil/trace.go:171","msg":"trace[1240123747] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:27073; }","duration":"170.314799ms","start":"2026-10-09T03:14:38.992629Z","end":"2026-10-09T03:14:39.162944Z","steps":["trace[1240123747] 'agreement among raft nodes before linearized reading' (duration: 170.041542ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:14:40.068032Z","caller":"traceutil/trace.go:171","msg":"trace[1438569004] linearizableReadLoop","detail":"{readStateIndex:27958; appliedIndex:27957; }","duration":"199.707828ms","start":"2026-10-09T03:14:39.868296Z","end":"2026-10-09T03:14:40.068004Z","steps":["trace[1438569004] 'read index received' (duration: 181.26735ms)","trace[1438569004] 'applied index is now lower than readState.Index' (duration: 18.439108ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:14:40.068143Z","caller":"traceutil/trace.go:171","msg":"trace[410301639] transaction","detail":"{read_only:false; response_revision:27075; number_of_response:1; }","duration":"231.988336ms","start":"2026-10-09T03:14:39.836114Z","end":"2026-10-09T03:14:40.068102Z","steps":["trace[410301639] 'process raft request' (duration: 213.374104ms)","trace[410301639] 'compare' (duration: 18.17013ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:14:40.068232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.912723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T03:14:40.068281Z","caller":"traceutil/trace.go:171","msg":"trace[416269692] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:27075; }","duration":"199.998005ms","start":"2026-10-09T03:14:39.868267Z","end":"2026-10-09T03:14:40.068265Z","steps":["trace[416269692] 'agreement among raft nodes before linearized reading' (duration: 199.902162ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:16:01.889838Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24979} {"level":"info","ts":"2026-10-09T03:16:02.043628Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24979,"took":"147.878311ms","hash":857898664,"current-db-size-bytes":57913344,"current-db-size":"58 MB","current-db-size-in-use-bytes":36130816,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-09T03:16:02.043699Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":857898664,"revision":24979,"compact-revision":21758} {"level":"info","ts":"2026-10-09T03:17:30.468557Z","caller":"traceutil/trace.go:171","msg":"trace[262833161] transaction","detail":"{read_only:false; response_revision:28719; number_of_response:1; }","duration":"105.693499ms","start":"2026-10-09T03:17:30.362843Z","end":"2026-10-09T03:17:30.468537Z","steps":["trace[262833161] 'process raft request' (duration: 105.554496ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:18:02.958644Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d078b1e39c21939c","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-09T03:18:02.963299Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-09T03:18:02.963396Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-09T03:21:01.89825Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27822} {"level":"info","ts":"2026-10-09T03:21:02.042527Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27822,"took":"140.115747ms","hash":2098584295,"current-db-size-bytes":57913344,"current-db-size":"58 MB","current-db-size-in-use-bytes":33878016,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-09T03:21:02.042604Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2098584295,"revision":27822,"compact-revision":24979} {"level":"info","ts":"2026-10-09T03:26:01.904604Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30441} {"level":"info","ts":"2026-10-09T03:26:02.042316Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30441,"took":"133.381043ms","hash":1029448956,"current-db-size-bytes":57913344,"current-db-size":"58 MB","current-db-size-in-use-bytes":30588928,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-09T03:26:02.042389Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1029448956,"revision":30441,"compact-revision":27822} {"level":"info","ts":"2026-10-09T03:31:01.912086Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32689} {"level":"info","ts":"2026-10-09T03:31:02.070017Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32689,"took":"153.596889ms","hash":3473958382,"current-db-size-bytes":57913344,"current-db-size":"58 MB","current-db-size-in-use-bytes":30187520,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-09T03:31:02.070101Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3473958382,"revision":32689,"compact-revision":30441} {"level":"info","ts":"2026-10-09T03:36:01.920983Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34920} {"level":"info","ts":"2026-10-09T03:36:02.062806Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34920,"took":"136.18786ms","hash":811038482,"current-db-size-bytes":57913344,"current-db-size":"58 MB","current-db-size-in-use-bytes":31698944,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-09T03:36:02.062945Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":811038482,"revision":34920,"compact-revision":32689} {"level":"info","ts":"2026-10-09T03:39:32.500143Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d078b1e39c21939c","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-09T03:39:32.507703Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-09T03:39:32.50798Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-09T03:41:01.929646Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37214} {"level":"info","ts":"2026-10-09T03:41:02.060331Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37214,"took":"126.595426ms","hash":641344425,"current-db-size-bytes":60035072,"current-db-size":"60 MB","current-db-size-in-use-bytes":33214464,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-09T03:41:02.060543Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":641344425,"revision":37214,"compact-revision":34920} {"level":"info","ts":"2026-10-09T03:46:01.94039Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39491} {"level":"info","ts":"2026-10-09T03:46:02.071067Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39491,"took":"126.843668ms","hash":3573631039,"current-db-size-bytes":60035072,"current-db-size":"60 MB","current-db-size-in-use-bytes":30875648,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-09T03:46:02.071127Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3573631039,"revision":39491,"compact-revision":37214}