{"level":"warn","ts":"2026-08-26T05:09:13.861092Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-26T05:09:13.861325Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.20: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.20:2380","--initial-cluster=instance=https://162.253.55.20:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.20:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.20: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-08-26T05:09:13.861451Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-26T05:09:13.861498Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.20:2380"]} {"level":"info","ts":"2026-08-26T05:09:13.86154Z","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-08-26T05:09:13.862444Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.20:2379"]} {"level":"info","ts":"2026-08-26T05:09:13.862803Z","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.20:2380"],"listen-peer-urls":["https://162.253.55.20:2380"],"advertise-client-urls":["https://162.253.55.20:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.20:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.20: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-08-26T05:09:13.867589Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.393661ms"} {"level":"info","ts":"2026-08-26T05:09:13.872056Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"eae99266654eff69","cluster-id":"6d4184aac664e4ac"} {"level":"info","ts":"2026-08-26T05:09:13.872154Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eae99266654eff69 switched to configuration voters=()"} {"level":"info","ts":"2026-08-26T05:09:13.872188Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eae99266654eff69 became follower at term 0"} {"level":"info","ts":"2026-08-26T05:09:13.872223Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft eae99266654eff69 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-26T05:09:13.872257Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eae99266654eff69 became follower at term 1"} {"level":"info","ts":"2026-08-26T05:09:13.872916Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eae99266654eff69 switched to configuration voters=(16927221642932715369)"} {"level":"warn","ts":"2026-08-26T05:09:13.885534Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-26T05:09:13.897137Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-26T05:09:13.907734Z","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-08-26T05:09:13.914691Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"eae99266654eff69","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-26T05:09:13.914974Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"eae99266654eff69","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-26T05:09:13.915356Z","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-08-26T05:09:13.915561Z","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-08-26T05:09:13.915633Z","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-08-26T05:09:13.916107Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eae99266654eff69 switched to configuration voters=(16927221642932715369)"} {"level":"info","ts":"2026-08-26T05:09:13.916293Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6d4184aac664e4ac","local-member-id":"eae99266654eff69","added-peer-id":"eae99266654eff69","added-peer-peer-urls":["https://162.253.55.20:2380"]} {"level":"info","ts":"2026-08-26T05:09:13.919598Z","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-08-26T05:09:13.919746Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.20:2380"} {"level":"info","ts":"2026-08-26T05:09:13.91983Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.20:2380"} {"level":"info","ts":"2026-08-26T05:09:13.92122Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"eae99266654eff69","initial-advertise-peer-urls":["https://162.253.55.20:2380"],"listen-peer-urls":["https://162.253.55.20:2380"],"advertise-client-urls":["https://162.253.55.20:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.20:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-26T05:09:13.921299Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-26T05:09:14.273588Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eae99266654eff69 is starting a new election at term 1"} {"level":"info","ts":"2026-08-26T05:09:14.273643Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eae99266654eff69 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-26T05:09:14.273678Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eae99266654eff69 received MsgPreVoteResp from eae99266654eff69 at term 1"} {"level":"info","ts":"2026-08-26T05:09:14.273698Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eae99266654eff69 became candidate at term 2"} {"level":"info","ts":"2026-08-26T05:09:14.273713Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eae99266654eff69 received MsgVoteResp from eae99266654eff69 at term 2"} {"level":"info","ts":"2026-08-26T05:09:14.273733Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eae99266654eff69 became leader at term 2"} {"level":"info","ts":"2026-08-26T05:09:14.273752Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: eae99266654eff69 elected leader eae99266654eff69 at term 2"} {"level":"info","ts":"2026-08-26T05:09:14.274654Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-26T05:09:14.275619Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-26T05:09:14.275623Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"eae99266654eff69","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.20:2379]}","request-path":"/0/members/eae99266654eff69/attributes","cluster-id":"6d4184aac664e4ac","publish-timeout":"7s"} {"level":"info","ts":"2026-08-26T05:09:14.275667Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-26T05:09:14.275996Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6d4184aac664e4ac","local-member-id":"eae99266654eff69","cluster-version":"3.5"} {"level":"info","ts":"2026-08-26T05:09:14.276073Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-26T05:09:14.276136Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-26T05:09:14.27619Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-26T05:09:14.276212Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-26T05:09:14.278695Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-26T05:09:14.279571Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.20:2379"} {"level":"info","ts":"2026-08-26T05:09:32.751405Z","caller":"traceutil/trace.go:171","msg":"trace[702975381] linearizableReadLoop","detail":"{readStateIndex:428; appliedIndex:427; }","duration":"172.187222ms","start":"2026-08-26T05:09:32.579189Z","end":"2026-08-26T05:09:32.751377Z","steps":["trace[702975381] 'read index received' (duration: 172.019808ms)","trace[702975381] 'applied index is now lower than readState.Index' (duration: 166.494µs)"],"step_count":2} {"level":"info","ts":"2026-08-26T05:09:32.751512Z","caller":"traceutil/trace.go:171","msg":"trace[1628994574] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"210.359527ms","start":"2026-08-26T05:09:32.541112Z","end":"2026-08-26T05:09:32.751472Z","steps":["trace[1628994574] 'process raft request' (duration: 210.08724ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:09:32.751569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.365987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4322"} {"level":"info","ts":"2026-08-26T05:09:32.751659Z","caller":"traceutil/trace.go:171","msg":"trace[1400894095] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:414; }","duration":"172.517661ms","start":"2026-08-26T05:09:32.579127Z","end":"2026-08-26T05:09:32.751645Z","steps":["trace[1400894095] 'agreement among raft nodes before linearized reading' (duration: 172.374717ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:09:32.998855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.198647ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-26T05:09:32.998977Z","caller":"traceutil/trace.go:171","msg":"trace[899966273] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"239.752714ms","start":"2026-08-26T05:09:32.759202Z","end":"2026-08-26T05:09:32.998955Z","steps":["trace[899966273] 'process raft request' (duration: 107.863228ms)","trace[899966273] 'compare' (duration: 131.076334ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T05:09:33.326173Z","caller":"traceutil/trace.go:171","msg":"trace[197560966] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"318.0572ms","start":"2026-08-26T05:09:33.008081Z","end":"2026-08-26T05:09:33.326138Z","steps":["trace[197560966] 'process raft request' (duration: 317.728451ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:09:33.326346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T05:09:33.008061Z","time spent":"318.204284ms","remote":"127.0.0.1:59420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4115,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T05:09:49.973354Z","caller":"traceutil/trace.go:171","msg":"trace[607194843] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"105.00959ms","start":"2026-08-26T05:09:49.868319Z","end":"2026-08-26T05:09:49.973328Z","steps":["trace[607194843] 'process raft request' (duration: 88.455846ms)","trace[607194843] 'compare' (duration: 16.43957ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T05:09:55.535833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.025202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-2tqr8\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-08-26T05:09:55.53594Z","caller":"traceutil/trace.go:171","msg":"trace[1823257526] range","detail":"{range_begin:/registry/pods/kube-system/cilium-2tqr8; range_end:; response_count:1; response_revision:621; }","duration":"201.201506ms","start":"2026-08-26T05:09:55.334714Z","end":"2026-08-26T05:09:55.535916Z","steps":["trace[1823257526] 'range keys from in-memory index tree' (duration: 200.788356ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:09:55.564332Z","caller":"traceutil/trace.go:171","msg":"trace[999738978] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"122.302204ms","start":"2026-08-26T05:09:55.442011Z","end":"2026-08-26T05:09:55.564313Z","steps":["trace[999738978] 'process raft request' (duration: 121.77127ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:09:55.981061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.89559ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-26T05:09:55.981156Z","caller":"traceutil/trace.go:171","msg":"trace[566461426] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"430.34242ms","start":"2026-08-26T05:09:55.550799Z","end":"2026-08-26T05:09:55.981142Z","steps":["trace[566461426] 'process raft request' (duration: 210.276686ms)","trace[566461426] 'compare' (duration: 219.715944ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T05:09:55.981201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T05:09:55.550765Z","time spent":"430.415881ms","remote":"127.0.0.1:59420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T05:09:56.025517Z","caller":"traceutil/trace.go:171","msg":"trace[822430068] linearizableReadLoop","detail":"{readStateIndex:643; appliedIndex:642; }","duration":"189.961129ms","start":"2026-08-26T05:09:55.835524Z","end":"2026-08-26T05:09:56.025485Z","steps":["trace[822430068] 'read index received' (duration: 189.788534ms)","trace[822430068] 'applied index is now lower than readState.Index' (duration: 171.375µs)"],"step_count":2} {"level":"info","ts":"2026-08-26T05:09:56.025614Z","caller":"traceutil/trace.go:171","msg":"trace[228001839] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"393.464758ms","start":"2026-08-26T05:09:55.632114Z","end":"2026-08-26T05:09:56.025579Z","steps":["trace[228001839] 'process raft request' (duration: 393.212862ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:09:56.025766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T05:09:55.632084Z","time spent":"393.608712ms","remote":"127.0.0.1:59616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-26T05:09:56.025824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.282587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-26T05:09:56.025894Z","caller":"traceutil/trace.go:171","msg":"trace[1876275162] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:624; }","duration":"190.409381ms","start":"2026-08-26T05:09:55.835467Z","end":"2026-08-26T05:09:56.025877Z","steps":["trace[1876275162] 'agreement among raft nodes before linearized reading' (duration: 190.135933ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:09:56.73993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.073845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T05:09:56.740107Z","caller":"traceutil/trace.go:171","msg":"trace[688581210] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:626; }","duration":"429.31585ms","start":"2026-08-26T05:09:56.310763Z","end":"2026-08-26T05:09:56.740079Z","steps":["trace[688581210] 'range keys from in-memory index tree' (duration: 428.971551ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:09:56.74017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T05:09:56.31074Z","time spent":"429.418223ms","remote":"127.0.0.1:59194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-26T05:09:56.740843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.060616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-26T05:09:56.740917Z","caller":"traceutil/trace.go:171","msg":"trace[1275015304] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:626; }","duration":"513.145849ms","start":"2026-08-26T05:09:56.227731Z","end":"2026-08-26T05:09:56.740877Z","steps":["trace[1275015304] 'range keys from in-memory index tree' (duration: 512.895563ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:09:56.740963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T05:09:56.22771Z","time spent":"513.243121ms","remote":"127.0.0.1:59510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-26T05:09:56.744607Z","caller":"traceutil/trace.go:171","msg":"trace[1200238160] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"278.63731ms","start":"2026-08-26T05:09:56.465943Z","end":"2026-08-26T05:09:56.744581Z","steps":["trace[1200238160] 'process raft request' (duration: 275.183675ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:09:56.744624Z","caller":"traceutil/trace.go:171","msg":"trace[1389581564] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"130.873628ms","start":"2026-08-26T05:09:56.613705Z","end":"2026-08-26T05:09:56.744579Z","steps":["trace[1389581564] 'process raft request' (duration: 130.564759ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:10:16.996736Z","caller":"traceutil/trace.go:171","msg":"trace[1418878386] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"159.105572ms","start":"2026-08-26T05:10:16.837607Z","end":"2026-08-26T05:10:16.996712Z","steps":["trace[1418878386] 'process raft request' (duration: 158.579927ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:11:10.966726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.61233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-26T05:11:10.966794Z","caller":"traceutil/trace.go:171","msg":"trace[1189109707] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1473; }","duration":"130.734145ms","start":"2026-08-26T05:11:10.836047Z","end":"2026-08-26T05:11:10.966781Z","steps":["trace[1189109707] 'count revisions from in-memory index tree' (duration: 130.523379ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:11:34.183762Z","caller":"traceutil/trace.go:171","msg":"trace[762137262] transaction","detail":"{read_only:false; response_revision:1870; number_of_response:1; }","duration":"244.788831ms","start":"2026-08-26T05:11:33.938951Z","end":"2026-08-26T05:11:34.18374Z","steps":["trace[762137262] 'process raft request' (duration: 244.646878ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:11:34.650648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.688042ms","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-08-26T05:11:34.650732Z","caller":"traceutil/trace.go:171","msg":"trace[1831412842] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1871; }","duration":"146.829065ms","start":"2026-08-26T05:11:34.503885Z","end":"2026-08-26T05:11:34.650714Z","steps":["trace[1831412842] 'range keys from in-memory index tree' (duration: 146.460995ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:11:34.650837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.202486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-26T05:11:34.650922Z","caller":"traceutil/trace.go:171","msg":"trace[548250848] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1871; }","duration":"120.333378ms","start":"2026-08-26T05:11:34.530569Z","end":"2026-08-26T05:11:34.650902Z","steps":["trace[548250848] 'range keys from in-memory index tree' (duration: 120.00388ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:11:34.651033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.860479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-26T05:11:34.651398Z","caller":"traceutil/trace.go:171","msg":"trace[1753164315] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1871; }","duration":"123.244669ms","start":"2026-08-26T05:11:34.528119Z","end":"2026-08-26T05:11:34.651364Z","steps":["trace[1753164315] 'range keys from in-memory index tree' (duration: 122.257501ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:11:47.00976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.337588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-08-26T05:11:47.009932Z","caller":"traceutil/trace.go:171","msg":"trace[1321193528] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1981; }","duration":"167.563775ms","start":"2026-08-26T05:11:46.842342Z","end":"2026-08-26T05:11:47.009906Z","steps":["trace[1321193528] 'range keys from in-memory index tree' (duration: 167.140963ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:11:47.009927Z","caller":"traceutil/trace.go:171","msg":"trace[1964576140] transaction","detail":"{read_only:false; response_revision:1982; number_of_response:1; }","duration":"155.836733ms","start":"2026-08-26T05:11:46.854061Z","end":"2026-08-26T05:11:47.009898Z","steps":["trace[1964576140] 'process raft request' (duration: 143.728911ms)","trace[1964576140] 'compare' (duration: 11.802384ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T05:11:47.0099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.616711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-08-26T05:11:47.010081Z","caller":"traceutil/trace.go:171","msg":"trace[1740614421] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1981; }","duration":"200.865147ms","start":"2026-08-26T05:11:46.809189Z","end":"2026-08-26T05:11:47.010054Z","steps":["trace[1740614421] 'range keys from in-memory index tree' (duration: 200.358914ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:12:17.676316Z","caller":"traceutil/trace.go:171","msg":"trace[687768028] transaction","detail":"{read_only:false; response_revision:2163; number_of_response:1; }","duration":"101.836433ms","start":"2026-08-26T05:12:17.574436Z","end":"2026-08-26T05:12:17.676273Z","steps":["trace[687768028] 'process raft request' (duration: 101.621877ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:12:17.894851Z","caller":"traceutil/trace.go:171","msg":"trace[1031746397] linearizableReadLoop","detail":"{readStateIndex:2243; appliedIndex:2242; }","duration":"180.773295ms","start":"2026-08-26T05:12:17.71404Z","end":"2026-08-26T05:12:17.894814Z","steps":["trace[1031746397] 'read index received' (duration: 137.87879ms)","trace[1031746397] 'applied index is now lower than readState.Index' (duration: 42.893255ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T05:12:17.894948Z","caller":"traceutil/trace.go:171","msg":"trace[1248371447] transaction","detail":"{read_only:false; response_revision:2164; number_of_response:1; }","duration":"214.603754ms","start":"2026-08-26T05:12:17.680294Z","end":"2026-08-26T05:12:17.894898Z","steps":["trace[1248371447] 'process raft request' (duration: 171.582865ms)","trace[1248371447] 'compare' (duration: 42.787883ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T05:12:17.895147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.077805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18cf42e3ac875703\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-08-26T05:12:17.895393Z","caller":"traceutil/trace.go:171","msg":"trace[1995111605] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18cf42e3ac875703; range_end:; response_count:1; response_revision:2164; }","duration":"181.211829ms","start":"2026-08-26T05:12:17.713974Z","end":"2026-08-26T05:12:17.895186Z","steps":["trace[1995111605] 'agreement among raft nodes before linearized reading' (duration: 180.984363ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:12:17.913374Z","caller":"traceutil/trace.go:171","msg":"trace[1100831046] transaction","detail":"{read_only:false; response_revision:2165; number_of_response:1; }","duration":"138.069356ms","start":"2026-08-26T05:12:17.775279Z","end":"2026-08-26T05:12:17.913348Z","steps":["trace[1100831046] 'process raft request' (duration: 137.831559ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:12:23.833574Z","caller":"traceutil/trace.go:171","msg":"trace[516139906] transaction","detail":"{read_only:false; response_revision:2198; number_of_response:1; }","duration":"126.960381ms","start":"2026-08-26T05:12:23.706577Z","end":"2026-08-26T05:12:23.833537Z","steps":["trace[516139906] 'process raft request' (duration: 126.591931ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:13:14.502678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.408211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T05:13:14.502796Z","caller":"traceutil/trace.go:171","msg":"trace[610678336] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2644; }","duration":"191.611436ms","start":"2026-08-26T05:13:14.311165Z","end":"2026-08-26T05:13:14.502777Z","steps":["trace[610678336] 'range keys from in-memory index tree' (duration: 191.286187ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:13:48.353699Z","caller":"traceutil/trace.go:171","msg":"trace[1182496190] transaction","detail":"{read_only:false; response_revision:2847; number_of_response:1; }","duration":"123.839933ms","start":"2026-08-26T05:13:48.229831Z","end":"2026-08-26T05:13:48.35367Z","steps":["trace[1182496190] 'process raft request' (duration: 123.665978ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:13:48.492677Z","caller":"traceutil/trace.go:171","msg":"trace[1337383213] linearizableReadLoop","detail":"{readStateIndex:2955; appliedIndex:2953; }","duration":"194.118735ms","start":"2026-08-26T05:13:48.298523Z","end":"2026-08-26T05:13:48.492642Z","steps":["trace[1337383213] 'read index received' (duration: 55.071642ms)","trace[1337383213] 'applied index is now lower than readState.Index' (duration: 139.045513ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T05:13:48.492733Z","caller":"traceutil/trace.go:171","msg":"trace[1627653408] transaction","detail":"{read_only:false; response_revision:2848; number_of_response:1; }","duration":"243.593673ms","start":"2026-08-26T05:13:48.249101Z","end":"2026-08-26T05:13:48.492695Z","steps":["trace[1627653408] 'process raft request' (duration: 237.226326ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:13:48.493014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.460965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-26T05:13:48.493088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.905216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T05:13:48.493132Z","caller":"traceutil/trace.go:171","msg":"trace[935289846] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2848; }","duration":"194.651319ms","start":"2026-08-26T05:13:48.298455Z","end":"2026-08-26T05:13:48.493106Z","steps":["trace[935289846] 'agreement among raft nodes before linearized reading' (duration: 194.364112ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:13:48.493192Z","caller":"traceutil/trace.go:171","msg":"trace[1826355743] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2848; }","duration":"183.04801ms","start":"2026-08-26T05:13:48.310119Z","end":"2026-08-26T05:13:48.493167Z","steps":["trace[1826355743] 'agreement among raft nodes before linearized reading' (duration: 182.860444ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:13:48.493187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.470956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-26T05:13:48.493534Z","caller":"traceutil/trace.go:171","msg":"trace[670490069] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2848; }","duration":"126.837806ms","start":"2026-08-26T05:13:48.366682Z","end":"2026-08-26T05:13:48.49352Z","steps":["trace[670490069] 'agreement among raft nodes before linearized reading' (duration: 126.300011ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:13:48.833553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.038963ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-26T05:13:48.834017Z","caller":"traceutil/trace.go:171","msg":"trace[1072814004] transaction","detail":"{read_only:false; response_revision:2849; number_of_response:1; }","duration":"336.539572ms","start":"2026-08-26T05:13:48.497451Z","end":"2026-08-26T05:13:48.83399Z","steps":["trace[1072814004] 'process raft request' (duration: 145.915903ms)","trace[1072814004] 'compare' (duration: 189.831447ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T05:13:48.834106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T05:13:48.497426Z","time spent":"336.640575ms","remote":"127.0.0.1:59510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T05:16:13.845958Z","caller":"traceutil/trace.go:171","msg":"trace[807014654] transaction","detail":"{read_only:false; response_revision:3816; number_of_response:1; }","duration":"118.729283ms","start":"2026-08-26T05:16:13.727197Z","end":"2026-08-26T05:16:13.845926Z","steps":["trace[807014654] 'process raft request' (duration: 118.509177ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:17:36.042575Z","caller":"traceutil/trace.go:171","msg":"trace[839631208] linearizableReadLoop","detail":"{readStateIndex:5060; appliedIndex:5059; }","duration":"125.126376ms","start":"2026-08-26T05:17:35.917418Z","end":"2026-08-26T05:17:36.042544Z","steps":["trace[839631208] 'read index received' (duration: 124.918431ms)","trace[839631208] 'applied index is now lower than readState.Index' (duration: 206.805µs)"],"step_count":2} {"level":"info","ts":"2026-08-26T05:17:36.042658Z","caller":"traceutil/trace.go:171","msg":"trace[224716538] transaction","detail":"{read_only:false; response_revision:4877; number_of_response:1; }","duration":"125.826865ms","start":"2026-08-26T05:17:35.9168Z","end":"2026-08-26T05:17:36.042627Z","steps":["trace[224716538] 'process raft request' (duration: 125.567908ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:17:36.042833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.422475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"info","ts":"2026-08-26T05:17:36.042905Z","caller":"traceutil/trace.go:171","msg":"trace[384903179] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4877; }","duration":"125.530708ms","start":"2026-08-26T05:17:35.917361Z","end":"2026-08-26T05:17:36.042891Z","steps":["trace[384903179] 'agreement among raft nodes before linearized reading' (duration: 125.298752ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:17:36.300907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.588109ms","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-08-26T05:17:36.300961Z","caller":"traceutil/trace.go:171","msg":"trace[1192580835] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4878; }","duration":"186.706682ms","start":"2026-08-26T05:17:36.114242Z","end":"2026-08-26T05:17:36.300949Z","steps":["trace[1192580835] 'count revisions from in-memory index tree' (duration: 186.504787ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:17:36.386645Z","caller":"traceutil/trace.go:171","msg":"trace[989173660] transaction","detail":"{read_only:false; response_revision:4879; number_of_response:1; }","duration":"163.64213ms","start":"2026-08-26T05:17:36.222973Z","end":"2026-08-26T05:17:36.386615Z","steps":["trace[989173660] 'process raft request' (duration: 163.461246ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:17:36.398421Z","caller":"traceutil/trace.go:171","msg":"trace[1079204161] transaction","detail":"{read_only:false; response_revision:4880; number_of_response:1; }","duration":"115.366119ms","start":"2026-08-26T05:17:36.283025Z","end":"2026-08-26T05:17:36.398391Z","steps":["trace[1079204161] 'process raft request' (duration: 115.195303ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:17:40.353552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.929554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-26T05:17:40.353659Z","caller":"traceutil/trace.go:171","msg":"trace[389821397] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:5032; }","duration":"158.091859ms","start":"2026-08-26T05:17:40.195546Z","end":"2026-08-26T05:17:40.353638Z","steps":["trace[389821397] 'count revisions from in-memory index tree' (duration: 157.823242ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:17:40.35382Z","caller":"traceutil/trace.go:171","msg":"trace[762451302] transaction","detail":"{read_only:false; response_revision:5033; number_of_response:1; }","duration":"102.031885ms","start":"2026-08-26T05:17:40.25175Z","end":"2026-08-26T05:17:40.353782Z","steps":["trace[762451302] 'process raft request' (duration: 19.485464ms)","trace[762451302] 'compare' (duration: 81.837571ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T05:17:40.72779Z","caller":"traceutil/trace.go:171","msg":"trace[1036082943] transaction","detail":"{read_only:false; response_revision:5037; number_of_response:1; }","duration":"214.917914ms","start":"2026-08-26T05:17:40.512846Z","end":"2026-08-26T05:17:40.727764Z","steps":["trace[1036082943] 'process raft request' (duration: 214.74818ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:17:40.741106Z","caller":"traceutil/trace.go:171","msg":"trace[872319734] transaction","detail":"{read_only:false; response_revision:5038; number_of_response:1; }","duration":"183.183346ms","start":"2026-08-26T05:17:40.557894Z","end":"2026-08-26T05:17:40.741078Z","steps":["trace[872319734] 'process raft request' (duration: 182.98452ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:17:52.258449Z","caller":"traceutil/trace.go:171","msg":"trace[109081635] transaction","detail":"{read_only:false; response_revision:5280; number_of_response:1; }","duration":"261.891021ms","start":"2026-08-26T05:17:51.996524Z","end":"2026-08-26T05:17:52.258415Z","steps":["trace[109081635] 'process raft request' (duration: 169.279435ms)","trace[109081635] 'compare' (duration: 92.39306ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T05:17:52.258454Z","caller":"traceutil/trace.go:171","msg":"trace[552263575] transaction","detail":"{read_only:false; response_revision:5281; number_of_response:1; }","duration":"261.853299ms","start":"2026-08-26T05:17:51.996573Z","end":"2026-08-26T05:17:52.258426Z","steps":["trace[552263575] 'process raft request' (duration: 261.757096ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:17:52.258741Z","caller":"traceutil/trace.go:171","msg":"trace[2118465611] transaction","detail":"{read_only:false; response_revision:5282; number_of_response:1; }","duration":"261.402067ms","start":"2026-08-26T05:17:51.997315Z","end":"2026-08-26T05:17:52.258717Z","steps":["trace[2118465611] 'process raft request' (duration: 261.055598ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:17:52.258798Z","caller":"traceutil/trace.go:171","msg":"trace[1368368093] transaction","detail":"{read_only:false; response_revision:5283; number_of_response:1; }","duration":"255.387152ms","start":"2026-08-26T05:17:52.003385Z","end":"2026-08-26T05:17:52.258772Z","steps":["trace[1368368093] 'process raft request' (duration: 255.185417ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:17:52.43326Z","caller":"traceutil/trace.go:171","msg":"trace[2133392577] linearizableReadLoop","detail":"{readStateIndex:5480; appliedIndex:5479; }","duration":"122.075222ms","start":"2026-08-26T05:17:52.311157Z","end":"2026-08-26T05:17:52.433232Z","steps":["trace[2133392577] 'read index received' (duration: 4.967656ms)","trace[2133392577] 'applied index is now lower than readState.Index' (duration: 117.106096ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T05:17:52.433435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.262357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T05:17:52.433492Z","caller":"traceutil/trace.go:171","msg":"trace[1765950866] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5285; }","duration":"122.357632ms","start":"2026-08-26T05:17:52.311118Z","end":"2026-08-26T05:17:52.433476Z","steps":["trace[1765950866] 'agreement among raft nodes before linearized reading' (duration: 122.227937ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:17:52.43376Z","caller":"traceutil/trace.go:171","msg":"trace[1353752741] transaction","detail":"{read_only:false; response_revision:5285; number_of_response:1; }","duration":"163.464586ms","start":"2026-08-26T05:17:52.27026Z","end":"2026-08-26T05:17:52.433725Z","steps":["trace[1353752741] 'process raft request' (duration: 102.386844ms)","trace[1353752741] 'compare' (duration: 60.066074ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T05:17:52.766994Z","caller":"traceutil/trace.go:171","msg":"trace[2126834650] transaction","detail":"{read_only:false; response_revision:5286; number_of_response:1; }","duration":"328.049662ms","start":"2026-08-26T05:17:52.438916Z","end":"2026-08-26T05:17:52.766965Z","steps":["trace[2126834650] 'process raft request' (duration: 273.226271ms)","trace[2126834650] 'compare' (duration: 54.559324ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T05:17:52.767219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T05:17:52.438899Z","time spent":"328.236867ms","remote":"127.0.0.1:59400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":917,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T05:17:52.787191Z","caller":"traceutil/trace.go:171","msg":"trace[190696747] transaction","detail":"{read_only:false; response_revision:5287; number_of_response:1; }","duration":"347.528276ms","start":"2026-08-26T05:17:52.43964Z","end":"2026-08-26T05:17:52.787168Z","steps":["trace[190696747] 'process raft request' (duration: 347.34366ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:17:52.787332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T05:17:52.439621Z","time spent":"347.635968ms","remote":"127.0.0.1:59522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1433,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T05:17:52.787482Z","caller":"traceutil/trace.go:171","msg":"trace[1516979120] transaction","detail":"{read_only:false; response_revision:5288; number_of_response:1; }","duration":"345.619543ms","start":"2026-08-26T05:17:52.441833Z","end":"2026-08-26T05:17:52.787453Z","steps":["trace[1516979120] 'process raft request' (duration: 345.279864ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:17:52.787532Z","caller":"traceutil/trace.go:171","msg":"trace[1644049984] transaction","detail":"{read_only:false; response_revision:5289; number_of_response:1; }","duration":"344.120322ms","start":"2026-08-26T05:17:52.443396Z","end":"2026-08-26T05:17:52.787516Z","steps":["trace[1644049984] 'process raft request' (duration: 343.958147ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:17:52.78762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T05:17:52.441814Z","time spent":"345.734347ms","remote":"127.0.0.1:59756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3912,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-26T05:17:52.787713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T05:17:52.443387Z","time spent":"344.227515ms","remote":"127.0.0.1:59510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T05:17:52.80352Z","caller":"traceutil/trace.go:171","msg":"trace[1427584168] linearizableReadLoop","detail":"{readStateIndex:5485; appliedIndex:5480; }","duration":"166.14781ms","start":"2026-08-26T05:17:52.637332Z","end":"2026-08-26T05:17:52.80348Z","steps":["trace[1427584168] 'read index received' (duration: 74.820559ms)","trace[1427584168] 'applied index is now lower than readState.Index' (duration: 91.325011ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T05:17:52.803821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.448938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-26T05:17:52.803921Z","caller":"traceutil/trace.go:171","msg":"trace[342492924] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5289; }","duration":"166.609952ms","start":"2026-08-26T05:17:52.637294Z","end":"2026-08-26T05:17:52.803904Z","steps":["trace[342492924] 'agreement among raft nodes before linearized reading' (duration: 166.333215ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:18:00.019907Z","caller":"traceutil/trace.go:171","msg":"trace[1982548280] transaction","detail":"{read_only:false; response_revision:5462; number_of_response:1; }","duration":"117.9746ms","start":"2026-08-26T05:17:59.901891Z","end":"2026-08-26T05:18:00.019865Z","steps":["trace[1982548280] 'process raft request' (duration: 32.607072ms)","trace[1982548280] 'compare' (duration: 85.209363ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T05:19:14.3995Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2991} {"level":"info","ts":"2026-08-26T05:19:14.474372Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2991,"took":"73.324985ms","hash":1190081077,"current-db-size-bytes":58335232,"current-db-size":"58 MB","current-db-size-in-use-bytes":34562048,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-08-26T05:19:14.474433Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1190081077,"revision":2991,"compact-revision":-1} {"level":"info","ts":"2026-08-26T05:23:44.568667Z","caller":"traceutil/trace.go:171","msg":"trace[239400860] linearizableReadLoop","detail":"{readStateIndex:8301; appliedIndex:8300; }","duration":"259.094151ms","start":"2026-08-26T05:23:44.309548Z","end":"2026-08-26T05:23:44.568642Z","steps":["trace[239400860] 'read index received' (duration: 258.632019ms)","trace[239400860] 'applied index is now lower than readState.Index' (duration: 460.862µs)"],"step_count":2} {"level":"warn","ts":"2026-08-26T05:23:44.568807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.236334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T05:23:44.568863Z","caller":"traceutil/trace.go:171","msg":"trace[911474472] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8017; }","duration":"259.355978ms","start":"2026-08-26T05:23:44.309496Z","end":"2026-08-26T05:23:44.568852Z","steps":["trace[911474472] 'agreement among raft nodes before linearized reading' (duration: 259.254745ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:23:44.568923Z","caller":"traceutil/trace.go:171","msg":"trace[1202723781] transaction","detail":"{read_only:false; response_revision:8017; number_of_response:1; }","duration":"290.936268ms","start":"2026-08-26T05:23:44.277913Z","end":"2026-08-26T05:23:44.568849Z","steps":["trace[1202723781] 'process raft request' (duration: 290.338292ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:23:44.573294Z","caller":"traceutil/trace.go:171","msg":"trace[1269324960] transaction","detail":"{read_only:false; response_revision:8018; number_of_response:1; }","duration":"222.276437ms","start":"2026-08-26T05:23:44.351Z","end":"2026-08-26T05:23:44.573277Z","steps":["trace[1269324960] 'process raft request' (duration: 221.958708ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:23:51.087766Z","caller":"traceutil/trace.go:171","msg":"trace[1342975375] linearizableReadLoop","detail":"{readStateIndex:8475; appliedIndex:8474; }","duration":"317.49145ms","start":"2026-08-26T05:23:50.770248Z","end":"2026-08-26T05:23:51.087739Z","steps":["trace[1342975375] 'read index received' (duration: 317.281374ms)","trace[1342975375] 'applied index is now lower than readState.Index' (duration: 208.466µs)"],"step_count":2} {"level":"info","ts":"2026-08-26T05:23:51.087854Z","caller":"traceutil/trace.go:171","msg":"trace[842714157] transaction","detail":"{read_only:false; response_revision:8189; number_of_response:1; }","duration":"320.660748ms","start":"2026-08-26T05:23:50.767159Z","end":"2026-08-26T05:23:51.087819Z","steps":["trace[842714157] 'process raft request' (duration: 320.407221ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:23:51.08802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T05:23:50.76714Z","time spent":"320.798181ms","remote":"127.0.0.1:59510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-26T05:23:51.088119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.023195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"warn","ts":"2026-08-26T05:23:51.088147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.888242ms","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-08-26T05:23:51.088188Z","caller":"traceutil/trace.go:171","msg":"trace[315827010] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:8189; }","duration":"170.138499ms","start":"2026-08-26T05:23:50.918033Z","end":"2026-08-26T05:23:51.088172Z","steps":["trace[315827010] 'agreement among raft nodes before linearized reading' (duration: 169.895733ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:23:51.088212Z","caller":"traceutil/trace.go:171","msg":"trace[535132248] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8189; }","duration":"317.968133ms","start":"2026-08-26T05:23:50.770223Z","end":"2026-08-26T05:23:51.088191Z","steps":["trace[535132248] 'agreement among raft nodes before linearized reading' (duration: 317.677396ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:23:51.088257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T05:23:50.770214Z","time spent":"318.031415ms","remote":"127.0.0.1:59510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-26T05:23:51.088565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.307162ms","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-08-26T05:23:51.088629Z","caller":"traceutil/trace.go:171","msg":"trace[517586562] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8189; }","duration":"318.412135ms","start":"2026-08-26T05:23:50.770204Z","end":"2026-08-26T05:23:51.088616Z","steps":["trace[517586562] 'agreement among raft nodes before linearized reading' (duration: 318.184288ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:23:51.08868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T05:23:50.770174Z","time spent":"318.494099ms","remote":"127.0.0.1:59510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-26T05:23:51.330972Z","caller":"traceutil/trace.go:171","msg":"trace[1531649997] transaction","detail":"{read_only:false; response_revision:8190; number_of_response:1; }","duration":"238.881874ms","start":"2026-08-26T05:23:51.092031Z","end":"2026-08-26T05:23:51.330913Z","steps":["trace[1531649997] 'process raft request' (duration: 179.23303ms)","trace[1531649997] 'compare' (duration: 59.418108ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T05:23:51.332303Z","caller":"traceutil/trace.go:171","msg":"trace[1683880508] transaction","detail":"{read_only:false; response_revision:8191; number_of_response:1; }","duration":"239.912833ms","start":"2026-08-26T05:23:51.092372Z","end":"2026-08-26T05:23:51.332284Z","steps":["trace[1683880508] 'process raft request' (duration: 239.726147ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:23:51.332546Z","caller":"traceutil/trace.go:171","msg":"trace[961816565] linearizableReadLoop","detail":"{readStateIndex:8478; appliedIndex:8475; }","duration":"147.521486ms","start":"2026-08-26T05:23:51.185007Z","end":"2026-08-26T05:23:51.332529Z","steps":["trace[961816565] 'read index received' (duration: 86.264728ms)","trace[961816565] 'applied index is now lower than readState.Index' (duration: 61.253898ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T05:23:51.332581Z","caller":"traceutil/trace.go:171","msg":"trace[934567953] transaction","detail":"{read_only:false; response_revision:8192; number_of_response:1; }","duration":"240.154699ms","start":"2026-08-26T05:23:51.092406Z","end":"2026-08-26T05:23:51.332561Z","steps":["trace[934567953] 'process raft request' (duration: 239.83156ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:23:51.332776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.750102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-cl84d\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-08-26T05:23:51.332819Z","caller":"traceutil/trace.go:171","msg":"trace[127161586] range","detail":"{range_begin:/registry/pods/openstack/keepalived-cl84d; range_end:; response_count:1; response_revision:8193; }","duration":"147.830894ms","start":"2026-08-26T05:23:51.184971Z","end":"2026-08-26T05:23:51.332802Z","steps":["trace[127161586] 'agreement among raft nodes before linearized reading' (duration: 147.675639ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:24:02.899216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.892303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-08-26T05:24:02.899311Z","caller":"traceutil/trace.go:171","msg":"trace[2143231131] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8251; }","duration":"129.039216ms","start":"2026-08-26T05:24:02.770246Z","end":"2026-08-26T05:24:02.899286Z","steps":["trace[2143231131] 'range keys from in-memory index tree' (duration: 128.677526ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:24:04.785153Z","caller":"traceutil/trace.go:171","msg":"trace[312976977] transaction","detail":"{read_only:false; response_revision:8259; number_of_response:1; }","duration":"108.281535ms","start":"2026-08-26T05:24:04.676771Z","end":"2026-08-26T05:24:04.785052Z","steps":["trace[312976977] 'process raft request' (duration: 108.008087ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:24:04.926575Z","caller":"traceutil/trace.go:171","msg":"trace[1213387369] transaction","detail":"{read_only:false; response_revision:8260; number_of_response:1; }","duration":"136.640846ms","start":"2026-08-26T05:24:04.789702Z","end":"2026-08-26T05:24:04.926343Z","steps":["trace[1213387369] 'process raft request' (duration: 40.375193ms)","trace[1213387369] 'compare' (duration: 96.088168ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T05:24:05.504303Z","caller":"traceutil/trace.go:171","msg":"trace[1086437340] transaction","detail":"{read_only:false; response_revision:8263; number_of_response:1; }","duration":"104.792538ms","start":"2026-08-26T05:24:05.399489Z","end":"2026-08-26T05:24:05.504282Z","steps":["trace[1086437340] 'process raft request' (duration: 104.587892ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:24:05.504233Z","caller":"traceutil/trace.go:171","msg":"trace[1263267403] linearizableReadLoop","detail":"{readStateIndex:8552; appliedIndex:8551; }","duration":"100.157211ms","start":"2026-08-26T05:24:05.404054Z","end":"2026-08-26T05:24:05.504211Z","steps":["trace[1263267403] 'read index received' (duration: 99.961645ms)","trace[1263267403] 'applied index is now lower than readState.Index' (duration: 194.366µs)"],"step_count":2} {"level":"warn","ts":"2026-08-26T05:24:05.504666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.595502ms","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-08-26T05:24:05.504761Z","caller":"traceutil/trace.go:171","msg":"trace[1053745623] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8263; }","duration":"100.735935ms","start":"2026-08-26T05:24:05.404004Z","end":"2026-08-26T05:24:05.50474Z","steps":["trace[1053745623] 'agreement among raft nodes before linearized reading' (duration: 100.424447ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:24:14.406764Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6141} {"level":"info","ts":"2026-08-26T05:24:14.511439Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6141,"took":"102.783023ms","hash":3062458145,"current-db-size-bytes":58335232,"current-db-size":"58 MB","current-db-size-in-use-bytes":23465984,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-08-26T05:24:14.511503Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3062458145,"revision":6141,"compact-revision":2991} {"level":"warn","ts":"2026-08-26T05:24:31.616356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.064056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-26T05:24:31.616438Z","caller":"traceutil/trace.go:171","msg":"trace[490938867] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8460; }","duration":"232.19306ms","start":"2026-08-26T05:24:31.38423Z","end":"2026-08-26T05:24:31.616423Z","steps":["trace[490938867] 'range keys from in-memory index tree' (duration: 231.920273ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:24:31.616722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.991305ms","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-08-26T05:24:31.616796Z","caller":"traceutil/trace.go:171","msg":"trace[1395721752] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:8460; }","duration":"121.113307ms","start":"2026-08-26T05:24:31.495667Z","end":"2026-08-26T05:24:31.61678Z","steps":["trace[1395721752] 'count revisions from in-memory index tree' (duration: 120.912873ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:24:31.616969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.093736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T05:24:31.617003Z","caller":"traceutil/trace.go:171","msg":"trace[641118726] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8460; }","duration":"306.156827ms","start":"2026-08-26T05:24:31.310836Z","end":"2026-08-26T05:24:31.616993Z","steps":["trace[641118726] 'range keys from in-memory index tree' (duration: 306.026295ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:24:31.617033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T05:24:31.3108Z","time spent":"306.223231ms","remote":"127.0.0.1:59194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-26T05:24:38.587067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.596701ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-26T05:24:38.587614Z","caller":"traceutil/trace.go:171","msg":"trace[1336410308] transaction","detail":"{read_only:false; response_revision:8501; number_of_response:1; }","duration":"345.660577ms","start":"2026-08-26T05:24:38.241932Z","end":"2026-08-26T05:24:38.587592Z","steps":["trace[1336410308] 'process raft request' (duration: 157.283115ms)","trace[1336410308] 'compare' (duration: 187.24923ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T05:24:38.587759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T05:24:38.24191Z","time spent":"345.7928ms","remote":"127.0.0.1:59510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-26T05:24:38.587842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.924203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T05:24:38.587931Z","caller":"traceutil/trace.go:171","msg":"trace[39321950] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8501; }","duration":"289.055657ms","start":"2026-08-26T05:24:38.298855Z","end":"2026-08-26T05:24:38.58791Z","steps":["trace[39321950] 'agreement among raft nodes before linearized reading' (duration: 288.911283ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:24:38.587549Z","caller":"traceutil/trace.go:171","msg":"trace[1204562872] linearizableReadLoop","detail":"{readStateIndex:8801; appliedIndex:8800; }","duration":"288.624785ms","start":"2026-08-26T05:24:38.298904Z","end":"2026-08-26T05:24:38.587529Z","steps":["trace[1204562872] 'read index received' (duration: 100.305925ms)","trace[1204562872] 'applied index is now lower than readState.Index' (duration: 188.3174ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T05:24:38.620736Z","caller":"traceutil/trace.go:171","msg":"trace[316444632] transaction","detail":"{read_only:false; response_revision:8502; number_of_response:1; }","duration":"156.983226ms","start":"2026-08-26T05:24:38.463728Z","end":"2026-08-26T05:24:38.620711Z","steps":["trace[316444632] 'process raft request' (duration: 156.868493ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:24:38.621573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.678748ms","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-08-26T05:24:38.62164Z","caller":"traceutil/trace.go:171","msg":"trace[783960029] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8502; }","duration":"164.797281ms","start":"2026-08-26T05:24:38.456826Z","end":"2026-08-26T05:24:38.621624Z","steps":["trace[783960029] 'agreement among raft nodes before linearized reading' (duration: 164.666958ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:24:38.621838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.952028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-26T05:24:38.621997Z","caller":"traceutil/trace.go:171","msg":"trace[1784876705] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8502; }","duration":"275.361489ms","start":"2026-08-26T05:24:38.346621Z","end":"2026-08-26T05:24:38.621982Z","steps":["trace[1784876705] 'agreement among raft nodes before linearized reading' (duration: 274.934688ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:24:38.621923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.582888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T05:24:38.622237Z","caller":"traceutil/trace.go:171","msg":"trace[487023512] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8502; }","duration":"311.919007ms","start":"2026-08-26T05:24:38.310306Z","end":"2026-08-26T05:24:38.622225Z","steps":["trace[487023512] 'agreement among raft nodes before linearized reading' (duration: 311.588908ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:24:38.622306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T05:24:38.310291Z","time spent":"312.001459ms","remote":"127.0.0.1:59206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-26T05:24:44.515497Z","caller":"traceutil/trace.go:171","msg":"trace[1691755598] transaction","detail":"{read_only:false; response_revision:8571; number_of_response:1; }","duration":"280.539072ms","start":"2026-08-26T05:24:44.234932Z","end":"2026-08-26T05:24:44.515471Z","steps":["trace[1691755598] 'process raft request' (duration: 280.302566ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:24:44.515758Z","caller":"traceutil/trace.go:171","msg":"trace[1317883180] transaction","detail":"{read_only:false; response_revision:8570; number_of_response:1; }","duration":"324.857403ms","start":"2026-08-26T05:24:44.190852Z","end":"2026-08-26T05:24:44.515709Z","steps":["trace[1317883180] 'process raft request' (duration: 320.71173ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:24:44.51594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T05:24:44.190818Z","time spent":"325.047118ms","remote":"127.0.0.1:59420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4637,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T05:24:44.733174Z","caller":"traceutil/trace.go:171","msg":"trace[1865776418] transaction","detail":"{read_only:false; response_revision:8572; number_of_response:1; }","duration":"131.869704ms","start":"2026-08-26T05:24:44.601278Z","end":"2026-08-26T05:24:44.733148Z","steps":["trace[1865776418] 'process raft request' (duration: 131.71778ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:24:44.744635Z","caller":"traceutil/trace.go:171","msg":"trace[1133963478] transaction","detail":"{read_only:false; response_revision:8573; number_of_response:1; }","duration":"125.942942ms","start":"2026-08-26T05:24:44.618668Z","end":"2026-08-26T05:24:44.744611Z","steps":["trace[1133963478] 'process raft request' (duration: 125.307054ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:24:44.74479Z","caller":"traceutil/trace.go:171","msg":"trace[49177901] transaction","detail":"{read_only:false; response_revision:8574; number_of_response:1; }","duration":"124.11983ms","start":"2026-08-26T05:24:44.620636Z","end":"2026-08-26T05:24:44.744756Z","steps":["trace[49177901] 'process raft request' (duration: 123.458892ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:25:04.448847Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000238a.wal"} {"level":"info","ts":"2026-08-26T05:25:44.987933Z","caller":"traceutil/trace.go:171","msg":"trace[773850537] transaction","detail":"{read_only:false; response_revision:9218; number_of_response:1; }","duration":"103.71114ms","start":"2026-08-26T05:25:44.884184Z","end":"2026-08-26T05:25:44.987895Z","steps":["trace[773850537] 'process raft request' (duration: 62.603402ms)","trace[773850537] 'compare' (duration: 40.851141ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T05:25:56.321142Z","caller":"traceutil/trace.go:171","msg":"trace[1519957010] transaction","detail":"{read_only:false; response_revision:9292; number_of_response:1; }","duration":"194.685155ms","start":"2026-08-26T05:25:56.126431Z","end":"2026-08-26T05:25:56.321116Z","steps":["trace[1519957010] 'process raft request' (duration: 194.520631ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:25:56.471888Z","caller":"traceutil/trace.go:171","msg":"trace[735574478] transaction","detail":"{read_only:false; response_revision:9293; number_of_response:1; }","duration":"145.876237ms","start":"2026-08-26T05:25:56.325979Z","end":"2026-08-26T05:25:56.471855Z","steps":["trace[735574478] 'process raft request' (duration: 145.710463ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:25:56.4898Z","caller":"traceutil/trace.go:171","msg":"trace[1144201389] transaction","detail":"{read_only:false; response_revision:9294; number_of_response:1; }","duration":"163.553188ms","start":"2026-08-26T05:25:56.326214Z","end":"2026-08-26T05:25:56.489768Z","steps":["trace[1144201389] 'process raft request' (duration: 163.398674ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:25:56.490031Z","caller":"traceutil/trace.go:171","msg":"trace[525519227] transaction","detail":"{read_only:false; response_revision:9295; number_of_response:1; }","duration":"163.770635ms","start":"2026-08-26T05:25:56.326246Z","end":"2026-08-26T05:25:56.490016Z","steps":["trace[525519227] 'process raft request' (duration: 163.470297ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:25:56.490339Z","caller":"traceutil/trace.go:171","msg":"trace[660095098] linearizableReadLoop","detail":"{readStateIndex:9618; appliedIndex:9614; }","duration":"162.750318ms","start":"2026-08-26T05:25:56.327576Z","end":"2026-08-26T05:25:56.490326Z","steps":["trace[660095098] 'read index received' (duration: 144.144531ms)","trace[660095098] 'applied index is now lower than readState.Index' (duration: 18.604567ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T05:25:56.490409Z","caller":"traceutil/trace.go:171","msg":"trace[424134459] transaction","detail":"{read_only:false; response_revision:9296; number_of_response:1; }","duration":"163.97114ms","start":"2026-08-26T05:25:56.326417Z","end":"2026-08-26T05:25:56.490389Z","steps":["trace[424134459] 'process raft request' (duration: 163.522298ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:25:56.490942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.338543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:31 size:291194"} {"level":"info","ts":"2026-08-26T05:25:56.491014Z","caller":"traceutil/trace.go:171","msg":"trace[795150564] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:31; response_revision:9296; }","duration":"163.433576ms","start":"2026-08-26T05:25:56.327561Z","end":"2026-08-26T05:25:56.490995Z","steps":["trace[795150564] 'agreement among raft nodes before linearized reading' (duration: 162.813279ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:26:46.507348Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"eae99266654eff69","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-26T05:26:46.515445Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-26T05:26:46.515565Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-26T05:29:14.413232Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8320} {"level":"info","ts":"2026-08-26T05:29:14.491663Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8320,"took":"76.688007ms","hash":3239297389,"current-db-size-bytes":58335232,"current-db-size":"58 MB","current-db-size-in-use-bytes":26021888,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-08-26T05:29:14.491743Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3239297389,"revision":8320,"compact-revision":6141} {"level":"warn","ts":"2026-08-26T05:29:35.894633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.449693ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-26T05:29:35.895284Z","caller":"traceutil/trace.go:171","msg":"trace[1310068088] transaction","detail":"{read_only:false; response_revision:11115; number_of_response:1; }","duration":"235.23731ms","start":"2026-08-26T05:29:35.660015Z","end":"2026-08-26T05:29:35.895252Z","steps":["trace[1310068088] 'process raft request' (duration: 234.840699ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:29:35.895343Z","caller":"traceutil/trace.go:171","msg":"trace[1921717212] transaction","detail":"{read_only:false; response_revision:11114; number_of_response:1; }","duration":"236.563626ms","start":"2026-08-26T05:29:35.658755Z","end":"2026-08-26T05:29:35.895319Z","steps":["trace[1921717212] 'process raft request' (duration: 123.332341ms)","trace[1921717212] 'compare' (duration: 112.281459ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T05:29:35.895569Z","caller":"traceutil/trace.go:171","msg":"trace[2123470902] transaction","detail":"{read_only:false; response_revision:11116; number_of_response:1; }","duration":"235.490548ms","start":"2026-08-26T05:29:35.660068Z","end":"2026-08-26T05:29:35.895558Z","steps":["trace[2123470902] 'process raft request' (duration: 234.944542ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:29:35.895798Z","caller":"traceutil/trace.go:171","msg":"trace[498677624] linearizableReadLoop","detail":"{readStateIndex:11492; appliedIndex:11489; }","duration":"135.583406ms","start":"2026-08-26T05:29:35.760197Z","end":"2026-08-26T05:29:35.89578Z","steps":["trace[498677624] 'read index received' (duration: 21.837486ms)","trace[498677624] 'applied index is now lower than readState.Index' (duration: 113.74413ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T05:29:35.895978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.769762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T05:29:35.896041Z","caller":"traceutil/trace.go:171","msg":"trace[876184301] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:11117; }","duration":"135.871914ms","start":"2026-08-26T05:29:35.760154Z","end":"2026-08-26T05:29:35.896026Z","steps":["trace[876184301] 'agreement among raft nodes before linearized reading' (duration: 135.742831ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:29:35.896255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.972427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T05:29:35.896354Z","caller":"traceutil/trace.go:171","msg":"trace[1138615078] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:11117; }","duration":"136.120851ms","start":"2026-08-26T05:29:35.760211Z","end":"2026-08-26T05:29:35.896331Z","steps":["trace[1138615078] 'agreement among raft nodes before linearized reading' (duration: 135.984386ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:29:35.896702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.10868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-26T05:29:35.89671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.241484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-26T05:29:35.896753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.527974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5079"} {"level":"info","ts":"2026-08-26T05:29:35.896769Z","caller":"traceutil/trace.go:171","msg":"trace[234512225] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:11117; }","duration":"136.219163ms","start":"2026-08-26T05:29:35.76053Z","end":"2026-08-26T05:29:35.896749Z","steps":["trace[234512225] 'agreement among raft nodes before linearized reading' (duration: 136.086709ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:29:35.896806Z","caller":"traceutil/trace.go:171","msg":"trace[1991651715] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:11117; }","duration":"136.365867ms","start":"2026-08-26T05:29:35.760413Z","end":"2026-08-26T05:29:35.896779Z","steps":["trace[1991651715] 'agreement among raft nodes before linearized reading' (duration: 136.173181ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:29:35.896816Z","caller":"traceutil/trace.go:171","msg":"trace[1990327190] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:11117; }","duration":"135.615527ms","start":"2026-08-26T05:29:35.761182Z","end":"2026-08-26T05:29:35.896797Z","steps":["trace[1990327190] 'agreement among raft nodes before linearized reading' (duration: 135.343779ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:29:35.896796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.837949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-26T05:29:35.896879Z","caller":"traceutil/trace.go:171","msg":"trace[1528606923] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:11117; }","duration":"107.982722ms","start":"2026-08-26T05:29:35.788873Z","end":"2026-08-26T05:29:35.896856Z","steps":["trace[1528606923] 'agreement among raft nodes before linearized reading' (duration: 107.687764ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:29:36.15713Z","caller":"traceutil/trace.go:171","msg":"trace[295756020] transaction","detail":"{read_only:false; response_revision:11118; number_of_response:1; }","duration":"237.302046ms","start":"2026-08-26T05:29:35.919799Z","end":"2026-08-26T05:29:36.157101Z","steps":["trace[295756020] 'process raft request' (duration: 153.3191ms)","trace[295756020] 'compare' (duration: 83.686527ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T05:29:36.35965Z","caller":"traceutil/trace.go:171","msg":"trace[1366003835] transaction","detail":"{read_only:false; response_revision:11119; number_of_response:1; }","duration":"141.382564ms","start":"2026-08-26T05:29:36.218223Z","end":"2026-08-26T05:29:36.359605Z","steps":["trace[1366003835] 'process raft request' (duration: 58.747046ms)","trace[1366003835] 'compare' (duration: 82.195786ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T05:34:14.419088Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10975} {"level":"info","ts":"2026-08-26T05:34:14.503726Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10975,"took":"80.809849ms","hash":288616141,"current-db-size-bytes":58335232,"current-db-size":"58 MB","current-db-size-in-use-bytes":27877376,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-26T05:34:14.503796Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":288616141,"revision":10975,"compact-revision":8320} {"level":"info","ts":"2026-08-26T05:35:42.23086Z","caller":"traceutil/trace.go:171","msg":"trace[1051162307] transaction","detail":"{read_only:false; response_revision:13597; number_of_response:1; }","duration":"200.41147ms","start":"2026-08-26T05:35:42.030417Z","end":"2026-08-26T05:35:42.230829Z","steps":["trace[1051162307] 'process raft request' (duration: 200.205235ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:36:00.272809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.115183ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-26T05:36:00.27295Z","caller":"traceutil/trace.go:171","msg":"trace[775994659] linearizableReadLoop","detail":"{readStateIndex:14173; appliedIndex:14172; }","duration":"734.179583ms","start":"2026-08-26T05:35:59.538753Z","end":"2026-08-26T05:36:00.272933Z","steps":["trace[775994659] 'read index received' (duration: 160.755771ms)","trace[775994659] 'applied index is now lower than readState.Index' (duration: 573.422482ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T05:36:00.273094Z","caller":"traceutil/trace.go:171","msg":"trace[61347978] transaction","detail":"{read_only:false; response_revision:13705; number_of_response:1; }","duration":"807.652856ms","start":"2026-08-26T05:35:59.465378Z","end":"2026-08-26T05:36:00.273031Z","steps":["trace[61347978] 'process raft request' (duration: 234.232154ms)","trace[61347978] 'compare' (duration: 572.872097ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T05:36:00.273279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T05:35:59.46536Z","time spent":"807.82182ms","remote":"127.0.0.1:59510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-26T05:36:00.273302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"734.539842ms","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-08-26T05:36:00.273402Z","caller":"traceutil/trace.go:171","msg":"trace[843288205] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:13705; }","duration":"734.639097ms","start":"2026-08-26T05:35:59.53872Z","end":"2026-08-26T05:36:00.273359Z","steps":["trace[843288205] 'agreement among raft nodes before linearized reading' (duration: 734.305298ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:36:00.273501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T05:35:59.538703Z","time spent":"734.78168ms","remote":"127.0.0.1:59510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-26T05:36:00.27348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.57882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-26T05:36:00.273523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.088933ms","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-08-26T05:36:00.273749Z","caller":"traceutil/trace.go:171","msg":"trace[3745453] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:13705; }","duration":"243.792295ms","start":"2026-08-26T05:36:00.029752Z","end":"2026-08-26T05:36:00.273544Z","steps":["trace[3745453] 'agreement among raft nodes before linearized reading' (duration: 243.606011ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:36:00.273777Z","caller":"traceutil/trace.go:171","msg":"trace[70160041] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:13705; }","duration":"519.34543ms","start":"2026-08-26T05:35:59.754411Z","end":"2026-08-26T05:36:00.273756Z","steps":["trace[70160041] 'agreement among raft nodes before linearized reading' (duration: 518.934029ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:36:00.273765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.828768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-26T05:36:00.273825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.747636ms","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-08-26T05:36:00.27389Z","caller":"traceutil/trace.go:171","msg":"trace[2010294604] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:13705; }","duration":"339.208918ms","start":"2026-08-26T05:35:59.934655Z","end":"2026-08-26T05:36:00.273863Z","steps":["trace[2010294604] 'agreement among raft nodes before linearized reading' (duration: 338.732245ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:36:00.273939Z","caller":"traceutil/trace.go:171","msg":"trace[521491045] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:13705; }","duration":"521.915061ms","start":"2026-08-26T05:35:59.751995Z","end":"2026-08-26T05:36:00.27391Z","steps":["trace[521491045] 'agreement among raft nodes before linearized reading' (duration: 521.395546ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:36:00.273955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T05:35:59.934629Z","time spent":"339.313671ms","remote":"127.0.0.1:33210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true "} {"level":"warn","ts":"2026-08-26T05:36:00.273994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T05:35:59.751972Z","time spent":"522.013195ms","remote":"127.0.0.1:59510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-26T05:36:00.273406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.043784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-26T05:36:00.274222Z","caller":"traceutil/trace.go:171","msg":"trace[1251338127] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:13705; }","duration":"522.96629ms","start":"2026-08-26T05:35:59.751228Z","end":"2026-08-26T05:36:00.274194Z","steps":["trace[1251338127] 'agreement among raft nodes before linearized reading' (duration: 521.867579ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:36:00.274305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T05:35:59.751208Z","time spent":"523.082152ms","remote":"127.0.0.1:59510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-26T05:36:00.273816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T05:35:59.754389Z","time spent":"519.418553ms","remote":"127.0.0.1:59510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-26T05:36:00.274831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.743803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:86 size:929956"} {"level":"info","ts":"2026-08-26T05:36:00.274925Z","caller":"traceutil/trace.go:171","msg":"trace[2125123766] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:86; response_revision:13705; }","duration":"511.878197ms","start":"2026-08-26T05:35:59.763028Z","end":"2026-08-26T05:36:00.274906Z","steps":["trace[2125123766] 'agreement among raft nodes before linearized reading' (duration: 510.310514ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:36:00.27498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T05:35:59.763013Z","time spent":"511.952399ms","remote":"127.0.0.1:59324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":86,"response size":929979,"request content":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" "} {"level":"info","ts":"2026-08-26T05:37:07.063288Z","caller":"traceutil/trace.go:171","msg":"trace[1140919479] transaction","detail":"{read_only:false; response_revision:14730; number_of_response:1; }","duration":"100.818241ms","start":"2026-08-26T05:37:06.962428Z","end":"2026-08-26T05:37:07.063246Z","steps":["trace[1140919479] 'process raft request' (duration: 90.317604ms)","trace[1140919479] 'compare' (duration: 10.327392ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T05:37:07.063803Z","caller":"traceutil/trace.go:171","msg":"trace[783815675] transaction","detail":"{read_only:false; response_revision:14731; number_of_response:1; }","duration":"101.286852ms","start":"2026-08-26T05:37:06.962491Z","end":"2026-08-26T05:37:07.063778Z","steps":["trace[783815675] 'process raft request' (duration: 101.024826ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:37:07.064664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.637806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:79 size:769546"} {"level":"info","ts":"2026-08-26T05:37:07.064771Z","caller":"traceutil/trace.go:171","msg":"trace[1906801697] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:79; response_revision:14731; }","duration":"100.764489ms","start":"2026-08-26T05:37:06.963985Z","end":"2026-08-26T05:37:07.064749Z","steps":["trace[1906801697] 'agreement among raft nodes before linearized reading' (duration: 99.69623ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:37:41.102046Z","caller":"traceutil/trace.go:171","msg":"trace[314706546] transaction","detail":"{read_only:false; response_revision:14906; number_of_response:1; }","duration":"284.749887ms","start":"2026-08-26T05:37:40.81727Z","end":"2026-08-26T05:37:41.10202Z","steps":["trace[314706546] 'process raft request' (duration: 284.579681ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:37:41.110891Z","caller":"traceutil/trace.go:171","msg":"trace[1890688634] linearizableReadLoop","detail":"{readStateIndex:15409; appliedIndex:15407; }","duration":"289.539188ms","start":"2026-08-26T05:37:40.821337Z","end":"2026-08-26T05:37:41.110877Z","steps":["trace[1890688634] 'read index received' (duration: 280.607543ms)","trace[1890688634] 'applied index is now lower than readState.Index' (duration: 8.930605ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T05:37:41.111109Z","caller":"traceutil/trace.go:171","msg":"trace[1353875082] transaction","detail":"{read_only:false; response_revision:14907; number_of_response:1; }","duration":"290.851923ms","start":"2026-08-26T05:37:40.820249Z","end":"2026-08-26T05:37:41.1111Z","steps":["trace[1353875082] 'process raft request' (duration: 290.566456ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:37:41.111303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.800885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-08-26T05:37:41.1113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.953479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-08-26T05:37:41.11136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.267319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:682"} {"level":"info","ts":"2026-08-26T05:37:41.111357Z","caller":"traceutil/trace.go:171","msg":"trace[232393759] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14907; }","duration":"289.882397ms","start":"2026-08-26T05:37:40.821461Z","end":"2026-08-26T05:37:41.111344Z","steps":["trace[232393759] 'agreement among raft nodes before linearized reading' (duration: 289.701022ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:37:41.111369Z","caller":"traceutil/trace.go:171","msg":"trace[1256027070] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14907; }","duration":"290.060893ms","start":"2026-08-26T05:37:40.821291Z","end":"2026-08-26T05:37:41.111352Z","steps":["trace[1256027070] 'agreement among raft nodes before linearized reading' (duration: 289.867307ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:37:41.111389Z","caller":"traceutil/trace.go:171","msg":"trace[2028717866] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:14907; }","duration":"286.31391ms","start":"2026-08-26T05:37:40.825063Z","end":"2026-08-26T05:37:41.111377Z","steps":["trace[2028717866] 'agreement among raft nodes before linearized reading' (duration: 286.236838ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:37:41.111508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.651229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-08-26T05:37:41.111559Z","caller":"traceutil/trace.go:171","msg":"trace[609342722] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14907; }","duration":"286.714071ms","start":"2026-08-26T05:37:40.824836Z","end":"2026-08-26T05:37:41.111551Z","steps":["trace[609342722] 'agreement among raft nodes before linearized reading' (duration: 286.603498ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:37:41.111583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.198991ms","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":"warn","ts":"2026-08-26T05:37:41.111654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.840329ms","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-08-26T05:37:41.111643Z","caller":"traceutil/trace.go:171","msg":"trace[1946422141] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:14907; }","duration":"216.314723ms","start":"2026-08-26T05:37:40.895319Z","end":"2026-08-26T05:37:41.111633Z","steps":["trace[1946422141] 'agreement among raft nodes before linearized reading' (duration: 216.21877ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:37:41.111697Z","caller":"traceutil/trace.go:171","msg":"trace[1081804442] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:14907; }","duration":"288.885371ms","start":"2026-08-26T05:37:40.822795Z","end":"2026-08-26T05:37:41.11168Z","steps":["trace[1081804442] 'agreement among raft nodes before linearized reading' (duration: 288.817408ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:37:41.111523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.396939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T05:37:41.111744Z","caller":"traceutil/trace.go:171","msg":"trace[1066878332] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:14907; }","duration":"161.650117ms","start":"2026-08-26T05:37:40.950081Z","end":"2026-08-26T05:37:41.111731Z","steps":["trace[1066878332] 'agreement among raft nodes before linearized reading' (duration: 161.41772ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:37:41.112033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.40927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:666"} {"level":"info","ts":"2026-08-26T05:37:41.112062Z","caller":"traceutil/trace.go:171","msg":"trace[1471873681] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:14907; }","duration":"287.479211ms","start":"2026-08-26T05:37:40.824575Z","end":"2026-08-26T05:37:41.112054Z","steps":["trace[1471873681] 'agreement among raft nodes before linearized reading' (duration: 286.902756ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:39:14.426842Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":13017} {"level":"info","ts":"2026-08-26T05:39:14.504302Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":13017,"took":"75.294113ms","hash":2013206593,"current-db-size-bytes":58335232,"current-db-size":"58 MB","current-db-size-in-use-bytes":30846976,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-26T05:39:14.504355Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2013206593,"revision":13017,"compact-revision":10975} {"level":"info","ts":"2026-08-26T05:40:22.398493Z","caller":"traceutil/trace.go:171","msg":"trace[837644731] transaction","detail":"{read_only:false; response_revision:16212; number_of_response:1; }","duration":"159.701923ms","start":"2026-08-26T05:40:22.238753Z","end":"2026-08-26T05:40:22.398455Z","steps":["trace[837644731] 'process raft request' (duration: 159.439995ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:43:21.163975Z","caller":"traceutil/trace.go:171","msg":"trace[1674956122] linearizableReadLoop","detail":"{readStateIndex:18086; appliedIndex:18085; }","duration":"183.557937ms","start":"2026-08-26T05:43:20.980389Z","end":"2026-08-26T05:43:21.163947Z","steps":["trace[1674956122] 'read index received' (duration: 183.455694ms)","trace[1674956122] 'applied index is now lower than readState.Index' (duration: 101.063µs)"],"step_count":2} {"level":"info","ts":"2026-08-26T05:43:21.164029Z","caller":"traceutil/trace.go:171","msg":"trace[5119906] transaction","detail":"{read_only:false; response_revision:17501; number_of_response:1; }","duration":"185.977593ms","start":"2026-08-26T05:43:20.978018Z","end":"2026-08-26T05:43:21.163995Z","steps":["trace[5119906] 'process raft request' (duration: 185.782907ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:43:21.164184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.775573ms","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-08-26T05:43:21.164232Z","caller":"traceutil/trace.go:171","msg":"trace[112196578] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:17501; }","duration":"183.896147ms","start":"2026-08-26T05:43:20.980322Z","end":"2026-08-26T05:43:21.164218Z","steps":["trace[112196578] 'agreement among raft nodes before linearized reading' (duration: 183.756882ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:43:21.171397Z","caller":"traceutil/trace.go:171","msg":"trace[393776011] transaction","detail":"{read_only:false; response_revision:17502; number_of_response:1; }","duration":"179.496347ms","start":"2026-08-26T05:43:20.991869Z","end":"2026-08-26T05:43:21.171366Z","steps":["trace[393776011] 'process raft request' (duration: 179.365594ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:43:21.171567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.601609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-26T05:43:21.171623Z","caller":"traceutil/trace.go:171","msg":"trace[917855920] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:17503; }","duration":"123.71764ms","start":"2026-08-26T05:43:21.047895Z","end":"2026-08-26T05:43:21.171613Z","steps":["trace[917855920] 'agreement among raft nodes before linearized reading' (duration: 123.450144ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:43:26.862023Z","caller":"traceutil/trace.go:171","msg":"trace[2134649524] transaction","detail":"{read_only:false; response_revision:17684; number_of_response:1; }","duration":"256.212603ms","start":"2026-08-26T05:43:26.605776Z","end":"2026-08-26T05:43:26.861989Z","steps":["trace[2134649524] 'process raft request' (duration: 255.836414ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:43:27.264694Z","caller":"traceutil/trace.go:171","msg":"trace[1976499963] transaction","detail":"{read_only:false; response_revision:17688; number_of_response:1; }","duration":"394.988125ms","start":"2026-08-26T05:43:26.869685Z","end":"2026-08-26T05:43:27.264673Z","steps":["trace[1976499963] 'process raft request' (duration: 394.948905ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:43:27.264858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T05:43:26.869655Z","time spent":"395.120428ms","remote":"127.0.0.1:59510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T05:43:27.264885Z","caller":"traceutil/trace.go:171","msg":"trace[1327478234] transaction","detail":"{read_only:false; response_revision:17686; number_of_response:1; }","duration":"396.097046ms","start":"2026-08-26T05:43:26.868751Z","end":"2026-08-26T05:43:27.264848Z","steps":["trace[1327478234] 'process raft request' (duration: 395.724717ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:43:27.265106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T05:43:26.868732Z","time spent":"396.280811ms","remote":"127.0.0.1:59510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T05:43:27.26524Z","caller":"traceutil/trace.go:171","msg":"trace[544387314] transaction","detail":"{read_only:false; response_revision:17687; number_of_response:1; }","duration":"395.651234ms","start":"2026-08-26T05:43:26.869561Z","end":"2026-08-26T05:43:27.265212Z","steps":["trace[544387314] 'process raft request' (duration: 395.033987ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:43:27.26552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T05:43:26.869533Z","time spent":"395.923231ms","remote":"127.0.0.1:59510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T05:43:27.469072Z","caller":"traceutil/trace.go:171","msg":"trace[1555164141] linearizableReadLoop","detail":"{readStateIndex:18276; appliedIndex:18272; }","duration":"277.444493ms","start":"2026-08-26T05:43:27.191582Z","end":"2026-08-26T05:43:27.469027Z","steps":["trace[1555164141] 'read index received' (duration: 72.876062ms)","trace[1555164141] 'applied index is now lower than readState.Index' (duration: 204.566921ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T05:43:27.469565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.954237ms","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-08-26T05:43:27.469636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.860195ms","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-08-26T05:43:27.469684Z","caller":"traceutil/trace.go:171","msg":"trace[2083442605] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:17689; }","duration":"278.114102ms","start":"2026-08-26T05:43:27.191539Z","end":"2026-08-26T05:43:27.469653Z","steps":["trace[2083442605] 'agreement among raft nodes before linearized reading' (duration: 277.817033ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:43:27.46973Z","caller":"traceutil/trace.go:171","msg":"trace[385234447] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17689; }","duration":"277.980808ms","start":"2026-08-26T05:43:27.191725Z","end":"2026-08-26T05:43:27.469706Z","steps":["trace[385234447] 'agreement among raft nodes before linearized reading' (duration: 277.711071ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:43:27.46982Z","caller":"traceutil/trace.go:171","msg":"trace[726058953] transaction","detail":"{read_only:false; response_revision:17689; number_of_response:1; }","duration":"281.606497ms","start":"2026-08-26T05:43:27.18818Z","end":"2026-08-26T05:43:27.469786Z","steps":["trace[726058953] 'process raft request' (duration: 226.418739ms)","trace[726058953] 'compare' (duration: 54.155259ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T05:43:27.469869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.103679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.20\" ","response":"range_response_count:1 size:137"} {"level":"warn","ts":"2026-08-26T05:43:27.469715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.44107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-26T05:43:27.469963Z","caller":"traceutil/trace.go:171","msg":"trace[1191170267] range","detail":"{range_begin:/registry/masterleases/162.253.55.20; range_end:; response_count:1; response_revision:17689; }","duration":"129.340785ms","start":"2026-08-26T05:43:27.340603Z","end":"2026-08-26T05:43:27.469944Z","steps":["trace[1191170267] 'agreement among raft nodes before linearized reading' (duration: 129.039156ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:43:27.470002Z","caller":"traceutil/trace.go:171","msg":"trace[1694484438] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17689; }","duration":"158.752379ms","start":"2026-08-26T05:43:27.311225Z","end":"2026-08-26T05:43:27.469978Z","steps":["trace[1694484438] 'agreement among raft nodes before linearized reading' (duration: 158.418699ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:43:35.724384Z","caller":"traceutil/trace.go:171","msg":"trace[718129831] linearizableReadLoop","detail":"{readStateIndex:18416; appliedIndex:18415; }","duration":"125.869341ms","start":"2026-08-26T05:43:35.598493Z","end":"2026-08-26T05:43:35.724362Z","steps":["trace[718129831] 'read index received' (duration: 125.785158ms)","trace[718129831] 'applied index is now lower than readState.Index' (duration: 82.813µs)"],"step_count":2} {"level":"info","ts":"2026-08-26T05:43:35.724475Z","caller":"traceutil/trace.go:171","msg":"trace[4257131] transaction","detail":"{read_only:false; response_revision:17821; number_of_response:1; }","duration":"126.291462ms","start":"2026-08-26T05:43:35.598146Z","end":"2026-08-26T05:43:35.724437Z","steps":["trace[4257131] 'process raft request' (duration: 126.036595ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:43:35.724692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.168219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:709"} {"level":"info","ts":"2026-08-26T05:43:35.724803Z","caller":"traceutil/trace.go:171","msg":"trace[403268491] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:17821; }","duration":"126.335912ms","start":"2026-08-26T05:43:35.598444Z","end":"2026-08-26T05:43:35.72478Z","steps":["trace[403268491] 'agreement among raft nodes before linearized reading' (duration: 126.051134ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:43:35.73366Z","caller":"traceutil/trace.go:171","msg":"trace[1890251815] transaction","detail":"{read_only:false; response_revision:17822; number_of_response:1; }","duration":"134.979528ms","start":"2026-08-26T05:43:35.598666Z","end":"2026-08-26T05:43:35.733646Z","steps":["trace[1890251815] 'process raft request' (duration: 134.737292ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:43:35.73365Z","caller":"traceutil/trace.go:171","msg":"trace[801691852] transaction","detail":"{read_only:false; response_revision:17823; number_of_response:1; }","duration":"122.160829ms","start":"2026-08-26T05:43:35.611466Z","end":"2026-08-26T05:43:35.733627Z","steps":["trace[801691852] 'process raft request' (duration: 122.030226ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:43:35.733866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.039904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-26T05:43:35.733892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.97083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-26T05:43:35.733923Z","caller":"traceutil/trace.go:171","msg":"trace[228185620] range","detail":"{range_begin:/registry/jobs/openstack/heat-rabbit-init; range_end:; response_count:0; response_revision:17823; }","duration":"123.030763ms","start":"2026-08-26T05:43:35.610884Z","end":"2026-08-26T05:43:35.733914Z","steps":["trace[228185620] 'agreement among raft nodes before linearized reading' (duration: 122.981101ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:43:35.733935Z","caller":"traceutil/trace.go:171","msg":"trace[752817616] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-endpoints; range_end:; response_count:0; response_revision:17823; }","duration":"123.123496ms","start":"2026-08-26T05:43:35.610799Z","end":"2026-08-26T05:43:35.733922Z","steps":["trace[752817616] 'agreement among raft nodes before linearized reading' (duration: 123.006383ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:43:35.734138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.080211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-db-sync\" ","response":"range_response_count:1 size:5032"} {"level":"warn","ts":"2026-08-26T05:43:35.734146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.866151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-bootstrap\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-26T05:43:35.734174Z","caller":"traceutil/trace.go:171","msg":"trace[215392319] range","detail":"{range_begin:/registry/jobs/openstack/heat-db-sync; range_end:; response_count:1; response_revision:17823; }","duration":"117.128152ms","start":"2026-08-26T05:43:35.617036Z","end":"2026-08-26T05:43:35.734164Z","steps":["trace[215392319] 'agreement among raft nodes before linearized reading' (duration: 116.990858ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:43:35.734183Z","caller":"traceutil/trace.go:171","msg":"trace[59946912] range","detail":"{range_begin:/registry/jobs/openstack/heat-bootstrap; range_end:; response_count:0; response_revision:17823; }","duration":"122.032165ms","start":"2026-08-26T05:43:35.612141Z","end":"2026-08-26T05:43:35.734173Z","steps":["trace[59946912] 'agreement among raft nodes before linearized reading' (duration: 121.84434ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:43:35.734212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.732735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-domain-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-26T05:43:35.734277Z","caller":"traceutil/trace.go:171","msg":"trace[46177727] range","detail":"{range_begin:/registry/jobs/openstack/heat-domain-ks-user; range_end:; response_count:0; response_revision:17823; }","duration":"122.823318ms","start":"2026-08-26T05:43:35.611438Z","end":"2026-08-26T05:43:35.734261Z","steps":["trace[46177727] 'agreement among raft nodes before linearized reading' (duration: 122.676343ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:43:35.734464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.282979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-26T05:43:35.734541Z","caller":"traceutil/trace.go:171","msg":"trace[314637354] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-user; range_end:; response_count:0; response_revision:17823; }","duration":"123.377851ms","start":"2026-08-26T05:43:35.611146Z","end":"2026-08-26T05:43:35.734523Z","steps":["trace[314637354] 'agreement among raft nodes before linearized reading' (duration: 122.619711ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:43:35.735015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.996798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2225402"} {"level":"info","ts":"2026-08-26T05:43:35.735073Z","caller":"traceutil/trace.go:171","msg":"trace[1826415102] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:17823; }","duration":"102.07134ms","start":"2026-08-26T05:43:35.632988Z","end":"2026-08-26T05:43:35.735059Z","steps":["trace[1826415102] 'agreement among raft nodes before linearized reading' (duration: 100.608051ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:44:14.433903Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15518} {"level":"info","ts":"2026-08-26T05:44:14.531308Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15518,"took":"93.156716ms","hash":1431736399,"current-db-size-bytes":58335232,"current-db-size":"58 MB","current-db-size-in-use-bytes":34160640,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-26T05:44:14.531368Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1431736399,"revision":15518,"compact-revision":13017} {"level":"info","ts":"2026-08-26T05:46:52.803036Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"eae99266654eff69","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-26T05:46:52.808048Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-26T05:46:52.808123Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"warn","ts":"2026-08-26T05:47:46.230004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.589656ms","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-08-26T05:47:46.230108Z","caller":"traceutil/trace.go:171","msg":"trace[269221371] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:19659; }","duration":"120.748321ms","start":"2026-08-26T05:47:46.109338Z","end":"2026-08-26T05:47:46.230086Z","steps":["trace[269221371] 'range keys from in-memory index tree' (duration: 120.405691ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:48:01.715232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.032817ms","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-08-26T05:48:01.715293Z","caller":"traceutil/trace.go:171","msg":"trace[519383777] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:19740; }","duration":"114.14716ms","start":"2026-08-26T05:48:01.601131Z","end":"2026-08-26T05:48:01.715278Z","steps":["trace[519383777] 'count revisions from in-memory index tree' (duration: 113.954945ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:48:07.081829Z","caller":"traceutil/trace.go:171","msg":"trace[1213563952] transaction","detail":"{read_only:false; response_revision:19765; number_of_response:1; }","duration":"156.477117ms","start":"2026-08-26T05:48:06.92532Z","end":"2026-08-26T05:48:07.081797Z","steps":["trace[1213563952] 'process raft request' (duration: 156.308942ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:48:07.429909Z","caller":"traceutil/trace.go:171","msg":"trace[375943323] transaction","detail":"{read_only:false; response_revision:19767; number_of_response:1; }","duration":"181.704066ms","start":"2026-08-26T05:48:07.248175Z","end":"2026-08-26T05:48:07.429879Z","steps":["trace[375943323] 'process raft request' (duration: 160.455856ms)","trace[375943323] 'compare' (duration: 20.568602ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T05:48:07.758417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.118785ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-08-26T05:48:07.758684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T05:48:07.431135Z","time spent":"327.541752ms","remote":"127.0.0.1:59236","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-08-26T05:48:07.758742Z","caller":"traceutil/trace.go:171","msg":"trace[1356737561] transaction","detail":"{read_only:false; response_revision:19768; number_of_response:1; }","duration":"261.430735ms","start":"2026-08-26T05:48:07.497283Z","end":"2026-08-26T05:48:07.758714Z","steps":["trace[1356737561] 'process raft request' (duration: 261.269901ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:48:07.758705Z","caller":"traceutil/trace.go:171","msg":"trace[2060597976] linearizableReadLoop","detail":"{readStateIndex:20429; appliedIndex:20427; }","duration":"259.733469ms","start":"2026-08-26T05:48:07.498948Z","end":"2026-08-26T05:48:07.758681Z","steps":["trace[2060597976] 'read index received' (duration: 91.45935ms)","trace[2060597976] 'applied index is now lower than readState.Index' (duration: 168.272829ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T05:48:07.758888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.924425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-08-26T05:48:07.758948Z","caller":"traceutil/trace.go:171","msg":"trace[528949012] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:19768; }","duration":"260.039047ms","start":"2026-08-26T05:48:07.498893Z","end":"2026-08-26T05:48:07.758932Z","steps":["trace[528949012] 'agreement among raft nodes before linearized reading' (duration: 259.850612ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:48:08.025686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.259314ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-26T05:48:08.026004Z","caller":"traceutil/trace.go:171","msg":"trace[1468006580] transaction","detail":"{read_only:false; response_revision:19770; number_of_response:1; }","duration":"262.905726ms","start":"2026-08-26T05:48:07.763028Z","end":"2026-08-26T05:48:08.025934Z","steps":["trace[1468006580] 'process raft request' (duration: 262.798953ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:48:08.026012Z","caller":"traceutil/trace.go:171","msg":"trace[1390423953] transaction","detail":"{read_only:false; response_revision:19769; number_of_response:1; }","duration":"265.585959ms","start":"2026-08-26T05:48:07.760385Z","end":"2026-08-26T05:48:08.025971Z","steps":["trace[1390423953] 'process raft request' (duration: 128.890753ms)","trace[1390423953] 'compare' (duration: 136.084399ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T05:48:08.360886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.555531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-26T05:48:08.361045Z","caller":"traceutil/trace.go:171","msg":"trace[1440872987] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:19770; }","duration":"223.789396ms","start":"2026-08-26T05:48:08.137226Z","end":"2026-08-26T05:48:08.361015Z","steps":["trace[1440872987] 'count revisions from in-memory index tree' (duration: 223.468287ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:48:08.361215Z","caller":"traceutil/trace.go:171","msg":"trace[638144528] transaction","detail":"{read_only:false; response_revision:19771; number_of_response:1; }","duration":"196.631995ms","start":"2026-08-26T05:48:08.164561Z","end":"2026-08-26T05:48:08.361193Z","steps":["trace[638144528] 'process raft request' (duration: 135.604536ms)","trace[638144528] 'compare' (duration: 60.781761ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T05:48:08.494502Z","caller":"traceutil/trace.go:171","msg":"trace[1275264639] transaction","detail":"{read_only:false; response_revision:19772; number_of_response:1; }","duration":"128.698777ms","start":"2026-08-26T05:48:08.365773Z","end":"2026-08-26T05:48:08.494472Z","steps":["trace[1275264639] 'process raft request' (duration: 101.675158ms)","trace[1275264639] 'compare' (duration: 26.805393ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T05:48:08.495185Z","caller":"traceutil/trace.go:171","msg":"trace[2143305688] transaction","detail":"{read_only:false; response_revision:19773; number_of_response:1; }","duration":"128.727869ms","start":"2026-08-26T05:48:08.366444Z","end":"2026-08-26T05:48:08.495172Z","steps":["trace[2143305688] 'process raft request' (duration: 127.969248ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:48:08.868735Z","caller":"traceutil/trace.go:171","msg":"trace[1200252759] linearizableReadLoop","detail":"{readStateIndex:20436; appliedIndex:20435; }","duration":"177.982124ms","start":"2026-08-26T05:48:08.690726Z","end":"2026-08-26T05:48:08.868708Z","steps":["trace[1200252759] 'read index received' (duration: 177.82265ms)","trace[1200252759] 'applied index is now lower than readState.Index' (duration: 158.444µs)"],"step_count":2} {"level":"info","ts":"2026-08-26T05:48:08.868796Z","caller":"traceutil/trace.go:171","msg":"trace[1610302133] transaction","detail":"{read_only:false; response_revision:19775; number_of_response:1; }","duration":"300.214936ms","start":"2026-08-26T05:48:08.568547Z","end":"2026-08-26T05:48:08.868762Z","steps":["trace[1610302133] 'process raft request' (duration: 299.99195ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:48:08.868968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T05:48:08.568525Z","time spent":"300.333579ms","remote":"127.0.0.1:59510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-26T05:48:08.869003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.343856ms","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-08-26T05:48:08.86904Z","caller":"traceutil/trace.go:171","msg":"trace[789311642] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:19775; }","duration":"174.425447ms","start":"2026-08-26T05:48:08.694605Z","end":"2026-08-26T05:48:08.86903Z","steps":["trace[789311642] 'agreement among raft nodes before linearized reading' (duration: 174.250612ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:48:08.869118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.755435ms","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-08-26T05:48:08.869201Z","caller":"traceutil/trace.go:171","msg":"trace[229845935] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:19775; }","duration":"171.889228ms","start":"2026-08-26T05:48:08.697294Z","end":"2026-08-26T05:48:08.869183Z","steps":["trace[229845935] 'agreement among raft nodes before linearized reading' (duration: 171.630821ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:48:08.869046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.302104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-08-26T05:48:08.869359Z","caller":"traceutil/trace.go:171","msg":"trace[140055308] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:19775; }","duration":"178.629753ms","start":"2026-08-26T05:48:08.69071Z","end":"2026-08-26T05:48:08.869339Z","steps":["trace[140055308] 'agreement among raft nodes before linearized reading' (duration: 178.111309ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:49:14.440766Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18109} {"level":"info","ts":"2026-08-26T05:49:14.556115Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18109,"took":"111.57745ms","hash":460485144,"current-db-size-bytes":58335232,"current-db-size":"58 MB","current-db-size-in-use-bytes":30994432,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-26T05:49:14.556184Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":460485144,"revision":18109,"compact-revision":15518} {"level":"warn","ts":"2026-08-26T05:51:45.602582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.033535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-26T05:51:45.602589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.060297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-26T05:51:45.602676Z","caller":"traceutil/trace.go:171","msg":"trace[1078276671] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:22125; }","duration":"106.171748ms","start":"2026-08-26T05:51:45.49648Z","end":"2026-08-26T05:51:45.602652Z","steps":["trace[1078276671] 'count revisions from in-memory index tree' (duration: 105.951522ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:51:45.602703Z","caller":"traceutil/trace.go:171","msg":"trace[1304744042] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:22125; }","duration":"205.19112ms","start":"2026-08-26T05:51:45.397487Z","end":"2026-08-26T05:51:45.602678Z","steps":["trace[1304744042] 'count revisions from in-memory index tree' (duration: 204.982125ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:54:14.447147Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20401} {"level":"info","ts":"2026-08-26T05:54:14.563789Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20401,"took":"112.478629ms","hash":854626190,"current-db-size-bytes":58335232,"current-db-size":"58 MB","current-db-size-in-use-bytes":43528192,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-08-26T05:54:14.563865Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":854626190,"revision":20401,"compact-revision":18109} {"level":"info","ts":"2026-08-26T05:59:14.452281Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23516} {"level":"info","ts":"2026-08-26T05:59:14.584799Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23516,"took":"128.658562ms","hash":3102973280,"current-db-size-bytes":58335232,"current-db-size":"58 MB","current-db-size-in-use-bytes":30314496,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-26T05:59:14.584879Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3102973280,"revision":23516,"compact-revision":20401}