{"level":"warn","ts":"2026-04-07T00:12:31.761484Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-07T00:12:31.761568Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.213: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://199.19.213.213:2380","--initial-cluster=instance=https://199.19.213.213:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.213:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.213: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-04-07T00:12:31.761633Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-07T00:12:31.761645Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.213:2380"]} {"level":"info","ts":"2026-04-07T00:12:31.761663Z","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-04-07T00:12:31.7623Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.213:2379"]} {"level":"info","ts":"2026-04-07T00:12:31.762387Z","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://199.19.213.213:2380"],"listen-peer-urls":["https://199.19.213.213:2380"],"advertise-client-urls":["https://199.19.213.213:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.213:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.213: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-04-07T00:12:31.766984Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.401156ms"} {"level":"info","ts":"2026-04-07T00:12:31.774457Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f3faf6a3dfbe0100","cluster-id":"4c2e50e35fb92a79"} {"level":"info","ts":"2026-04-07T00:12:31.774558Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3faf6a3dfbe0100 switched to configuration voters=()"} {"level":"info","ts":"2026-04-07T00:12:31.774592Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3faf6a3dfbe0100 became follower at term 0"} {"level":"info","ts":"2026-04-07T00:12:31.774613Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f3faf6a3dfbe0100 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-07T00:12:31.774628Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3faf6a3dfbe0100 became follower at term 1"} {"level":"info","ts":"2026-04-07T00:12:31.774685Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3faf6a3dfbe0100 switched to configuration voters=(17580635279088025856)"} {"level":"warn","ts":"2026-04-07T00:12:31.780955Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-07T00:12:31.782581Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-07T00:12:31.783803Z","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-04-07T00:12:31.786694Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f3faf6a3dfbe0100","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-07T00:12:31.786999Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f3faf6a3dfbe0100","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-07T00:12:31.787132Z","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-04-07T00:12:31.787486Z","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-04-07T00:12:31.787655Z","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-04-07T00:12:31.788061Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3faf6a3dfbe0100 switched to configuration voters=(17580635279088025856)"} {"level":"info","ts":"2026-04-07T00:12:31.788392Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4c2e50e35fb92a79","local-member-id":"f3faf6a3dfbe0100","added-peer-id":"f3faf6a3dfbe0100","added-peer-peer-urls":["https://199.19.213.213:2380"]} {"level":"info","ts":"2026-04-07T00:12:31.791445Z","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-04-07T00:12:31.791639Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.213:2380"} {"level":"info","ts":"2026-04-07T00:12:31.791685Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.213:2380"} {"level":"info","ts":"2026-04-07T00:12:31.791879Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f3faf6a3dfbe0100","initial-advertise-peer-urls":["https://199.19.213.213:2380"],"listen-peer-urls":["https://199.19.213.213:2380"],"advertise-client-urls":["https://199.19.213.213:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.213:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-07T00:12:31.791928Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-07T00:12:32.576107Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3faf6a3dfbe0100 is starting a new election at term 1"} {"level":"info","ts":"2026-04-07T00:12:32.576183Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3faf6a3dfbe0100 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-07T00:12:32.576208Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3faf6a3dfbe0100 received MsgPreVoteResp from f3faf6a3dfbe0100 at term 1"} {"level":"info","ts":"2026-04-07T00:12:32.576227Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3faf6a3dfbe0100 became candidate at term 2"} {"level":"info","ts":"2026-04-07T00:12:32.576238Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3faf6a3dfbe0100 received MsgVoteResp from f3faf6a3dfbe0100 at term 2"} {"level":"info","ts":"2026-04-07T00:12:32.576254Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3faf6a3dfbe0100 became leader at term 2"} {"level":"info","ts":"2026-04-07T00:12:32.576268Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f3faf6a3dfbe0100 elected leader f3faf6a3dfbe0100 at term 2"} {"level":"info","ts":"2026-04-07T00:12:32.577695Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T00:12:32.578619Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f3faf6a3dfbe0100","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.213:2379]}","request-path":"/0/members/f3faf6a3dfbe0100/attributes","cluster-id":"4c2e50e35fb92a79","publish-timeout":"7s"} {"level":"info","ts":"2026-04-07T00:12:32.57872Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T00:12:32.578661Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T00:12:32.579185Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4c2e50e35fb92a79","local-member-id":"f3faf6a3dfbe0100","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T00:12:32.579238Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-07T00:12:32.579316Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-07T00:12:32.579353Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T00:12:32.579405Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T00:12:32.581876Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.213:2379"} {"level":"info","ts":"2026-04-07T00:12:32.582463Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-07T00:12:50.755051Z","caller":"traceutil/trace.go:171","msg":"trace[61131068] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"289.8521ms","start":"2026-04-07T00:12:50.465173Z","end":"2026-04-07T00:12:50.755025Z","steps":["trace[61131068] 'process raft request' (duration: 289.699107ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:12:51.144109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.129841ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-07T00:13:04.808081Z","caller":"traceutil/trace.go:171","msg":"trace[2011481700] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"120.390814ms","start":"2026-04-07T00:13:04.687652Z","end":"2026-04-07T00:13:04.808043Z","steps":["trace[2011481700] 'process raft request' (duration: 120.18659ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:13:04.817879Z","caller":"traceutil/trace.go:171","msg":"trace[800337259] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"121.931943ms","start":"2026-04-07T00:13:04.695921Z","end":"2026-04-07T00:13:04.817853Z","steps":["trace[800337259] 'process raft request' (duration: 120.895972ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:13:11.762095Z","caller":"traceutil/trace.go:171","msg":"trace[1104866558] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"226.534308ms","start":"2026-04-07T00:13:11.535519Z","end":"2026-04-07T00:13:11.762053Z","steps":["trace[1104866558] 'process raft request' (duration: 226.200301ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:14:39.114037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.315033ms","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-04-07T00:14:39.114133Z","caller":"traceutil/trace.go:171","msg":"trace[245372219] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1799; }","duration":"156.480165ms","start":"2026-04-07T00:14:38.957633Z","end":"2026-04-07T00:14:39.114113Z","steps":["trace[245372219] 'range keys from in-memory index tree' (duration: 156.12949ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:14:39.114152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.130486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:14:39.114247Z","caller":"traceutil/trace.go:171","msg":"trace[28184569] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1799; }","duration":"160.282019ms","start":"2026-04-07T00:14:38.953947Z","end":"2026-04-07T00:14:39.11423Z","steps":["trace[28184569] 'count revisions from in-memory index tree' (duration: 160.025414ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:14:39.670746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.731917ms","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-04-07T00:14:39.670829Z","caller":"traceutil/trace.go:171","msg":"trace[1604450781] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1803; }","duration":"171.86759ms","start":"2026-04-07T00:14:39.498941Z","end":"2026-04-07T00:14:39.670809Z","steps":["trace[1604450781] 'range keys from in-memory index tree' (duration: 171.509383ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:14:52.610642Z","caller":"traceutil/trace.go:171","msg":"trace[1100808404] transaction","detail":"{read_only:false; response_revision:1917; number_of_response:1; }","duration":"107.653521ms","start":"2026-04-07T00:14:52.502951Z","end":"2026-04-07T00:14:52.610604Z","steps":["trace[1100808404] 'process raft request' (duration: 107.366295ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:15:24.566883Z","caller":"traceutil/trace.go:171","msg":"trace[1433484581] transaction","detail":"{read_only:false; response_revision:2103; number_of_response:1; }","duration":"116.464746ms","start":"2026-04-07T00:15:24.450359Z","end":"2026-04-07T00:15:24.566824Z","steps":["trace[1433484581] 'process raft request' (duration: 114.997158ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:16:47.279127Z","caller":"traceutil/trace.go:171","msg":"trace[1707320579] transaction","detail":"{read_only:false; response_revision:2599; number_of_response:1; }","duration":"121.005825ms","start":"2026-04-07T00:16:47.158067Z","end":"2026-04-07T00:16:47.279073Z","steps":["trace[1707320579] 'process raft request' (duration: 120.765111ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:16:47.302223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.025722ms","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-04-07T00:16:47.302306Z","caller":"traceutil/trace.go:171","msg":"trace[972296038] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2600; }","duration":"105.176814ms","start":"2026-04-07T00:16:47.197111Z","end":"2026-04-07T00:16:47.302288Z","steps":["trace[972296038] 'agreement among raft nodes before linearized reading' (duration: 104.92289ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:16:47.302483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.764064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-07T00:16:47.302587Z","caller":"traceutil/trace.go:171","msg":"trace[81261940] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2600; }","duration":"100.911116ms","start":"2026-04-07T00:16:47.201654Z","end":"2026-04-07T00:16:47.302565Z","steps":["trace[81261940] 'agreement among raft nodes before linearized reading' (duration: 100.58059ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:20:58.398017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.818371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:20:58.398101Z","caller":"traceutil/trace.go:171","msg":"trace[1199525389] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4824; }","duration":"179.978383ms","start":"2026-04-07T00:20:58.218107Z","end":"2026-04-07T00:20:58.398085Z","steps":["trace[1199525389] 'range keys from in-memory index tree' (duration: 179.640757ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:20:58.398176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.803542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fdf99ebb-83b7-4704-9b49-b205bcf9ff7a\" ","response":"range_response_count:1 size:3721"} {"level":"info","ts":"2026-04-07T00:20:58.398306Z","caller":"traceutil/trace.go:171","msg":"trace[1037398477] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-fdf99ebb-83b7-4704-9b49-b205bcf9ff7a; range_end:; response_count:1; response_revision:4824; }","duration":"170.991026ms","start":"2026-04-07T00:20:58.227286Z","end":"2026-04-07T00:20:58.398277Z","steps":["trace[1037398477] 'range keys from in-memory index tree' (duration: 170.69268ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:20:58.398221Z","caller":"traceutil/trace.go:171","msg":"trace[1264791166] transaction","detail":"{read_only:false; response_revision:4825; number_of_response:1; }","duration":"131.606467ms","start":"2026-04-07T00:20:58.266583Z","end":"2026-04-07T00:20:58.398189Z","steps":["trace[1264791166] 'process raft request' (duration: 128.660212ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:21:10.289821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.022782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-ktsrh\" ","response":"range_response_count:1 size:7818"} {"level":"info","ts":"2026-04-07T00:21:10.289925Z","caller":"traceutil/trace.go:171","msg":"trace[1959464596] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-ktsrh; range_end:; response_count:1; response_revision:5053; }","duration":"118.210046ms","start":"2026-04-07T00:21:10.171693Z","end":"2026-04-07T00:21:10.289903Z","steps":["trace[1959464596] 'range keys from in-memory index tree' (duration: 117.805408ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:21:18.02545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.466317ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T00:21:18.025593Z","caller":"traceutil/trace.go:171","msg":"trace[1103495327] linearizableReadLoop","detail":"{readStateIndex:5416; appliedIndex:5415; }","duration":"178.871743ms","start":"2026-04-07T00:21:17.846702Z","end":"2026-04-07T00:21:18.025574Z","steps":["trace[1103495327] 'read index received' (duration: 64.673582ms)","trace[1103495327] 'applied index is now lower than readState.Index' (duration: 114.196401ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:21:18.025727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.021576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:21:18.025717Z","caller":"traceutil/trace.go:171","msg":"trace[859847651] transaction","detail":"{read_only:false; response_revision:5223; number_of_response:1; }","duration":"229.622224ms","start":"2026-04-07T00:21:17.796059Z","end":"2026-04-07T00:21:18.025682Z","steps":["trace[859847651] 'process raft request' (duration: 115.367532ms)","trace[859847651] 'compare' (duration: 113.097331ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:21:18.025772Z","caller":"traceutil/trace.go:171","msg":"trace[1894513915] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:5223; }","duration":"179.148849ms","start":"2026-04-07T00:21:17.846611Z","end":"2026-04-07T00:21:18.02576Z","steps":["trace[1894513915] 'agreement among raft nodes before linearized reading' (duration: 179.067097ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:21:18.02579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.051839ms","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-04-07T00:21:18.025909Z","caller":"traceutil/trace.go:171","msg":"trace[539846580] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:5223; }","duration":"130.213681ms","start":"2026-04-07T00:21:17.895675Z","end":"2026-04-07T00:21:18.025889Z","steps":["trace[539846580] 'agreement among raft nodes before linearized reading' (duration: 130.028848ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:21:18.468939Z","caller":"traceutil/trace.go:171","msg":"trace[364798946] linearizableReadLoop","detail":"{readStateIndex:5418; appliedIndex:5417; }","duration":"135.45968ms","start":"2026-04-07T00:21:18.333454Z","end":"2026-04-07T00:21:18.468914Z","steps":["trace[364798946] 'read index received' (duration: 135.222666ms)","trace[364798946] 'applied index is now lower than readState.Index' (duration: 235.024µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:21:18.469097Z","caller":"traceutil/trace.go:171","msg":"trace[2032973623] transaction","detail":"{read_only:false; response_revision:5225; number_of_response:1; }","duration":"180.474123ms","start":"2026-04-07T00:21:18.288573Z","end":"2026-04-07T00:21:18.469047Z","steps":["trace[2032973623] 'process raft request' (duration: 180.067686ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:21:18.469326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.840467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-t45lb\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-07T00:21:18.469381Z","caller":"traceutil/trace.go:171","msg":"trace[1360275610] range","detail":"{range_begin:/registry/pods/openstack/keepalived-t45lb; range_end:; response_count:1; response_revision:5225; }","duration":"135.934968ms","start":"2026-04-07T00:21:18.333435Z","end":"2026-04-07T00:21:18.46937Z","steps":["trace[1360275610] 'agreement among raft nodes before linearized reading' (duration: 135.631023ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:22:32.601763Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2840} {"level":"info","ts":"2026-04-07T00:22:32.671063Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2840,"took":"67.488933ms","hash":2141840656,"current-db-size-bytes":57229312,"current-db-size":"57 MB","current-db-size-in-use-bytes":32915456,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-04-07T00:22:32.671154Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2141840656,"revision":2840,"compact-revision":-1} {"level":"info","ts":"2026-04-07T00:27:22.19973Z","caller":"traceutil/trace.go:171","msg":"trace[1609091102] transaction","detail":"{read_only:false; response_revision:8052; number_of_response:1; }","duration":"139.793559ms","start":"2026-04-07T00:27:22.059901Z","end":"2026-04-07T00:27:22.199695Z","steps":["trace[1609091102] 'process raft request' (duration: 139.595655ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:27:22.951793Z","caller":"traceutil/trace.go:171","msg":"trace[1840600031] linearizableReadLoop","detail":"{readStateIndex:8334; appliedIndex:8333; }","duration":"209.600162ms","start":"2026-04-07T00:27:22.742161Z","end":"2026-04-07T00:27:22.951761Z","steps":["trace[1840600031] 'read index received' (duration: 209.427118ms)","trace[1840600031] 'applied index is now lower than readState.Index' (duration: 171.524µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:27:22.951892Z","caller":"traceutil/trace.go:171","msg":"trace[2033624167] transaction","detail":"{read_only:false; response_revision:8053; number_of_response:1; }","duration":"216.501689ms","start":"2026-04-07T00:27:22.735334Z","end":"2026-04-07T00:27:22.951835Z","steps":["trace[2033624167] 'process raft request' (duration: 216.256315ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:27:22.952181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.99776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-t45lb\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-07T00:27:22.952302Z","caller":"traceutil/trace.go:171","msg":"trace[1042079603] range","detail":"{range_begin:/registry/pods/openstack/keepalived-t45lb; range_end:; response_count:1; response_revision:8053; }","duration":"210.193395ms","start":"2026-04-07T00:27:22.742078Z","end":"2026-04-07T00:27:22.952272Z","steps":["trace[1042079603] 'agreement among raft nodes before linearized reading' (duration: 209.843557ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:27:22.952376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.653008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-07T00:27:22.952476Z","caller":"traceutil/trace.go:171","msg":"trace[373434065] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8053; }","duration":"100.784671ms","start":"2026-04-07T00:27:22.851672Z","end":"2026-04-07T00:27:22.952457Z","steps":["trace[373434065] 'agreement among raft nodes before linearized reading' (duration: 100.584707ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:27:22.952682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.092237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-07T00:27:22.952783Z","caller":"traceutil/trace.go:171","msg":"trace[922037324] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8053; }","duration":"106.28488ms","start":"2026-04-07T00:27:22.846482Z","end":"2026-04-07T00:27:22.952767Z","steps":["trace[922037324] 'agreement among raft nodes before linearized reading' (duration: 105.74079ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:27:32.608998Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5919} {"level":"info","ts":"2026-04-07T00:27:32.696366Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5919,"took":"84.915085ms","hash":105200036,"current-db-size-bytes":57229312,"current-db-size":"57 MB","current-db-size-in-use-bytes":26054656,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-04-07T00:27:32.696444Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":105200036,"revision":5919,"compact-revision":2840} {"level":"info","ts":"2026-04-07T00:28:04.944283Z","caller":"traceutil/trace.go:171","msg":"trace[2002595466] transaction","detail":"{read_only:false; response_revision:8374; number_of_response:1; }","duration":"157.793019ms","start":"2026-04-07T00:28:04.786463Z","end":"2026-04-07T00:28:04.944256Z","steps":["trace[2002595466] 'process raft request' (duration: 157.611615ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:28:04.951233Z","caller":"traceutil/trace.go:171","msg":"trace[880933830] transaction","detail":"{read_only:false; response_revision:8375; number_of_response:1; }","duration":"158.112195ms","start":"2026-04-07T00:28:04.793081Z","end":"2026-04-07T00:28:04.951193Z","steps":["trace[880933830] 'process raft request' (duration: 157.922562ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:28:05.729517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.133953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:5732"} {"level":"info","ts":"2026-04-07T00:28:05.729673Z","caller":"traceutil/trace.go:171","msg":"trace[2057866452] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:8380; }","duration":"196.382368ms","start":"2026-04-07T00:28:05.533256Z","end":"2026-04-07T00:28:05.729638Z","steps":["trace[2057866452] 'range keys from in-memory index tree' (duration: 195.851328ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:28:06.396543Z","caller":"traceutil/trace.go:171","msg":"trace[1633563134] linearizableReadLoop","detail":"{readStateIndex:8682; appliedIndex:8681; }","duration":"177.43265ms","start":"2026-04-07T00:28:06.219074Z","end":"2026-04-07T00:28:06.396507Z","steps":["trace[1633563134] 'read index received' (duration: 177.233106ms)","trace[1633563134] 'applied index is now lower than readState.Index' (duration: 198.174µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:28:06.396727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.598673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:28:06.396776Z","caller":"traceutil/trace.go:171","msg":"trace[596935951] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8386; }","duration":"177.714625ms","start":"2026-04-07T00:28:06.219045Z","end":"2026-04-07T00:28:06.39676Z","steps":["trace[596935951] 'agreement among raft nodes before linearized reading' (duration: 177.594042ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:28:06.396768Z","caller":"traceutil/trace.go:171","msg":"trace[678277252] transaction","detail":"{read_only:false; response_revision:8386; number_of_response:1; }","duration":"206.325707ms","start":"2026-04-07T00:28:06.190226Z","end":"2026-04-07T00:28:06.396552Z","steps":["trace[678277252] 'process raft request' (duration: 206.105232ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:29:02.818938Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002438.wal"} {"level":"info","ts":"2026-04-07T00:30:31.998307Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"f3faf6a3dfbe0100","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-07T00:30:32.005343Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-07T00:30:32.005508Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-07T00:32:32.615894Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8110} {"level":"info","ts":"2026-04-07T00:32:32.681043Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8110,"took":"62.491032ms","hash":2867859264,"current-db-size-bytes":57229312,"current-db-size":"57 MB","current-db-size-in-use-bytes":29421568,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-07T00:32:32.681136Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2867859264,"revision":8110,"compact-revision":5919} {"level":"info","ts":"2026-04-07T00:37:32.624263Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10536} {"level":"info","ts":"2026-04-07T00:37:32.70553Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10536,"took":"77.698401ms","hash":1211296293,"current-db-size-bytes":57229312,"current-db-size":"57 MB","current-db-size-in-use-bytes":20017152,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-04-07T00:37:32.705722Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1211296293,"revision":10536,"compact-revision":8110}