{"level":"warn","ts":"2026-10-16T00:07:23.336133Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-16T00:07:23.336226Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.93: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.93:2380","--initial-cluster=instance=https://162.253.55.93:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.93:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.93:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-10-16T00:07:23.336296Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-16T00:07:23.336309Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.93:2380"]} {"level":"info","ts":"2026-10-16T00:07:23.336333Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-16T00:07:23.337129Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.93:2379"]} {"level":"info","ts":"2026-10-16T00:07:23.337228Z","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.93:2380"],"listen-peer-urls":["https://162.253.55.93:2380"],"advertise-client-urls":["https://162.253.55.93:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.93:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.93:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-10-16T00:07:23.34143Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.950283ms"} {"level":"info","ts":"2026-10-16T00:07:23.348831Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ea06c5748459930b","cluster-id":"49d4e1d71c75bc5"} {"level":"info","ts":"2026-10-16T00:07:23.349087Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b switched to configuration voters=()"} {"level":"info","ts":"2026-10-16T00:07:23.349161Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b became follower at term 0"} {"level":"info","ts":"2026-10-16T00:07:23.349195Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ea06c5748459930b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-16T00:07:23.349249Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b became follower at term 1"} {"level":"info","ts":"2026-10-16T00:07:23.349366Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b switched to configuration voters=(16863382958962742027)"} {"level":"warn","ts":"2026-10-16T00:07:23.355696Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-16T00:07:23.357391Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-16T00:07:23.359015Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-10-16T00:07:23.363425Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ea06c5748459930b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-16T00:07:23.363901Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ea06c5748459930b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-16T00:07:23.363975Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-16T00:07:23.364104Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-16T00:07:23.364129Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-16T00:07:23.367278Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b switched to configuration voters=(16863382958962742027)"} {"level":"info","ts":"2026-10-16T00:07:23.367462Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"49d4e1d71c75bc5","local-member-id":"ea06c5748459930b","added-peer-id":"ea06c5748459930b","added-peer-peer-urls":["https://162.253.55.93:2380"]} {"level":"info","ts":"2026-10-16T00:07:23.370353Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-16T00:07:23.370469Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.93:2380"} {"level":"info","ts":"2026-10-16T00:07:23.370518Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.93:2380"} {"level":"info","ts":"2026-10-16T00:07:23.370805Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ea06c5748459930b","initial-advertise-peer-urls":["https://162.253.55.93:2380"],"listen-peer-urls":["https://162.253.55.93:2380"],"advertise-client-urls":["https://162.253.55.93:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.93:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-16T00:07:23.370858Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-16T00:07:23.650569Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b is starting a new election at term 1"} {"level":"info","ts":"2026-10-16T00:07:23.650684Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b became pre-candidate at term 1"} {"level":"info","ts":"2026-10-16T00:07:23.650715Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b received MsgPreVoteResp from ea06c5748459930b at term 1"} {"level":"info","ts":"2026-10-16T00:07:23.650741Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b became candidate at term 2"} {"level":"info","ts":"2026-10-16T00:07:23.650759Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b received MsgVoteResp from ea06c5748459930b at term 2"} {"level":"info","ts":"2026-10-16T00:07:23.65078Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b became leader at term 2"} {"level":"info","ts":"2026-10-16T00:07:23.650799Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ea06c5748459930b elected leader ea06c5748459930b at term 2"} {"level":"info","ts":"2026-10-16T00:07:23.652375Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ea06c5748459930b","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.93:2379]}","request-path":"/0/members/ea06c5748459930b/attributes","cluster-id":"49d4e1d71c75bc5","publish-timeout":"7s"} {"level":"info","ts":"2026-10-16T00:07:23.652571Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-16T00:07:23.652668Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-16T00:07:23.652738Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-16T00:07:23.65417Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"49d4e1d71c75bc5","local-member-id":"ea06c5748459930b","cluster-version":"3.5"} {"level":"info","ts":"2026-10-16T00:07:23.654294Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-16T00:07:23.654334Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-16T00:07:23.657053Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-16T00:07:23.657445Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.93:2379"} {"level":"info","ts":"2026-10-16T00:07:23.658702Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-16T00:07:23.658798Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-16T00:07:42.058694Z","caller":"traceutil/trace.go:171","msg":"trace[1721972090] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"161.086256ms","start":"2026-10-16T00:07:41.897567Z","end":"2026-10-16T00:07:42.058653Z","steps":["trace[1721972090] 'process raft request' (duration: 160.776101ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:07:42.24279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.154325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:07:42.242915Z","caller":"traceutil/trace.go:171","msg":"trace[1628780495] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:380; }","duration":"100.333528ms","start":"2026-10-16T00:07:42.142558Z","end":"2026-10-16T00:07:42.242892Z","steps":["trace[1628780495] 'range keys from in-memory index tree' (duration: 99.954071ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:07:47.554938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.031218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-16T00:07:47.554957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.07419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-16T00:07:47.555037Z","caller":"traceutil/trace.go:171","msg":"trace[1785777692] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:391; }","duration":"210.192232ms","start":"2026-10-16T00:07:47.344822Z","end":"2026-10-16T00:07:47.555014Z","steps":["trace[1785777692] 'range keys from in-memory index tree' (duration: 209.942187ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:07:47.555042Z","caller":"traceutil/trace.go:171","msg":"trace[1211721804] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:391; }","duration":"159.211682ms","start":"2026-10-16T00:07:47.39581Z","end":"2026-10-16T00:07:47.555022Z","steps":["trace[1211721804] 'range keys from in-memory index tree' (duration: 158.864387ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:08:20.276913Z","caller":"traceutil/trace.go:171","msg":"trace[2124185105] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"118.514791ms","start":"2026-10-16T00:08:20.158365Z","end":"2026-10-16T00:08:20.27688Z","steps":["trace[2124185105] 'process raft request' (duration: 118.170775ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:08:20.404236Z","caller":"traceutil/trace.go:171","msg":"trace[243179502] linearizableReadLoop","detail":"{readStateIndex:650; appliedIndex:648; }","duration":"135.364148ms","start":"2026-10-16T00:08:20.268797Z","end":"2026-10-16T00:08:20.404162Z","steps":["trace[243179502] 'read index received' (duration: 7.770905ms)","trace[243179502] 'applied index is now lower than readState.Index' (duration: 127.591333ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:08:20.404352Z","caller":"traceutil/trace.go:171","msg":"trace[1350724423] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"207.070631ms","start":"2026-10-16T00:08:20.197227Z","end":"2026-10-16T00:08:20.404297Z","steps":["trace[1350724423] 'process raft request' (duration: 178.500216ms)","trace[1350724423] 'compare' (duration: 27.554626ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:08:20.404706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.854697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-10-16T00:08:20.404881Z","caller":"traceutil/trace.go:171","msg":"trace[641874450] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:625; }","duration":"136.047231ms","start":"2026-10-16T00:08:20.268789Z","end":"2026-10-16T00:08:20.404836Z","steps":["trace[641874450] 'agreement among raft nodes before linearized reading' (duration: 135.613892ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:08:20.712591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.298521ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-16T00:08:20.712812Z","caller":"traceutil/trace.go:171","msg":"trace[959471070] linearizableReadLoop","detail":"{readStateIndex:652; appliedIndex:650; }","duration":"229.553023ms","start":"2026-10-16T00:08:20.483238Z","end":"2026-10-16T00:08:20.712791Z","steps":["trace[959471070] 'read index received' (duration: 88.294715ms)","trace[959471070] 'applied index is now lower than readState.Index' (duration: 141.257268ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:08:20.712903Z","caller":"traceutil/trace.go:171","msg":"trace[1642847663] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"236.543285ms","start":"2026-10-16T00:08:20.476317Z","end":"2026-10-16T00:08:20.71286Z","steps":["trace[1642847663] 'process raft request' (duration: 236.396902ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:08:20.71305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.802708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-10-16T00:08:20.71312Z","caller":"traceutil/trace.go:171","msg":"trace[216699323] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:627; }","duration":"229.902009ms","start":"2026-10-16T00:08:20.483204Z","end":"2026-10-16T00:08:20.713106Z","steps":["trace[216699323] 'agreement among raft nodes before linearized reading' (duration: 229.653055ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:08:20.713195Z","caller":"traceutil/trace.go:171","msg":"trace[827479521] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"302.909058ms","start":"2026-10-16T00:08:20.409986Z","end":"2026-10-16T00:08:20.712895Z","steps":["trace[827479521] 'process raft request' (duration: 161.537438ms)","trace[827479521] 'compare' (duration: 140.105226ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:08:20.713307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:08:20.409955Z","time spent":"303.290335ms","remote":"127.0.0.1:47518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T00:08:21.238433Z","caller":"traceutil/trace.go:171","msg":"trace[932453178] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"410.600436ms","start":"2026-10-16T00:08:20.827786Z","end":"2026-10-16T00:08:21.238387Z","steps":["trace[932453178] 'process raft request' (duration: 408.965546ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:08:21.238757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:08:20.827753Z","time spent":"410.7968ms","remote":"127.0.0.1:47444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T00:08:37.228664Z","caller":"traceutil/trace.go:171","msg":"trace[720040414] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"300.119006ms","start":"2026-10-16T00:08:36.928516Z","end":"2026-10-16T00:08:37.228635Z","steps":["trace[720040414] 'process raft request' (duration: 299.928132ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:08:37.228877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:08:36.928484Z","time spent":"300.30095ms","remote":"127.0.0.1:47444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4027,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T00:09:30.41907Z","caller":"traceutil/trace.go:171","msg":"trace[1030813938] transaction","detail":"{read_only:false; response_revision:1456; number_of_response:1; }","duration":"107.061442ms","start":"2026-10-16T00:09:30.311981Z","end":"2026-10-16T00:09:30.419043Z","steps":["trace[1030813938] 'process raft request' (duration: 106.800298ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:09:56.704858Z","caller":"traceutil/trace.go:171","msg":"trace[743452852] transaction","detail":"{read_only:false; response_revision:1880; number_of_response:1; }","duration":"326.487169ms","start":"2026-10-16T00:09:56.378344Z","end":"2026-10-16T00:09:56.704831Z","steps":["trace[743452852] 'process raft request' (duration: 326.291205ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:09:56.705056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:09:56.378317Z","time spent":"326.646681ms","remote":"127.0.0.1:47444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3731,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T00:09:56.732996Z","caller":"traceutil/trace.go:171","msg":"trace[475281348] transaction","detail":"{read_only:false; response_revision:1881; number_of_response:1; }","duration":"245.924631ms","start":"2026-10-16T00:09:56.487024Z","end":"2026-10-16T00:09:56.732949Z","steps":["trace[475281348] 'process raft request' (duration: 245.683486ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:09:56.733027Z","caller":"traceutil/trace.go:171","msg":"trace[324157029] transaction","detail":"{read_only:false; response_revision:1882; number_of_response:1; }","duration":"215.835638ms","start":"2026-10-16T00:09:56.517157Z","end":"2026-10-16T00:09:56.732993Z","steps":["trace[324157029] 'process raft request' (duration: 215.656334ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:10:13.37673Z","caller":"traceutil/trace.go:171","msg":"trace[1008079199] transaction","detail":"{read_only:false; response_revision:2021; number_of_response:1; }","duration":"112.785045ms","start":"2026-10-16T00:10:13.263879Z","end":"2026-10-16T00:10:13.376664Z","steps":["trace[1008079199] 'process raft request' (duration: 112.652012ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:10:46.540361Z","caller":"traceutil/trace.go:171","msg":"trace[261796446] transaction","detail":"{read_only:false; response_revision:2205; number_of_response:1; }","duration":"172.383112ms","start":"2026-10-16T00:10:46.367939Z","end":"2026-10-16T00:10:46.540322Z","steps":["trace[261796446] 'process raft request' (duration: 172.126047ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:10:46.746175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.6451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-16T00:10:46.746343Z","caller":"traceutil/trace.go:171","msg":"trace[58498208] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2205; }","duration":"160.857634ms","start":"2026-10-16T00:10:46.585416Z","end":"2026-10-16T00:10:46.746273Z","steps":["trace[58498208] 'range keys from in-memory index tree' (duration: 160.429166ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:11:27.105483Z","caller":"traceutil/trace.go:171","msg":"trace[957183202] transaction","detail":"{read_only:false; response_revision:2600; number_of_response:1; }","duration":"111.740125ms","start":"2026-10-16T00:11:26.993715Z","end":"2026-10-16T00:11:27.105455Z","steps":["trace[957183202] 'process raft request' (duration: 111.560881ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:11:27.195014Z","caller":"traceutil/trace.go:171","msg":"trace[1669914386] transaction","detail":"{read_only:false; response_revision:2601; number_of_response:1; }","duration":"120.97815ms","start":"2026-10-16T00:11:27.074018Z","end":"2026-10-16T00:11:27.194996Z","steps":["trace[1669914386] 'process raft request' (duration: 120.507751ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:15:13.15905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.420655ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-10-16T00:15:13.159165Z","caller":"traceutil/trace.go:171","msg":"trace[1557765288] linearizableReadLoop","detail":"{readStateIndex:4176; appliedIndex:4175; }","duration":"114.829854ms","start":"2026-10-16T00:15:13.044319Z","end":"2026-10-16T00:15:13.159149Z","steps":["trace[1557765288] 'read index received' (duration: 70.782µs)","trace[1557765288] 'applied index is now lower than readState.Index' (duration: 114.757722ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:15:13.159361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.040118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-10-16T00:15:13.159392Z","caller":"traceutil/trace.go:171","msg":"trace[1287010802] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4019; }","duration":"115.15144ms","start":"2026-10-16T00:15:13.044232Z","end":"2026-10-16T00:15:13.159384Z","steps":["trace[1287010802] 'agreement among raft nodes before linearized reading' (duration: 114.999787ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:15:13.159459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.437701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-10-16T00:15:13.159494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.013745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-10-16T00:15:13.159544Z","caller":"traceutil/trace.go:171","msg":"trace[1270708248] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4019; }","duration":"110.566603ms","start":"2026-10-16T00:15:13.048959Z","end":"2026-10-16T00:15:13.159525Z","steps":["trace[1270708248] 'agreement among raft nodes before linearized reading' (duration: 110.310828ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:15:13.159591Z","caller":"traceutil/trace.go:171","msg":"trace[638635796] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4019; }","duration":"106.121567ms","start":"2026-10-16T00:15:13.053444Z","end":"2026-10-16T00:15:13.159565Z","steps":["trace[638635796] 'agreement among raft nodes before linearized reading' (duration: 105.862772ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:15:13.159632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.450343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-10-16T00:15:13.159688Z","caller":"traceutil/trace.go:171","msg":"trace[579116322] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4019; }","duration":"107.552806ms","start":"2026-10-16T00:15:13.052119Z","end":"2026-10-16T00:15:13.159672Z","steps":["trace[579116322] 'agreement among raft nodes before linearized reading' (duration: 107.397913ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:16:51.094944Z","caller":"traceutil/trace.go:171","msg":"trace[308097267] transaction","detail":"{read_only:false; response_revision:5261; number_of_response:1; }","duration":"119.424673ms","start":"2026-10-16T00:16:50.975481Z","end":"2026-10-16T00:16:51.094906Z","steps":["trace[308097267] 'process raft request' (duration: 84.395344ms)","trace[308097267] 'compare' (duration: 34.268164ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:17:05.100713Z","caller":"traceutil/trace.go:171","msg":"trace[637402190] transaction","detail":"{read_only:false; response_revision:5504; number_of_response:1; }","duration":"204.991599ms","start":"2026-10-16T00:17:04.895686Z","end":"2026-10-16T00:17:05.100677Z","steps":["trace[637402190] 'process raft request' (duration: 204.703943ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:17:05.210065Z","caller":"traceutil/trace.go:171","msg":"trace[525950690] transaction","detail":"{read_only:false; response_revision:5505; number_of_response:1; }","duration":"100.194186ms","start":"2026-10-16T00:17:05.109833Z","end":"2026-10-16T00:17:05.210027Z","steps":["trace[525950690] 'process raft request' (duration: 74.748309ms)","trace[525950690] 'compare' (duration: 25.1095ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:17:05.213774Z","caller":"traceutil/trace.go:171","msg":"trace[1864441523] transaction","detail":"{read_only:false; response_revision:5506; number_of_response:1; }","duration":"103.787894ms","start":"2026-10-16T00:17:05.109954Z","end":"2026-10-16T00:17:05.213742Z","steps":["trace[1864441523] 'process raft request' (duration: 103.56825ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:17:05.214106Z","caller":"traceutil/trace.go:171","msg":"trace[1148540853] transaction","detail":"{read_only:false; response_revision:5507; number_of_response:1; }","duration":"104.017279ms","start":"2026-10-16T00:17:05.110065Z","end":"2026-10-16T00:17:05.214082Z","steps":["trace[1148540853] 'process raft request' (duration: 103.604071ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:17:05.214237Z","caller":"traceutil/trace.go:171","msg":"trace[937584544] transaction","detail":"{read_only:false; response_revision:5508; number_of_response:1; }","duration":"104.118741ms","start":"2026-10-16T00:17:05.1101Z","end":"2026-10-16T00:17:05.214218Z","steps":["trace[937584544] 'process raft request' (duration: 103.836016ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:17:13.740446Z","caller":"traceutil/trace.go:171","msg":"trace[719258403] linearizableReadLoop","detail":"{readStateIndex:5903; appliedIndex:5902; }","duration":"207.861694ms","start":"2026-10-16T00:17:13.532546Z","end":"2026-10-16T00:17:13.740407Z","steps":["trace[719258403] 'read index received' (duration: 207.593379ms)","trace[719258403] 'applied index is now lower than readState.Index' (duration: 267.215µs)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:17:13.740704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.13117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d8a2ee6a-d233-49e6-a00d-fe2b427e8708\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-10-16T00:17:13.740678Z","caller":"traceutil/trace.go:171","msg":"trace[446074866] transaction","detail":"{read_only:false; response_revision:5686; number_of_response:1; }","duration":"295.489019ms","start":"2026-10-16T00:17:13.445156Z","end":"2026-10-16T00:17:13.740645Z","steps":["trace[446074866] 'process raft request' (duration: 295.05176ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:17:13.740758Z","caller":"traceutil/trace.go:171","msg":"trace[2113356759] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d8a2ee6a-d233-49e6-a00d-fe2b427e8708; range_end:; response_count:1; response_revision:5686; }","duration":"208.260893ms","start":"2026-10-16T00:17:13.532483Z","end":"2026-10-16T00:17:13.740744Z","steps":["trace[2113356759] 'agreement among raft nodes before linearized reading' (duration: 208.061479ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:17:14.053391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.027249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-10-16T00:17:14.053527Z","caller":"traceutil/trace.go:171","msg":"trace[496000640] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5688; }","duration":"103.260814ms","start":"2026-10-16T00:17:13.950238Z","end":"2026-10-16T00:17:14.053498Z","steps":["trace[496000640] 'range keys from in-memory index tree' (duration: 102.087123ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:17:23.846801Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2931} {"level":"info","ts":"2026-10-16T00:17:23.92934Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2931,"took":"80.746285ms","hash":338222049,"current-db-size-bytes":56197120,"current-db-size":"56 MB","current-db-size-in-use-bytes":31834112,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-16T00:17:23.929454Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":338222049,"revision":2931,"compact-revision":-1} {"level":"info","ts":"2026-10-16T00:22:23.854252Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5813} {"level":"info","ts":"2026-10-16T00:22:23.951732Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5813,"took":"95.30952ms","hash":2518196031,"current-db-size-bytes":56197120,"current-db-size":"56 MB","current-db-size-in-use-bytes":26288128,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-16T00:22:23.951817Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2518196031,"revision":5813,"compact-revision":2931} {"level":"warn","ts":"2026-10-16T00:23:36.685137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.929237ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:23:36.685273Z","caller":"traceutil/trace.go:171","msg":"trace[1050435159] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:8601; }","duration":"189.066049ms","start":"2026-10-16T00:23:36.496179Z","end":"2026-10-16T00:23:36.685245Z","steps":["trace[1050435159] 'range keys from in-memory index tree' (duration: 188.900287ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:23:36.685299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.363643ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-16T00:23:36.685406Z","caller":"traceutil/trace.go:171","msg":"trace[1791557738] linearizableReadLoop","detail":"{readStateIndex:8913; appliedIndex:8912; }","duration":"214.437726ms","start":"2026-10-16T00:23:36.470948Z","end":"2026-10-16T00:23:36.685386Z","steps":["trace[1791557738] 'read index received' (duration: 7.81912ms)","trace[1791557738] 'applied index is now lower than readState.Index' (duration: 206.616766ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:23:36.685667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.685871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:23:36.685756Z","caller":"traceutil/trace.go:171","msg":"trace[375159228] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:8602; }","duration":"214.829554ms","start":"2026-10-16T00:23:36.470906Z","end":"2026-10-16T00:23:36.685736Z","steps":["trace[375159228] 'agreement among raft nodes before linearized reading' (duration: 214.667451ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:23:36.685778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.681641ms","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:5"} {"level":"info","ts":"2026-10-16T00:23:36.685819Z","caller":"traceutil/trace.go:171","msg":"trace[208461987] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:8602; }","duration":"214.734032ms","start":"2026-10-16T00:23:36.471072Z","end":"2026-10-16T00:23:36.685806Z","steps":["trace[208461987] 'agreement among raft nodes before linearized reading' (duration: 214.65705ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:23:36.686042Z","caller":"traceutil/trace.go:171","msg":"trace[2088630573] transaction","detail":"{read_only:false; response_revision:8602; number_of_response:1; }","duration":"243.139928ms","start":"2026-10-16T00:23:36.442885Z","end":"2026-10-16T00:23:36.686025Z","steps":["trace[2088630573] 'process raft request' (duration: 35.94166ms)","trace[2088630573] 'compare' (duration: 206.095787ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:23:36.686786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.413813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:23:36.686829Z","caller":"traceutil/trace.go:171","msg":"trace[2003384908] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:8602; }","duration":"169.508604ms","start":"2026-10-16T00:23:36.517311Z","end":"2026-10-16T00:23:36.68682Z","steps":["trace[2003384908] 'agreement among raft nodes before linearized reading' (duration: 169.438113ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:23:36.686904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.82051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-16T00:23:36.686926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.760312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-16T00:23:36.687009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.616315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:23:36.687006Z","caller":"traceutil/trace.go:171","msg":"trace[1751243654] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:8602; }","duration":"132.995784ms","start":"2026-10-16T00:23:36.553982Z","end":"2026-10-16T00:23:36.686977Z","steps":["trace[1751243654] 'agreement among raft nodes before linearized reading' (duration: 132.79865ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:23:36.68698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.517435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:23:36.687049Z","caller":"traceutil/trace.go:171","msg":"trace[331545528] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:8602; }","duration":"133.657686ms","start":"2026-10-16T00:23:36.553382Z","end":"2026-10-16T00:23:36.687039Z","steps":["trace[331545528] 'agreement among raft nodes before linearized reading' (duration: 133.598895ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:23:36.687052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.749508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-16T00:23:36.687058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.732808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:23:36.687094Z","caller":"traceutil/trace.go:171","msg":"trace[1571922731] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:8602; }","duration":"194.725379ms","start":"2026-10-16T00:23:36.492341Z","end":"2026-10-16T00:23:36.687067Z","steps":["trace[1571922731] 'agreement among raft nodes before linearized reading' (duration: 194.475694ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:23:36.687139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.938672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:23:36.687149Z","caller":"traceutil/trace.go:171","msg":"trace[235077078] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:8602; }","duration":"170.906501ms","start":"2026-10-16T00:23:36.516214Z","end":"2026-10-16T00:23:36.68712Z","steps":["trace[235077078] 'agreement among raft nodes before linearized reading' (duration: 170.689727ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:23:36.68716Z","caller":"traceutil/trace.go:171","msg":"trace[1540588115] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:8602; }","duration":"170.890341ms","start":"2026-10-16T00:23:36.516241Z","end":"2026-10-16T00:23:36.687131Z","steps":["trace[1540588115] 'agreement among raft nodes before linearized reading' (duration: 170.651356ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:23:36.687171Z","caller":"traceutil/trace.go:171","msg":"trace[308527056] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:8602; }","duration":"196.000713ms","start":"2026-10-16T00:23:36.491162Z","end":"2026-10-16T00:23:36.687162Z","steps":["trace[308527056] 'agreement among raft nodes before linearized reading' (duration: 195.954962ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:23:36.687173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.062448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-16T00:23:36.687146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.896336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:23:36.687216Z","caller":"traceutil/trace.go:171","msg":"trace[1788570239] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:8602; }","duration":"103.106979ms","start":"2026-10-16T00:23:36.584097Z","end":"2026-10-16T00:23:36.687204Z","steps":["trace[1788570239] 'agreement among raft nodes before linearized reading' (duration: 103.041808ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:23:36.687197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.11993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:23:36.687037Z","caller":"traceutil/trace.go:171","msg":"trace[2113797866] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:8602; }","duration":"215.912185ms","start":"2026-10-16T00:23:36.471095Z","end":"2026-10-16T00:23:36.687007Z","steps":["trace[2113797866] 'agreement among raft nodes before linearized reading' (duration: 215.67095ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:23:36.687096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.883391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:23:36.687265Z","caller":"traceutil/trace.go:171","msg":"trace[2018220732] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:8602; }","duration":"103.195071ms","start":"2026-10-16T00:23:36.584045Z","end":"2026-10-16T00:23:36.687241Z","steps":["trace[2018220732] 'agreement among raft nodes before linearized reading' (duration: 103.120929ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:23:36.687275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.922571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:23:36.687317Z","caller":"traceutil/trace.go:171","msg":"trace[292495166] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:8602; }","duration":"133.976872ms","start":"2026-10-16T00:23:36.553324Z","end":"2026-10-16T00:23:36.687301Z","steps":["trace[292495166] 'agreement among raft nodes before linearized reading' (duration: 133.926881ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:23:36.68734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.07278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:23:36.687323Z","caller":"traceutil/trace.go:171","msg":"trace[907747786] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:8602; }","duration":"196.110635ms","start":"2026-10-16T00:23:36.491177Z","end":"2026-10-16T00:23:36.687288Z","steps":["trace[907747786] 'agreement among raft nodes before linearized reading' (duration: 195.89205ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:23:36.687373Z","caller":"traceutil/trace.go:171","msg":"trace[13283828] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:8602; }","duration":"151.125321ms","start":"2026-10-16T00:23:36.536238Z","end":"2026-10-16T00:23:36.687363Z","steps":["trace[13283828] 'agreement among raft nodes before linearized reading' (duration: 151.07569ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:23:36.687222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.967438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:23:36.687269Z","caller":"traceutil/trace.go:171","msg":"trace[1904644143] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:8602; }","duration":"102.111361ms","start":"2026-10-16T00:23:36.585121Z","end":"2026-10-16T00:23:36.687232Z","steps":["trace[1904644143] 'agreement among raft nodes before linearized reading' (duration: 101.732423ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:23:36.687565Z","caller":"traceutil/trace.go:171","msg":"trace[17831358] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:8602; }","duration":"152.314045ms","start":"2026-10-16T00:23:36.535225Z","end":"2026-10-16T00:23:36.687539Z","steps":["trace[17831358] 'agreement among raft nodes before linearized reading' (duration: 151.951337ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:23:36.687491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.217612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:23:36.687678Z","caller":"traceutil/trace.go:171","msg":"trace[14321473] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:8602; }","duration":"152.414717ms","start":"2026-10-16T00:23:36.535242Z","end":"2026-10-16T00:23:36.687656Z","steps":["trace[14321473] 'agreement among raft nodes before linearized reading' (duration: 152.214263ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:24:07.659298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.678732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:24:07.659441Z","caller":"traceutil/trace.go:171","msg":"trace[951964221] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8818; }","duration":"313.931676ms","start":"2026-10-16T00:24:07.345486Z","end":"2026-10-16T00:24:07.659418Z","steps":["trace[951964221] 'range keys from in-memory index tree' (duration: 313.557629ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:24:07.659498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:24:07.345457Z","time spent":"314.026688ms","remote":"127.0.0.1:47264","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-10-16T00:24:15.657727Z","caller":"traceutil/trace.go:171","msg":"trace[1042911991] transaction","detail":"{read_only:false; response_revision:8863; number_of_response:1; }","duration":"117.408274ms","start":"2026-10-16T00:24:15.540287Z","end":"2026-10-16T00:24:15.657696Z","steps":["trace[1042911991] 'process raft request' (duration: 117.14196ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:24:22.434299Z","caller":"traceutil/trace.go:171","msg":"trace[1038679396] linearizableReadLoop","detail":"{readStateIndex:9264; appliedIndex:9262; }","duration":"181.63627ms","start":"2026-10-16T00:24:22.252627Z","end":"2026-10-16T00:24:22.434263Z","steps":["trace[1038679396] 'read index received' (duration: 181.220192ms)","trace[1038679396] 'applied index is now lower than readState.Index' (duration: 414.968µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:24:22.434785Z","caller":"traceutil/trace.go:171","msg":"trace[605856029] transaction","detail":"{read_only:false; response_revision:8939; number_of_response:1; }","duration":"186.274488ms","start":"2026-10-16T00:24:22.248457Z","end":"2026-10-16T00:24:22.434732Z","steps":["trace[605856029] 'process raft request' (duration: 185.719988ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:24:22.435032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.199511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-qwnx4\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-10-16T00:24:22.435078Z","caller":"traceutil/trace.go:171","msg":"trace[1392534862] range","detail":"{range_begin:/registry/pods/openstack/keepalived-qwnx4; range_end:; response_count:1; response_revision:8939; }","duration":"182.475747ms","start":"2026-10-16T00:24:22.252588Z","end":"2026-10-16T00:24:22.435064Z","steps":["trace[1392534862] 'agreement among raft nodes before linearized reading' (duration: 182.10644ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:24:22.43507Z","caller":"traceutil/trace.go:171","msg":"trace[2056389097] transaction","detail":"{read_only:false; response_revision:8938; number_of_response:1; }","duration":"187.834348ms","start":"2026-10-16T00:24:22.247182Z","end":"2026-10-16T00:24:22.435016Z","steps":["trace[2056389097] 'process raft request' (duration: 186.768517ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:24:22.625732Z","caller":"traceutil/trace.go:171","msg":"trace[1311476476] linearizableReadLoop","detail":"{readStateIndex:9265; appliedIndex:9264; }","duration":"183.24989ms","start":"2026-10-16T00:24:22.442453Z","end":"2026-10-16T00:24:22.625703Z","steps":["trace[1311476476] 'read index received' (duration: 182.965126ms)","trace[1311476476] 'applied index is now lower than readState.Index' (duration: 283.734µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:24:22.626044Z","caller":"traceutil/trace.go:171","msg":"trace[171272060] transaction","detail":"{read_only:false; response_revision:8940; number_of_response:1; }","duration":"185.113586ms","start":"2026-10-16T00:24:22.440917Z","end":"2026-10-16T00:24:22.62603Z","steps":["trace[171272060] 'process raft request' (duration: 184.570447ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:24:22.62681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.297891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228419"} {"level":"info","ts":"2026-10-16T00:24:22.626944Z","caller":"traceutil/trace.go:171","msg":"trace[3977189] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8940; }","duration":"184.524005ms","start":"2026-10-16T00:24:22.442391Z","end":"2026-10-16T00:24:22.626915Z","steps":["trace[3977189] 'agreement among raft nodes before linearized reading' (duration: 183.73163ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:24:22.65114Z","caller":"traceutil/trace.go:171","msg":"trace[823047023] transaction","detail":"{read_only:false; response_revision:8941; number_of_response:1; }","duration":"107.997435ms","start":"2026-10-16T00:24:22.543103Z","end":"2026-10-16T00:24:22.6511Z","steps":["trace[823047023] 'process raft request' (duration: 106.589619ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:24:22.828114Z","caller":"traceutil/trace.go:171","msg":"trace[1761238722] linearizableReadLoop","detail":"{readStateIndex:9268; appliedIndex:9267; }","duration":"105.051139ms","start":"2026-10-16T00:24:22.723034Z","end":"2026-10-16T00:24:22.828085Z","steps":["trace[1761238722] 'read index received' (duration: 58.773274ms)","trace[1761238722] 'applied index is now lower than readState.Index' (duration: 46.276445ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:24:22.82829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.231183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:24:22.82833Z","caller":"traceutil/trace.go:171","msg":"trace[45660373] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8943; }","duration":"105.337825ms","start":"2026-10-16T00:24:22.722982Z","end":"2026-10-16T00:24:22.82832Z","steps":["trace[45660373] 'agreement among raft nodes before linearized reading' (duration: 105.248273ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:24:22.82824Z","caller":"traceutil/trace.go:171","msg":"trace[712920283] transaction","detail":"{read_only:false; response_revision:8943; number_of_response:1; }","duration":"123.857666ms","start":"2026-10-16T00:24:22.704329Z","end":"2026-10-16T00:24:22.828187Z","steps":["trace[712920283] 'process raft request' (duration: 77.5585ms)","trace[712920283] 'compare' (duration: 46.062352ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:24:41.828785Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024e3.wal"} {"level":"info","ts":"2026-10-16T00:25:33.232449Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ea06c5748459930b","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-16T00:25:33.241507Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-16T00:25:33.241657Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-16T00:25:45.551042Z","caller":"traceutil/trace.go:171","msg":"trace[265357515] transaction","detail":"{read_only:false; response_revision:9726; number_of_response:1; }","duration":"250.971743ms","start":"2026-10-16T00:25:45.300029Z","end":"2026-10-16T00:25:45.551Z","steps":["trace[265357515] 'process raft request' (duration: 219.08472ms)","trace[265357515] 'compare' (duration: 31.548268ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:27:23.877558Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7986} {"level":"info","ts":"2026-10-16T00:27:23.947869Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7986,"took":"67.528811ms","hash":1539887250,"current-db-size-bytes":56197120,"current-db-size":"56 MB","current-db-size-in-use-bytes":28045312,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-16T00:27:23.947938Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1539887250,"revision":7986,"compact-revision":5813} {"level":"info","ts":"2026-10-16T00:32:23.885793Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10412} {"level":"info","ts":"2026-10-16T00:32:23.988708Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10412,"took":"100.018865ms","hash":3049679123,"current-db-size-bytes":56197120,"current-db-size":"56 MB","current-db-size-in-use-bytes":25976832,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-16T00:32:23.9888Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3049679123,"revision":10412,"compact-revision":7986} {"level":"info","ts":"2026-10-16T00:37:23.89212Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12575} {"level":"info","ts":"2026-10-16T00:37:23.980979Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12575,"took":"85.846657ms","hash":3471174004,"current-db-size-bytes":56197120,"current-db-size":"56 MB","current-db-size-in-use-bytes":27418624,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-10-16T00:37:23.981052Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3471174004,"revision":12575,"compact-revision":10412} {"level":"info","ts":"2026-10-16T00:37:57.926281Z","caller":"traceutil/trace.go:171","msg":"trace[1462465149] linearizableReadLoop","detail":"{readStateIndex:15313; appliedIndex:15312; }","duration":"165.907281ms","start":"2026-10-16T00:37:57.760321Z","end":"2026-10-16T00:37:57.926228Z","steps":["trace[1462465149] 'read index received' (duration: 147.173206ms)","trace[1462465149] 'applied index is now lower than readState.Index' (duration: 18.731395ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:37:57.926331Z","caller":"traceutil/trace.go:171","msg":"trace[164973677] transaction","detail":"{read_only:false; response_revision:14792; number_of_response:1; }","duration":"171.16026ms","start":"2026-10-16T00:37:57.755137Z","end":"2026-10-16T00:37:57.926297Z","steps":["trace[164973677] 'process raft request' (duration: 152.248402ms)","trace[164973677] 'compare' (duration: 18.539092ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:37:57.927108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.756588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:63 size:594707"} {"level":"info","ts":"2026-10-16T00:37:57.927205Z","caller":"traceutil/trace.go:171","msg":"trace[1948569651] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:63; response_revision:14792; }","duration":"166.8891ms","start":"2026-10-16T00:37:57.760295Z","end":"2026-10-16T00:37:57.927184Z","steps":["trace[1948569651] 'agreement among raft nodes before linearized reading' (duration: 166.020683ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:37:57.927324Z","caller":"traceutil/trace.go:171","msg":"trace[1339759468] transaction","detail":"{read_only:false; response_revision:14793; number_of_response:1; }","duration":"163.457586ms","start":"2026-10-16T00:37:57.763841Z","end":"2026-10-16T00:37:57.927299Z","steps":["trace[1339759468] 'process raft request' (duration: 163.305464ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:38:08.53475Z","caller":"traceutil/trace.go:171","msg":"trace[1169917568] linearizableReadLoop","detail":"{readStateIndex:15492; appliedIndex:15491; }","duration":"145.026237ms","start":"2026-10-16T00:38:08.389687Z","end":"2026-10-16T00:38:08.534714Z","steps":["trace[1169917568] 'read index received' (duration: 144.825803ms)","trace[1169917568] 'applied index is now lower than readState.Index' (duration: 199.184µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:38:08.534869Z","caller":"traceutil/trace.go:171","msg":"trace[955005187] transaction","detail":"{read_only:false; response_revision:14968; number_of_response:1; }","duration":"149.495982ms","start":"2026-10-16T00:38:08.385352Z","end":"2026-10-16T00:38:08.534848Z","steps":["trace[955005187] 'process raft request' (duration: 149.156436ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:38:08.535061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.346313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5683"} {"level":"warn","ts":"2026-10-16T00:38:08.535093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.735057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:356"} {"level":"info","ts":"2026-10-16T00:38:08.535134Z","caller":"traceutil/trace.go:171","msg":"trace[1857695138] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:14968; }","duration":"145.464965ms","start":"2026-10-16T00:38:08.389639Z","end":"2026-10-16T00:38:08.535104Z","steps":["trace[1857695138] 'agreement among raft nodes before linearized reading' (duration: 145.269441ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:38:08.535137Z","caller":"traceutil/trace.go:171","msg":"trace[528747146] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:14968; }","duration":"120.811478ms","start":"2026-10-16T00:38:08.414301Z","end":"2026-10-16T00:38:08.535113Z","steps":["trace[528747146] 'agreement among raft nodes before linearized reading' (duration: 120.700836ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:39:49.413222Z","caller":"traceutil/trace.go:171","msg":"trace[637377586] linearizableReadLoop","detail":"{readStateIndex:16618; appliedIndex:16617; }","duration":"238.090193ms","start":"2026-10-16T00:39:49.175106Z","end":"2026-10-16T00:39:49.413197Z","steps":["trace[637377586] 'read index received' (duration: 173.500082ms)","trace[637377586] 'applied index is now lower than readState.Index' (duration: 64.589431ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:39:49.413335Z","caller":"traceutil/trace.go:171","msg":"trace[720077234] transaction","detail":"{read_only:false; response_revision:16060; number_of_response:1; }","duration":"246.309479ms","start":"2026-10-16T00:39:49.167016Z","end":"2026-10-16T00:39:49.413326Z","steps":["trace[720077234] 'process raft request' (duration: 181.572884ms)","trace[720077234] 'compare' (duration: 64.4737ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:39:49.413885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.991427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-qwnx4\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-10-16T00:39:49.413928Z","caller":"traceutil/trace.go:171","msg":"trace[1933461174] range","detail":"{range_begin:/registry/pods/openstack/keepalived-qwnx4; range_end:; response_count:1; response_revision:16060; }","duration":"206.116449ms","start":"2026-10-16T00:39:49.2078Z","end":"2026-10-16T00:39:49.413917Z","steps":["trace[1933461174] 'agreement among raft nodes before linearized reading' (duration: 205.949576ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:39:49.413884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.313906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:39:49.413976Z","caller":"traceutil/trace.go:171","msg":"trace[1055315732] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16060; }","duration":"147.465069ms","start":"2026-10-16T00:39:49.266488Z","end":"2026-10-16T00:39:49.413953Z","steps":["trace[1055315732] 'agreement among raft nodes before linearized reading' (duration: 147.278885ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:39:49.414125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.02871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-10-16T00:39:49.414155Z","caller":"traceutil/trace.go:171","msg":"trace[1663398832] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16060; }","duration":"148.116181ms","start":"2026-10-16T00:39:49.266032Z","end":"2026-10-16T00:39:49.414148Z","steps":["trace[1663398832] 'agreement among raft nodes before linearized reading' (duration: 148.03566ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:39:49.41497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.012053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-10-16T00:39:49.415028Z","caller":"traceutil/trace.go:171","msg":"trace[1659641889] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16060; }","duration":"144.098284ms","start":"2026-10-16T00:39:49.270915Z","end":"2026-10-16T00:39:49.415013Z","steps":["trace[1659641889] 'agreement among raft nodes before linearized reading' (duration: 143.129856ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:39:49.430717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.584774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:79 size:769684"} {"level":"info","ts":"2026-10-16T00:39:49.43086Z","caller":"traceutil/trace.go:171","msg":"trace[1460043379] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:79; response_revision:16060; }","duration":"255.753578ms","start":"2026-10-16T00:39:49.175054Z","end":"2026-10-16T00:39:49.430808Z","steps":["trace[1460043379] 'agreement among raft nodes before linearized reading' (duration: 238.370019ms)","trace[1460043379] 'range keys from bolt db' (duration: 17.076593ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:39:49.702851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.10398ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-16T00:39:49.703304Z","caller":"traceutil/trace.go:171","msg":"trace[1886375364] linearizableReadLoop","detail":"{readStateIndex:16619; appliedIndex:16618; }","duration":"202.395618ms","start":"2026-10-16T00:39:49.500885Z","end":"2026-10-16T00:39:49.70328Z","steps":["trace[1886375364] 'read index received' (duration: 51.720248ms)","trace[1886375364] 'applied index is now lower than readState.Index' (duration: 150.67349ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:39:49.70329Z","caller":"traceutil/trace.go:171","msg":"trace[1044736319] transaction","detail":"{read_only:false; response_revision:16061; number_of_response:1; }","duration":"247.659205ms","start":"2026-10-16T00:39:49.455597Z","end":"2026-10-16T00:39:49.703257Z","steps":["trace[1044736319] 'process raft request' (duration: 96.985224ms)","trace[1044736319] 'compare' (duration: 149.924357ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:39:49.70353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.639482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:39:49.703575Z","caller":"traceutil/trace.go:171","msg":"trace[1606502250] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:16062; }","duration":"202.714334ms","start":"2026-10-16T00:39:49.500847Z","end":"2026-10-16T00:39:49.703561Z","steps":["trace[1606502250] 'agreement among raft nodes before linearized reading' (duration: 202.605592ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:39:49.703709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.737426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"warn","ts":"2026-10-16T00:39:49.703688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.600044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-16T00:39:49.703742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.87167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-10-16T00:39:49.70376Z","caller":"traceutil/trace.go:171","msg":"trace[33723668] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16062; }","duration":"200.822638ms","start":"2026-10-16T00:39:49.502926Z","end":"2026-10-16T00:39:49.703749Z","steps":["trace[33723668] 'agreement among raft nodes before linearized reading' (duration: 200.649405ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:39:49.70371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.720554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-16T00:39:49.703714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.660109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-10-16T00:39:49.703804Z","caller":"traceutil/trace.go:171","msg":"trace[349375946] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16062; }","duration":"199.950741ms","start":"2026-10-16T00:39:49.503834Z","end":"2026-10-16T00:39:49.703784Z","steps":["trace[349375946] 'agreement among raft nodes before linearized reading' (duration: 199.722187ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:39:49.703856Z","caller":"traceutil/trace.go:171","msg":"trace[594477649] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16062; }","duration":"178.873073ms","start":"2026-10-16T00:39:49.524973Z","end":"2026-10-16T00:39:49.703846Z","steps":["trace[594477649] 'agreement among raft nodes before linearized reading' (duration: 178.634649ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:39:49.703874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.630288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"warn","ts":"2026-10-16T00:39:49.703839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.400836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-16T00:39:49.703913Z","caller":"traceutil/trace.go:171","msg":"trace[1230695233] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16062; }","duration":"197.677378ms","start":"2026-10-16T00:39:49.506222Z","end":"2026-10-16T00:39:49.7039Z","steps":["trace[1230695233] 'agreement among raft nodes before linearized reading' (duration: 197.559386ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:39:49.70391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.935757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:39:49.703959Z","caller":"traceutil/trace.go:171","msg":"trace[147411508] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16062; }","duration":"202.997539ms","start":"2026-10-16T00:39:49.500951Z","end":"2026-10-16T00:39:49.703949Z","steps":["trace[147411508] 'agreement among raft nodes before linearized reading' (duration: 202.927598ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:39:49.70388Z","caller":"traceutil/trace.go:171","msg":"trace[1130476806] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:16062; }","duration":"202.916937ms","start":"2026-10-16T00:39:49.500926Z","end":"2026-10-16T00:39:49.703843Z","steps":["trace[1130476806] 'agreement among raft nodes before linearized reading' (duration: 202.632822ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:39:49.703986Z","caller":"traceutil/trace.go:171","msg":"trace[1803542565] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:16062; }","duration":"130.5965ms","start":"2026-10-16T00:39:49.57334Z","end":"2026-10-16T00:39:49.703937Z","steps":["trace[1803542565] 'agreement among raft nodes before linearized reading' (duration: 130.300644ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:39:49.703787Z","caller":"traceutil/trace.go:171","msg":"trace[2104876738] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16062; }","duration":"200.723376ms","start":"2026-10-16T00:39:49.503038Z","end":"2026-10-16T00:39:49.703761Z","steps":["trace[2104876738] 'agreement among raft nodes before linearized reading' (duration: 200.574273ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:42:23.897901Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14602} {"level":"info","ts":"2026-10-16T00:42:23.989441Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14602,"took":"88.148567ms","hash":3043662812,"current-db-size-bytes":56197120,"current-db-size":"56 MB","current-db-size-in-use-bytes":32333824,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-16T00:42:23.98953Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3043662812,"revision":14602,"compact-revision":12575} {"level":"info","ts":"2026-10-16T00:45:29.557344Z","caller":"traceutil/trace.go:171","msg":"trace[98980728] transaction","detail":"{read_only:false; response_revision:18634; number_of_response:1; }","duration":"115.808165ms","start":"2026-10-16T00:45:29.441508Z","end":"2026-10-16T00:45:29.557316Z","steps":["trace[98980728] 'process raft request' (duration: 115.675663ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:45:29.635814Z","caller":"traceutil/trace.go:171","msg":"trace[1691819874] transaction","detail":"{read_only:false; response_revision:18635; number_of_response:1; }","duration":"190.801876ms","start":"2026-10-16T00:45:29.444967Z","end":"2026-10-16T00:45:29.635769Z","steps":["trace[1691819874] 'process raft request' (duration: 190.50068ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:45:29.636157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.729656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0\" ","response":"range_response_count:1 size:1852"} {"level":"info","ts":"2026-10-16T00:45:29.636274Z","caller":"traceutil/trace.go:171","msg":"trace[1047575805] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0; range_end:; response_count:1; response_revision:18635; }","duration":"103.858418ms","start":"2026-10-16T00:45:29.532388Z","end":"2026-10-16T00:45:29.636247Z","steps":["trace[1047575805] 'agreement among raft nodes before linearized reading' (duration: 103.549952ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:45:29.636561Z","caller":"traceutil/trace.go:171","msg":"trace[79460299] linearizableReadLoop","detail":"{readStateIndex:19279; appliedIndex:19278; }","duration":"103.246107ms","start":"2026-10-16T00:45:29.532408Z","end":"2026-10-16T00:45:29.635654Z","steps":["trace[79460299] 'read index received' (duration: 25.158877ms)","trace[79460299] 'applied index is now lower than readState.Index' (duration: 78.08615ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:45:33.227753Z","caller":"traceutil/trace.go:171","msg":"trace[1053745487] transaction","detail":"{read_only:false; response_revision:18666; number_of_response:1; }","duration":"140.959762ms","start":"2026-10-16T00:45:33.08677Z","end":"2026-10-16T00:45:33.227729Z","steps":["trace[1053745487] 'process raft request' (duration: 140.827879ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:46:32.312022Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ea06c5748459930b","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-16T00:46:32.318892Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-16T00:46:32.319047Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-16T00:47:23.904685Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17185} {"level":"info","ts":"2026-10-16T00:47:24.003695Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17185,"took":"95.52559ms","hash":2365713843,"current-db-size-bytes":56197120,"current-db-size":"56 MB","current-db-size-in-use-bytes":34164736,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-16T00:47:24.003778Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2365713843,"revision":17185,"compact-revision":14602} {"level":"info","ts":"2026-10-16T00:50:41.448216Z","caller":"traceutil/trace.go:171","msg":"trace[1921354040] linearizableReadLoop","detail":"{readStateIndex:21783; appliedIndex:21782; }","duration":"113.467341ms","start":"2026-10-16T00:50:41.33472Z","end":"2026-10-16T00:50:41.448187Z","steps":["trace[1921354040] 'read index received' (duration: 113.221346ms)","trace[1921354040] 'applied index is now lower than readState.Index' (duration: 245.205µs)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:50:41.448484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.749076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-16T00:50:41.448528Z","caller":"traceutil/trace.go:171","msg":"trace[384089141] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:21060; }","duration":"113.929709ms","start":"2026-10-16T00:50:41.334583Z","end":"2026-10-16T00:50:41.448513Z","steps":["trace[384089141] 'agreement among raft nodes before linearized reading' (duration: 113.744336ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:50:41.448508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.490332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T00:50:41.448601Z","caller":"traceutil/trace.go:171","msg":"trace[1873802866] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21060; }","duration":"103.618943ms","start":"2026-10-16T00:50:41.344957Z","end":"2026-10-16T00:50:41.448576Z","steps":["trace[1873802866] 'agreement among raft nodes before linearized reading' (duration: 103.46354ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:50:41.448975Z","caller":"traceutil/trace.go:171","msg":"trace[1657601736] transaction","detail":"{read_only:false; response_revision:21060; number_of_response:1; }","duration":"217.670714ms","start":"2026-10-16T00:50:41.230681Z","end":"2026-10-16T00:50:41.448351Z","steps":["trace[1657601736] 'process raft request' (duration: 217.316147ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:50:51.706115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.43181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-16T00:50:51.70626Z","caller":"traceutil/trace.go:171","msg":"trace[282015349] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:21113; }","duration":"150.628935ms","start":"2026-10-16T00:50:51.5556Z","end":"2026-10-16T00:50:51.706229Z","steps":["trace[282015349] 'count revisions from in-memory index tree' (duration: 150.316799ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:50:58.123388Z","caller":"traceutil/trace.go:171","msg":"trace[2096473845] transaction","detail":"{read_only:false; response_revision:21144; number_of_response:1; }","duration":"298.401405ms","start":"2026-10-16T00:50:57.824957Z","end":"2026-10-16T00:50:58.123358Z","steps":["trace[2096473845] 'process raft request' (duration: 298.197341ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:50:58.143566Z","caller":"traceutil/trace.go:171","msg":"trace[59562052] linearizableReadLoop","detail":"{readStateIndex:21872; appliedIndex:21870; }","duration":"314.711653ms","start":"2026-10-16T00:50:57.828825Z","end":"2026-10-16T00:50:58.143537Z","steps":["trace[59562052] 'read index received' (duration: 294.360108ms)","trace[59562052] 'applied index is now lower than readState.Index' (duration: 20.350335ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:50:58.143665Z","caller":"traceutil/trace.go:171","msg":"trace[916459486] transaction","detail":"{read_only:false; response_revision:21145; number_of_response:1; }","duration":"316.793954ms","start":"2026-10-16T00:50:57.826849Z","end":"2026-10-16T00:50:58.143643Z","steps":["trace[916459486] 'process raft request' (duration: 316.506528ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:50:58.143763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.925318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-10-16T00:50:58.143758Z","caller":"traceutil/trace.go:171","msg":"trace[1422297593] transaction","detail":"{read_only:false; response_revision:21146; number_of_response:1; }","duration":"230.228023ms","start":"2026-10-16T00:50:57.913499Z","end":"2026-10-16T00:50:58.143727Z","steps":["trace[1422297593] 'process raft request' (duration: 229.992999ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:50:58.143803Z","caller":"traceutil/trace.go:171","msg":"trace[428700235] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:21146; }","duration":"315.020809ms","start":"2026-10-16T00:50:57.828771Z","end":"2026-10-16T00:50:58.143792Z","steps":["trace[428700235] 'agreement among raft nodes before linearized reading' (duration: 314.865276ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:50:58.143835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:50:57.828753Z","time spent":"315.07379ms","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-10-16T00:50:58.1438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:50:57.826813Z","time spent":"316.911725ms","remote":"127.0.0.1:47518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T00:50:58.297688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.335475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-16T00:50:58.297802Z","caller":"traceutil/trace.go:171","msg":"trace[786530563] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:21146; }","duration":"105.490399ms","start":"2026-10-16T00:50:58.192281Z","end":"2026-10-16T00:50:58.297772Z","steps":["trace[786530563] 'range keys from in-memory index tree' (duration: 105.120472ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:52:23.913481Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19679} {"level":"info","ts":"2026-10-16T00:52:24.033487Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19679,"took":"116.319339ms","hash":385332378,"current-db-size-bytes":56197120,"current-db-size":"56 MB","current-db-size-in-use-bytes":29872128,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-16T00:52:24.033642Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":385332378,"revision":19679,"compact-revision":17185} {"level":"info","ts":"2026-10-16T00:57:23.920693Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21908} {"level":"info","ts":"2026-10-16T00:57:24.042851Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21908,"took":"117.749736ms","hash":1240911331,"current-db-size-bytes":56197120,"current-db-size":"56 MB","current-db-size-in-use-bytes":44204032,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-10-16T00:57:24.042924Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1240911331,"revision":21908,"compact-revision":19679} {"level":"warn","ts":"2026-10-16T00:58:16.169068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.771044ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-16T00:58:16.169143Z","caller":"traceutil/trace.go:171","msg":"trace[1975881499] transaction","detail":"{read_only:false; response_revision:25603; number_of_response:1; }","duration":"303.785984ms","start":"2026-10-16T00:58:15.865343Z","end":"2026-10-16T00:58:16.169129Z","steps":["trace[1975881499] 'process raft request' (duration: 173.903218ms)","trace[1975881499] 'compare' (duration: 129.648722ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:58:16.169171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:58:15.865314Z","time spent":"303.844695ms","remote":"127.0.0.1:47518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T00:58:16.219283Z","caller":"traceutil/trace.go:171","msg":"trace[449381053] linearizableReadLoop","detail":"{readStateIndex:26456; appliedIndex:26453; }","duration":"203.926355ms","start":"2026-10-16T00:58:16.015322Z","end":"2026-10-16T00:58:16.219249Z","steps":["trace[449381053] 'read index received' (duration: 23.950423ms)","trace[449381053] 'applied index is now lower than readState.Index' (duration: 179.974832ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:58:16.219581Z","caller":"traceutil/trace.go:171","msg":"trace[1046608272] transaction","detail":"{read_only:false; response_revision:25604; number_of_response:1; }","duration":"327.829549ms","start":"2026-10-16T00:58:15.891737Z","end":"2026-10-16T00:58:16.219566Z","steps":["trace[1046608272] 'process raft request' (duration: 327.328879ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:58:16.219713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:58:15.891713Z","time spent":"327.92218ms","remote":"127.0.0.1:47518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T00:58:16.219902Z","caller":"traceutil/trace.go:171","msg":"trace[160359402] transaction","detail":"{read_only:false; response_revision:25605; number_of_response:1; }","duration":"256.047691ms","start":"2026-10-16T00:58:15.963843Z","end":"2026-10-16T00:58:16.219891Z","steps":["trace[160359402] 'process raft request' (duration: 255.360298ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:58:16.220162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.836812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-16T00:58:16.220203Z","caller":"traceutil/trace.go:171","msg":"trace[1831323732] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25605; }","duration":"204.939754ms","start":"2026-10-16T00:58:16.015254Z","end":"2026-10-16T00:58:16.220194Z","steps":["trace[1831323732] 'agreement among raft nodes before linearized reading' (duration: 204.806381ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:58:16.413303Z","caller":"traceutil/trace.go:171","msg":"trace[678921031] linearizableReadLoop","detail":"{readStateIndex:26457; appliedIndex:26456; }","duration":"130.502286ms","start":"2026-10-16T00:58:16.282743Z","end":"2026-10-16T00:58:16.413245Z","steps":["trace[678921031] 'read index received' (duration: 73.02214ms)","trace[678921031] 'applied index is now lower than readState.Index' (duration: 57.478046ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:58:16.413968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.203861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-16T00:58:16.414053Z","caller":"traceutil/trace.go:171","msg":"trace[1621908251] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25606; }","duration":"131.334483ms","start":"2026-10-16T00:58:16.282697Z","end":"2026-10-16T00:58:16.414031Z","steps":["trace[1621908251] 'agreement among raft nodes before linearized reading' (duration: 131.041357ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:58:16.414186Z","caller":"traceutil/trace.go:171","msg":"trace[974229624] transaction","detail":"{read_only:false; response_revision:25606; number_of_response:1; }","duration":"189.34854ms","start":"2026-10-16T00:58:16.224804Z","end":"2026-10-16T00:58:16.414153Z","steps":["trace[974229624] 'process raft request' (duration: 130.993467ms)","trace[974229624] 'compare' (duration: 56.749763ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:58:16.730012Z","caller":"traceutil/trace.go:171","msg":"trace[745197924] transaction","detail":"{read_only:false; response_revision:25611; number_of_response:1; }","duration":"214.861703ms","start":"2026-10-16T00:58:16.515095Z","end":"2026-10-16T00:58:16.729957Z","steps":["trace[745197924] 'process raft request' (duration: 214.666769ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:58:16.732746Z","caller":"traceutil/trace.go:171","msg":"trace[936853502] transaction","detail":"{read_only:false; response_revision:25612; number_of_response:1; }","duration":"216.196136ms","start":"2026-10-16T00:58:16.516508Z","end":"2026-10-16T00:58:16.732704Z","steps":["trace[936853502] 'process raft request' (duration: 215.753759ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:59:21.391407Z","caller":"traceutil/trace.go:171","msg":"trace[60958835] transaction","detail":"{read_only:false; response_revision:26184; number_of_response:1; }","duration":"262.660736ms","start":"2026-10-16T00:59:21.128715Z","end":"2026-10-16T00:59:21.391376Z","steps":["trace[60958835] 'process raft request' (duration: 262.502693ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:59:21.396184Z","caller":"traceutil/trace.go:171","msg":"trace[1712263823] transaction","detail":"{read_only:false; response_revision:26185; number_of_response:1; }","duration":"202.786603ms","start":"2026-10-16T00:59:21.193369Z","end":"2026-10-16T00:59:21.396155Z","steps":["trace[1712263823] 'process raft request' (duration: 202.630271ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:59:21.795448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.206478ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-16T00:59:21.79579Z","caller":"traceutil/trace.go:171","msg":"trace[961041483] transaction","detail":"{read_only:false; response_revision:26187; number_of_response:1; }","duration":"211.471709ms","start":"2026-10-16T00:59:21.584254Z","end":"2026-10-16T00:59:21.795726Z","steps":["trace[961041483] 'process raft request' (duration: 104.903454ms)","trace[961041483] 'compare' (duration: 105.963083ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:59:56.572629Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006b44.wal"} {"level":"info","ts":"2026-10-16T01:02:23.927429Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25125} {"level":"info","ts":"2026-10-16T01:02:24.095857Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25125,"took":"164.03951ms","hash":1933880228,"current-db-size-bytes":56197120,"current-db-size":"56 MB","current-db-size-in-use-bytes":36691968,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-10-16T01:02:24.095925Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1933880228,"revision":25125,"compact-revision":21908} {"level":"info","ts":"2026-10-16T01:04:14.835894Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ea06c5748459930b","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-16T01:04:14.843055Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-16T01:04:14.843161Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-16T01:07:23.934804Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27982} {"level":"info","ts":"2026-10-16T01:07:24.081373Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27982,"took":"141.405562ms","hash":1027177761,"current-db-size-bytes":56197120,"current-db-size":"56 MB","current-db-size-in-use-bytes":32612352,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-16T01:07:24.081439Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1027177761,"revision":27982,"compact-revision":25125} {"level":"info","ts":"2026-10-16T01:12:23.942285Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30514} {"level":"info","ts":"2026-10-16T01:12:24.081168Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30514,"took":"135.490135ms","hash":1842256482,"current-db-size-bytes":56197120,"current-db-size":"56 MB","current-db-size-in-use-bytes":30449664,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-16T01:12:24.081224Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1842256482,"revision":30514,"compact-revision":27982} {"level":"info","ts":"2026-10-16T01:17:23.95083Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32768} {"level":"info","ts":"2026-10-16T01:17:24.114136Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32768,"took":"158.430948ms","hash":2447061108,"current-db-size-bytes":57237504,"current-db-size":"57 MB","current-db-size-in-use-bytes":30121984,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-16T01:17:24.114253Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2447061108,"revision":32768,"compact-revision":30514} {"level":"info","ts":"2026-10-16T01:22:23.960354Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34994} {"level":"info","ts":"2026-10-16T01:22:24.100314Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34994,"took":"135.717829ms","hash":318845065,"current-db-size-bytes":57237504,"current-db-size":"57 MB","current-db-size-in-use-bytes":31948800,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-16T01:22:24.100393Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":318845065,"revision":34994,"compact-revision":32768} {"level":"info","ts":"2026-10-16T01:25:37.882731Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ea06c5748459930b","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-16T01:25:37.890811Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-16T01:25:37.890942Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-16T01:27:23.967016Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37285} {"level":"info","ts":"2026-10-16T01:27:24.102113Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37285,"took":"130.627199ms","hash":4101161958,"current-db-size-bytes":57237504,"current-db-size":"57 MB","current-db-size-in-use-bytes":32604160,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-16T01:27:24.102257Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4101161958,"revision":37285,"compact-revision":34994} {"level":"info","ts":"2026-10-16T01:32:23.977358Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39562} {"level":"info","ts":"2026-10-16T01:32:24.113085Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39562,"took":"131.542046ms","hash":1329281376,"current-db-size-bytes":57659392,"current-db-size":"58 MB","current-db-size-in-use-bytes":31064064,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-16T01:32:24.113167Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1329281376,"revision":39562,"compact-revision":37285}