{"level":"warn","ts":"2026-10-16T00:07:10.202051Z","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:10.202416Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.36: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.36:2380","--initial-cluster=instance=https://162.253.55.36:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.36:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.36: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:10.20256Z","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:10.202588Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.36:2380"]} {"level":"info","ts":"2026-10-16T00:07:10.202627Z","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:10.203869Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.36:2379"]} {"level":"info","ts":"2026-10-16T00:07:10.204043Z","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.36:2380"],"listen-peer-urls":["https://162.253.55.36:2380"],"advertise-client-urls":["https://162.253.55.36:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.36:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.36: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:10.21022Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.712536ms"} {"level":"info","ts":"2026-10-16T00:07:10.218315Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"cb89ec34a9687f0b","cluster-id":"d8e0d5e222e856c9"} {"level":"info","ts":"2026-10-16T00:07:10.218399Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b switched to configuration voters=()"} {"level":"info","ts":"2026-10-16T00:07:10.218428Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b became follower at term 0"} {"level":"info","ts":"2026-10-16T00:07:10.21845Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft cb89ec34a9687f0b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-16T00:07:10.21846Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b became follower at term 1"} {"level":"info","ts":"2026-10-16T00:07:10.218513Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b switched to configuration voters=(14666513372433383179)"} {"level":"warn","ts":"2026-10-16T00:07:10.2253Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-16T00:07:10.227234Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-16T00:07:10.228846Z","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:10.231598Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"cb89ec34a9687f0b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-16T00:07:10.231863Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"cb89ec34a9687f0b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-16T00:07:10.23196Z","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:10.232104Z","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:10.232128Z","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:10.242404Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b switched to configuration voters=(14666513372433383179)"} {"level":"info","ts":"2026-10-16T00:07:10.242648Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"d8e0d5e222e856c9","local-member-id":"cb89ec34a9687f0b","added-peer-id":"cb89ec34a9687f0b","added-peer-peer-urls":["https://162.253.55.36:2380"]} {"level":"info","ts":"2026-10-16T00:07:10.243804Z","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:10.24391Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.36:2380"} {"level":"info","ts":"2026-10-16T00:07:10.244822Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.36:2380"} {"level":"info","ts":"2026-10-16T00:07:10.245069Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"cb89ec34a9687f0b","initial-advertise-peer-urls":["https://162.253.55.36:2380"],"listen-peer-urls":["https://162.253.55.36:2380"],"advertise-client-urls":["https://162.253.55.36:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.36:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-16T00:07:10.245157Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-16T00:07:10.918847Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b is starting a new election at term 1"} {"level":"info","ts":"2026-10-16T00:07:10.918953Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b became pre-candidate at term 1"} {"level":"info","ts":"2026-10-16T00:07:10.918981Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b received MsgPreVoteResp from cb89ec34a9687f0b at term 1"} {"level":"info","ts":"2026-10-16T00:07:10.919006Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b became candidate at term 2"} {"level":"info","ts":"2026-10-16T00:07:10.919018Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b received MsgVoteResp from cb89ec34a9687f0b at term 2"} {"level":"info","ts":"2026-10-16T00:07:10.919036Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b became leader at term 2"} {"level":"info","ts":"2026-10-16T00:07:10.919058Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: cb89ec34a9687f0b elected leader cb89ec34a9687f0b at term 2"} {"level":"info","ts":"2026-10-16T00:07:10.9206Z","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:10.921107Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"cb89ec34a9687f0b","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.36:2379]}","request-path":"/0/members/cb89ec34a9687f0b/attributes","cluster-id":"d8e0d5e222e856c9","publish-timeout":"7s"} {"level":"info","ts":"2026-10-16T00:07:10.921153Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-16T00:07:10.921113Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-16T00:07:10.921533Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-16T00:07:10.921623Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-16T00:07:10.922052Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"d8e0d5e222e856c9","local-member-id":"cb89ec34a9687f0b","cluster-version":"3.5"} {"level":"info","ts":"2026-10-16T00:07:10.922213Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-16T00:07:10.92225Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-16T00:07:10.925088Z","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:10.925548Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.36:2379"} {"level":"info","ts":"2026-10-16T00:08:04.970711Z","caller":"traceutil/trace.go:171","msg":"trace[429144034] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"129.945556ms","start":"2026-10-16T00:08:04.840734Z","end":"2026-10-16T00:08:04.97068Z","steps":["trace[429144034] 'process raft request' (duration: 71.8125ms)","trace[429144034] 'compare' (duration: 57.968492ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:08:05.333133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.135559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-10-16T00:08:05.333325Z","caller":"traceutil/trace.go:171","msg":"trace[1670131105] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:658; }","duration":"243.397853ms","start":"2026-10-16T00:08:05.089899Z","end":"2026-10-16T00:08:05.333297Z","steps":["trace[1670131105] 'range keys from in-memory index tree' (duration: 242.925644ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:08:05.888358Z","caller":"traceutil/trace.go:171","msg":"trace[457190071] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"463.36237ms","start":"2026-10-16T00:08:05.424955Z","end":"2026-10-16T00:08:05.888317Z","steps":["trace[457190071] 'process raft request' (duration: 459.828083ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:08:05.888963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:08:05.42492Z","time spent":"463.531682ms","remote":"127.0.0.1:57690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-16T00:08:55.817749Z","caller":"traceutil/trace.go:171","msg":"trace[1088463723] linearizableReadLoop","detail":"{readStateIndex:1281; appliedIndex:1280; }","duration":"210.985358ms","start":"2026-10-16T00:08:55.606744Z","end":"2026-10-16T00:08:55.817729Z","steps":["trace[1088463723] 'read index received' (duration: 210.899897ms)","trace[1088463723] 'applied index is now lower than readState.Index' (duration: 84.391µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:08:55.81791Z","caller":"traceutil/trace.go:171","msg":"trace[1345964094] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"214.612756ms","start":"2026-10-16T00:08:55.603237Z","end":"2026-10-16T00:08:55.81785Z","steps":["trace[1345964094] 'process raft request' (duration: 214.382671ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:08:55.818039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.278204ms","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:55.81832Z","caller":"traceutil/trace.go:171","msg":"trace[505532195] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1245; }","duration":"211.60143ms","start":"2026-10-16T00:08:55.606699Z","end":"2026-10-16T00:08:55.8183Z","steps":["trace[505532195] 'agreement among raft nodes before linearized reading' (duration: 211.242493ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:08:55.85464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.768611ms","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:08:55.854739Z","caller":"traceutil/trace.go:171","msg":"trace[1431296424] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1246; }","duration":"235.899354ms","start":"2026-10-16T00:08:55.618816Z","end":"2026-10-16T00:08:55.854716Z","steps":["trace[1431296424] 'agreement among raft nodes before linearized reading' (duration: 235.640079ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:08:55.855081Z","caller":"traceutil/trace.go:171","msg":"trace[1174941715] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"183.174459ms","start":"2026-10-16T00:08:55.671886Z","end":"2026-10-16T00:08:55.85506Z","steps":["trace[1174941715] 'process raft request' (duration: 182.396915ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:09:44.274801Z","caller":"traceutil/trace.go:171","msg":"trace[675951879] transaction","detail":"{read_only:false; response_revision:1917; number_of_response:1; }","duration":"116.322082ms","start":"2026-10-16T00:09:44.158432Z","end":"2026-10-16T00:09:44.274754Z","steps":["trace[675951879] 'process raft request' (duration: 70.839813ms)","trace[675951879] 'compare' (duration: 45.296545ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:09:44.563653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.912329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.36\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-10-16T00:09:44.563793Z","caller":"traceutil/trace.go:171","msg":"trace[1723235379] range","detail":"{range_begin:/registry/masterleases/162.253.55.36; range_end:; response_count:1; response_revision:1917; }","duration":"165.118043ms","start":"2026-10-16T00:09:44.398646Z","end":"2026-10-16T00:09:44.563764Z","steps":["trace[1723235379] 'range keys from in-memory index tree' (duration: 164.649574ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:10:23.948624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.576198ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-16T00:10:23.948784Z","caller":"traceutil/trace.go:171","msg":"trace[2074707203] transaction","detail":"{read_only:false; response_revision:2179; number_of_response:1; }","duration":"136.186529ms","start":"2026-10-16T00:10:23.812579Z","end":"2026-10-16T00:10:23.948766Z","steps":["trace[2074707203] 'process raft request' (duration: 10.093741ms)","trace[2074707203] 'compare' (duration: 125.443187ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:11:24.244439Z","caller":"traceutil/trace.go:171","msg":"trace[894190671] transaction","detail":"{read_only:false; response_revision:2674; number_of_response:1; }","duration":"182.71445ms","start":"2026-10-16T00:11:24.061697Z","end":"2026-10-16T00:11:24.244412Z","steps":["trace[894190671] 'process raft request' (duration: 182.388693ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:12:09.036308Z","caller":"traceutil/trace.go:171","msg":"trace[889920221] linearizableReadLoop","detail":"{readStateIndex:3053; appliedIndex:3052; }","duration":"105.34832ms","start":"2026-10-16T00:12:08.930925Z","end":"2026-10-16T00:12:09.036273Z","steps":["trace[889920221] 'read index received' (duration: 27.46099ms)","trace[889920221] 'applied index is now lower than readState.Index' (duration: 77.88548ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:12:09.036351Z","caller":"traceutil/trace.go:171","msg":"trace[1949322938] transaction","detail":"{read_only:false; response_revision:2941; number_of_response:1; }","duration":"108.738883ms","start":"2026-10-16T00:12:08.927571Z","end":"2026-10-16T00:12:09.03631Z","steps":["trace[1949322938] 'process raft request' (duration: 30.807372ms)","trace[1949322938] 'compare' (duration: 77.703326ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:12:09.036554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.600725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak\" ","response":"range_response_count:1 size:1315"} {"level":"info","ts":"2026-10-16T00:12:09.036622Z","caller":"traceutil/trace.go:171","msg":"trace[1769345947] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak; range_end:; response_count:1; response_revision:2941; }","duration":"105.709306ms","start":"2026-10-16T00:12:08.930893Z","end":"2026-10-16T00:12:09.036602Z","steps":["trace[1769345947] 'agreement among raft nodes before linearized reading' (duration: 105.487412ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:16:31.142152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.291405ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-16T00:16:31.142276Z","caller":"traceutil/trace.go:171","msg":"trace[1339308703] transaction","detail":"{read_only:false; response_revision:5119; number_of_response:1; }","duration":"179.199158ms","start":"2026-10-16T00:16:30.963057Z","end":"2026-10-16T00:16:31.142256Z","steps":["trace[1339308703] 'process raft request' (duration: 72.682548ms)","trace[1339308703] 'compare' (duration: 106.183903ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:16:31.143364Z","caller":"traceutil/trace.go:171","msg":"trace[748774553] linearizableReadLoop","detail":"{readStateIndex:5317; appliedIndex:5314; }","duration":"174.118491ms","start":"2026-10-16T00:16:30.969226Z","end":"2026-10-16T00:16:31.143344Z","steps":["trace[748774553] 'read index received' (duration: 66.547781ms)","trace[748774553] 'applied index is now lower than readState.Index' (duration: 107.56988ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:16:31.14352Z","caller":"traceutil/trace.go:171","msg":"trace[233354885] transaction","detail":"{read_only:false; response_revision:5120; number_of_response:1; }","duration":"180.30554ms","start":"2026-10-16T00:16:30.963164Z","end":"2026-10-16T00:16:31.14347Z","steps":["trace[233354885] 'process raft request' (duration: 180.015714ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:16:31.143595Z","caller":"traceutil/trace.go:171","msg":"trace[647928580] transaction","detail":"{read_only:false; response_revision:5121; number_of_response:1; }","duration":"180.147327ms","start":"2026-10-16T00:16:30.963421Z","end":"2026-10-16T00:16:31.143568Z","steps":["trace[647928580] 'process raft request' (duration: 179.866281ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:16:31.14364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.391956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-gfsdh.18deda5be3e7ea24\" ","response":"range_response_count:1 size:962"} {"level":"info","ts":"2026-10-16T00:16:31.143722Z","caller":"traceutil/trace.go:171","msg":"trace[1792406353] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-gfsdh.18deda5be3e7ea24; range_end:; response_count:1; response_revision:5121; }","duration":"174.509258ms","start":"2026-10-16T00:16:30.969193Z","end":"2026-10-16T00:16:31.143702Z","steps":["trace[1792406353] 'agreement among raft nodes before linearized reading' (duration: 174.299424ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:16:33.735387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.474525ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-16T00:16:33.735543Z","caller":"traceutil/trace.go:171","msg":"trace[1165511771] linearizableReadLoop","detail":"{readStateIndex:5452; appliedIndex:5451; }","duration":"284.291431ms","start":"2026-10-16T00:16:33.451233Z","end":"2026-10-16T00:16:33.735524Z","steps":["trace[1165511771] 'read index received' (duration: 80.485159ms)","trace[1165511771] 'applied index is now lower than readState.Index' (duration: 203.804512ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:16:33.73571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.484515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-88765dc3-10a8-46b1-a2e3-1a6007242572\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-10-16T00:16:33.735713Z","caller":"traceutil/trace.go:171","msg":"trace[2050465818] transaction","detail":"{read_only:false; response_revision:5255; number_of_response:1; }","duration":"311.669158ms","start":"2026-10-16T00:16:33.424003Z","end":"2026-10-16T00:16:33.735672Z","steps":["trace[2050465818] 'process raft request' (duration: 107.821665ms)","trace[2050465818] 'compare' (duration: 203.366733ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:16:33.735878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:16:33.423981Z","time spent":"311.812441ms","remote":"127.0.0.1:57450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T00:16:33.73591Z","caller":"traceutil/trace.go:171","msg":"trace[1590596473] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-88765dc3-10a8-46b1-a2e3-1a6007242572; range_end:; response_count:1; response_revision:5255; }","duration":"284.557107ms","start":"2026-10-16T00:16:33.451173Z","end":"2026-10-16T00:16:33.73573Z","steps":["trace[1590596473] 'agreement among raft nodes before linearized reading' (duration: 284.396894ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:16:33.775539Z","caller":"traceutil/trace.go:171","msg":"trace[382114158] transaction","detail":"{read_only:false; response_revision:5256; number_of_response:1; }","duration":"169.044463ms","start":"2026-10-16T00:16:33.606459Z","end":"2026-10-16T00:16:33.775504Z","steps":["trace[382114158] 'process raft request' (duration: 168.732427ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:16:43.258676Z","caller":"traceutil/trace.go:171","msg":"trace[1845852850] transaction","detail":"{read_only:false; response_revision:5442; number_of_response:1; }","duration":"129.302738ms","start":"2026-10-16T00:16:43.129349Z","end":"2026-10-16T00:16:43.258651Z","steps":["trace[1845852850] 'process raft request' (duration: 129.208956ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:16:43.258681Z","caller":"traceutil/trace.go:171","msg":"trace[1479669312] transaction","detail":"{read_only:false; response_revision:5441; number_of_response:1; }","duration":"129.342039ms","start":"2026-10-16T00:16:43.12931Z","end":"2026-10-16T00:16:43.258652Z","steps":["trace[1479669312] 'process raft request' (duration: 129.092804ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:16:43.259033Z","caller":"traceutil/trace.go:171","msg":"trace[299594193] transaction","detail":"{read_only:false; response_revision:5443; number_of_response:1; }","duration":"129.562513ms","start":"2026-10-16T00:16:43.129445Z","end":"2026-10-16T00:16:43.259008Z","steps":["trace[299594193] 'process raft request' (duration: 129.174205ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:16:43.259287Z","caller":"traceutil/trace.go:171","msg":"trace[1946291380] linearizableReadLoop","detail":"{readStateIndex:5649; appliedIndex:5644; }","duration":"114.692137ms","start":"2026-10-16T00:16:43.144564Z","end":"2026-10-16T00:16:43.259256Z","steps":["trace[1946291380] 'read index received' (duration: 10.89869ms)","trace[1946291380] 'applied index is now lower than readState.Index' (duration: 103.789567ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:16:43.259328Z","caller":"traceutil/trace.go:171","msg":"trace[278767183] transaction","detail":"{read_only:false; response_revision:5444; number_of_response:1; }","duration":"129.503492ms","start":"2026-10-16T00:16:43.129807Z","end":"2026-10-16T00:16:43.25931Z","steps":["trace[278767183] 'process raft request' (duration: 129.139095ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:16:43.259597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.008333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-10-16T00:16:43.259676Z","caller":"traceutil/trace.go:171","msg":"trace[1414049542] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5444; }","duration":"115.090514ms","start":"2026-10-16T00:16:43.144538Z","end":"2026-10-16T00:16:43.259629Z","steps":["trace[1414049542] 'agreement among raft nodes before linearized reading' (duration: 114.826029ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:16:47.955529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.168427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:16:47.955592Z","caller":"traceutil/trace.go:171","msg":"trace[1257575532] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5521; }","duration":"206.27932ms","start":"2026-10-16T00:16:47.749298Z","end":"2026-10-16T00:16:47.955578Z","steps":["trace[1257575532] 'range keys from in-memory index tree' (duration: 206.097146ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:16:47.959335Z","caller":"traceutil/trace.go:171","msg":"trace[708009075] transaction","detail":"{read_only:false; response_revision:5522; number_of_response:1; }","duration":"167.192117ms","start":"2026-10-16T00:16:47.79212Z","end":"2026-10-16T00:16:47.959312Z","steps":["trace[708009075] 'process raft request' (duration: 167.043544ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:16:47.960339Z","caller":"traceutil/trace.go:171","msg":"trace[88833793] transaction","detail":"{read_only:false; response_revision:5523; number_of_response:1; }","duration":"139.398794ms","start":"2026-10-16T00:16:47.820926Z","end":"2026-10-16T00:16:47.960324Z","steps":["trace[88833793] 'process raft request' (duration: 139.20608ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:16:47.960395Z","caller":"traceutil/trace.go:171","msg":"trace[2146655498] transaction","detail":"{read_only:false; response_revision:5524; number_of_response:1; }","duration":"104.41178ms","start":"2026-10-16T00:16:47.855966Z","end":"2026-10-16T00:16:47.960377Z","steps":["trace[2146655498] 'process raft request' (duration: 104.287077ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:16:56.195765Z","caller":"traceutil/trace.go:171","msg":"trace[1165945684] transaction","detail":"{read_only:false; response_revision:5675; number_of_response:1; }","duration":"129.699306ms","start":"2026-10-16T00:16:56.066037Z","end":"2026-10-16T00:16:56.195736Z","steps":["trace[1165945684] 'process raft request' (duration: 114.545984ms)","trace[1165945684] 'compare' (duration: 14.698583ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:16:56.196318Z","caller":"traceutil/trace.go:171","msg":"trace[1903760709] transaction","detail":"{read_only:false; response_revision:5676; number_of_response:1; }","duration":"129.89764ms","start":"2026-10-16T00:16:56.066391Z","end":"2026-10-16T00:16:56.196289Z","steps":["trace[1903760709] 'process raft request' (duration: 129.274978ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:16:56.196554Z","caller":"traceutil/trace.go:171","msg":"trace[2036459275] transaction","detail":"{read_only:false; response_revision:5677; number_of_response:1; }","duration":"124.15185ms","start":"2026-10-16T00:16:56.072343Z","end":"2026-10-16T00:16:56.196495Z","steps":["trace[2036459275] 'process raft request' (duration: 123.819123ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:16:56.196406Z","caller":"traceutil/trace.go:171","msg":"trace[48279439] linearizableReadLoop","detail":"{readStateIndex:5886; appliedIndex:5882; }","duration":"103.895969ms","start":"2026-10-16T00:16:56.092495Z","end":"2026-10-16T00:16:56.196391Z","steps":["trace[48279439] 'read index received' (duration: 11.234766ms)","trace[48279439] 'applied index is now lower than readState.Index' (duration: 92.660273ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:16:56.197599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.835297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-keystone-default-user\" ","response":"range_response_count:1 size:1162"} {"level":"info","ts":"2026-10-16T00:16:56.197984Z","caller":"traceutil/trace.go:171","msg":"trace[897186336] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-keystone-default-user; range_end:; response_count:1; response_revision:5677; }","duration":"105.550561ms","start":"2026-10-16T00:16:56.092409Z","end":"2026-10-16T00:16:56.19796Z","steps":["trace[897186336] 'agreement among raft nodes before linearized reading' (duration: 104.386249ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:16:59.49645Z","caller":"traceutil/trace.go:171","msg":"trace[704147023] transaction","detail":"{read_only:false; response_revision:5729; number_of_response:1; }","duration":"165.503845ms","start":"2026-10-16T00:16:59.330868Z","end":"2026-10-16T00:16:59.496372Z","steps":["trace[704147023] 'process raft request' (duration: 144.262467ms)","trace[704147023] 'compare' (duration: 20.602126ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:17:10.954958Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2953} {"level":"info","ts":"2026-10-16T00:17:11.031976Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2953,"took":"75.818039ms","hash":2515965335,"current-db-size-bytes":56840192,"current-db-size":"57 MB","current-db-size-in-use-bytes":32169984,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-16T00:17:11.032058Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2515965335,"revision":2953,"compact-revision":-1} {"level":"info","ts":"2026-10-16T00:22:10.96408Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5875} {"level":"info","ts":"2026-10-16T00:22:11.061272Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5875,"took":"95.374983ms","hash":2047336509,"current-db-size-bytes":56840192,"current-db-size":"57 MB","current-db-size-in-use-bytes":26812416,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-10-16T00:22:11.061323Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2047336509,"revision":5875,"compact-revision":2953} {"level":"warn","ts":"2026-10-16T00:23:22.228822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.858419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-16T00:23:22.228886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.936901ms","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":"warn","ts":"2026-10-16T00:23:22.228886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.865468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:23:22.228951Z","caller":"traceutil/trace.go:171","msg":"trace[1583197005] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:8625; }","duration":"132.063413ms","start":"2026-10-16T00:23:22.096869Z","end":"2026-10-16T00:23:22.228933Z","steps":["trace[1583197005] 'range keys from in-memory index tree' (duration: 131.814729ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:23:22.228959Z","caller":"traceutil/trace.go:171","msg":"trace[1426844623] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:8625; }","duration":"109.973761ms","start":"2026-10-16T00:23:22.118969Z","end":"2026-10-16T00:23:22.228943Z","steps":["trace[1426844623] 'range keys from in-memory index tree' (duration: 109.796327ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:23:22.229008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.166345ms","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:22.229082Z","caller":"traceutil/trace.go:171","msg":"trace[308795412] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:8625; }","duration":"131.258988ms","start":"2026-10-16T00:23:22.097812Z","end":"2026-10-16T00:23:22.229071Z","steps":["trace[308795412] 'range keys from in-memory index tree' (duration: 131.119085ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:23:22.228907Z","caller":"traceutil/trace.go:171","msg":"trace[1824753402] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:8625; }","duration":"110.009371ms","start":"2026-10-16T00:23:22.11888Z","end":"2026-10-16T00:23:22.22889Z","steps":["trace[1824753402] 'count revisions from in-memory index tree' (duration: 109.781407ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:23:22.228994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.177595ms","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:22.229238Z","caller":"traceutil/trace.go:171","msg":"trace[564995883] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:8625; }","duration":"132.44585ms","start":"2026-10-16T00:23:22.096753Z","end":"2026-10-16T00:23:22.229199Z","steps":["trace[564995883] 'count revisions from in-memory index tree' (duration: 132.114434ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:23:22.230061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.092163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:23:22.230159Z","caller":"traceutil/trace.go:171","msg":"trace[1883710425] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:8625; }","duration":"111.258706ms","start":"2026-10-16T00:23:22.11888Z","end":"2026-10-16T00:23:22.230139Z","steps":["trace[1883710425] 'range keys from in-memory index tree' (duration: 110.978431ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:23:22.381121Z","caller":"traceutil/trace.go:171","msg":"trace[2047328993] linearizableReadLoop","detail":"{readStateIndex:8932; appliedIndex:8931; }","duration":"116.471805ms","start":"2026-10-16T00:23:22.264625Z","end":"2026-10-16T00:23:22.381097Z","steps":["trace[2047328993] 'read index received' (duration: 116.19564ms)","trace[2047328993] 'applied index is now lower than readState.Index' (duration: 275.305µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:23:22.381232Z","caller":"traceutil/trace.go:171","msg":"trace[1265953778] transaction","detail":"{read_only:false; response_revision:8626; number_of_response:1; }","duration":"121.027872ms","start":"2026-10-16T00:23:22.260166Z","end":"2026-10-16T00:23:22.381194Z","steps":["trace[1265953778] 'process raft request' (duration: 120.760387ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:23:22.381364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.712981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-16T00:23:22.381407Z","caller":"traceutil/trace.go:171","msg":"trace[266587800] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8626; }","duration":"116.803302ms","start":"2026-10-16T00:23:22.264592Z","end":"2026-10-16T00:23:22.381396Z","steps":["trace[266587800] 'agreement among raft nodes before linearized reading' (duration: 116.635339ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:23:22.49876Z","caller":"traceutil/trace.go:171","msg":"trace[250032647] transaction","detail":"{read_only:false; response_revision:8628; number_of_response:1; }","duration":"110.98171ms","start":"2026-10-16T00:23:22.387749Z","end":"2026-10-16T00:23:22.49873Z","steps":["trace[250032647] 'process raft request' (duration: 110.786017ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:23:49.917936Z","caller":"traceutil/trace.go:171","msg":"trace[432429119] transaction","detail":"{read_only:false; response_revision:8829; number_of_response:1; }","duration":"100.398429ms","start":"2026-10-16T00:23:49.817482Z","end":"2026-10-16T00:23:49.91788Z","steps":["trace[432429119] 'process raft request' (duration: 99.850538ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:23:52.764962Z","caller":"traceutil/trace.go:171","msg":"trace[1516393077] transaction","detail":"{read_only:false; response_revision:8843; number_of_response:1; }","duration":"219.461732ms","start":"2026-10-16T00:23:52.545477Z","end":"2026-10-16T00:23:52.764939Z","steps":["trace[1516393077] 'process raft request' (duration: 219.33603ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:23:52.765506Z","caller":"traceutil/trace.go:171","msg":"trace[1986913375] linearizableReadLoop","detail":"{readStateIndex:9157; appliedIndex:9157; }","duration":"219.35776ms","start":"2026-10-16T00:23:52.546133Z","end":"2026-10-16T00:23:52.765491Z","steps":["trace[1986913375] 'read index received' (duration: 219.35273ms)","trace[1986913375] 'applied index is now lower than readState.Index' (duration: 4.25µs)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:23:52.765688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.530254ms","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":"info","ts":"2026-10-16T00:23:52.765726Z","caller":"traceutil/trace.go:171","msg":"trace[1071925295] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:8843; }","duration":"219.603205ms","start":"2026-10-16T00:23:52.546112Z","end":"2026-10-16T00:23:52.765715Z","steps":["trace[1071925295] 'agreement among raft nodes before linearized reading' (duration: 219.464982ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:23:52.819928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.812751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-10-16T00:23:52.819996Z","caller":"traceutil/trace.go:171","msg":"trace[1967140147] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8844; }","duration":"194.915563ms","start":"2026-10-16T00:23:52.625063Z","end":"2026-10-16T00:23:52.819978Z","steps":["trace[1967140147] 'agreement among raft nodes before linearized reading' (duration: 194.72834ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:23:52.820274Z","caller":"traceutil/trace.go:171","msg":"trace[1651195362] transaction","detail":"{read_only:false; response_revision:8844; number_of_response:1; }","duration":"205.19629ms","start":"2026-10-16T00:23:52.615068Z","end":"2026-10-16T00:23:52.820264Z","steps":["trace[1651195362] 'process raft request' (duration: 204.622479ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:23:52.820448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.658307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-slqqb\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-10-16T00:23:52.820481Z","caller":"traceutil/trace.go:171","msg":"trace[135823137] range","detail":"{range_begin:/registry/pods/openstack/keepalived-slqqb; range_end:; response_count:1; response_revision:8844; }","duration":"117.731769ms","start":"2026-10-16T00:23:52.702741Z","end":"2026-10-16T00:23:52.820473Z","steps":["trace[135823137] 'agreement among raft nodes before linearized reading' (duration: 117.639367ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:23:52.820678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.471038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-16T00:23:52.82071Z","caller":"traceutil/trace.go:171","msg":"trace[1283076045] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8844; }","duration":"146.53689ms","start":"2026-10-16T00:23:52.674166Z","end":"2026-10-16T00:23:52.820702Z","steps":["trace[1283076045] 'agreement among raft nodes before linearized reading' (duration: 146.459608ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:23:52.820895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.169477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-16T00:23:52.820926Z","caller":"traceutil/trace.go:171","msg":"trace[741618224] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8844; }","duration":"152.236599ms","start":"2026-10-16T00:23:52.668682Z","end":"2026-10-16T00:23:52.820919Z","steps":["trace[741618224] 'agreement among raft nodes before linearized reading' (duration: 152.155897ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:23:52.821029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.871754ms","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:23:52.821056Z","caller":"traceutil/trace.go:171","msg":"trace[375961592] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8844; }","duration":"171.921195ms","start":"2026-10-16T00:23:52.649128Z","end":"2026-10-16T00:23:52.82105Z","steps":["trace[375961592] 'agreement among raft nodes before linearized reading' (duration: 171.854734ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:24:00.417702Z","caller":"traceutil/trace.go:171","msg":"trace[225010547] transaction","detail":"{read_only:false; response_revision:8887; number_of_response:1; }","duration":"127.943184ms","start":"2026-10-16T00:24:00.289723Z","end":"2026-10-16T00:24:00.417666Z","steps":["trace[225010547] 'process raft request' (duration: 127.319982ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:24:01.013761Z","caller":"traceutil/trace.go:171","msg":"trace[1746492587] linearizableReadLoop","detail":"{readStateIndex:9206; appliedIndex:9204; }","duration":"154.986842ms","start":"2026-10-16T00:24:00.85874Z","end":"2026-10-16T00:24:01.013727Z","steps":["trace[1746492587] 'read index received' (duration: 9.141695ms)","trace[1746492587] 'applied index is now lower than readState.Index' (duration: 145.843507ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:24:01.013838Z","caller":"traceutil/trace.go:171","msg":"trace[478722614] transaction","detail":"{read_only:false; response_revision:8889; number_of_response:1; }","duration":"164.400671ms","start":"2026-10-16T00:24:00.849397Z","end":"2026-10-16T00:24:01.013798Z","steps":["trace[478722614] 'process raft request' (duration: 74.999313ms)","trace[478722614] 'compare' (duration: 89.136763ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:24:01.014113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.920991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-16T00:24:01.01416Z","caller":"traceutil/trace.go:171","msg":"trace[2099780192] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8889; }","duration":"153.013463ms","start":"2026-10-16T00:24:00.861134Z","end":"2026-10-16T00:24:01.014148Z","steps":["trace[2099780192] 'agreement among raft nodes before linearized reading' (duration: 152.784338ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:24:01.01416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.659281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-10-16T00:24:01.014179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.443549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-10-16T00:24:01.014225Z","caller":"traceutil/trace.go:171","msg":"trace[94840959] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8889; }","duration":"147.785353ms","start":"2026-10-16T00:24:00.866427Z","end":"2026-10-16T00:24:01.014212Z","steps":["trace[94840959] 'agreement among raft nodes before linearized reading' (duration: 147.593449ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:24:01.014244Z","caller":"traceutil/trace.go:171","msg":"trace[579545281] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8889; }","duration":"134.55246ms","start":"2026-10-16T00:24:00.879677Z","end":"2026-10-16T00:24:01.014229Z","steps":["trace[579545281] 'agreement among raft nodes before linearized reading' (duration: 134.348326ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:24:01.01444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.267007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-slqqb\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-10-16T00:24:01.014514Z","caller":"traceutil/trace.go:171","msg":"trace[138975698] range","detail":"{range_begin:/registry/pods/openstack/keepalived-slqqb; range_end:; response_count:1; response_revision:8889; }","duration":"131.35807ms","start":"2026-10-16T00:24:00.883141Z","end":"2026-10-16T00:24:01.014499Z","steps":["trace[138975698] 'agreement among raft nodes before linearized reading' (duration: 130.814379ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:24:01.01489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.113373ms","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:24:01.014965Z","caller":"traceutil/trace.go:171","msg":"trace[626301410] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8889; }","duration":"156.249785ms","start":"2026-10-16T00:24:00.858698Z","end":"2026-10-16T00:24:01.014948Z","steps":["trace[626301410] 'agreement among raft nodes before linearized reading' (duration: 155.176204ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:24:07.046124Z","caller":"traceutil/trace.go:171","msg":"trace[431393455] transaction","detail":"{read_only:false; response_revision:8956; number_of_response:1; }","duration":"118.677866ms","start":"2026-10-16T00:24:06.927423Z","end":"2026-10-16T00:24:07.046101Z","steps":["trace[431393455] 'process raft request' (duration: 118.501832ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:24:07.052172Z","caller":"traceutil/trace.go:171","msg":"trace[935824437] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8957; }","duration":"123.924508ms","start":"2026-10-16T00:24:06.928223Z","end":"2026-10-16T00:24:07.052148Z","steps":["trace[935824437] 'process raft request' (duration: 123.682703ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:24:07.288713Z","caller":"traceutil/trace.go:171","msg":"trace[1833880438] transaction","detail":"{read_only:false; response_revision:8966; number_of_response:1; }","duration":"147.860186ms","start":"2026-10-16T00:24:07.140822Z","end":"2026-10-16T00:24:07.288682Z","steps":["trace[1833880438] 'process raft request' (duration: 99.604273ms)","trace[1833880438] 'compare' (duration: 48.060139ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:24:07.499113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.895424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/prometheus-operated\" ","response":"range_response_count:1 size:993"} {"level":"info","ts":"2026-10-16T00:24:07.499196Z","caller":"traceutil/trace.go:171","msg":"trace[1251431264] range","detail":"{range_begin:/registry/services/specs/monitoring/prometheus-operated; range_end:; response_count:1; response_revision:8966; }","duration":"106.042086ms","start":"2026-10-16T00:24:07.393133Z","end":"2026-10-16T00:24:07.499175Z","steps":["trace[1251431264] 'range keys from in-memory index tree' (duration: 105.7024ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:24:07.669312Z","caller":"traceutil/trace.go:171","msg":"trace[403931685] transaction","detail":"{read_only:false; response_revision:8967; number_of_response:1; }","duration":"139.008376ms","start":"2026-10-16T00:24:07.530279Z","end":"2026-10-16T00:24:07.669287Z","steps":["trace[403931685] 'process raft request' (duration: 138.828343ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:24:21.765888Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024cb.wal"} {"level":"info","ts":"2026-10-16T00:25:15.263285Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"cb89ec34a9687f0b","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-16T00:25:15.2698Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-16T00:25:15.269938Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-16T00:25:31.453981Z","caller":"traceutil/trace.go:171","msg":"trace[566058347] transaction","detail":"{read_only:false; response_revision:9755; number_of_response:1; }","duration":"109.530112ms","start":"2026-10-16T00:25:31.344413Z","end":"2026-10-16T00:25:31.453943Z","steps":["trace[566058347] 'process raft request' (duration: 89.545372ms)","trace[566058347] 'compare' (duration: 19.810597ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:27:10.975544Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8023} {"level":"info","ts":"2026-10-16T00:27:11.0414Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8023,"took":"63.856864ms","hash":1187741840,"current-db-size-bytes":56840192,"current-db-size":"57 MB","current-db-size-in-use-bytes":28581888,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-16T00:27:11.041472Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1187741840,"revision":8023,"compact-revision":5875} {"level":"info","ts":"2026-10-16T00:32:10.984308Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10451} {"level":"info","ts":"2026-10-16T00:32:11.079302Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10451,"took":"92.609511ms","hash":3213167117,"current-db-size-bytes":56840192,"current-db-size":"57 MB","current-db-size-in-use-bytes":27721728,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-16T00:32:11.079381Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3213167117,"revision":10451,"compact-revision":8023} {"level":"info","ts":"2026-10-16T00:35:36.702781Z","caller":"traceutil/trace.go:171","msg":"trace[1191820782] transaction","detail":"{read_only:false; response_revision:14167; number_of_response:1; }","duration":"164.230593ms","start":"2026-10-16T00:35:36.53853Z","end":"2026-10-16T00:35:36.702761Z","steps":["trace[1191820782] 'process raft request' (duration: 164.06133ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:35:40.479151Z","caller":"traceutil/trace.go:171","msg":"trace[82024709] transaction","detail":"{read_only:false; response_revision:14201; number_of_response:1; }","duration":"114.577969ms","start":"2026-10-16T00:35:40.364532Z","end":"2026-10-16T00:35:40.47911Z","steps":["trace[82024709] 'process raft request' (duration: 112.863586ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:37:11.021094Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12769} {"level":"info","ts":"2026-10-16T00:37:11.126399Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12769,"took":"102.236914ms","hash":4220405242,"current-db-size-bytes":56840192,"current-db-size":"57 MB","current-db-size-in-use-bytes":30441472,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-16T00:37:11.126475Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4220405242,"revision":12769,"compact-revision":10451} {"level":"info","ts":"2026-10-16T00:42:11.028653Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15226} {"level":"info","ts":"2026-10-16T00:42:11.131682Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15226,"took":"99.85819ms","hash":1726378442,"current-db-size-bytes":56840192,"current-db-size":"57 MB","current-db-size-in-use-bytes":32038912,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-16T00:42:11.131756Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1726378442,"revision":15226,"compact-revision":12769} {"level":"warn","ts":"2026-10-16T00:43:09.141487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.203044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-10-16T00:43:09.141571Z","caller":"traceutil/trace.go:171","msg":"trace[1968431072] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:17864; }","duration":"104.332766ms","start":"2026-10-16T00:43:09.037219Z","end":"2026-10-16T00:43:09.141552Z","steps":["trace[1968431072] 'range keys from in-memory index tree' (duration: 104.090671ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:43:16.04946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.583584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3685"} {"level":"info","ts":"2026-10-16T00:43:16.049569Z","caller":"traceutil/trace.go:171","msg":"trace[953853548] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:17957; }","duration":"239.758997ms","start":"2026-10-16T00:43:15.80978Z","end":"2026-10-16T00:43:16.049539Z","steps":["trace[953853548] 'range keys from in-memory index tree' (duration: 239.359859ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:43:16.40855Z","caller":"traceutil/trace.go:171","msg":"trace[1751349731] linearizableReadLoop","detail":"{readStateIndex:18568; appliedIndex:18567; }","duration":"231.53164ms","start":"2026-10-16T00:43:16.176978Z","end":"2026-10-16T00:43:16.408509Z","steps":["trace[1751349731] 'read index received' (duration: 231.227084ms)","trace[1751349731] 'applied index is now lower than readState.Index' (duration: 298.926µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:43:16.408668Z","caller":"traceutil/trace.go:171","msg":"trace[1286650359] transaction","detail":"{read_only:false; response_revision:17959; number_of_response:1; }","duration":"282.447574ms","start":"2026-10-16T00:43:16.126175Z","end":"2026-10-16T00:43:16.408623Z","steps":["trace[1286650359] 'process raft request' (duration: 282.062217ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:43:16.408911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.894487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0\" ","response":"range_response_count:1 size:1850"} {"level":"info","ts":"2026-10-16T00:43:16.408975Z","caller":"traceutil/trace.go:171","msg":"trace[2060152345] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0; range_end:; response_count:1; response_revision:17959; }","duration":"232.03162ms","start":"2026-10-16T00:43:16.176924Z","end":"2026-10-16T00:43:16.408955Z","steps":["trace[2060152345] 'agreement among raft nodes before linearized reading' (duration: 231.759024ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:43:16.433691Z","caller":"traceutil/trace.go:171","msg":"trace[1518153090] transaction","detail":"{read_only:false; response_revision:17960; number_of_response:1; }","duration":"197.991598ms","start":"2026-10-16T00:43:16.235667Z","end":"2026-10-16T00:43:16.433658Z","steps":["trace[1518153090] 'process raft request' (duration: 197.839885ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:43:16.434189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.293924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-kkx5h\" ","response":"range_response_count:1 size:20759"} {"level":"info","ts":"2026-10-16T00:43:16.434293Z","caller":"traceutil/trace.go:171","msg":"trace[1305166987] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-kkx5h; range_end:; response_count:1; response_revision:17960; }","duration":"187.471597ms","start":"2026-10-16T00:43:16.246789Z","end":"2026-10-16T00:43:16.434261Z","steps":["trace[1305166987] 'agreement among raft nodes before linearized reading' (duration: 187.193982ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:43:16.896964Z","caller":"traceutil/trace.go:171","msg":"trace[2141923948] linearizableReadLoop","detail":"{readStateIndex:18570; appliedIndex:18569; }","duration":"389.90473ms","start":"2026-10-16T00:43:16.507025Z","end":"2026-10-16T00:43:16.896929Z","steps":["trace[2141923948] 'read index received' (duration: 389.770337ms)","trace[2141923948] 'applied index is now lower than readState.Index' (duration: 133.323µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:43:16.897012Z","caller":"traceutil/trace.go:171","msg":"trace[85208583] transaction","detail":"{read_only:false; response_revision:17961; number_of_response:1; }","duration":"438.04457ms","start":"2026-10-16T00:43:16.458924Z","end":"2026-10-16T00:43:16.896969Z","steps":["trace[85208583] 'process raft request' (duration: 437.800476ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:43:16.897212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:43:16.458891Z","time spent":"438.225054ms","remote":"127.0.0.1:57372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20874,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T00:43:16.897974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.934209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2225402"} {"level":"info","ts":"2026-10-16T00:43:16.898032Z","caller":"traceutil/trace.go:171","msg":"trace[2124826183] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:17961; }","duration":"391.038322ms","start":"2026-10-16T00:43:16.506977Z","end":"2026-10-16T00:43:16.898016Z","steps":["trace[2124826183] 'agreement among raft nodes before linearized reading' (duration: 390.083253ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:43:16.898081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:43:16.506956Z","time spent":"391.110513ms","remote":"127.0.0.1:57282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":57,"response size":2225426,"request content":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" "} {"level":"warn","ts":"2026-10-16T00:43:16.98723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.463273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-16T00:43:16.987309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.729632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7fc7d5e3-fa88-48ee-809a-a1e04a7ecabc\" ","response":"range_response_count:1 size:3639"} {"level":"warn","ts":"2026-10-16T00:43:16.987312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.842051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-10-16T00:43:16.987362Z","caller":"traceutil/trace.go:171","msg":"trace[352414815] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7fc7d5e3-fa88-48ee-809a-a1e04a7ecabc; range_end:; response_count:1; response_revision:17962; }","duration":"476.819624ms","start":"2026-10-16T00:43:16.510527Z","end":"2026-10-16T00:43:16.987347Z","steps":["trace[352414815] 'agreement among raft nodes before linearized reading' (duration: 476.6495ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:43:16.987388Z","caller":"traceutil/trace.go:171","msg":"trace[509055276] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:17962; }","duration":"456.967023ms","start":"2026-10-16T00:43:16.530404Z","end":"2026-10-16T00:43:16.987371Z","steps":["trace[509055276] 'agreement among raft nodes before linearized reading' (duration: 456.655727ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:43:16.987398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:43:16.510511Z","time spent":"476.879885ms","remote":"127.0.0.1:57372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3663,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7fc7d5e3-fa88-48ee-809a-a1e04a7ecabc\" "} {"level":"warn","ts":"2026-10-16T00:43:16.987431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:43:16.530392Z","time spent":"457.029894ms","remote":"127.0.0.1:57268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":181,"response size":33,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"info","ts":"2026-10-16T00:43:16.987563Z","caller":"traceutil/trace.go:171","msg":"trace[2091576286] transaction","detail":"{read_only:false; response_revision:17962; number_of_response:1; }","duration":"471.93023ms","start":"2026-10-16T00:43:16.51561Z","end":"2026-10-16T00:43:16.98754Z","steps":["trace[2091576286] 'process raft request' (duration: 430.718271ms)","trace[2091576286] 'compare' (duration: 40.549706ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:43:16.987671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:43:16.515591Z","time spent":"472.027561ms","remote":"127.0.0.1:53180","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2228,"response count":0,"response size":42,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-16T00:43:16.987689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.979579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-16T00:43:16.987719Z","caller":"traceutil/trace.go:171","msg":"trace[1493280900] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:17962; }","duration":"254.053721ms","start":"2026-10-16T00:43:16.733657Z","end":"2026-10-16T00:43:16.987711Z","steps":["trace[1493280900] 'agreement among raft nodes before linearized reading' (duration: 253.937779ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:43:16.987339Z","caller":"traceutil/trace.go:171","msg":"trace[271972718] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:17962; }","duration":"365.633885ms","start":"2026-10-16T00:43:16.621676Z","end":"2026-10-16T00:43:16.98731Z","steps":["trace[271972718] 'agreement among raft nodes before linearized reading' (duration: 365.37593ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:43:16.988033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:43:16.621653Z","time spent":"366.36681ms","remote":"127.0.0.1:42100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":30,"request content":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true "} {"level":"info","ts":"2026-10-16T00:43:53.51035Z","caller":"traceutil/trace.go:171","msg":"trace[1452052589] transaction","detail":"{read_only:false; response_revision:18427; number_of_response:1; }","duration":"126.624872ms","start":"2026-10-16T00:43:53.383691Z","end":"2026-10-16T00:43:53.510316Z","steps":["trace[1452052589] 'process raft request' (duration: 118.844883ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:43:53.519467Z","caller":"traceutil/trace.go:171","msg":"trace[1069870196] transaction","detail":"{read_only:false; response_revision:18428; number_of_response:1; }","duration":"135.293188ms","start":"2026-10-16T00:43:53.384139Z","end":"2026-10-16T00:43:53.519432Z","steps":["trace[1069870196] 'process raft request' (duration: 135.102384ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:43:53.519632Z","caller":"traceutil/trace.go:171","msg":"trace[1773011640] linearizableReadLoop","detail":"{readStateIndex:19050; appliedIndex:19048; }","duration":"133.402942ms","start":"2026-10-16T00:43:53.38621Z","end":"2026-10-16T00:43:53.519613Z","steps":["trace[1773011640] 'read index received' (duration: 116.339556ms)","trace[1773011640] 'applied index is now lower than readState.Index' (duration: 17.062146ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:43:53.52009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.630021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-16T00:43:53.520165Z","caller":"traceutil/trace.go:171","msg":"trace[490530169] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18429; }","duration":"127.723653ms","start":"2026-10-16T00:43:53.392429Z","end":"2026-10-16T00:43:53.520153Z","steps":["trace[490530169] 'agreement among raft nodes before linearized reading' (duration: 127.53066ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:43:53.52049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.313499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-16T00:43:53.520534Z","caller":"traceutil/trace.go:171","msg":"trace[1134611556] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:18429; }","duration":"134.570814ms","start":"2026-10-16T00:43:53.38595Z","end":"2026-10-16T00:43:53.520521Z","steps":["trace[1134611556] 'agreement among raft nodes before linearized reading' (duration: 133.956123ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:45:56.456883Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"cb89ec34a9687f0b","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-16T00:45:56.463062Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-16T00:45:56.463159Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-16T00:47:11.036428Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17459} {"level":"info","ts":"2026-10-16T00:47:11.139107Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17459,"took":"98.855184ms","hash":1180367723,"current-db-size-bytes":56840192,"current-db-size":"57 MB","current-db-size-in-use-bytes":34267136,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-16T00:47:11.139192Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1180367723,"revision":17459,"compact-revision":15226} {"level":"info","ts":"2026-10-16T00:48:27.21673Z","caller":"traceutil/trace.go:171","msg":"trace[1052134168] transaction","detail":"{read_only:false; response_revision:20409; number_of_response:1; }","duration":"127.141943ms","start":"2026-10-16T00:48:27.089541Z","end":"2026-10-16T00:48:27.216683Z","steps":["trace[1052134168] 'process raft request' (duration: 126.906539ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:48:27.216919Z","caller":"traceutil/trace.go:171","msg":"trace[1780757389] transaction","detail":"{read_only:false; response_revision:20408; number_of_response:1; }","duration":"137.716023ms","start":"2026-10-16T00:48:27.07916Z","end":"2026-10-16T00:48:27.216876Z","steps":["trace[1780757389] 'process raft request' (duration: 136.233385ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:48:32.854695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.345498ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-16T00:48:32.854801Z","caller":"traceutil/trace.go:171","msg":"trace[1632878732] transaction","detail":"{read_only:false; response_revision:20435; number_of_response:1; }","duration":"175.28819ms","start":"2026-10-16T00:48:32.679494Z","end":"2026-10-16T00:48:32.854782Z","steps":["trace[1632878732] 'process raft request' (duration: 69.776489ms)","trace[1632878732] 'compare' (duration: 105.241226ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:48:49.271733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.816125ms","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:48:49.271844Z","caller":"traceutil/trace.go:171","msg":"trace[317444955] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20518; }","duration":"148.975688ms","start":"2026-10-16T00:48:49.122848Z","end":"2026-10-16T00:48:49.271824Z","steps":["trace[317444955] 'range keys from in-memory index tree' (duration: 148.57392ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:48:49.466857Z","caller":"traceutil/trace.go:171","msg":"trace[1701466631] transaction","detail":"{read_only:false; response_revision:20520; number_of_response:1; }","duration":"190.063271ms","start":"2026-10-16T00:48:49.27677Z","end":"2026-10-16T00:48:49.466833Z","steps":["trace[1701466631] 'process raft request' (duration: 94.753415ms)","trace[1701466631] 'compare' (duration: 94.935189ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:48:49.466957Z","caller":"traceutil/trace.go:171","msg":"trace[1974083012] linearizableReadLoop","detail":"{readStateIndex:21213; appliedIndex:21212; }","duration":"136.801756ms","start":"2026-10-16T00:48:49.330031Z","end":"2026-10-16T00:48:49.466833Z","steps":["trace[1974083012] 'read index received' (duration: 41.630273ms)","trace[1974083012] 'applied index is now lower than readState.Index' (duration: 95.168673ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:48:49.466957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.921568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-16T00:48:49.467039Z","caller":"traceutil/trace.go:171","msg":"trace[1990027738] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20521; }","duration":"137.053261ms","start":"2026-10-16T00:48:49.329965Z","end":"2026-10-16T00:48:49.467018Z","steps":["trace[1990027738] 'agreement among raft nodes before linearized reading' (duration: 136.872338ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:48:49.467182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.418206ms","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":"warn","ts":"2026-10-16T00:48:49.467191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.242075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-16T00:48:49.467228Z","caller":"traceutil/trace.go:171","msg":"trace[2023426555] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20521; }","duration":"119.507208ms","start":"2026-10-16T00:48:49.347706Z","end":"2026-10-16T00:48:49.467213Z","steps":["trace[2023426555] 'agreement among raft nodes before linearized reading' (duration: 119.343655ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:48:49.46723Z","caller":"traceutil/trace.go:171","msg":"trace[132751471] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20521; }","duration":"117.312616ms","start":"2026-10-16T00:48:49.349905Z","end":"2026-10-16T00:48:49.467218Z","steps":["trace[132751471] 'agreement among raft nodes before linearized reading' (duration: 117.201414ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:48:49.467365Z","caller":"traceutil/trace.go:171","msg":"trace[1065568681] transaction","detail":"{read_only:false; response_revision:20521; number_of_response:1; }","duration":"123.221128ms","start":"2026-10-16T00:48:49.343472Z","end":"2026-10-16T00:48:49.466693Z","steps":["trace[1065568681] 'process raft request' (duration: 123.161107ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:48:49.74131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.345159ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-16T00:48:49.741562Z","caller":"traceutil/trace.go:171","msg":"trace[964846619] transaction","detail":"{read_only:false; response_revision:20522; number_of_response:1; }","duration":"269.788721ms","start":"2026-10-16T00:48:49.471735Z","end":"2026-10-16T00:48:49.741524Z","steps":["trace[964846619] 'process raft request' (duration: 113.087295ms)","trace[964846619] 'compare' (duration: 156.157005ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:48:49.74162Z","caller":"traceutil/trace.go:171","msg":"trace[1192987254] transaction","detail":"{read_only:false; response_revision:20524; number_of_response:1; }","duration":"268.856403ms","start":"2026-10-16T00:48:49.472735Z","end":"2026-10-16T00:48:49.741591Z","steps":["trace[1192987254] 'process raft request' (duration: 268.789062ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:48:49.74177Z","caller":"traceutil/trace.go:171","msg":"trace[945221659] transaction","detail":"{read_only:false; response_revision:20523; number_of_response:1; }","duration":"269.717569ms","start":"2026-10-16T00:48:49.471891Z","end":"2026-10-16T00:48:49.741609Z","steps":["trace[945221659] 'process raft request' (duration: 269.550496ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:52:11.043816Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19894} {"level":"info","ts":"2026-10-16T00:52:11.173489Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19894,"took":"123.986023ms","hash":3395773033,"current-db-size-bytes":56840192,"current-db-size":"57 MB","current-db-size-in-use-bytes":30662656,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-16T00:52:11.173562Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3395773033,"revision":19894,"compact-revision":17459} {"level":"info","ts":"2026-10-16T00:52:44.879512Z","caller":"traceutil/trace.go:171","msg":"trace[221783685] transaction","detail":"{read_only:false; response_revision:22973; number_of_response:1; }","duration":"111.74028ms","start":"2026-10-16T00:52:44.767727Z","end":"2026-10-16T00:52:44.879467Z","steps":["trace[221783685] 'process raft request' (duration: 101.794499ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:52:45.201912Z","caller":"traceutil/trace.go:171","msg":"trace[775324094] linearizableReadLoop","detail":"{readStateIndex:23744; appliedIndex:23743; }","duration":"157.901059ms","start":"2026-10-16T00:52:45.043979Z","end":"2026-10-16T00:52:45.20188Z","steps":["trace[775324094] 'read index received' (duration: 155.593165ms)","trace[775324094] 'applied index is now lower than readState.Index' (duration: 2.305874ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:52:45.201966Z","caller":"traceutil/trace.go:171","msg":"trace[1843153909] transaction","detail":"{read_only:false; response_revision:22974; number_of_response:1; }","duration":"268.562358ms","start":"2026-10-16T00:52:44.933357Z","end":"2026-10-16T00:52:45.20192Z","steps":["trace[1843153909] 'process raft request' (duration: 266.337425ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:52:45.202441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.425759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-10-16T00:52:45.202534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.404812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-10-16T00:52:45.202565Z","caller":"traceutil/trace.go:171","msg":"trace[1275196699] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:22974; }","duration":"158.625492ms","start":"2026-10-16T00:52:45.043913Z","end":"2026-10-16T00:52:45.202539Z","steps":["trace[1275196699] 'agreement among raft nodes before linearized reading' (duration: 158.155083ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:52:45.202632Z","caller":"traceutil/trace.go:171","msg":"trace[1013277450] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:22974; }","duration":"111.538595ms","start":"2026-10-16T00:52:45.091064Z","end":"2026-10-16T00:52:45.202603Z","steps":["trace[1013277450] 'agreement among raft nodes before linearized reading' (duration: 111.277061ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:52:45.20253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.064272ms","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:52:45.202847Z","caller":"traceutil/trace.go:171","msg":"trace[1367491903] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:22974; }","duration":"125.4425ms","start":"2026-10-16T00:52:45.077377Z","end":"2026-10-16T00:52:45.202819Z","steps":["trace[1367491903] 'agreement among raft nodes before linearized reading' (duration: 124.85909ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:56:12.592925Z","caller":"traceutil/trace.go:171","msg":"trace[1145834051] linearizableReadLoop","detail":"{readStateIndex:25858; appliedIndex:25857; }","duration":"209.969444ms","start":"2026-10-16T00:56:12.382929Z","end":"2026-10-16T00:56:12.592898Z","steps":["trace[1145834051] 'read index received' (duration: 209.724899ms)","trace[1145834051] 'applied index is now lower than readState.Index' (duration: 243.535µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:56:12.593044Z","caller":"traceutil/trace.go:171","msg":"trace[1075189214] transaction","detail":"{read_only:false; response_revision:25038; number_of_response:1; }","duration":"225.085901ms","start":"2026-10-16T00:56:12.367946Z","end":"2026-10-16T00:56:12.593032Z","steps":["trace[1075189214] 'process raft request' (duration: 224.765095ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:56:12.771976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.011996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-16T00:56:12.772108Z","caller":"traceutil/trace.go:171","msg":"trace[1252669148] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25038; }","duration":"389.18051ms","start":"2026-10-16T00:56:12.382889Z","end":"2026-10-16T00:56:12.77207Z","steps":["trace[1252669148] 'agreement among raft nodes before linearized reading' (duration: 210.159147ms)","trace[1252669148] 'range keys from in-memory index tree' (duration: 178.706586ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:56:12.77217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:56:12.382862Z","time spent":"389.289141ms","remote":"127.0.0.1:57450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-16T00:56:12.772439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.21599ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-16T00:56:12.772682Z","caller":"traceutil/trace.go:171","msg":"trace[2056714505] linearizableReadLoop","detail":"{readStateIndex:25860; appliedIndex:25858; }","duration":"179.663524ms","start":"2026-10-16T00:56:12.593001Z","end":"2026-10-16T00:56:12.772665Z","steps":["trace[2056714505] 'read index received' (duration: 50.082907ms)","trace[2056714505] 'applied index is now lower than readState.Index' (duration: 129.579587ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:56:12.772718Z","caller":"traceutil/trace.go:171","msg":"trace[1795757297] transaction","detail":"{read_only:false; response_revision:25039; number_of_response:1; }","duration":"305.666933ms","start":"2026-10-16T00:56:12.467002Z","end":"2026-10-16T00:56:12.772669Z","steps":["trace[1795757297] 'process raft request' (duration: 176.083536ms)","trace[1795757297] 'compare' (duration: 129.006456ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:56:12.772791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.365465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T00:56:12.772791Z","caller":"traceutil/trace.go:171","msg":"trace[835820589] transaction","detail":"{read_only:false; response_revision:25040; number_of_response:1; }","duration":"290.101005ms","start":"2026-10-16T00:56:12.482664Z","end":"2026-10-16T00:56:12.772765Z","steps":["trace[835820589] 'process raft request' (duration: 289.873201ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:56:12.77282Z","caller":"traceutil/trace.go:171","msg":"trace[1037412300] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25040; }","duration":"386.433237ms","start":"2026-10-16T00:56:12.386377Z","end":"2026-10-16T00:56:12.772811Z","steps":["trace[1037412300] 'agreement among raft nodes before linearized reading' (duration: 386.338025ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:56:12.77289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.977041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-16T00:56:12.772949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:56:12.466976Z","time spent":"305.840546ms","remote":"127.0.0.1:57450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T00:56:12.772988Z","caller":"traceutil/trace.go:171","msg":"trace[2135089976] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:25040; }","duration":"214.141584ms","start":"2026-10-16T00:56:12.558824Z","end":"2026-10-16T00:56:12.772965Z","steps":["trace[2135089976] 'agreement among raft nodes before linearized reading' (duration: 213.963811ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:56:12.77293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.357925ms","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:56:12.773122Z","caller":"traceutil/trace.go:171","msg":"trace[1055850058] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25040; }","duration":"386.551779ms","start":"2026-10-16T00:56:12.38655Z","end":"2026-10-16T00:56:12.773102Z","steps":["trace[1055850058] 'agreement among raft nodes before linearized reading' (duration: 386.286354ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:56:12.773185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:56:12.386544Z","time spent":"386.622461ms","remote":"127.0.0.1:57450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":582,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-10-16T00:56:12.773137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:56:12.386355Z","time spent":"386.650461ms","remote":"127.0.0.1:57642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":30,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-10-16T00:56:13.040742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.880759ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-16T00:56:13.040934Z","caller":"traceutil/trace.go:171","msg":"trace[1839225166] transaction","detail":"{read_only:false; response_revision:25042; number_of_response:1; }","duration":"260.970109ms","start":"2026-10-16T00:56:12.779945Z","end":"2026-10-16T00:56:13.040915Z","steps":["trace[1839225166] 'process raft request' (duration: 260.896757ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:56:13.040953Z","caller":"traceutil/trace.go:171","msg":"trace[793992639] linearizableReadLoop","detail":"{readStateIndex:25861; appliedIndex:25860; }","duration":"261.777253ms","start":"2026-10-16T00:56:12.779142Z","end":"2026-10-16T00:56:13.040919Z","steps":["trace[793992639] 'read index received' (duration: 128.71966ms)","trace[793992639] 'applied index is now lower than readState.Index' (duration: 133.055973ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:56:13.041105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.951917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T00:56:13.041147Z","caller":"traceutil/trace.go:171","msg":"trace[1850086161] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25042; }","duration":"262.053199ms","start":"2026-10-16T00:56:12.779082Z","end":"2026-10-16T00:56:13.041135Z","steps":["trace[1850086161] 'agreement among raft nodes before linearized reading' (duration: 261.941417ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:56:13.041372Z","caller":"traceutil/trace.go:171","msg":"trace[1579065908] transaction","detail":"{read_only:false; response_revision:25041; number_of_response:1; }","duration":"262.918685ms","start":"2026-10-16T00:56:12.778435Z","end":"2026-10-16T00:56:13.041354Z","steps":["trace[1579065908] 'process raft request' (duration: 129.329532ms)","trace[1579065908] 'compare' (duration: 132.655075ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:56:13.365369Z","caller":"traceutil/trace.go:171","msg":"trace[1458781184] transaction","detail":"{read_only:false; response_revision:25043; number_of_response:1; }","duration":"202.704834ms","start":"2026-10-16T00:56:13.16259Z","end":"2026-10-16T00:56:13.365295Z","steps":["trace[1458781184] 'process raft request' (duration: 202.450899ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:56:13.367293Z","caller":"traceutil/trace.go:171","msg":"trace[1322145366] transaction","detail":"{read_only:false; response_revision:25044; number_of_response:1; }","duration":"201.282287ms","start":"2026-10-16T00:56:13.165979Z","end":"2026-10-16T00:56:13.367261Z","steps":["trace[1322145366] 'process raft request' (duration: 201.095194ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:57:11.051033Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22166} {"level":"info","ts":"2026-10-16T00:57:11.163715Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22166,"took":"107.829562ms","hash":2340867912,"current-db-size-bytes":56840192,"current-db-size":"57 MB","current-db-size-in-use-bytes":44802048,"current-db-size-in-use":"45 MB"} {"level":"info","ts":"2026-10-16T00:57:11.163781Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2340867912,"revision":22166,"compact-revision":19894} {"level":"info","ts":"2026-10-16T00:57:19.20985Z","caller":"traceutil/trace.go:171","msg":"trace[484244349] transaction","detail":"{read_only:false; response_revision:25629; number_of_response:1; }","duration":"245.473373ms","start":"2026-10-16T00:57:18.964343Z","end":"2026-10-16T00:57:19.209817Z","steps":["trace[484244349] 'process raft request' (duration: 245.282089ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:57:19.230941Z","caller":"traceutil/trace.go:171","msg":"trace[291427987] linearizableReadLoop","detail":"{readStateIndex:26467; appliedIndex:26464; }","duration":"171.248784ms","start":"2026-10-16T00:57:19.059675Z","end":"2026-10-16T00:57:19.230924Z","steps":["trace[291427987] 'read index received' (duration: 149.956217ms)","trace[291427987] 'applied index is now lower than readState.Index' (duration: 21.291467ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:57:19.231073Z","caller":"traceutil/trace.go:171","msg":"trace[285918880] transaction","detail":"{read_only:false; response_revision:25630; number_of_response:1; }","duration":"203.640022ms","start":"2026-10-16T00:57:19.027396Z","end":"2026-10-16T00:57:19.231036Z","steps":["trace[285918880] 'process raft request' (duration: 203.373597ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:57:19.231115Z","caller":"traceutil/trace.go:171","msg":"trace[323590610] transaction","detail":"{read_only:false; response_revision:25631; number_of_response:1; }","duration":"199.708347ms","start":"2026-10-16T00:57:19.031386Z","end":"2026-10-16T00:57:19.231095Z","steps":["trace[323590610] 'process raft request' (duration: 199.458392ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:57:19.231161Z","caller":"traceutil/trace.go:171","msg":"trace[1424275902] transaction","detail":"{read_only:false; response_revision:25632; number_of_response:1; }","duration":"120.995863ms","start":"2026-10-16T00:57:19.110132Z","end":"2026-10-16T00:57:19.231128Z","steps":["trace[1424275902] 'process raft request' (duration: 120.757108ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:57:19.231587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.896906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T00:57:19.231633Z","caller":"traceutil/trace.go:171","msg":"trace[1568536535] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25632; }","duration":"171.998329ms","start":"2026-10-16T00:57:19.059623Z","end":"2026-10-16T00:57:19.231622Z","steps":["trace[1568536535] 'agreement among raft nodes before linearized reading' (duration: 171.374296ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:57:19.624124Z","caller":"traceutil/trace.go:171","msg":"trace[2025277064] transaction","detail":"{read_only:false; response_revision:25634; number_of_response:1; }","duration":"172.08527ms","start":"2026-10-16T00:57:19.451997Z","end":"2026-10-16T00:57:19.624082Z","steps":["trace[2025277064] 'process raft request' (duration: 171.797234ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:57:35.146241Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000067da.wal"} {"level":"info","ts":"2026-10-16T00:58:15.973739Z","caller":"traceutil/trace.go:171","msg":"trace[1817429029] transaction","detail":"{read_only:false; response_revision:26220; number_of_response:1; }","duration":"114.392896ms","start":"2026-10-16T00:58:15.85932Z","end":"2026-10-16T00:58:15.973713Z","steps":["trace[1817429029] 'process raft request' (duration: 114.179502ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:58:15.973845Z","caller":"traceutil/trace.go:171","msg":"trace[1714815158] linearizableReadLoop","detail":"{readStateIndex:27071; appliedIndex:27068; }","duration":"102.789784ms","start":"2026-10-16T00:58:15.871031Z","end":"2026-10-16T00:58:15.973821Z","steps":["trace[1714815158] 'read index received' (duration: 62.890692ms)","trace[1714815158] 'applied index is now lower than readState.Index' (duration: 39.897242ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:58:15.973947Z","caller":"traceutil/trace.go:171","msg":"trace[304710989] transaction","detail":"{read_only:false; response_revision:26221; number_of_response:1; }","duration":"114.471048ms","start":"2026-10-16T00:58:15.859462Z","end":"2026-10-16T00:58:15.973933Z","steps":["trace[304710989] 'process raft request' (duration: 114.209443ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:58:15.974127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.05848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-10-16T00:58:15.97417Z","caller":"traceutil/trace.go:171","msg":"trace[1638558350] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26221; }","duration":"103.187053ms","start":"2026-10-16T00:58:15.870971Z","end":"2026-10-16T00:58:15.974158Z","steps":["trace[1638558350] 'agreement among raft nodes before linearized reading' (duration: 102.991809ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T01:02:11.060881Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25572} {"level":"info","ts":"2026-10-16T01:02:11.243661Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25572,"took":"177.275189ms","hash":2449990673,"current-db-size-bytes":57634816,"current-db-size":"58 MB","current-db-size-in-use-bytes":36098048,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-16T01:02:11.243761Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2449990673,"revision":25572,"compact-revision":22166} {"level":"info","ts":"2026-10-16T01:03:29.242543Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"cb89ec34a9687f0b","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-16T01:03:29.249506Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-16T01:03:29.249599Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-16T01:07:11.071965Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28507} {"level":"info","ts":"2026-10-16T01:07:11.224614Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28507,"took":"147.05897ms","hash":213525403,"current-db-size-bytes":57634816,"current-db-size":"58 MB","current-db-size-in-use-bytes":31510528,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-16T01:07:11.224685Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":213525403,"revision":28507,"compact-revision":25572} {"level":"info","ts":"2026-10-16T01:12:11.080345Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30764} {"level":"info","ts":"2026-10-16T01:12:11.204729Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30764,"took":"120.278615ms","hash":3761166850,"current-db-size-bytes":57634816,"current-db-size":"58 MB","current-db-size-in-use-bytes":31420416,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-16T01:12:11.204795Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3761166850,"revision":30764,"compact-revision":28507} {"level":"info","ts":"2026-10-16T01:17:11.087617Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33021} {"level":"info","ts":"2026-10-16T01:17:11.262713Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33021,"took":"170.251768ms","hash":3751887478,"current-db-size-bytes":57634816,"current-db-size":"58 MB","current-db-size-in-use-bytes":31105024,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-16T01:17:11.262846Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3751887478,"revision":33021,"compact-revision":30764} {"level":"info","ts":"2026-10-16T01:22:11.09482Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35319} {"level":"info","ts":"2026-10-16T01:22:11.234542Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35319,"took":"131.738154ms","hash":4151542664,"current-db-size-bytes":57634816,"current-db-size":"58 MB","current-db-size-in-use-bytes":33280000,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-16T01:22:11.234632Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4151542664,"revision":35319,"compact-revision":33021} {"level":"info","ts":"2026-10-16T01:24:56.09771Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"cb89ec34a9687f0b","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-16T01:24:56.104217Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-16T01:24:56.104311Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-16T01:27:11.102837Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37544} {"level":"info","ts":"2026-10-16T01:27:11.246327Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37544,"took":"135.706459ms","hash":410128105,"current-db-size-bytes":57634816,"current-db-size":"58 MB","current-db-size-in-use-bytes":32223232,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-16T01:27:11.246396Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":410128105,"revision":37544,"compact-revision":35319}