{"level":"warn","ts":"2026-09-01T18:29:30.055588Z","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-01T18:29:30.055716Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.14: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.14:2380","--initial-cluster=instance=https://162.253.55.14:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.14:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.14: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-01T18:29:30.055814Z","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-01T18:29:30.055833Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.14:2380"]} {"level":"info","ts":"2026-09-01T18:29:30.055863Z","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-01T18:29:30.056944Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.14:2379"]} {"level":"info","ts":"2026-09-01T18:29:30.057066Z","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.14:2380"],"listen-peer-urls":["https://162.253.55.14:2380"],"advertise-client-urls":["https://162.253.55.14:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.14:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.14: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-01T18:29:30.061187Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.760879ms"} {"level":"info","ts":"2026-09-01T18:29:30.073772Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5db0a1b394e67b72","cluster-id":"cfda6ccfe21bda40"} {"level":"info","ts":"2026-09-01T18:29:30.073875Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5db0a1b394e67b72 switched to configuration voters=()"} {"level":"info","ts":"2026-09-01T18:29:30.073904Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5db0a1b394e67b72 became follower at term 0"} {"level":"info","ts":"2026-09-01T18:29:30.073924Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5db0a1b394e67b72 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-01T18:29:30.073936Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5db0a1b394e67b72 became follower at term 1"} {"level":"info","ts":"2026-09-01T18:29:30.073985Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5db0a1b394e67b72 switched to configuration voters=(6751073634097724274)"} {"level":"warn","ts":"2026-09-01T18:29:30.087258Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-01T18:29:30.089175Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-01T18:29:30.090672Z","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-01T18:29:30.093382Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5db0a1b394e67b72","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-01T18:29:30.093606Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5db0a1b394e67b72","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-01T18:29:30.093591Z","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-01T18:29:30.093843Z","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-01T18:29:30.093884Z","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-01T18:29:30.095808Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5db0a1b394e67b72 switched to configuration voters=(6751073634097724274)"} {"level":"info","ts":"2026-09-01T18:29:30.09603Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cfda6ccfe21bda40","local-member-id":"5db0a1b394e67b72","added-peer-id":"5db0a1b394e67b72","added-peer-peer-urls":["https://162.253.55.14:2380"]} {"level":"info","ts":"2026-09-01T18:29:30.102942Z","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-01T18:29:30.103144Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5db0a1b394e67b72","initial-advertise-peer-urls":["https://162.253.55.14:2380"],"listen-peer-urls":["https://162.253.55.14:2380"],"advertise-client-urls":["https://162.253.55.14:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.14:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-01T18:29:30.103172Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-01T18:29:30.103165Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.14:2380"} {"level":"info","ts":"2026-09-01T18:29:30.103255Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.14:2380"} {"level":"info","ts":"2026-09-01T18:29:30.675094Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5db0a1b394e67b72 is starting a new election at term 1"} {"level":"info","ts":"2026-09-01T18:29:30.675261Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5db0a1b394e67b72 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-01T18:29:30.675299Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5db0a1b394e67b72 received MsgPreVoteResp from 5db0a1b394e67b72 at term 1"} {"level":"info","ts":"2026-09-01T18:29:30.67533Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5db0a1b394e67b72 became candidate at term 2"} {"level":"info","ts":"2026-09-01T18:29:30.675351Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5db0a1b394e67b72 received MsgVoteResp from 5db0a1b394e67b72 at term 2"} {"level":"info","ts":"2026-09-01T18:29:30.675373Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5db0a1b394e67b72 became leader at term 2"} {"level":"info","ts":"2026-09-01T18:29:30.675397Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5db0a1b394e67b72 elected leader 5db0a1b394e67b72 at term 2"} {"level":"info","ts":"2026-09-01T18:29:30.676663Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5db0a1b394e67b72","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.14:2379]}","request-path":"/0/members/5db0a1b394e67b72/attributes","cluster-id":"cfda6ccfe21bda40","publish-timeout":"7s"} {"level":"info","ts":"2026-09-01T18:29:30.676678Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-01T18:29:30.676688Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-01T18:29:30.676875Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-01T18:29:30.677027Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-01T18:29:30.677076Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-01T18:29:30.677699Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cfda6ccfe21bda40","local-member-id":"5db0a1b394e67b72","cluster-version":"3.5"} {"level":"info","ts":"2026-09-01T18:29:30.677785Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-01T18:29:30.677811Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-01T18:29:30.679989Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-01T18:29:30.680144Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.14:2379"} {"level":"warn","ts":"2026-09-01T18:30:14.187308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.667659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-4qkk7\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-09-01T18:30:14.187442Z","caller":"traceutil/trace.go:171","msg":"trace[1209161509] range","detail":"{range_begin:/registry/pods/kube-system/cilium-4qkk7; range_end:; response_count:1; response_revision:632; }","duration":"178.818412ms","start":"2026-09-01T18:30:14.0086Z","end":"2026-09-01T18:30:14.187419Z","steps":["trace[1209161509] 'range keys from in-memory index tree' (duration: 178.439885ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:30:14.187291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.673355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.14\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-09-01T18:30:14.187587Z","caller":"traceutil/trace.go:171","msg":"trace[1782463916] range","detail":"{range_begin:/registry/masterleases/162.253.55.14; range_end:; response_count:1; response_revision:632; }","duration":"197.025082ms","start":"2026-09-01T18:30:13.990535Z","end":"2026-09-01T18:30:14.18756Z","steps":["trace[1782463916] 'range keys from in-memory index tree' (duration: 196.47776ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:30:14.188783Z","caller":"traceutil/trace.go:171","msg":"trace[1768056705] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"142.583358ms","start":"2026-09-01T18:30:14.046161Z","end":"2026-09-01T18:30:14.188744Z","steps":["trace[1768056705] 'process raft request' (duration: 141.461094ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:31:09.920309Z","caller":"traceutil/trace.go:171","msg":"trace[1041020694] linearizableReadLoop","detail":"{readStateIndex:1268; appliedIndex:1267; }","duration":"140.998648ms","start":"2026-09-01T18:31:09.779265Z","end":"2026-09-01T18:31:09.920263Z","steps":["trace[1041020694] 'read index received' (duration: 132.407064ms)","trace[1041020694] 'applied index is now lower than readState.Index' (duration: 8.589864ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:31:09.920374Z","caller":"traceutil/trace.go:171","msg":"trace[67777527] transaction","detail":"{read_only:false; response_revision:1230; number_of_response:1; }","duration":"142.774616ms","start":"2026-09-01T18:31:09.777563Z","end":"2026-09-01T18:31:09.920338Z","steps":["trace[67777527] 'process raft request' (duration: 134.196612ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:31:09.920631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.338155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-01T18:31:09.920716Z","caller":"traceutil/trace.go:171","msg":"trace[387287382] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1230; }","duration":"141.467728ms","start":"2026-09-01T18:31:09.77923Z","end":"2026-09-01T18:31:09.920698Z","steps":["trace[387287382] 'agreement among raft nodes before linearized reading' (duration: 141.187932ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:31:56.242711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.06036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-09-01T18:31:56.242804Z","caller":"traceutil/trace.go:171","msg":"trace[2030835739] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1890; }","duration":"135.196433ms","start":"2026-09-01T18:31:56.107586Z","end":"2026-09-01T18:31:56.242783Z","steps":["trace[2030835739] 'range keys from in-memory index tree' (duration: 134.866556ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:31:56.243188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.291182ms","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-01T18:31:56.243269Z","caller":"traceutil/trace.go:171","msg":"trace[177119670] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1890; }","duration":"104.415085ms","start":"2026-09-01T18:31:56.13884Z","end":"2026-09-01T18:31:56.243255Z","steps":["trace[177119670] 'range keys from in-memory index tree' (duration: 104.143569ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:31:56.682976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.861413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T18:31:56.68316Z","caller":"traceutil/trace.go:171","msg":"trace[1760625356] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1893; }","duration":"140.096328ms","start":"2026-09-01T18:31:56.543041Z","end":"2026-09-01T18:31:56.683137Z","steps":["trace[1760625356] 'range keys from in-memory index tree' (duration: 139.75398ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:31:56.683155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.418337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-da877070-8098-409c-bede-7ad3febcf182\" ","response":"range_response_count:1 size:3626"} {"level":"warn","ts":"2026-09-01T18:31:56.683199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.67431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-da877070-8098-409c-bede-7ad3febcf182\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-09-01T18:31:56.683274Z","caller":"traceutil/trace.go:171","msg":"trace[33059258] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-da877070-8098-409c-bede-7ad3febcf182; range_end:; response_count:1; response_revision:1893; }","duration":"277.587552ms","start":"2026-09-01T18:31:56.405666Z","end":"2026-09-01T18:31:56.683253Z","steps":["trace[33059258] 'range keys from in-memory index tree' (duration: 277.231463ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:31:56.68331Z","caller":"traceutil/trace.go:171","msg":"trace[221454539] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-da877070-8098-409c-bede-7ad3febcf182; range_end:; response_count:1; response_revision:1893; }","duration":"118.829253ms","start":"2026-09-01T18:31:56.56446Z","end":"2026-09-01T18:31:56.683289Z","steps":["trace[221454539] 'range keys from in-memory index tree' (duration: 118.482955ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:31:56.794057Z","caller":"traceutil/trace.go:171","msg":"trace[1877725994] transaction","detail":"{read_only:false; response_revision:1894; number_of_response:1; }","duration":"102.174536ms","start":"2026-09-01T18:31:56.691844Z","end":"2026-09-01T18:31:56.794018Z","steps":["trace[1877725994] 'process raft request' (duration: 101.87644ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:31:56.816472Z","caller":"traceutil/trace.go:171","msg":"trace[1765394369] transaction","detail":"{read_only:false; response_revision:1895; number_of_response:1; }","duration":"112.16163ms","start":"2026-09-01T18:31:56.704278Z","end":"2026-09-01T18:31:56.81644Z","steps":["trace[1765394369] 'process raft request' (duration: 112.030248ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:32:40.662012Z","caller":"traceutil/trace.go:171","msg":"trace[45474967] linearizableReadLoop","detail":"{readStateIndex:2273; appliedIndex:2272; }","duration":"131.808287ms","start":"2026-09-01T18:32:40.530168Z","end":"2026-09-01T18:32:40.661976Z","steps":["trace[45474967] 'read index received' (duration: 38.543746ms)","trace[45474967] 'applied index is now lower than readState.Index' (duration: 93.262671ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:32:40.662118Z","caller":"traceutil/trace.go:171","msg":"trace[486582535] transaction","detail":"{read_only:false; response_revision:2189; number_of_response:1; }","duration":"150.15508ms","start":"2026-09-01T18:32:40.511909Z","end":"2026-09-01T18:32:40.662064Z","steps":["trace[486582535] 'process raft request' (duration: 56.92041ms)","trace[486582535] 'compare' (duration: 92.81889ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:32:40.662443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.243906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-09-01T18:32:40.662499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.322592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-01T18:32:40.662565Z","caller":"traceutil/trace.go:171","msg":"trace[1148025521] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2189; }","duration":"128.456244ms","start":"2026-09-01T18:32:40.534094Z","end":"2026-09-01T18:32:40.662551Z","steps":["trace[1148025521] 'agreement among raft nodes before linearized reading' (duration: 128.154707ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:32:40.662579Z","caller":"traceutil/trace.go:171","msg":"trace[1003105995] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2189; }","duration":"132.41665ms","start":"2026-09-01T18:32:40.530127Z","end":"2026-09-01T18:32:40.662544Z","steps":["trace[1003105995] 'agreement among raft nodes before linearized reading' (duration: 132.015981ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:32:46.652233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.090119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T18:32:46.652308Z","caller":"traceutil/trace.go:171","msg":"trace[260939345] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2218; }","duration":"109.230932ms","start":"2026-09-01T18:32:46.54306Z","end":"2026-09-01T18:32:46.652291Z","steps":["trace[260939345] 'range keys from in-memory index tree' (duration: 109.004877ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:32:46.896261Z","caller":"traceutil/trace.go:171","msg":"trace[1279501166] linearizableReadLoop","detail":"{readStateIndex:2305; appliedIndex:2304; }","duration":"168.830849ms","start":"2026-09-01T18:32:46.727359Z","end":"2026-09-01T18:32:46.896189Z","steps":["trace[1279501166] 'read index received' (duration: 102.723201ms)","trace[1279501166] 'applied index is now lower than readState.Index' (duration: 66.105648ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:32:46.896324Z","caller":"traceutil/trace.go:171","msg":"trace[876020938] transaction","detail":"{read_only:false; response_revision:2220; number_of_response:1; }","duration":"180.073731ms","start":"2026-09-01T18:32:46.716194Z","end":"2026-09-01T18:32:46.896267Z","steps":["trace[876020938] 'process raft request' (duration: 113.992374ms)","trace[876020938] 'compare' (duration: 65.76886ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:32:46.896473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.065344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T18:32:46.89677Z","caller":"traceutil/trace.go:171","msg":"trace[1957156637] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:2220; }","duration":"169.405951ms","start":"2026-09-01T18:32:46.727343Z","end":"2026-09-01T18:32:46.896749Z","steps":["trace[1957156637] 'agreement among raft nodes before linearized reading' (duration: 169.023183ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:32:46.901015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.473821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-01T18:32:46.901114Z","caller":"traceutil/trace.go:171","msg":"trace[1762448037] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2221; }","duration":"167.599334ms","start":"2026-09-01T18:32:46.733488Z","end":"2026-09-01T18:32:46.901087Z","steps":["trace[1762448037] 'agreement among raft nodes before linearized reading' (duration: 167.325958ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:34:09.077534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.796379ms","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-09-01T18:34:09.07763Z","caller":"traceutil/trace.go:171","msg":"trace[1368057625] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2859; }","duration":"195.942522ms","start":"2026-09-01T18:34:08.881667Z","end":"2026-09-01T18:34:09.07761Z","steps":["trace[1368057625] 'range keys from in-memory index tree' (duration: 195.617855ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:39:07.080256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.011983ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-01T18:39:07.080918Z","caller":"traceutil/trace.go:171","msg":"trace[1983160834] transaction","detail":"{read_only:false; response_revision:5332; number_of_response:1; }","duration":"259.916974ms","start":"2026-09-01T18:39:06.820973Z","end":"2026-09-01T18:39:07.08089Z","steps":["trace[1983160834] 'process raft request' (duration: 132.47268ms)","trace[1983160834] 'compare' (duration: 125.642856ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:39:07.081146Z","caller":"traceutil/trace.go:171","msg":"trace[2046903504] linearizableReadLoop","detail":"{readStateIndex:5535; appliedIndex:5534; }","duration":"256.730357ms","start":"2026-09-01T18:39:06.824365Z","end":"2026-09-01T18:39:07.081096Z","steps":["trace[2046903504] 'read index received' (duration: 128.936885ms)","trace[2046903504] 'applied index is now lower than readState.Index' (duration: 127.790122ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:39:07.081404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.026383ms","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-09-01T18:39:07.081466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.23901ms","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-09-01T18:39:07.081472Z","caller":"traceutil/trace.go:171","msg":"trace[1560646963] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5333; }","duration":"257.122855ms","start":"2026-09-01T18:39:06.824332Z","end":"2026-09-01T18:39:07.081455Z","steps":["trace[1560646963] 'agreement among raft nodes before linearized reading' (duration: 256.920221ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:39:07.081538Z","caller":"traceutil/trace.go:171","msg":"trace[521929953] 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:5333; }","duration":"197.338041ms","start":"2026-09-01T18:39:06.884166Z","end":"2026-09-01T18:39:07.081504Z","steps":["trace[521929953] 'agreement among raft nodes before linearized reading' (duration: 197.120277ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:39:23.166091Z","caller":"traceutil/trace.go:171","msg":"trace[2051479064] transaction","detail":"{read_only:false; response_revision:5612; number_of_response:1; }","duration":"136.243301ms","start":"2026-09-01T18:39:23.029817Z","end":"2026-09-01T18:39:23.16606Z","steps":["trace[2051479064] 'process raft request' (duration: 135.999516ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:39:23.439258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.47874ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-01T18:39:23.439419Z","caller":"traceutil/trace.go:171","msg":"trace[815166479] linearizableReadLoop","detail":"{readStateIndex:5824; appliedIndex:5823; }","duration":"264.673845ms","start":"2026-09-01T18:39:23.174725Z","end":"2026-09-01T18:39:23.439399Z","steps":["trace[815166479] 'read index received' (duration: 93.97369ms)","trace[815166479] 'applied index is now lower than readState.Index' (duration: 170.698895ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:39:23.439553Z","caller":"traceutil/trace.go:171","msg":"trace[1668116008] transaction","detail":"{read_only:false; response_revision:5613; number_of_response:1; }","duration":"271.481361ms","start":"2026-09-01T18:39:23.168059Z","end":"2026-09-01T18:39:23.43954Z","steps":["trace[1668116008] 'process raft request' (duration: 100.625923ms)","trace[1668116008] 'compare' (duration: 170.294956ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:39:23.439924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.721599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-01T18:39:23.440043Z","caller":"traceutil/trace.go:171","msg":"trace[1419991606] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5613; }","duration":"258.859182ms","start":"2026-09-01T18:39:23.181157Z","end":"2026-09-01T18:39:23.440016Z","steps":["trace[1419991606] 'agreement among raft nodes before linearized reading' (duration: 258.483374ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:39:23.440117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.533818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-09-01T18:39:23.440108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.063234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-01T18:39:23.440169Z","caller":"traceutil/trace.go:171","msg":"trace[1367986145] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5613; }","duration":"230.611431ms","start":"2026-09-01T18:39:23.209542Z","end":"2026-09-01T18:39:23.440154Z","steps":["trace[1367986145] 'agreement among raft nodes before linearized reading' (duration: 230.411626ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:39:23.440212Z","caller":"traceutil/trace.go:171","msg":"trace[1799826658] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:5613; }","duration":"110.237057ms","start":"2026-09-01T18:39:23.329944Z","end":"2026-09-01T18:39:23.440181Z","steps":["trace[1799826658] 'agreement among raft nodes before linearized reading' (duration: 110.031783ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:39:23.440244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.554678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-bwkcj\" ","response":"range_response_count:1 size:7817"} {"level":"warn","ts":"2026-09-01T18:39:23.44031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.088848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T18:39:23.440347Z","caller":"traceutil/trace.go:171","msg":"trace[1218251981] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:5613; }","duration":"228.148509ms","start":"2026-09-01T18:39:23.212188Z","end":"2026-09-01T18:39:23.440337Z","steps":["trace[1218251981] 'agreement among raft nodes before linearized reading' (duration: 228.076997ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:39:23.440119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.86918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-jz44q\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-09-01T18:39:23.440485Z","caller":"traceutil/trace.go:171","msg":"trace[217738684] range","detail":"{range_begin:/registry/pods/openstack/keepalived-jz44q; range_end:; response_count:1; response_revision:5613; }","duration":"181.307781ms","start":"2026-09-01T18:39:23.259164Z","end":"2026-09-01T18:39:23.440472Z","steps":["trace[217738684] 'agreement among raft nodes before linearized reading' (duration: 180.784399ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:39:23.440435Z","caller":"traceutil/trace.go:171","msg":"trace[1254254570] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-bwkcj; range_end:; response_count:1; response_revision:5613; }","duration":"168.639732ms","start":"2026-09-01T18:39:23.271629Z","end":"2026-09-01T18:39:23.440269Z","steps":["trace[1254254570] 'agreement among raft nodes before linearized reading' (duration: 168.544799ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:39:23.440727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.909092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-09-01T18:39:23.440767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.947055ms","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-01T18:39:23.440941Z","caller":"traceutil/trace.go:171","msg":"trace[1089190384] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5613; }","duration":"236.16895ms","start":"2026-09-01T18:39:23.204747Z","end":"2026-09-01T18:39:23.440916Z","steps":["trace[1089190384] 'agreement among raft nodes before linearized reading' (duration: 235.235219ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:39:23.440899Z","caller":"traceutil/trace.go:171","msg":"trace[1019362288] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5613; }","duration":"266.182848ms","start":"2026-09-01T18:39:23.174678Z","end":"2026-09-01T18:39:23.440861Z","steps":["trace[1019362288] 'agreement among raft nodes before linearized reading' (duration: 265.424561ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:39:30.726888Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2980} {"level":"info","ts":"2026-09-01T18:39:30.805355Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2980,"took":"76.376017ms","hash":454264348,"current-db-size-bytes":56090624,"current-db-size":"56 MB","current-db-size-in-use-bytes":31608832,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-01T18:39:30.805441Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":454264348,"revision":2980,"compact-revision":-1} {"level":"info","ts":"2026-09-01T18:44:30.73577Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5747} {"level":"info","ts":"2026-09-01T18:44:30.846122Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5747,"took":"107.377695ms","hash":953612093,"current-db-size-bytes":56090624,"current-db-size":"56 MB","current-db-size-in-use-bytes":26243072,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-01T18:44:30.846223Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":953612093,"revision":5747,"compact-revision":2980} {"level":"info","ts":"2026-09-01T18:45:45.806066Z","caller":"traceutil/trace.go:171","msg":"trace[626457424] transaction","detail":"{read_only:false; response_revision:8658; number_of_response:1; }","duration":"129.421993ms","start":"2026-09-01T18:45:45.676589Z","end":"2026-09-01T18:45:45.806011Z","steps":["trace[626457424] 'process raft request' (duration: 129.070305ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:45:45.949934Z","caller":"traceutil/trace.go:171","msg":"trace[1267723151] transaction","detail":"{read_only:false; response_revision:8661; number_of_response:1; }","duration":"137.296792ms","start":"2026-09-01T18:45:45.812592Z","end":"2026-09-01T18:45:45.949889Z","steps":["trace[1267723151] 'process raft request' (duration: 137.19371ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:45:45.950355Z","caller":"traceutil/trace.go:171","msg":"trace[465102696] transaction","detail":"{read_only:false; response_revision:8660; number_of_response:1; }","duration":"138.059528ms","start":"2026-09-01T18:45:45.811938Z","end":"2026-09-01T18:45:45.949997Z","steps":["trace[465102696] 'process raft request' (duration: 114.31697ms)","trace[465102696] 'compare' (duration: 23.331809ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:45:57.543011Z","caller":"traceutil/trace.go:171","msg":"trace[171261589] transaction","detail":"{read_only:false; response_revision:8717; number_of_response:1; }","duration":"166.667682ms","start":"2026-09-01T18:45:57.37629Z","end":"2026-09-01T18:45:57.542958Z","steps":["trace[171261589] 'process raft request' (duration: 166.410397ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:45:57.558149Z","caller":"traceutil/trace.go:171","msg":"trace[1400070672] transaction","detail":"{read_only:false; response_revision:8718; number_of_response:1; }","duration":"172.692171ms","start":"2026-09-01T18:45:57.385418Z","end":"2026-09-01T18:45:57.55811Z","steps":["trace[1400070672] 'process raft request' (duration: 172.475776ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:46:27.431902Z","caller":"traceutil/trace.go:171","msg":"trace[1780273746] transaction","detail":"{read_only:false; response_revision:8930; number_of_response:1; }","duration":"152.882426ms","start":"2026-09-01T18:46:27.278985Z","end":"2026-09-01T18:46:27.431868Z","steps":["trace[1780273746] 'process raft request' (duration: 152.173941ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:46:27.792261Z","caller":"traceutil/trace.go:171","msg":"trace[603112749] transaction","detail":"{read_only:false; response_revision:8932; number_of_response:1; }","duration":"114.225558ms","start":"2026-09-01T18:46:27.677988Z","end":"2026-09-01T18:46:27.792214Z","steps":["trace[603112749] 'process raft request' (duration: 90.752754ms)","trace[603112749] 'compare' (duration: 23.3004ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:46:33.88078Z","caller":"traceutil/trace.go:171","msg":"trace[167907439] transaction","detail":"{read_only:false; response_revision:8964; number_of_response:1; }","duration":"137.16099ms","start":"2026-09-01T18:46:33.743588Z","end":"2026-09-01T18:46:33.880749Z","steps":["trace[167907439] 'process raft request' (duration: 127.564925ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:46:33.945297Z","caller":"traceutil/trace.go:171","msg":"trace[219141670] transaction","detail":"{read_only:false; response_revision:8965; number_of_response:1; }","duration":"133.103811ms","start":"2026-09-01T18:46:33.812166Z","end":"2026-09-01T18:46:33.94527Z","steps":["trace[219141670] 'process raft request' (duration: 132.899887ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:46:41.333091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.867493ms","expected-duration":"100ms","prefix":"","request":"header: txn: success: > failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-01T18:46:41.333314Z","caller":"traceutil/trace.go:171","msg":"trace[125570000] transaction","detail":"{read_only:false; number_of_response:1; response_revision:9042; }","duration":"180.854185ms","start":"2026-09-01T18:46:41.152437Z","end":"2026-09-01T18:46:41.333291Z","steps":["trace[125570000] 'process raft request' (duration: 43.606374ms)","trace[125570000] 'compare' (duration: 136.552936ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:46:41.333615Z","caller":"traceutil/trace.go:171","msg":"trace[1744403187] transaction","detail":"{read_only:false; response_revision:9043; number_of_response:1; }","duration":"179.013957ms","start":"2026-09-01T18:46:41.154555Z","end":"2026-09-01T18:46:41.333569Z","steps":["trace[1744403187] 'process raft request' (duration: 178.673188ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:46:42.169161Z","caller":"traceutil/trace.go:171","msg":"trace[1415031597] transaction","detail":"{read_only:false; response_revision:9049; number_of_response:1; }","duration":"167.396767ms","start":"2026-09-01T18:46:42.001741Z","end":"2026-09-01T18:46:42.169137Z","steps":["trace[1415031597] 'process raft request' (duration: 167.154371ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:46:55.739524Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000251c.wal"} {"level":"info","ts":"2026-09-01T18:47:46.252158Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5db0a1b394e67b72","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-01T18:47:46.259308Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-09-01T18:47:46.259431Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-09-01T18:49:30.743793Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8029} {"level":"info","ts":"2026-09-01T18:49:30.832298Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8029,"took":"84.500029ms","hash":1394641438,"current-db-size-bytes":56090624,"current-db-size":"56 MB","current-db-size-in-use-bytes":25358336,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-09-01T18:49:30.832395Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1394641438,"revision":8029,"compact-revision":5747} {"level":"info","ts":"2026-09-01T18:51:41.455829Z","caller":"traceutil/trace.go:171","msg":"trace[274863434] transaction","detail":"{read_only:false; response_revision:11670; number_of_response:1; }","duration":"163.244548ms","start":"2026-09-01T18:51:41.292548Z","end":"2026-09-01T18:51:41.455793Z","steps":["trace[274863434] 'process raft request' (duration: 140.791087ms)","trace[274863434] 'compare' (duration: 22.314438ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:54:30.752245Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10478} {"level":"info","ts":"2026-09-01T18:54:30.856703Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10478,"took":"101.506897ms","hash":2280263706,"current-db-size-bytes":56090624,"current-db-size":"56 MB","current-db-size-in-use-bytes":28106752,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-01T18:54:30.856804Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2280263706,"revision":10478,"compact-revision":8029} {"level":"info","ts":"2026-09-01T18:57:31.784231Z","caller":"traceutil/trace.go:171","msg":"trace[802684301] transaction","detail":"{read_only:false; response_revision:14059; number_of_response:1; }","duration":"111.33685ms","start":"2026-09-01T18:57:31.672856Z","end":"2026-09-01T18:57:31.784193Z","steps":["trace[802684301] 'process raft request' (duration: 110.601504ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:57:36.232969Z","caller":"traceutil/trace.go:171","msg":"trace[1637889234] transaction","detail":"{read_only:false; response_revision:14083; number_of_response:1; }","duration":"119.759683ms","start":"2026-09-01T18:57:36.113187Z","end":"2026-09-01T18:57:36.232947Z","steps":["trace[1637889234] 'process raft request' (duration: 119.644411ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:58:14.15281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.265803ms","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-09-01T18:58:14.152917Z","caller":"traceutil/trace.go:171","msg":"trace[1461026436] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:14298; }","duration":"132.440837ms","start":"2026-09-01T18:58:14.020449Z","end":"2026-09-01T18:58:14.15289Z","steps":["trace[1461026436] 'range keys from in-memory index tree' (duration: 132.10134ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:58:38.326565Z","caller":"traceutil/trace.go:171","msg":"trace[1228538023] linearizableReadLoop","detail":"{readStateIndex:15128; appliedIndex:15127; }","duration":"144.937617ms","start":"2026-09-01T18:58:38.181592Z","end":"2026-09-01T18:58:38.32653Z","steps":["trace[1228538023] 'read index received' (duration: 144.61113ms)","trace[1228538023] 'applied index is now lower than readState.Index' (duration: 292.276µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:58:38.326601Z","caller":"traceutil/trace.go:171","msg":"trace[143894243] transaction","detail":"{read_only:false; response_revision:14618; number_of_response:1; }","duration":"202.291309ms","start":"2026-09-01T18:58:38.124264Z","end":"2026-09-01T18:58:38.326555Z","steps":["trace[143894243] 'process raft request' (duration: 202.034173ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:58:38.326845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.233763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-vpgh5\" ","response":"range_response_count:1 size:20656"} {"level":"info","ts":"2026-09-01T18:58:38.326927Z","caller":"traceutil/trace.go:171","msg":"trace[1185763864] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-vpgh5; range_end:; response_count:1; response_revision:14618; }","duration":"145.384866ms","start":"2026-09-01T18:58:38.181522Z","end":"2026-09-01T18:58:38.326907Z","steps":["trace[1185763864] 'agreement among raft nodes before linearized reading' (duration: 145.131601ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:59:30.760303Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12806} {"level":"info","ts":"2026-09-01T18:59:30.847572Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12806,"took":"83.750494ms","hash":9356541,"current-db-size-bytes":56090624,"current-db-size":"56 MB","current-db-size-in-use-bytes":30732288,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-01T18:59:30.847679Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":9356541,"revision":12806,"compact-revision":10478} {"level":"info","ts":"2026-09-01T19:00:06.297797Z","caller":"traceutil/trace.go:171","msg":"trace[866032964] transaction","detail":"{read_only:false; response_revision:15467; number_of_response:1; }","duration":"162.376915ms","start":"2026-09-01T19:00:06.135384Z","end":"2026-09-01T19:00:06.297761Z","steps":["trace[866032964] 'process raft request' (duration: 162.035308ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T19:00:07.192437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.688631ms","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-09-01T19:00:07.192578Z","caller":"traceutil/trace.go:171","msg":"trace[150073040] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15468; }","duration":"106.867515ms","start":"2026-09-01T19:00:07.085689Z","end":"2026-09-01T19:00:07.192557Z","steps":["trace[150073040] 'range keys from in-memory index tree' (duration: 106.519807ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T19:04:30.769416Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15247} {"level":"info","ts":"2026-09-01T19:04:30.889109Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15247,"took":"116.433901ms","hash":4221821348,"current-db-size-bytes":56090624,"current-db-size":"56 MB","current-db-size-in-use-bytes":31952896,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-01T19:04:30.889213Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4221821348,"revision":15247,"compact-revision":12806} {"level":"info","ts":"2026-09-01T19:05:48.449498Z","caller":"traceutil/trace.go:171","msg":"trace[1034910992] transaction","detail":"{read_only:false; response_revision:18066; number_of_response:1; }","duration":"108.55719ms","start":"2026-09-01T19:05:48.340913Z","end":"2026-09-01T19:05:48.44947Z","steps":["trace[1034910992] 'process raft request' (duration: 108.378226ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T19:08:13.451798Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5db0a1b394e67b72","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-01T19:08:13.462252Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-09-01T19:08:13.462347Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-09-01T19:09:30.778434Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17502} {"level":"info","ts":"2026-09-01T19:09:30.885424Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17502,"took":"103.361494ms","hash":122337152,"current-db-size-bytes":56090624,"current-db-size":"56 MB","current-db-size-in-use-bytes":35246080,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-09-01T19:09:30.885492Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":122337152,"revision":17502,"compact-revision":15247} {"level":"warn","ts":"2026-09-01T19:11:20.532358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.128327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-01T19:11:20.532457Z","caller":"traceutil/trace.go:171","msg":"trace[398702554] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:20686; }","duration":"130.28102ms","start":"2026-09-01T19:11:20.402157Z","end":"2026-09-01T19:11:20.532438Z","steps":["trace[398702554] 'count revisions from in-memory index tree' (duration: 130.049515ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T19:11:20.532977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.746803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-09-01T19:11:20.53307Z","caller":"traceutil/trace.go:171","msg":"trace[1460505430] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:20686; }","duration":"132.901036ms","start":"2026-09-01T19:11:20.400148Z","end":"2026-09-01T19:11:20.533049Z","steps":["trace[1460505430] 'range keys from in-memory index tree' (duration: 132.583469ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T19:11:20.802853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.970986ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-01T19:11:20.803162Z","caller":"traceutil/trace.go:171","msg":"trace[902998828] transaction","detail":"{read_only:false; response_revision:20688; number_of_response:1; }","duration":"265.148597ms","start":"2026-09-01T19:11:20.537962Z","end":"2026-09-01T19:11:20.80311Z","steps":["trace[902998828] 'process raft request' (duration: 132.237731ms)","trace[902998828] 'compare' (duration: 131.69373ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T19:11:20.803307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.077056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:7913"} {"level":"info","ts":"2026-09-01T19:11:20.803365Z","caller":"traceutil/trace.go:171","msg":"trace[1605814630] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:20688; }","duration":"180.183088ms","start":"2026-09-01T19:11:20.623167Z","end":"2026-09-01T19:11:20.80335Z","steps":["trace[1605814630] 'agreement among raft nodes before linearized reading' (duration: 179.961353ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T19:11:20.803616Z","caller":"traceutil/trace.go:171","msg":"trace[208684589] linearizableReadLoop","detail":"{readStateIndex:21389; appliedIndex:21388; }","duration":"179.880901ms","start":"2026-09-01T19:11:20.623199Z","end":"2026-09-01T19:11:20.80308Z","steps":["trace[208684589] 'read index received' (duration: 47.036177ms)","trace[208684589] 'applied index is now lower than readState.Index' (duration: 132.842624ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T19:11:21.106953Z","caller":"traceutil/trace.go:171","msg":"trace[2026059792] transaction","detail":"{read_only:false; response_revision:20690; number_of_response:1; }","duration":"100.510063ms","start":"2026-09-01T19:11:21.006417Z","end":"2026-09-01T19:11:21.106927Z","steps":["trace[2026059792] 'process raft request' (duration: 100.333199ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T19:11:21.107438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.678346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-09-01T19:11:21.107512Z","caller":"traceutil/trace.go:171","msg":"trace[540311669] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20691; }","duration":"100.765368ms","start":"2026-09-01T19:11:21.006729Z","end":"2026-09-01T19:11:21.107494Z","steps":["trace[540311669] 'agreement among raft nodes before linearized reading' (duration: 100.529253ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T19:11:21.107144Z","caller":"traceutil/trace.go:171","msg":"trace[1868407450] linearizableReadLoop","detail":"{readStateIndex:21391; appliedIndex:21389; }","duration":"100.36288ms","start":"2026-09-01T19:11:21.00674Z","end":"2026-09-01T19:11:21.107103Z","steps":["trace[1868407450] 'read index received' (duration: 86.433591ms)","trace[1868407450] 'applied index is now lower than readState.Index' (duration: 13.927099ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T19:14:30.788103Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19901} {"level":"info","ts":"2026-09-01T19:14:30.910929Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19901,"took":"118.903917ms","hash":2108793519,"current-db-size-bytes":56090624,"current-db-size":"56 MB","current-db-size-in-use-bytes":30511104,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-01T19:14:30.91101Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2108793519,"revision":19901,"compact-revision":17502} {"level":"info","ts":"2026-09-01T19:15:23.430438Z","caller":"traceutil/trace.go:171","msg":"trace[1661169879] transaction","detail":"{read_only:false; response_revision:23206; number_of_response:1; }","duration":"233.01645ms","start":"2026-09-01T19:15:23.197397Z","end":"2026-09-01T19:15:23.430414Z","steps":["trace[1661169879] 'process raft request' (duration: 232.850856ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T19:15:23.437965Z","caller":"traceutil/trace.go:171","msg":"trace[984266857] transaction","detail":"{read_only:false; response_revision:23207; number_of_response:1; }","duration":"198.365728ms","start":"2026-09-01T19:15:23.23958Z","end":"2026-09-01T19:15:23.437946Z","steps":["trace[984266857] 'process raft request' (duration: 197.866468ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T19:16:44.030778Z","caller":"traceutil/trace.go:171","msg":"trace[1252104147] transaction","detail":"{read_only:false; response_revision:24071; number_of_response:1; }","duration":"110.30749ms","start":"2026-09-01T19:16:43.920444Z","end":"2026-09-01T19:16:44.030752Z","steps":["trace[1252104147] 'process raft request' (duration: 110.143277ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T19:19:30.795479Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22242} {"level":"info","ts":"2026-09-01T19:19:30.913071Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22242,"took":"113.504429ms","hash":3883905541,"current-db-size-bytes":56090624,"current-db-size":"56 MB","current-db-size-in-use-bytes":42348544,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-09-01T19:19:30.913133Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3883905541,"revision":22242,"compact-revision":19901} {"level":"info","ts":"2026-09-01T19:24:30.803658Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25313} {"level":"info","ts":"2026-09-01T19:24:30.953018Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25313,"took":"145.293267ms","hash":3106094522,"current-db-size-bytes":56090624,"current-db-size":"56 MB","current-db-size-in-use-bytes":29646848,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-01T19:24:30.953097Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3106094522,"revision":25313,"compact-revision":22242}