{"level":"warn","ts":"2026-09-19T00:54:42.658308Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-19T00:54:42.658407Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.52: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.52:2380","--initial-cluster=instance=https://162.253.55.52:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.52:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.52: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-09-19T00:54:42.659698Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-19T00:54:42.659718Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.52:2380"]} {"level":"info","ts":"2026-09-19T00:54:42.659745Z","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-09-19T00:54:42.661549Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.52:2379"]} {"level":"info","ts":"2026-09-19T00:54:42.661668Z","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":8,"max-cpu-available":8,"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.52:2380"],"listen-peer-urls":["https://162.253.55.52:2380"],"advertise-client-urls":["https://162.253.55.52:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.52:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.52: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-09-19T00:54:42.669673Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.810142ms"} {"level":"info","ts":"2026-09-19T00:54:42.680456Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b11e7fc5714be64b","cluster-id":"9349e4f32a52a90a"} {"level":"info","ts":"2026-09-19T00:54:42.680588Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b switched to configuration voters=()"} {"level":"info","ts":"2026-09-19T00:54:42.68062Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became follower at term 0"} {"level":"info","ts":"2026-09-19T00:54:42.680654Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b11e7fc5714be64b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-19T00:54:42.680688Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became follower at term 1"} {"level":"info","ts":"2026-09-19T00:54:42.680726Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b switched to configuration voters=(12762778880000648779)"} {"level":"warn","ts":"2026-09-19T00:54:42.706037Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-19T00:54:42.708524Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-19T00:54:42.709975Z","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-09-19T00:54:42.712259Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b11e7fc5714be64b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-19T00:54:42.712406Z","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-09-19T00:54:42.712508Z","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-09-19T00:54:42.712519Z","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-09-19T00:54:42.712772Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b11e7fc5714be64b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-19T00:54:42.726661Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.52:2380"} {"level":"info","ts":"2026-09-19T00:54:42.726593Z","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-09-19T00:54:42.728166Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.52:2380"} {"level":"info","ts":"2026-09-19T00:54:42.728218Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b switched to configuration voters=(12762778880000648779)"} {"level":"info","ts":"2026-09-19T00:54:42.728442Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9349e4f32a52a90a","local-member-id":"b11e7fc5714be64b","added-peer-id":"b11e7fc5714be64b","added-peer-peer-urls":["https://162.253.55.52:2380"]} {"level":"info","ts":"2026-09-19T00:54:42.72863Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b11e7fc5714be64b","initial-advertise-peer-urls":["https://162.253.55.52:2380"],"listen-peer-urls":["https://162.253.55.52:2380"],"advertise-client-urls":["https://162.253.55.52:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.52:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-19T00:54:42.728698Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-19T00:54:42.781227Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b is starting a new election at term 1"} {"level":"info","ts":"2026-09-19T00:54:42.781283Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became pre-candidate at term 1"} {"level":"info","ts":"2026-09-19T00:54:42.781314Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b received MsgPreVoteResp from b11e7fc5714be64b at term 1"} {"level":"info","ts":"2026-09-19T00:54:42.781326Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became candidate at term 2"} {"level":"info","ts":"2026-09-19T00:54:42.781333Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b received MsgVoteResp from b11e7fc5714be64b at term 2"} {"level":"info","ts":"2026-09-19T00:54:42.7815Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became leader at term 2"} {"level":"info","ts":"2026-09-19T00:54:42.781511Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b11e7fc5714be64b elected leader b11e7fc5714be64b at term 2"} {"level":"info","ts":"2026-09-19T00:54:42.792274Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T00:54:42.793341Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b11e7fc5714be64b","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.52:2379]}","request-path":"/0/members/b11e7fc5714be64b/attributes","cluster-id":"9349e4f32a52a90a","publish-timeout":"7s"} {"level":"info","ts":"2026-09-19T00:54:42.793548Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-19T00:54:42.793604Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-19T00:54:42.793942Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9349e4f32a52a90a","local-member-id":"b11e7fc5714be64b","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T00:54:42.794166Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T00:54:42.794119Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-19T00:54:42.794273Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-19T00:54:42.794422Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T00:54:42.796352Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.52:2379"} {"level":"info","ts":"2026-09-19T00:54:42.802167Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-19T00:55:41.37237Z","caller":"traceutil/trace.go:171","msg":"trace[1915492436] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"141.532788ms","start":"2026-09-19T00:55:41.23078Z","end":"2026-09-19T00:55:41.372313Z","steps":["trace[1915492436] 'process raft request' (duration: 140.325667ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:55:41.739917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.69449ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-19T00:55:41.740029Z","caller":"traceutil/trace.go:171","msg":"trace[1795074441] linearizableReadLoop","detail":"{readStateIndex:650; appliedIndex:649; }","duration":"241.542303ms","start":"2026-09-19T00:55:41.498475Z","end":"2026-09-19T00:55:41.740017Z","steps":["trace[1795074441] 'read index received' (duration: 75.16µs)","trace[1795074441] 'applied index is now lower than readState.Index' (duration: 241.466093ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T00:55:41.740121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.659104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-ml9zz\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-09-19T00:55:41.740142Z","caller":"traceutil/trace.go:171","msg":"trace[1724859525] range","detail":"{range_begin:/registry/pods/kube-system/cilium-ml9zz; range_end:; response_count:1; response_revision:628; }","duration":"241.729445ms","start":"2026-09-19T00:55:41.498407Z","end":"2026-09-19T00:55:41.740136Z","steps":["trace[1724859525] 'agreement among raft nodes before linearized reading' (duration: 241.660604ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:55:41.740109Z","caller":"traceutil/trace.go:171","msg":"trace[1740394291] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"295.118462ms","start":"2026-09-19T00:55:41.444957Z","end":"2026-09-19T00:55:41.740075Z","steps":["trace[1740394291] 'process raft request' (duration: 41.937416ms)","trace[1740394291] 'compare' (duration: 252.534399ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T00:55:42.22935Z","caller":"traceutil/trace.go:171","msg":"trace[1146123025] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"469.614099ms","start":"2026-09-19T00:55:41.759687Z","end":"2026-09-19T00:55:42.229301Z","steps":["trace[1146123025] 'process raft request' (duration: 407.478238ms)","trace[1146123025] 'compare' (duration: 61.881189ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T00:55:42.229748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:55:41.759665Z","time spent":"469.78148ms","remote":"127.0.0.1:40170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T00:56:53.223916Z","caller":"traceutil/trace.go:171","msg":"trace[380838056] transaction","detail":"{read_only:false; response_revision:1465; number_of_response:1; }","duration":"122.740403ms","start":"2026-09-19T00:56:53.101157Z","end":"2026-09-19T00:56:53.223898Z","steps":["trace[380838056] 'process raft request' (duration: 122.408641ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:57:21.186097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.637665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-19T00:57:21.186188Z","caller":"traceutil/trace.go:171","msg":"trace[237191477] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1886; }","duration":"251.78202ms","start":"2026-09-19T00:57:20.934388Z","end":"2026-09-19T00:57:21.18617Z","steps":["trace[237191477] 'count revisions from in-memory index tree' (duration: 251.53082ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:57:21.186236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.315666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e1428583-d7a7-4808-9e9b-8e2a6165e664\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-09-19T00:57:21.186331Z","caller":"traceutil/trace.go:171","msg":"trace[561921726] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e1428583-d7a7-4808-9e9b-8e2a6165e664; range_end:; response_count:1; response_revision:1886; }","duration":"162.488473ms","start":"2026-09-19T00:57:21.023821Z","end":"2026-09-19T00:57:21.186309Z","steps":["trace[561921726] 'range keys from in-memory index tree' (duration: 162.074717ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:57:21.824602Z","caller":"traceutil/trace.go:171","msg":"trace[1261203954] transaction","detail":"{read_only:false; response_revision:1893; number_of_response:1; }","duration":"233.130625ms","start":"2026-09-19T00:57:21.590894Z","end":"2026-09-19T00:57:21.824024Z","steps":["trace[1261203954] 'process raft request' (duration: 216.526215ms)","trace[1261203954] 'compare' (duration: 16.027468ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T00:57:34.26304Z","caller":"traceutil/trace.go:171","msg":"trace[749511662] transaction","detail":"{read_only:false; response_revision:2006; number_of_response:1; }","duration":"109.948517ms","start":"2026-09-19T00:57:34.153062Z","end":"2026-09-19T00:57:34.263011Z","steps":["trace[749511662] 'process raft request' (duration: 94.810225ms)","trace[749511662] 'compare' (duration: 14.896023ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T00:57:37.613205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.538409ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T00:57:37.613441Z","caller":"traceutil/trace.go:171","msg":"trace[666174171] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2023; }","duration":"119.802809ms","start":"2026-09-19T00:57:37.493604Z","end":"2026-09-19T00:57:37.613406Z","steps":["trace[666174171] 'range keys from in-memory index tree' (duration: 119.508008ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:58:53.33439Z","caller":"traceutil/trace.go:171","msg":"trace[206823503] transaction","detail":"{read_only:false; response_revision:2538; number_of_response:1; }","duration":"253.620846ms","start":"2026-09-19T00:58:53.080748Z","end":"2026-09-19T00:58:53.334368Z","steps":["trace[206823503] 'process raft request' (duration: 240.750606ms)","trace[206823503] 'compare' (duration: 12.600424ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T00:59:19.029528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.328379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-09-19T00:59:19.029645Z","caller":"traceutil/trace.go:171","msg":"trace[1145795836] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2681; }","duration":"147.499333ms","start":"2026-09-19T00:59:18.882119Z","end":"2026-09-19T00:59:19.029618Z","steps":["trace[1145795836] 'range keys from in-memory index tree' (duration: 147.097324ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:59:19.029644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.898285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1366"} {"level":"info","ts":"2026-09-19T00:59:19.029703Z","caller":"traceutil/trace.go:171","msg":"trace[1041697352] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2681; }","duration":"113.006127ms","start":"2026-09-19T00:59:18.916682Z","end":"2026-09-19T00:59:19.029688Z","steps":["trace[1041697352] 'range keys from in-memory index tree' (duration: 112.759672ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:59:24.303115Z","caller":"traceutil/trace.go:171","msg":"trace[522172687] transaction","detail":"{read_only:false; response_revision:2708; number_of_response:1; }","duration":"120.04571ms","start":"2026-09-19T00:59:24.183042Z","end":"2026-09-19T00:59:24.303087Z","steps":["trace[522172687] 'process raft request' (duration: 119.573669ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:59:24.429537Z","caller":"traceutil/trace.go:171","msg":"trace[1475331382] transaction","detail":"{read_only:false; response_revision:2709; number_of_response:1; }","duration":"120.468449ms","start":"2026-09-19T00:59:24.309038Z","end":"2026-09-19T00:59:24.429507Z","steps":["trace[1475331382] 'process raft request' (duration: 120.258215ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:59:24.430048Z","caller":"traceutil/trace.go:171","msg":"trace[1036632430] linearizableReadLoop","detail":"{readStateIndex:2811; appliedIndex:2811; }","duration":"118.661241ms","start":"2026-09-19T00:59:24.31135Z","end":"2026-09-19T00:59:24.430011Z","steps":["trace[1036632430] 'read index received' (duration: 118.649391ms)","trace[1036632430] 'applied index is now lower than readState.Index' (duration: 9.17µs)"],"step_count":2} {"level":"warn","ts":"2026-09-19T00:59:24.43042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.983547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-09-19T00:59:24.430528Z","caller":"traceutil/trace.go:171","msg":"trace[1777667757] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2709; }","duration":"119.189601ms","start":"2026-09-19T00:59:24.311318Z","end":"2026-09-19T00:59:24.430507Z","steps":["trace[1777667757] 'agreement among raft nodes before linearized reading' (duration: 118.821433ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:59:30.118542Z","caller":"traceutil/trace.go:171","msg":"trace[1483213666] transaction","detail":"{read_only:false; response_revision:2737; number_of_response:1; }","duration":"267.921365ms","start":"2026-09-19T00:59:29.850582Z","end":"2026-09-19T00:59:30.118504Z","steps":["trace[1483213666] 'process raft request' (duration: 267.463626ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:59:30.143332Z","caller":"traceutil/trace.go:171","msg":"trace[1614447537] linearizableReadLoop","detail":"{readStateIndex:2841; appliedIndex:2839; }","duration":"116.717961ms","start":"2026-09-19T00:59:30.026587Z","end":"2026-09-19T00:59:30.143305Z","steps":["trace[1614447537] 'read index received' (duration: 91.541613ms)","trace[1614447537] 'applied index is now lower than readState.Index' (duration: 25.174948ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T00:59:30.14345Z","caller":"traceutil/trace.go:171","msg":"trace[1081855217] transaction","detail":"{read_only:false; response_revision:2738; number_of_response:1; }","duration":"210.856645ms","start":"2026-09-19T00:59:29.932556Z","end":"2026-09-19T00:59:30.143413Z","steps":["trace[1081855217] 'process raft request' (duration: 201.553624ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:59:30.143621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.003438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"} {"level":"info","ts":"2026-09-19T00:59:30.143693Z","caller":"traceutil/trace.go:171","msg":"trace[1491523650] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2738; }","duration":"117.12955ms","start":"2026-09-19T00:59:30.026516Z","end":"2026-09-19T00:59:30.143645Z","steps":["trace[1491523650] 'agreement among raft nodes before linearized reading' (duration: 116.883795ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:04:25.398294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.36465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:04:25.398388Z","caller":"traceutil/trace.go:171","msg":"trace[343974790] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4969; }","duration":"116.500971ms","start":"2026-09-19T01:04:25.28187Z","end":"2026-09-19T01:04:25.398371Z","steps":["trace[343974790] 'range keys from in-memory index tree' (duration: 116.281018ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:04:32.440399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.246229ms","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-09-19T01:04:32.440479Z","caller":"traceutil/trace.go:171","msg":"trace[1140583442] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5019; }","duration":"278.368591ms","start":"2026-09-19T01:04:32.162092Z","end":"2026-09-19T01:04:32.440461Z","steps":["trace[1140583442] 'range keys from in-memory index tree' (duration: 278.093887ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:04:32.440792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.565428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12973"} {"level":"info","ts":"2026-09-19T01:04:32.440832Z","caller":"traceutil/trace.go:171","msg":"trace[925316985] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5019; }","duration":"258.644188ms","start":"2026-09-19T01:04:32.182176Z","end":"2026-09-19T01:04:32.44082Z","steps":["trace[925316985] 'range keys from in-memory index tree' (duration: 258.447666ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:04:32.441006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.96475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-666d6cb894-vmjct.18d6934f67351aed\" ","response":"range_response_count:1 size:898"} {"level":"info","ts":"2026-09-19T01:04:32.44104Z","caller":"traceutil/trace.go:171","msg":"trace[1119587686] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-666d6cb894-vmjct.18d6934f67351aed; range_end:; response_count:1; response_revision:5019; }","duration":"258.02192ms","start":"2026-09-19T01:04:32.183009Z","end":"2026-09-19T01:04:32.441031Z","steps":["trace[1119587686] 'range keys from in-memory index tree' (duration: 257.873109ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:04:32.441202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.620448ms","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-09-19T01:04:32.441234Z","caller":"traceutil/trace.go:171","msg":"trace[1801926578] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5019; }","duration":"243.671749ms","start":"2026-09-19T01:04:32.197554Z","end":"2026-09-19T01:04:32.441226Z","steps":["trace[1801926578] 'range keys from in-memory index tree' (duration: 243.545028ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:04:32.441459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.307914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:04:32.441495Z","caller":"traceutil/trace.go:171","msg":"trace[1593872202] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:5019; }","duration":"196.374515ms","start":"2026-09-19T01:04:32.245111Z","end":"2026-09-19T01:04:32.441485Z","steps":["trace[1593872202] 'count revisions from in-memory index tree' (duration: 196.244414ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:04:32.441614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.194279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-19T01:04:32.441645Z","caller":"traceutil/trace.go:171","msg":"trace[1057121580] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:5019; }","duration":"192.245509ms","start":"2026-09-19T01:04:32.249392Z","end":"2026-09-19T01:04:32.441637Z","steps":["trace[1057121580] 'count revisions from in-memory index tree' (duration: 192.145848ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:04:32.441827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.686054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-09-19T01:04:32.441863Z","caller":"traceutil/trace.go:171","msg":"trace[1645785204] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5019; }","duration":"185.747225ms","start":"2026-09-19T01:04:32.256106Z","end":"2026-09-19T01:04:32.441853Z","steps":["trace[1645785204] 'range keys from in-memory index tree' (duration: 185.585224ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:04:32.442018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.769831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-09-19T01:04:32.442048Z","caller":"traceutil/trace.go:171","msg":"trace[776265995] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5019; }","duration":"166.819441ms","start":"2026-09-19T01:04:32.275221Z","end":"2026-09-19T01:04:32.44204Z","steps":["trace[776265995] 'range keys from in-memory index tree' (duration: 166.68235ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:04:32.442195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.371775ms","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-09-19T01:04:32.442223Z","caller":"traceutil/trace.go:171","msg":"trace[72582806] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5019; }","duration":"165.433526ms","start":"2026-09-19T01:04:32.276782Z","end":"2026-09-19T01:04:32.442216Z","steps":["trace[72582806] 'range keys from in-memory index tree' (duration: 164.80972ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:04:32.44233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.985242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:04:32.442361Z","caller":"traceutil/trace.go:171","msg":"trace[1412166272] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5019; }","duration":"158.033433ms","start":"2026-09-19T01:04:32.284318Z","end":"2026-09-19T01:04:32.442351Z","steps":["trace[1412166272] 'range keys from in-memory index tree' (duration: 157.943572ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:04:32.564627Z","caller":"traceutil/trace.go:171","msg":"trace[812462554] transaction","detail":"{read_only:false; response_revision:5025; number_of_response:1; }","duration":"104.336467ms","start":"2026-09-19T01:04:32.460266Z","end":"2026-09-19T01:04:32.564603Z","steps":["trace[812462554] 'process raft request' (duration: 104.271667ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:04:32.564989Z","caller":"traceutil/trace.go:171","msg":"trace[1258477319] transaction","detail":"{read_only:false; response_revision:5024; number_of_response:1; }","duration":"106.078296ms","start":"2026-09-19T01:04:32.458899Z","end":"2026-09-19T01:04:32.564977Z","steps":["trace[1258477319] 'process raft request' (duration: 105.604271ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:04:32.564979Z","caller":"traceutil/trace.go:171","msg":"trace[706020808] transaction","detail":"{read_only:false; response_revision:5023; number_of_response:1; }","duration":"114.35063ms","start":"2026-09-19T01:04:32.450591Z","end":"2026-09-19T01:04:32.564941Z","steps":["trace[706020808] 'process raft request' (duration: 113.877525ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:04:32.565413Z","caller":"traceutil/trace.go:171","msg":"trace[1411988573] transaction","detail":"{read_only:false; response_revision:5021; number_of_response:1; }","duration":"115.552424ms","start":"2026-09-19T01:04:32.449817Z","end":"2026-09-19T01:04:32.56537Z","steps":["trace[1411988573] 'process raft request' (duration: 104.176386ms)","trace[1411988573] 'compare' (duration: 10.318716ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:04:32.56563Z","caller":"traceutil/trace.go:171","msg":"trace[1091402049] transaction","detail":"{read_only:false; response_revision:5022; number_of_response:1; }","duration":"115.765775ms","start":"2026-09-19T01:04:32.449851Z","end":"2026-09-19T01:04:32.565617Z","steps":["trace[1091402049] 'process raft request' (duration: 114.575882ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:04:32.890263Z","caller":"traceutil/trace.go:171","msg":"trace[48473398] transaction","detail":"{read_only:false; response_revision:5032; number_of_response:1; }","duration":"183.684201ms","start":"2026-09-19T01:04:32.706555Z","end":"2026-09-19T01:04:32.890239Z","steps":["trace[48473398] 'process raft request' (duration: 183.52551ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:04:35.306017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.661547ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-19T01:04:35.306223Z","caller":"traceutil/trace.go:171","msg":"trace[1054835569] transaction","detail":"{read_only:false; response_revision:5109; number_of_response:1; }","duration":"175.973371ms","start":"2026-09-19T01:04:35.130226Z","end":"2026-09-19T01:04:35.3062Z","steps":["trace[1054835569] 'process raft request' (duration: 72.041312ms)","trace[1054835569] 'compare' (duration: 103.211051ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:04:37.503973Z","caller":"traceutil/trace.go:171","msg":"trace[1916117963] linearizableReadLoop","detail":"{readStateIndex:5332; appliedIndex:5331; }","duration":"114.70615ms","start":"2026-09-19T01:04:37.389243Z","end":"2026-09-19T01:04:37.503949Z","steps":["trace[1916117963] 'read index received' (duration: 114.537428ms)","trace[1916117963] 'applied index is now lower than readState.Index' (duration: 167.702µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:04:37.504188Z","caller":"traceutil/trace.go:171","msg":"trace[31091370] transaction","detail":"{read_only:false; response_revision:5137; number_of_response:1; }","duration":"154.683089ms","start":"2026-09-19T01:04:37.349446Z","end":"2026-09-19T01:04:37.504129Z","steps":["trace[31091370] 'process raft request' (duration: 154.347435ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:04:37.504297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.039213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6f32b282-0086-41cd-bc73-94d21cb8396e\" ","response":"range_response_count:1 size:3951"} {"level":"info","ts":"2026-09-19T01:04:37.504359Z","caller":"traceutil/trace.go:171","msg":"trace[2126848657] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-6f32b282-0086-41cd-bc73-94d21cb8396e; range_end:; response_count:1; response_revision:5137; }","duration":"115.148865ms","start":"2026-09-19T01:04:37.389196Z","end":"2026-09-19T01:04:37.504345Z","steps":["trace[2126848657] 'agreement among raft nodes before linearized reading' (duration: 114.952113ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:04:43.15922Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2810} {"level":"info","ts":"2026-09-19T01:04:43.432911Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2810,"took":"272.25848ms","hash":1212164316,"current-db-size-bytes":51437568,"current-db-size":"51 MB","current-db-size-in-use-bytes":27688960,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-19T01:04:43.43303Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1212164316,"revision":2810,"compact-revision":-1} {"level":"info","ts":"2026-09-19T01:04:43.570174Z","caller":"traceutil/trace.go:171","msg":"trace[1754339498] transaction","detail":"{read_only:false; response_revision:5237; number_of_response:1; }","duration":"113.987758ms","start":"2026-09-19T01:04:43.456165Z","end":"2026-09-19T01:04:43.570153Z","steps":["trace[1754339498] 'process raft request' (duration: 113.851286ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:04:43.580987Z","caller":"traceutil/trace.go:171","msg":"trace[1001490235] transaction","detail":"{read_only:false; response_revision:5238; number_of_response:1; }","duration":"124.707366ms","start":"2026-09-19T01:04:43.456226Z","end":"2026-09-19T01:04:43.580933Z","steps":["trace[1001490235] 'process raft request' (duration: 124.491044ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:04:43.581283Z","caller":"traceutil/trace.go:171","msg":"trace[432851256] transaction","detail":"{read_only:false; response_revision:5239; number_of_response:1; }","duration":"124.9462ms","start":"2026-09-19T01:04:43.456308Z","end":"2026-09-19T01:04:43.581254Z","steps":["trace[432851256] 'process raft request' (duration: 124.524174ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:04:43.581066Z","caller":"traceutil/trace.go:171","msg":"trace[1257309785] linearizableReadLoop","detail":"{readStateIndex:5439; appliedIndex:5436; }","duration":"124.667926ms","start":"2026-09-19T01:04:43.456379Z","end":"2026-09-19T01:04:43.581047Z","steps":["trace[1257309785] 'read index received' (duration: 113.775475ms)","trace[1257309785] 'applied index is now lower than readState.Index' (duration: 10.891181ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:04:43.581131Z","caller":"traceutil/trace.go:171","msg":"trace[204841681] transaction","detail":"{read_only:false; response_revision:5241; number_of_response:1; }","duration":"120.408108ms","start":"2026-09-19T01:04:43.460702Z","end":"2026-09-19T01:04:43.58111Z","steps":["trace[204841681] 'process raft request' (duration: 120.277066ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:04:43.581774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.89209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:04:43.58182Z","caller":"traceutil/trace.go:171","msg":"trace[278744510] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5241; }","duration":"100.966691ms","start":"2026-09-19T01:04:43.480841Z","end":"2026-09-19T01:04:43.581808Z","steps":["trace[278744510] 'agreement among raft nodes before linearized reading' (duration: 100.87704ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:04:43.581104Z","caller":"traceutil/trace.go:171","msg":"trace[942870040] transaction","detail":"{read_only:false; response_revision:5240; number_of_response:1; }","duration":"124.772168ms","start":"2026-09-19T01:04:43.456308Z","end":"2026-09-19T01:04:43.58108Z","steps":["trace[942870040] 'process raft request' (duration: 124.597405ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:04:43.581258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.855489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls\" ","response":"range_response_count:1 size:2319"} {"level":"info","ts":"2026-09-19T01:04:43.582119Z","caller":"traceutil/trace.go:171","msg":"trace[9954703] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls; range_end:; response_count:1; response_revision:5241; }","duration":"125.715338ms","start":"2026-09-19T01:04:43.456375Z","end":"2026-09-19T01:04:43.58209Z","steps":["trace[9954703] 'agreement among raft nodes before linearized reading' (duration: 124.725947ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:04:47.522522Z","caller":"traceutil/trace.go:171","msg":"trace[2107778794] transaction","detail":"{read_only:false; response_revision:5271; number_of_response:1; }","duration":"171.546486ms","start":"2026-09-19T01:04:47.350963Z","end":"2026-09-19T01:04:47.522509Z","steps":["trace[2107778794] 'process raft request' (duration: 171.332294ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:04:47.522599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.02094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:04:47.522637Z","caller":"traceutil/trace.go:171","msg":"trace[546635486] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:5271; }","duration":"152.09633ms","start":"2026-09-19T01:04:47.370529Z","end":"2026-09-19T01:04:47.522626Z","steps":["trace[546635486] 'agreement among raft nodes before linearized reading' (duration: 152.01741ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:04:47.522477Z","caller":"traceutil/trace.go:171","msg":"trace[1902920084] linearizableReadLoop","detail":"{readStateIndex:5471; appliedIndex:5470; }","duration":"151.899937ms","start":"2026-09-19T01:04:47.370554Z","end":"2026-09-19T01:04:47.522454Z","steps":["trace[1902920084] 'read index received' (duration: 151.723695ms)","trace[1902920084] 'applied index is now lower than readState.Index' (duration: 175.422µs)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:04:47.523113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.366933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-09-19T01:04:47.523227Z","caller":"traceutil/trace.go:171","msg":"trace[2046049088] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5271; }","duration":"135.537565ms","start":"2026-09-19T01:04:47.387653Z","end":"2026-09-19T01:04:47.523191Z","steps":["trace[2046049088] 'agreement among raft nodes before linearized reading' (duration: 135.189351ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:04:47.710215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.271898ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T01:04:47.710322Z","caller":"traceutil/trace.go:171","msg":"trace[1172848323] transaction","detail":"{read_only:false; response_revision:5272; number_of_response:1; }","duration":"179.74993ms","start":"2026-09-19T01:04:47.530554Z","end":"2026-09-19T01:04:47.710304Z","steps":["trace[1172848323] 'process raft request' (duration: 39.294929ms)","trace[1172848323] 'compare' (duration: 139.932804ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:04:47.728626Z","caller":"traceutil/trace.go:171","msg":"trace[1835196465] transaction","detail":"{read_only:false; response_revision:5273; number_of_response:1; }","duration":"197.342825ms","start":"2026-09-19T01:04:47.531263Z","end":"2026-09-19T01:04:47.728606Z","steps":["trace[1835196465] 'process raft request' (duration: 197.176823ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:04:47.729043Z","caller":"traceutil/trace.go:171","msg":"trace[459623593] transaction","detail":"{read_only:false; response_revision:5274; number_of_response:1; }","duration":"196.637937ms","start":"2026-09-19T01:04:47.532363Z","end":"2026-09-19T01:04:47.729001Z","steps":["trace[459623593] 'process raft request' (duration: 196.205312ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:04:47.729061Z","caller":"traceutil/trace.go:171","msg":"trace[175340022] linearizableReadLoop","detail":"{readStateIndex:5474; appliedIndex:5471; }","duration":"195.963249ms","start":"2026-09-19T01:04:47.533066Z","end":"2026-09-19T01:04:47.729029Z","steps":["trace[175340022] 'read index received' (duration: 36.78554ms)","trace[175340022] 'applied index is now lower than readState.Index' (duration: 159.176289ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:04:47.729351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.253123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-6f32b282-0086-41cd-bc73-94d21cb8396e\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-09-19T01:04:47.729392Z","caller":"traceutil/trace.go:171","msg":"trace[1725612149] range","detail":"{range_begin:/registry/persistentvolumes/pvc-6f32b282-0086-41cd-bc73-94d21cb8396e; range_end:; response_count:1; response_revision:5275; }","duration":"196.364054ms","start":"2026-09-19T01:04:47.533016Z","end":"2026-09-19T01:04:47.72938Z","steps":["trace[1725612149] 'agreement among raft nodes before linearized reading' (duration: 196.163152ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:04:47.729564Z","caller":"traceutil/trace.go:171","msg":"trace[1635443930] transaction","detail":"{read_only:false; response_revision:5275; number_of_response:1; }","duration":"193.938057ms","start":"2026-09-19T01:04:47.535213Z","end":"2026-09-19T01:04:47.729151Z","steps":["trace[1635443930] 'process raft request' (duration: 193.661254ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:09:43.167052Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5234} {"level":"info","ts":"2026-09-19T01:09:43.260455Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5234,"took":"92.147581ms","hash":2302350638,"current-db-size-bytes":51437568,"current-db-size":"51 MB","current-db-size-in-use-bytes":18907136,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-19T01:09:43.260525Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2302350638,"revision":5234,"compact-revision":2810}