{"level":"warn","ts":"2026-09-09T06:24:34.177248Z","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-09T06:24:34.177354Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.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://162.253.55.213:2380","--initial-cluster=instance=https://162.253.55.213:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.213:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.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-09-09T06:24:34.177424Z","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-09T06:24:34.177437Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.213:2380"]} {"level":"info","ts":"2026-09-09T06:24:34.17746Z","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-09T06:24:34.178285Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.213:2379"]} {"level":"info","ts":"2026-09-09T06:24:34.178379Z","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.213:2380"],"listen-peer-urls":["https://162.253.55.213:2380"],"advertise-client-urls":["https://162.253.55.213:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.213:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.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-09-09T06:24:34.181305Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.673313ms"} {"level":"info","ts":"2026-09-09T06:24:34.187925Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"43ce62d0a10d51da","cluster-id":"25e0c2268a6b42c6"} {"level":"info","ts":"2026-09-09T06:24:34.188037Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"43ce62d0a10d51da switched to configuration voters=()"} {"level":"info","ts":"2026-09-09T06:24:34.188378Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"43ce62d0a10d51da became follower at term 0"} {"level":"info","ts":"2026-09-09T06:24:34.188559Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 43ce62d0a10d51da [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-09T06:24:34.18859Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"43ce62d0a10d51da became follower at term 1"} {"level":"info","ts":"2026-09-09T06:24:34.188757Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"43ce62d0a10d51da switched to configuration voters=(4885951293938291162)"} {"level":"warn","ts":"2026-09-09T06:24:34.201545Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-09T06:24:34.203933Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-09T06:24:34.206148Z","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-09T06:24:34.209494Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"43ce62d0a10d51da","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-09T06:24:34.20994Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"43ce62d0a10d51da","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-09T06:24:34.209877Z","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-09T06:24:34.210067Z","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-09T06:24:34.210094Z","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-09T06:24:34.212308Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"43ce62d0a10d51da switched to configuration voters=(4885951293938291162)"} {"level":"info","ts":"2026-09-09T06:24:34.212517Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"25e0c2268a6b42c6","local-member-id":"43ce62d0a10d51da","added-peer-id":"43ce62d0a10d51da","added-peer-peer-urls":["https://162.253.55.213:2380"]} {"level":"info","ts":"2026-09-09T06:24:34.214912Z","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-09T06:24:34.214997Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.213:2380"} {"level":"info","ts":"2026-09-09T06:24:34.215055Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.213:2380"} {"level":"info","ts":"2026-09-09T06:24:34.215266Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"43ce62d0a10d51da","initial-advertise-peer-urls":["https://162.253.55.213:2380"],"listen-peer-urls":["https://162.253.55.213:2380"],"advertise-client-urls":["https://162.253.55.213:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.213:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-09T06:24:34.21531Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-09T06:24:34.389773Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"43ce62d0a10d51da is starting a new election at term 1"} {"level":"info","ts":"2026-09-09T06:24:34.389887Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"43ce62d0a10d51da became pre-candidate at term 1"} {"level":"info","ts":"2026-09-09T06:24:34.389945Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"43ce62d0a10d51da received MsgPreVoteResp from 43ce62d0a10d51da at term 1"} {"level":"info","ts":"2026-09-09T06:24:34.389974Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"43ce62d0a10d51da became candidate at term 2"} {"level":"info","ts":"2026-09-09T06:24:34.389995Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"43ce62d0a10d51da received MsgVoteResp from 43ce62d0a10d51da at term 2"} {"level":"info","ts":"2026-09-09T06:24:34.390023Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"43ce62d0a10d51da became leader at term 2"} {"level":"info","ts":"2026-09-09T06:24:34.390047Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 43ce62d0a10d51da elected leader 43ce62d0a10d51da at term 2"} {"level":"info","ts":"2026-09-09T06:24:34.393451Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"43ce62d0a10d51da","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.213:2379]}","request-path":"/0/members/43ce62d0a10d51da/attributes","cluster-id":"25e0c2268a6b42c6","publish-timeout":"7s"} {"level":"info","ts":"2026-09-09T06:24:34.393555Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-09T06:24:34.393673Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-09T06:24:34.393695Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-09T06:24:34.393816Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-09T06:24:34.39406Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-09T06:24:34.39555Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-09T06:24:34.395647Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"25e0c2268a6b42c6","local-member-id":"43ce62d0a10d51da","cluster-version":"3.5"} {"level":"info","ts":"2026-09-09T06:24:34.395787Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-09T06:24:34.395838Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-09T06:24:34.396124Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.213:2379"} {"level":"info","ts":"2026-09-09T06:24:46.083729Z","caller":"traceutil/trace.go:171","msg":"trace[509645012] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"110.957771ms","start":"2026-09-09T06:24:45.972746Z","end":"2026-09-09T06:24:46.083704Z","steps":["trace[509645012] 'process raft request' (duration: 110.56782ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:24:46.233794Z","caller":"traceutil/trace.go:171","msg":"trace[854419085] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"137.450221ms","start":"2026-09-09T06:24:46.096302Z","end":"2026-09-09T06:24:46.233752Z","steps":["trace[854419085] 'process raft request' (duration: 136.629661ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:25:10.852935Z","caller":"traceutil/trace.go:171","msg":"trace[897660755] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"134.138874ms","start":"2026-09-09T06:25:10.718768Z","end":"2026-09-09T06:25:10.852907Z","steps":["trace[897660755] 'process raft request' (duration: 133.845858ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:25:18.958306Z","caller":"traceutil/trace.go:171","msg":"trace[873492071] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"142.899054ms","start":"2026-09-09T06:25:18.81538Z","end":"2026-09-09T06:25:18.95828Z","steps":["trace[873492071] 'process raft request' (duration: 142.245969ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:25:36.145155Z","caller":"traceutil/trace.go:171","msg":"trace[906450138] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"100.009816ms","start":"2026-09-09T06:25:36.045122Z","end":"2026-09-09T06:25:36.145132Z","steps":["trace[906450138] 'process raft request' (duration: 99.811102ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:26:53.706405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.97415ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-09-09T06:26:53.706565Z","caller":"traceutil/trace.go:171","msg":"trace[807495693] linearizableReadLoop","detail":"{readStateIndex:1904; appliedIndex:1903; }","duration":"287.891764ms","start":"2026-09-09T06:26:53.418656Z","end":"2026-09-09T06:26:53.706548Z","steps":["trace[807495693] 'read index received' (duration: 57.620128ms)","trace[807495693] 'applied index is now lower than readState.Index' (duration: 230.270056ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:26:53.706898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.192401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7bc7e51e-1eca-476c-8287-81cb3a6da72f\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-09-09T06:26:53.707006Z","caller":"traceutil/trace.go:171","msg":"trace[557843055] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7bc7e51e-1eca-476c-8287-81cb3a6da72f; range_end:; response_count:1; response_revision:1828; }","duration":"288.371634ms","start":"2026-09-09T06:26:53.418607Z","end":"2026-09-09T06:26:53.706979Z","steps":["trace[557843055] 'agreement among raft nodes before linearized reading' (duration: 288.058647ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:26:53.707287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.376137ms","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-09T06:26:53.707393Z","caller":"traceutil/trace.go:171","msg":"trace[1465364675] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1828; }","duration":"193.51088ms","start":"2026-09-09T06:26:53.513858Z","end":"2026-09-09T06:26:53.707369Z","steps":["trace[1465364675] 'agreement among raft nodes before linearized reading' (duration: 192.852966ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:26:53.707517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.570465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7bc7e51e-1eca-476c-8287-81cb3a6da72f\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-09-09T06:26:53.707612Z","caller":"traceutil/trace.go:171","msg":"trace[844793381] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7bc7e51e-1eca-476c-8287-81cb3a6da72f; range_end:; response_count:1; response_revision:1828; }","duration":"274.728279ms","start":"2026-09-09T06:26:53.432861Z","end":"2026-09-09T06:26:53.707589Z","steps":["trace[844793381] 'agreement among raft nodes before linearized reading' (duration: 273.811039ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:26:53.921888Z","caller":"traceutil/trace.go:171","msg":"trace[21151790] transaction","detail":"{read_only:false; response_revision:1830; number_of_response:1; }","duration":"204.975918ms","start":"2026-09-09T06:26:53.716884Z","end":"2026-09-09T06:26:53.92186Z","steps":["trace[21151790] 'process raft request' (duration: 204.681202ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:26:54.240294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.77699ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T06:26:54.24041Z","caller":"traceutil/trace.go:171","msg":"trace[1837867171] transaction","detail":"{read_only:false; response_revision:1831; number_of_response:1; }","duration":"259.771724ms","start":"2026-09-09T06:26:53.980611Z","end":"2026-09-09T06:26:54.240383Z","steps":["trace[1837867171] 'process raft request' (duration: 50.425992ms)","trace[1837867171] 'compare' (duration: 208.673968ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:27:37.082338Z","caller":"traceutil/trace.go:171","msg":"trace[1769805448] transaction","detail":"{read_only:false; response_revision:2119; number_of_response:1; }","duration":"100.320482ms","start":"2026-09-09T06:27:36.981979Z","end":"2026-09-09T06:27:37.0823Z","steps":["trace[1769805448] 'process raft request' (duration: 100.137668ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:27:43.1467Z","caller":"traceutil/trace.go:171","msg":"trace[611074087] linearizableReadLoop","detail":"{readStateIndex:2239; appliedIndex:2238; }","duration":"214.632207ms","start":"2026-09-09T06:27:42.931955Z","end":"2026-09-09T06:27:43.146587Z","steps":["trace[611074087] 'read index received' (duration: 134.728308ms)","trace[611074087] 'applied index is now lower than readState.Index' (duration: 79.901959ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:27:43.146734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.746309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-09T06:27:43.146899Z","caller":"traceutil/trace.go:171","msg":"trace[1319700895] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:2151; }","duration":"214.940183ms","start":"2026-09-09T06:27:42.931937Z","end":"2026-09-09T06:27:43.146877Z","steps":["trace[1319700895] 'agreement among raft nodes before linearized reading' (duration: 214.666228ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:27:43.150547Z","caller":"traceutil/trace.go:171","msg":"trace[978096689] transaction","detail":"{read_only:false; response_revision:2152; number_of_response:1; }","duration":"143.497387ms","start":"2026-09-09T06:27:43.007019Z","end":"2026-09-09T06:27:43.150517Z","steps":["trace[978096689] 'process raft request' (duration: 143.258372ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:27:43.271142Z","caller":"traceutil/trace.go:171","msg":"trace[813019726] transaction","detail":"{read_only:false; response_revision:2154; number_of_response:1; }","duration":"112.936776ms","start":"2026-09-09T06:27:43.158175Z","end":"2026-09-09T06:27:43.271112Z","steps":["trace[813019726] 'process raft request' (duration: 103.982823ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:29:07.699145Z","caller":"traceutil/trace.go:171","msg":"trace[669750669] transaction","detail":"{read_only:false; response_revision:2803; number_of_response:1; }","duration":"107.197498ms","start":"2026-09-09T06:29:07.591925Z","end":"2026-09-09T06:29:07.699122Z","steps":["trace[669750669] 'process raft request' (duration: 105.781579ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:29:13.141949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.88154ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T06:29:13.142228Z","caller":"traceutil/trace.go:171","msg":"trace[735308516] transaction","detail":"{read_only:false; response_revision:2836; number_of_response:1; }","duration":"196.911747ms","start":"2026-09-09T06:29:12.945255Z","end":"2026-09-09T06:29:13.142167Z","steps":["trace[735308516] 'process raft request' (duration: 76.636239ms)","trace[735308516] 'compare' (duration: 119.718407ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:29:13.268433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.352734ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-09-09T06:29:13.268531Z","caller":"traceutil/trace.go:171","msg":"trace[1568887889] linearizableReadLoop","detail":"{readStateIndex:2951; appliedIndex:2949; }","duration":"224.628319ms","start":"2026-09-09T06:29:13.043884Z","end":"2026-09-09T06:29:13.268512Z","steps":["trace[1568887889] 'read index received' (duration: 34.811µs)","trace[1568887889] 'applied index is now lower than readState.Index' (duration: 224.592438ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:29:13.269166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.270142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:29:13.269224Z","caller":"traceutil/trace.go:171","msg":"trace[705376903] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2836; }","duration":"225.363344ms","start":"2026-09-09T06:29:13.043847Z","end":"2026-09-09T06:29:13.269211Z","steps":["trace[705376903] 'agreement among raft nodes before linearized reading' (duration: 225.271602ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:32:19.7307Z","caller":"traceutil/trace.go:171","msg":"trace[432008796] transaction","detail":"{read_only:false; response_revision:4019; number_of_response:1; }","duration":"185.231188ms","start":"2026-09-09T06:32:19.545446Z","end":"2026-09-09T06:32:19.730677Z","steps":["trace[432008796] 'process raft request' (duration: 185.080225ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:33:48.894985Z","caller":"traceutil/trace.go:171","msg":"trace[1751666565] transaction","detail":"{read_only:false; response_revision:5091; number_of_response:1; }","duration":"119.778465ms","start":"2026-09-09T06:33:48.775177Z","end":"2026-09-09T06:33:48.894956Z","steps":["trace[1751666565] 'process raft request' (duration: 118.423919ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:33:52.877732Z","caller":"traceutil/trace.go:171","msg":"trace[654614469] linearizableReadLoop","detail":"{readStateIndex:5449; appliedIndex:5448; }","duration":"154.406779ms","start":"2026-09-09T06:33:52.723302Z","end":"2026-09-09T06:33:52.877708Z","steps":["trace[654614469] 'read index received' (duration: 154.240236ms)","trace[654614469] 'applied index is now lower than readState.Index' (duration: 165.363µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:33:52.877788Z","caller":"traceutil/trace.go:171","msg":"trace[910404151] transaction","detail":"{read_only:false; response_revision:5242; number_of_response:1; }","duration":"175.457755ms","start":"2026-09-09T06:33:52.702293Z","end":"2026-09-09T06:33:52.87775Z","steps":["trace[910404151] 'process raft request' (duration: 175.21574ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:33:52.878046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.716545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5d346def-7cd9-497d-9536-ae46b946c3af\" ","response":"range_response_count:1 size:3721"} {"level":"info","ts":"2026-09-09T06:33:52.878118Z","caller":"traceutil/trace.go:171","msg":"trace[2096502726] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5d346def-7cd9-497d-9536-ae46b946c3af; range_end:; response_count:1; response_revision:5242; }","duration":"154.838267ms","start":"2026-09-09T06:33:52.723264Z","end":"2026-09-09T06:33:52.878102Z","steps":["trace[2096502726] 'agreement among raft nodes before linearized reading' (duration: 154.526771ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:33:53.239544Z","caller":"traceutil/trace.go:171","msg":"trace[81153904] linearizableReadLoop","detail":"{readStateIndex:5451; appliedIndex:5450; }","duration":"109.361549ms","start":"2026-09-09T06:33:53.130141Z","end":"2026-09-09T06:33:53.239503Z","steps":["trace[81153904] 'read index received' (duration: 109.198256ms)","trace[81153904] 'applied index is now lower than readState.Index' (duration: 162.423µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:33:53.239658Z","caller":"traceutil/trace.go:171","msg":"trace[367742288] transaction","detail":"{read_only:false; response_revision:5244; number_of_response:1; }","duration":"118.057921ms","start":"2026-09-09T06:33:53.121589Z","end":"2026-09-09T06:33:53.239647Z","steps":["trace[367742288] 'process raft request' (duration: 117.781726ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:33:53.239873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.706816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-09T06:33:53.239946Z","caller":"traceutil/trace.go:171","msg":"trace[134773675] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5244; }","duration":"109.830069ms","start":"2026-09-09T06:33:53.130095Z","end":"2026-09-09T06:33:53.239925Z","steps":["trace[134773675] 'agreement among raft nodes before linearized reading' (duration: 109.613414ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:34:15.495629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.362163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-76ce3973-88b0-4ea9-9606-a9b9ea2b2fa3\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-09-09T06:34:15.495715Z","caller":"traceutil/trace.go:171","msg":"trace[1419324716] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-76ce3973-88b0-4ea9-9606-a9b9ea2b2fa3; range_end:; response_count:1; response_revision:5681; }","duration":"176.492265ms","start":"2026-09-09T06:34:15.319205Z","end":"2026-09-09T06:34:15.495697Z","steps":["trace[1419324716] 'range keys from in-memory index tree' (duration: 176.146668ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:34:15.495802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.35644ms","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-09T06:34:15.495894Z","caller":"traceutil/trace.go:171","msg":"trace[894490681] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5681; }","duration":"145.483443ms","start":"2026-09-09T06:34:15.350387Z","end":"2026-09-09T06:34:15.49587Z","steps":["trace[894490681] 'range keys from in-memory index tree' (duration: 144.671477ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:34:15.900058Z","caller":"traceutil/trace.go:171","msg":"trace[478049181] transaction","detail":"{read_only:false; response_revision:5683; number_of_response:1; }","duration":"399.195663ms","start":"2026-09-09T06:34:15.500834Z","end":"2026-09-09T06:34:15.90003Z","steps":["trace[478049181] 'process raft request' (duration: 398.991999ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:34:15.900306Z","caller":"traceutil/trace.go:171","msg":"trace[1524814679] linearizableReadLoop","detail":"{readStateIndex:5903; appliedIndex:5901; }","duration":"399.139911ms","start":"2026-09-09T06:34:15.501131Z","end":"2026-09-09T06:34:15.900271Z","steps":["trace[1524814679] 'read index received' (duration: 27.534053ms)","trace[1524814679] 'applied index is now lower than readState.Index' (duration: 371.603228ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:34:15.900394Z","caller":"traceutil/trace.go:171","msg":"trace[1071215647] transaction","detail":"{read_only:false; response_revision:5684; number_of_response:1; }","duration":"399.160461ms","start":"2026-09-09T06:34:15.501204Z","end":"2026-09-09T06:34:15.900365Z","steps":["trace[1071215647] 'process raft request' (duration: 398.777524ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:34:15.900588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:34:15.501189Z","time spent":"399.282604ms","remote":"127.0.0.1:58034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T06:34:15.900534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:34:15.500809Z","time spent":"399.417086ms","remote":"127.0.0.1:58034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T06:34:15.900661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.682047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-09-09T06:34:15.9007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.556419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:9 size:80106"} {"level":"info","ts":"2026-09-09T06:34:15.900752Z","caller":"traceutil/trace.go:171","msg":"trace[1397226286] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5684; }","duration":"339.788289ms","start":"2026-09-09T06:34:15.560951Z","end":"2026-09-09T06:34:15.90074Z","steps":["trace[1397226286] 'agreement among raft nodes before linearized reading' (duration: 339.485124ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:34:15.900776Z","caller":"traceutil/trace.go:171","msg":"trace[23025041] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:9; response_revision:5684; }","duration":"399.667862ms","start":"2026-09-09T06:34:15.501091Z","end":"2026-09-09T06:34:15.900759Z","steps":["trace[23025041] 'agreement among raft nodes before linearized reading' (duration: 399.321275ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:34:15.900795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:34:15.560892Z","time spent":"339.893531ms","remote":"127.0.0.1:58034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-09-09T06:34:15.900824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:34:15.501063Z","time spent":"399.751253ms","remote":"127.0.0.1:57978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":9,"response size":80128,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-09-09T06:34:16.079034Z","caller":"traceutil/trace.go:171","msg":"trace[1597253674] transaction","detail":"{read_only:false; response_revision:5686; number_of_response:1; }","duration":"170.468556ms","start":"2026-09-09T06:34:15.908527Z","end":"2026-09-09T06:34:16.078996Z","steps":["trace[1597253674] 'process raft request' (duration: 82.300114ms)","trace[1597253674] 'compare' (duration: 87.643882ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:34:34.62014Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2955} {"level":"info","ts":"2026-09-09T06:34:34.695567Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2955,"took":"74.303716ms","hash":1934380816,"current-db-size-bytes":56868864,"current-db-size":"57 MB","current-db-size-in-use-bytes":32317440,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-09T06:34:34.695639Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1934380816,"revision":2955,"compact-revision":-1} {"level":"info","ts":"2026-09-09T06:37:17.881749Z","caller":"traceutil/trace.go:171","msg":"trace[1935386941] transaction","detail":"{read_only:false; response_revision:6981; number_of_response:1; }","duration":"181.315286ms","start":"2026-09-09T06:37:17.7004Z","end":"2026-09-09T06:37:17.881715Z","steps":["trace[1935386941] 'process raft request' (duration: 136.800987ms)","trace[1935386941] 'compare' (duration: 44.219063ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:37:18.70041Z","caller":"traceutil/trace.go:171","msg":"trace[951094893] linearizableReadLoop","detail":"{readStateIndex:7246; appliedIndex:7245; }","duration":"239.538024ms","start":"2026-09-09T06:37:18.460849Z","end":"2026-09-09T06:37:18.700387Z","steps":["trace[951094893] 'read index received' (duration: 239.234758ms)","trace[951094893] 'applied index is now lower than readState.Index' (duration: 302.076µs)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:37:18.700629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.772159ms","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-09T06:37:18.700666Z","caller":"traceutil/trace.go:171","msg":"trace[1105277892] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6983; }","duration":"239.891391ms","start":"2026-09-09T06:37:18.460764Z","end":"2026-09-09T06:37:18.700656Z","steps":["trace[1105277892] 'agreement among raft nodes before linearized reading' (duration: 239.713537ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:18.700904Z","caller":"traceutil/trace.go:171","msg":"trace[1209819469] transaction","detail":"{read_only:false; response_revision:6983; number_of_response:1; }","duration":"256.516562ms","start":"2026-09-09T06:37:18.444334Z","end":"2026-09-09T06:37:18.700851Z","steps":["trace[1209819469] 'process raft request' (duration: 255.813987ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:18.982791Z","caller":"traceutil/trace.go:171","msg":"trace[1978529414] transaction","detail":"{read_only:false; response_revision:6986; number_of_response:1; }","duration":"103.188337ms","start":"2026-09-09T06:37:18.879575Z","end":"2026-09-09T06:37:18.982763Z","steps":["trace[1978529414] 'process raft request' (duration: 103.012874ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:19.859686Z","caller":"traceutil/trace.go:171","msg":"trace[706991276] transaction","detail":"{read_only:false; response_revision:6995; number_of_response:1; }","duration":"123.867204ms","start":"2026-09-09T06:37:19.735783Z","end":"2026-09-09T06:37:19.85965Z","steps":["trace[706991276] 'process raft request' (duration: 123.659369ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:19.938724Z","caller":"traceutil/trace.go:171","msg":"trace[1943186980] transaction","detail":"{read_only:false; response_revision:6996; number_of_response:1; }","duration":"140.430193ms","start":"2026-09-09T06:37:19.798268Z","end":"2026-09-09T06:37:19.938698Z","steps":["trace[1943186980] 'process raft request' (duration: 107.283734ms)","trace[1943186980] 'compare' (duration: 32.917674ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:37:20.156957Z","caller":"traceutil/trace.go:171","msg":"trace[1356428636] linearizableReadLoop","detail":"{readStateIndex:7261; appliedIndex:7260; }","duration":"125.473257ms","start":"2026-09-09T06:37:20.031452Z","end":"2026-09-09T06:37:20.156926Z","steps":["trace[1356428636] 'read index received' (duration: 73.708705ms)","trace[1356428636] 'applied index is now lower than readState.Index' (duration: 51.762912ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:37:20.157274Z","caller":"traceutil/trace.go:171","msg":"trace[1763297373] transaction","detail":"{read_only:false; response_revision:6998; number_of_response:1; }","duration":"136.781996ms","start":"2026-09-09T06:37:20.020429Z","end":"2026-09-09T06:37:20.157211Z","steps":["trace[1763297373] 'process raft request' (duration: 84.714568ms)","trace[1763297373] 'compare' (duration: 51.563118ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:37:20.157362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.890416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-bootstrap\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:37:20.157413Z","caller":"traceutil/trace.go:171","msg":"trace[677152143] range","detail":"{range_begin:/registry/jobs/openstack/keystone-bootstrap; range_end:; response_count:0; response_revision:6998; }","duration":"126.011019ms","start":"2026-09-09T06:37:20.031387Z","end":"2026-09-09T06:37:20.157398Z","steps":["trace[677152143] 'agreement among raft nodes before linearized reading' (duration: 125.816055ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:20.361844Z","caller":"traceutil/trace.go:171","msg":"trace[910949699] transaction","detail":"{read_only:false; response_revision:6999; number_of_response:1; }","duration":"154.547751ms","start":"2026-09-09T06:37:20.207271Z","end":"2026-09-09T06:37:20.361819Z","steps":["trace[910949699] 'process raft request' (duration: 125.333795ms)","trace[910949699] 'compare' (duration: 29.086743ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:37:20.637953Z","caller":"traceutil/trace.go:171","msg":"trace[1235779495] transaction","detail":"{read_only:false; response_revision:7001; number_of_response:1; }","duration":"179.838555ms","start":"2026-09-09T06:37:20.458055Z","end":"2026-09-09T06:37:20.637893Z","steps":["trace[1235779495] 'process raft request' (duration: 179.698392ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:20.775958Z","caller":"traceutil/trace.go:171","msg":"trace[664026699] transaction","detail":"{read_only:false; response_revision:7003; number_of_response:1; }","duration":"132.181339ms","start":"2026-09-09T06:37:20.643752Z","end":"2026-09-09T06:37:20.775934Z","steps":["trace[664026699] 'process raft request' (duration: 131.441154ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:20.776058Z","caller":"traceutil/trace.go:171","msg":"trace[1281251105] transaction","detail":"{read_only:false; response_revision:7004; number_of_response:1; }","duration":"128.938571ms","start":"2026-09-09T06:37:20.647063Z","end":"2026-09-09T06:37:20.776002Z","steps":["trace[1281251105] 'process raft request' (duration: 128.303248ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:20.920951Z","caller":"traceutil/trace.go:171","msg":"trace[1402075969] linearizableReadLoop","detail":"{readStateIndex:7269; appliedIndex:7268; }","duration":"134.657591ms","start":"2026-09-09T06:37:20.786266Z","end":"2026-09-09T06:37:20.920924Z","steps":["trace[1402075969] 'read index received' (duration: 67.944374ms)","trace[1402075969] 'applied index is now lower than readState.Index' (duration: 66.712037ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:37:20.921073Z","caller":"traceutil/trace.go:171","msg":"trace[873665063] transaction","detail":"{read_only:false; response_revision:7006; number_of_response:1; }","duration":"138.481033ms","start":"2026-09-09T06:37:20.782581Z","end":"2026-09-09T06:37:20.921062Z","steps":["trace[873665063] 'process raft request' (duration: 93.340381ms)","trace[873665063] 'compare' (duration: 44.754644ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:37:20.921532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.258654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keystone-bootstrap-dmm6f\" ","response":"range_response_count:1 size:7177"} {"level":"info","ts":"2026-09-09T06:37:20.921593Z","caller":"traceutil/trace.go:171","msg":"trace[1482970293] range","detail":"{range_begin:/registry/pods/openstack/keystone-bootstrap-dmm6f; range_end:; response_count:1; response_revision:7006; }","duration":"135.380187ms","start":"2026-09-09T06:37:20.786198Z","end":"2026-09-09T06:37:20.921578Z","steps":["trace[1482970293] 'agreement among raft nodes before linearized reading' (duration: 135.162702ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:21.88703Z","caller":"traceutil/trace.go:171","msg":"trace[2062953715] transaction","detail":"{read_only:false; response_revision:7017; number_of_response:1; }","duration":"144.223964ms","start":"2026-09-09T06:37:21.742756Z","end":"2026-09-09T06:37:21.88698Z","steps":["trace[2062953715] 'process raft request' (duration: 92.605834ms)","trace[2062953715] 'compare' (duration: 51.346084ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:37:23.15953Z","caller":"traceutil/trace.go:171","msg":"trace[106778035] transaction","detail":"{read_only:false; response_revision:7021; number_of_response:1; }","duration":"149.693479ms","start":"2026-09-09T06:37:23.00979Z","end":"2026-09-09T06:37:23.159483Z","steps":["trace[106778035] 'process raft request' (duration: 76.098526ms)","trace[106778035] 'compare' (duration: 73.338548ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:37:23.293156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.685052ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-09-09T06:37:23.293307Z","caller":"traceutil/trace.go:171","msg":"trace[57640817] linearizableReadLoop","detail":"{readStateIndex:7285; appliedIndex:7283; }","duration":"196.337013ms","start":"2026-09-09T06:37:23.096946Z","end":"2026-09-09T06:37:23.293283Z","steps":["trace[57640817] 'read index received' (duration: 62.170712ms)","trace[57640817] 'applied index is now lower than readState.Index' (duration: 134.164141ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:37:23.293501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.529606ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-09T06:37:23.293578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.346326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-r9xdx\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-09-09T06:37:23.293623Z","caller":"traceutil/trace.go:171","msg":"trace[1248026124] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:7021; }","duration":"112.674749ms","start":"2026-09-09T06:37:23.18093Z","end":"2026-09-09T06:37:23.293605Z","steps":["trace[1248026124] 'range keys from in-memory index tree' (duration: 112.506704ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:23.293648Z","caller":"traceutil/trace.go:171","msg":"trace[1790087664] range","detail":"{range_begin:/registry/pods/openstack/keepalived-r9xdx; range_end:; response_count:1; response_revision:7021; }","duration":"188.426207ms","start":"2026-09-09T06:37:23.105203Z","end":"2026-09-09T06:37:23.293629Z","steps":["trace[1790087664] 'agreement among raft nodes before linearized reading' (duration: 188.214493ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:23.293836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.936981ms","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-09T06:37:23.293893Z","caller":"traceutil/trace.go:171","msg":"trace[82633357] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7021; }","duration":"111.025453ms","start":"2026-09-09T06:37:23.182851Z","end":"2026-09-09T06:37:23.293877Z","steps":["trace[82633357] 'agreement among raft nodes before linearized reading' (duration: 110.8544ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:23.293984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.050722ms","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-09T06:37:23.294097Z","caller":"traceutil/trace.go:171","msg":"trace[1388575468] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7021; }","duration":"100.306507ms","start":"2026-09-09T06:37:23.193763Z","end":"2026-09-09T06:37:23.294069Z","steps":["trace[1388575468] 'agreement among raft nodes before linearized reading' (duration: 99.893629ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:23.294344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.390185ms","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-09-09T06:37:23.294442Z","caller":"traceutil/trace.go:171","msg":"trace[2052835469] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7021; }","duration":"197.528118ms","start":"2026-09-09T06:37:23.096898Z","end":"2026-09-09T06:37:23.294426Z","steps":["trace[2052835469] 'agreement among raft nodes before linearized reading' (duration: 196.500366ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:23.50089Z","caller":"traceutil/trace.go:171","msg":"trace[537311093] linearizableReadLoop","detail":"{readStateIndex:7290; appliedIndex:7289; }","duration":"110.667276ms","start":"2026-09-09T06:37:23.390191Z","end":"2026-09-09T06:37:23.500858Z","steps":["trace[537311093] 'read index received' (duration: 41.723391ms)","trace[537311093] 'applied index is now lower than readState.Index' (duration: 68.942135ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:37:23.500968Z","caller":"traceutil/trace.go:171","msg":"trace[179789369] transaction","detail":"{read_only:false; response_revision:7026; number_of_response:1; }","duration":"149.403373ms","start":"2026-09-09T06:37:23.351515Z","end":"2026-09-09T06:37:23.500919Z","steps":["trace[179789369] 'process raft request' (duration: 80.394987ms)","trace[179789369] 'compare' (duration: 68.795812ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:37:23.501058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.837449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-09T06:37:23.501124Z","caller":"traceutil/trace.go:171","msg":"trace[85946841] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:7026; }","duration":"110.984302ms","start":"2026-09-09T06:37:23.390124Z","end":"2026-09-09T06:37:23.501109Z","steps":["trace[85946841] 'agreement among raft nodes before linearized reading' (duration: 110.841269ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:24.258298Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5898203154397384303,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-09-09T06:37:24.759087Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5898203154397384303,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-09-09T06:37:24.860055Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.117412219s","expected-duration":"1s"} {"level":"info","ts":"2026-09-09T06:37:24.86069Z","caller":"traceutil/trace.go:171","msg":"trace[1330264633] transaction","detail":"{read_only:false; response_revision:7027; number_of_response:1; }","duration":"1.118133534s","start":"2026-09-09T06:37:23.7425Z","end":"2026-09-09T06:37:24.860633Z","steps":["trace[1330264633] 'process raft request' (duration: 1.11792137s)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:24.860952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:37:23.742469Z","time spent":"1.118319328s","remote":"127.0.0.1:57870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":715,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-09T06:37:24.871201Z","caller":"traceutil/trace.go:171","msg":"trace[1556539142] linearizableReadLoop","detail":"{readStateIndex:7293; appliedIndex:7290; }","duration":"1.113317993s","start":"2026-09-09T06:37:23.757865Z","end":"2026-09-09T06:37:24.871183Z","steps":["trace[1556539142] 'read index received' (duration: 1.102665338s)","trace[1556539142] 'applied index is now lower than readState.Index' (duration: 10.651285ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:37:24.871275Z","caller":"traceutil/trace.go:171","msg":"trace[875778567] transaction","detail":"{read_only:false; response_revision:7028; number_of_response:1; }","duration":"977.499727ms","start":"2026-09-09T06:37:23.893725Z","end":"2026-09-09T06:37:24.871225Z","steps":["trace[875778567] 'process raft request' (duration: 977.276522ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:24.871422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:37:23.893695Z","time spent":"977.64028ms","remote":"127.0.0.1:58034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T06:37:24.871497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.113596899s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-09T06:37:24.871565Z","caller":"traceutil/trace.go:171","msg":"trace[1784990616] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7029; }","duration":"1.113741922s","start":"2026-09-09T06:37:23.757786Z","end":"2026-09-09T06:37:24.871528Z","steps":["trace[1784990616] 'agreement among raft nodes before linearized reading' (duration: 1.113536338s)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:24.871605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:37:23.757747Z","time spent":"1.113847725s","remote":"127.0.0.1:58034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-09-09T06:37:24.871396Z","caller":"traceutil/trace.go:171","msg":"trace[467650054] transaction","detail":"{read_only:false; response_revision:7029; number_of_response:1; }","duration":"158.136438ms","start":"2026-09-09T06:37:24.713177Z","end":"2026-09-09T06:37:24.871314Z","steps":["trace[467650054] 'process raft request' (duration: 157.928953ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:24.889477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.240689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-09T06:37:24.889542Z","caller":"traceutil/trace.go:171","msg":"trace[108014007] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:7030; }","duration":"603.356532ms","start":"2026-09-09T06:37:24.286172Z","end":"2026-09-09T06:37:24.889529Z","steps":["trace[108014007] 'agreement among raft nodes before linearized reading' (duration: 603.177868ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:24.889534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.052389727s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/keystone-api\" ","response":"range_response_count:1 size:675"} {"level":"warn","ts":"2026-09-09T06:37:24.889517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"688.733693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:37:24.890274Z","caller":"traceutil/trace.go:171","msg":"trace[100831803] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7030; }","duration":"689.53236ms","start":"2026-09-09T06:37:24.200689Z","end":"2026-09-09T06:37:24.890222Z","steps":["trace[100831803] 'agreement among raft nodes before linearized reading' (duration: 688.669932ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:24.889582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:37:24.286155Z","time spent":"603.417233ms","remote":"127.0.0.1:57966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":29,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-09-09T06:37:24.89035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:37:24.200668Z","time spent":"689.661764ms","remote":"127.0.0.1:57810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-09T06:37:24.889594Z","caller":"traceutil/trace.go:171","msg":"trace[2033383452] range","detail":"{range_begin:/registry/services/endpoints/openstack/keystone-api; range_end:; response_count:1; response_revision:7030; }","duration":"1.052494919s","start":"2026-09-09T06:37:23.837084Z","end":"2026-09-09T06:37:24.889579Z","steps":["trace[2033383452] 'agreement among raft nodes before linearized reading' (duration: 1.052317955s)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:24.890429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:37:23.837052Z","time spent":"1.053357698s","remote":"127.0.0.1:57950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":697,"request content":"key:\"/registry/services/endpoints/openstack/keystone-api\" "} {"level":"warn","ts":"2026-09-09T06:37:24.889599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.884542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:37:24.890537Z","caller":"traceutil/trace.go:171","msg":"trace[570168200] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:7030; }","duration":"267.849453ms","start":"2026-09-09T06:37:24.622667Z","end":"2026-09-09T06:37:24.890516Z","steps":["trace[570168200] 'agreement among raft nodes before linearized reading' (duration: 266.861981ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:24.889626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.052600222s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-domain-manage\" ","response":"range_response_count:1 size:6472"} {"level":"info","ts":"2026-09-09T06:37:24.890736Z","caller":"traceutil/trace.go:171","msg":"trace[857517491] range","detail":"{range_begin:/registry/jobs/openstack/keystone-domain-manage; range_end:; response_count:1; response_revision:7030; }","duration":"1.053734015s","start":"2026-09-09T06:37:23.836972Z","end":"2026-09-09T06:37:24.890706Z","steps":["trace[857517491] 'agreement among raft nodes before linearized reading' (duration: 1.052498029s)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:24.890786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:37:23.836952Z","time spent":"1.053819798s","remote":"127.0.0.1:58018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":6494,"request content":"key:\"/registry/jobs/openstack/keystone-domain-manage\" "} {"level":"warn","ts":"2026-09-09T06:37:24.889628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"734.788426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-09T06:37:24.890889Z","caller":"traceutil/trace.go:171","msg":"trace[232372998] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:7030; }","duration":"736.051383ms","start":"2026-09-09T06:37:24.154817Z","end":"2026-09-09T06:37:24.890868Z","steps":["trace[232372998] 'agreement among raft nodes before linearized reading' (duration: 734.626302ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:24.889824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.309767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-09T06:37:24.890973Z","caller":"traceutil/trace.go:171","msg":"trace[779524123] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:7030; }","duration":"282.507722ms","start":"2026-09-09T06:37:24.608453Z","end":"2026-09-09T06:37:24.89096Z","steps":["trace[779524123] 'agreement among raft nodes before linearized reading' (duration: 281.274025ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:24.891143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:37:24.154762Z","time spent":"736.354239ms","remote":"127.0.0.1:58002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":90,"response size":29,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"info","ts":"2026-09-09T06:37:25.522457Z","caller":"traceutil/trace.go:171","msg":"trace[2029662909] transaction","detail":"{read_only:false; response_revision:7037; number_of_response:1; }","duration":"133.903385ms","start":"2026-09-09T06:37:25.388503Z","end":"2026-09-09T06:37:25.522406Z","steps":["trace[2029662909] 'process raft request' (duration: 101.457691ms)","trace[2029662909] 'compare' (duration: 32.208499ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:37:25.522638Z","caller":"traceutil/trace.go:171","msg":"trace[485004592] linearizableReadLoop","detail":"{readStateIndex:7301; appliedIndex:7299; }","duration":"134.090398ms","start":"2026-09-09T06:37:25.388531Z","end":"2026-09-09T06:37:25.522621Z","steps":["trace[485004592] 'read index received' (duration: 31.78491ms)","trace[485004592] 'applied index is now lower than readState.Index' (duration: 102.304398ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:37:25.522695Z","caller":"traceutil/trace.go:171","msg":"trace[140919996] transaction","detail":"{read_only:false; response_revision:7038; number_of_response:1; }","duration":"134.103758ms","start":"2026-09-09T06:37:25.388568Z","end":"2026-09-09T06:37:25.522672Z","steps":["trace[140919996] 'process raft request' (duration: 133.791542ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:25.522801Z","caller":"traceutil/trace.go:171","msg":"trace[595921308] transaction","detail":"{read_only:false; response_revision:7039; number_of_response:1; }","duration":"134.085448ms","start":"2026-09-09T06:37:25.388696Z","end":"2026-09-09T06:37:25.522781Z","steps":["trace[595921308] 'process raft request' (duration: 133.840513ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:25.523206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.653621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:17 size:150627"} {"level":"info","ts":"2026-09-09T06:37:25.523308Z","caller":"traceutil/trace.go:171","msg":"trace[665711675] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:17; response_revision:7039; }","duration":"134.786174ms","start":"2026-09-09T06:37:25.3885Z","end":"2026-09-09T06:37:25.523286Z","steps":["trace[665711675] 'agreement among raft nodes before linearized reading' (duration: 134.352014ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:27.137654Z","caller":"traceutil/trace.go:171","msg":"trace[2063932611] linearizableReadLoop","detail":"{readStateIndex:7306; appliedIndex:7305; }","duration":"130.32769ms","start":"2026-09-09T06:37:27.007299Z","end":"2026-09-09T06:37:27.137626Z","steps":["trace[2063932611] 'read index received' (duration: 130.086045ms)","trace[2063932611] 'applied index is now lower than readState.Index' (duration: 240.655µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:37:27.137694Z","caller":"traceutil/trace.go:171","msg":"trace[1166893485] transaction","detail":"{read_only:false; response_revision:7042; number_of_response:1; }","duration":"198.430387ms","start":"2026-09-09T06:37:26.939238Z","end":"2026-09-09T06:37:27.137668Z","steps":["trace[1166893485] 'process raft request' (duration: 198.249573ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:27.138038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.734588ms","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-09T06:37:27.138105Z","caller":"traceutil/trace.go:171","msg":"trace[1705087065] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7042; }","duration":"130.858641ms","start":"2026-09-09T06:37:27.007232Z","end":"2026-09-09T06:37:27.138091Z","steps":["trace[1705087065] 'agreement among raft nodes before linearized reading' (duration: 130.517674ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:27.282266Z","caller":"traceutil/trace.go:171","msg":"trace[472256867] transaction","detail":"{read_only:false; response_revision:7043; number_of_response:1; }","duration":"139.740609ms","start":"2026-09-09T06:37:27.142481Z","end":"2026-09-09T06:37:27.282222Z","steps":["trace[472256867] 'process raft request' (duration: 138.159995ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:27.535823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.434873ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T06:37:27.536014Z","caller":"traceutil/trace.go:171","msg":"trace[2007014854] transaction","detail":"{read_only:false; response_revision:7044; number_of_response:1; }","duration":"393.462913ms","start":"2026-09-09T06:37:27.142531Z","end":"2026-09-09T06:37:27.535994Z","steps":["trace[2007014854] 'process raft request' (duration: 279.772684ms)","trace[2007014854] 'compare' (duration: 113.219149ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:37:27.53607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:37:27.142506Z","time spent":"393.536624ms","remote":"127.0.0.1:58034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T06:37:27.536162Z","caller":"traceutil/trace.go:171","msg":"trace[1725827379] linearizableReadLoop","detail":"{readStateIndex:7308; appliedIndex:7306; }","duration":"335.168052ms","start":"2026-09-09T06:37:27.200974Z","end":"2026-09-09T06:37:27.536142Z","steps":["trace[1725827379] 'read index received' (duration: 79.818003ms)","trace[1725827379] 'applied index is now lower than readState.Index' (duration: 255.348449ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:37:27.53628Z","caller":"traceutil/trace.go:171","msg":"trace[456751541] transaction","detail":"{read_only:false; response_revision:7045; number_of_response:1; }","duration":"312.998465ms","start":"2026-09-09T06:37:27.223231Z","end":"2026-09-09T06:37:27.536229Z","steps":["trace[456751541] 'process raft request' (duration: 312.682009ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:27.53635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.379897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:37:27.536396Z","caller":"traceutil/trace.go:171","msg":"trace[182747956] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7045; }","duration":"335.474349ms","start":"2026-09-09T06:37:27.200905Z","end":"2026-09-09T06:37:27.536379Z","steps":["trace[182747956] 'agreement among raft nodes before linearized reading' (duration: 335.401477ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:27.536427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.357949ms","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":"warn","ts":"2026-09-09T06:37:27.536428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:37:27.223205Z","time spent":"313.144948ms","remote":"127.0.0.1:58034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T06:37:27.536433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:37:27.200874Z","time spent":"335.549951ms","remote":"127.0.0.1:57810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-09T06:37:27.536459Z","caller":"traceutil/trace.go:171","msg":"trace[400365085] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:7045; }","duration":"147.42598ms","start":"2026-09-09T06:37:27.389022Z","end":"2026-09-09T06:37:27.536448Z","steps":["trace[400365085] 'agreement among raft nodes before linearized reading' (duration: 147.317288ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:27.536574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.618064ms","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-09-09T06:37:27.536595Z","caller":"traceutil/trace.go:171","msg":"trace[177885381] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7045; }","duration":"110.730606ms","start":"2026-09-09T06:37:27.425858Z","end":"2026-09-09T06:37:27.536588Z","steps":["trace[177885381] 'agreement among raft nodes before linearized reading' (duration: 110.666875ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:28.140619Z","caller":"traceutil/trace.go:171","msg":"trace[612769427] linearizableReadLoop","detail":"{readStateIndex:7316; appliedIndex:7315; }","duration":"209.514622ms","start":"2026-09-09T06:37:27.931076Z","end":"2026-09-09T06:37:28.140591Z","steps":["trace[612769427] 'read index received' (duration: 191.977962ms)","trace[612769427] 'applied index is now lower than readState.Index' (duration: 17.53529ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:37:28.140703Z","caller":"traceutil/trace.go:171","msg":"trace[197878390] transaction","detail":"{read_only:false; response_revision:7052; number_of_response:1; }","duration":"226.058071ms","start":"2026-09-09T06:37:27.914607Z","end":"2026-09-09T06:37:28.140665Z","steps":["trace[197878390] 'process raft request' (duration: 208.46473ms)","trace[197878390] 'compare' (duration: 17.252814ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:37:28.140927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.836688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.213\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-09-09T06:37:28.141003Z","caller":"traceutil/trace.go:171","msg":"trace[865240427] range","detail":"{range_begin:/registry/masterleases/162.253.55.213; range_end:; response_count:1; response_revision:7052; }","duration":"209.964191ms","start":"2026-09-09T06:37:27.931023Z","end":"2026-09-09T06:37:28.140987Z","steps":["trace[865240427] 'agreement among raft nodes before linearized reading' (duration: 209.720426ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:29.346793Z","caller":"traceutil/trace.go:171","msg":"trace[400516204] linearizableReadLoop","detail":"{readStateIndex:7325; appliedIndex:7323; }","duration":"146.091044ms","start":"2026-09-09T06:37:29.200681Z","end":"2026-09-09T06:37:29.346772Z","steps":["trace[400516204] 'read index received' (duration: 28.224646ms)","trace[400516204] 'applied index is now lower than readState.Index' (duration: 117.865558ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:37:29.346896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.194976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:37:29.34687Z","caller":"traceutil/trace.go:171","msg":"trace[709358453] transaction","detail":"{read_only:false; response_revision:7060; number_of_response:1; }","duration":"148.700418ms","start":"2026-09-09T06:37:29.198137Z","end":"2026-09-09T06:37:29.346837Z","steps":["trace[709358453] 'process raft request' (duration: 114.439405ms)","trace[709358453] 'compare' (duration: 34.096819ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:37:29.346925Z","caller":"traceutil/trace.go:171","msg":"trace[1032823976] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7060; }","duration":"146.268837ms","start":"2026-09-09T06:37:29.200647Z","end":"2026-09-09T06:37:29.346916Z","steps":["trace[1032823976] 'agreement among raft nodes before linearized reading' (duration: 146.200586ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:29.68841Z","caller":"traceutil/trace.go:171","msg":"trace[1687963313] transaction","detail":"{read_only:false; response_revision:7062; number_of_response:1; }","duration":"141.354322ms","start":"2026-09-09T06:37:29.547021Z","end":"2026-09-09T06:37:29.688375Z","steps":["trace[1687963313] 'process raft request' (duration: 125.315195ms)","trace[1687963313] 'compare' (duration: 15.752382ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:37:29.814289Z","caller":"traceutil/trace.go:171","msg":"trace[1145805424] transaction","detail":"{read_only:false; response_revision:7067; number_of_response:1; }","duration":"119.712226ms","start":"2026-09-09T06:37:29.694302Z","end":"2026-09-09T06:37:29.814014Z","steps":["trace[1145805424] 'process raft request' (duration: 119.650225ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:29.814363Z","caller":"traceutil/trace.go:171","msg":"trace[2049625841] transaction","detail":"{read_only:false; response_revision:7065; number_of_response:1; }","duration":"121.040734ms","start":"2026-09-09T06:37:29.69328Z","end":"2026-09-09T06:37:29.814321Z","steps":["trace[2049625841] 'process raft request' (duration: 97.191272ms)","trace[2049625841] 'compare' (duration: 23.195519ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:37:29.814653Z","caller":"traceutil/trace.go:171","msg":"trace[1724775039] transaction","detail":"{read_only:false; response_revision:7066; number_of_response:1; }","duration":"121.091396ms","start":"2026-09-09T06:37:29.693543Z","end":"2026-09-09T06:37:29.814634Z","steps":["trace[1724775039] 'process raft request' (duration: 120.316899ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:39:34.627414Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5876} {"level":"info","ts":"2026-09-09T06:39:34.719525Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5876,"took":"90.562071ms","hash":684431184,"current-db-size-bytes":56868864,"current-db-size":"57 MB","current-db-size-in-use-bytes":26320896,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-09T06:39:34.719594Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":684431184,"revision":5876,"compact-revision":2955} {"level":"warn","ts":"2026-09-09T06:40:36.693081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.145438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-09T06:40:36.693176Z","caller":"traceutil/trace.go:171","msg":"trace[1636152873] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:8562; }","duration":"178.283282ms","start":"2026-09-09T06:40:36.514871Z","end":"2026-09-09T06:40:36.693154Z","steps":["trace[1636152873] 'count revisions from in-memory index tree' (duration: 178.083887ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:41:05.8535Z","caller":"traceutil/trace.go:171","msg":"trace[1933805488] transaction","detail":"{read_only:false; response_revision:8775; number_of_response:1; }","duration":"164.656645ms","start":"2026-09-09T06:41:05.688813Z","end":"2026-09-09T06:41:05.85347Z","steps":["trace[1933805488] 'process raft request' (duration: 164.4724ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:41:05.893466Z","caller":"traceutil/trace.go:171","msg":"trace[1656151438] transaction","detail":"{read_only:false; response_revision:8776; number_of_response:1; }","duration":"114.544217ms","start":"2026-09-09T06:41:05.778865Z","end":"2026-09-09T06:41:05.893409Z","steps":["trace[1656151438] 'process raft request' (duration: 114.307822ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:41:19.645718Z","caller":"traceutil/trace.go:171","msg":"trace[903450376] linearizableReadLoop","detail":"{readStateIndex:9216; appliedIndex:9215; }","duration":"253.750204ms","start":"2026-09-09T06:41:19.391941Z","end":"2026-09-09T06:41:19.645691Z","steps":["trace[903450376] 'read index received' (duration: 155.090043ms)","trace[903450376] 'applied index is now lower than readState.Index' (duration: 98.658891ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:41:19.645777Z","caller":"traceutil/trace.go:171","msg":"trace[1971883717] transaction","detail":"{read_only:false; response_revision:8889; number_of_response:1; }","duration":"254.122753ms","start":"2026-09-09T06:41:19.391617Z","end":"2026-09-09T06:41:19.64574Z","steps":["trace[1971883717] 'process raft request' (duration: 155.479241ms)","trace[1971883717] 'compare' (duration: 98.263754ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:41:19.645982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.01854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-2ckhw\" ","response":"range_response_count:1 size:2803"} {"level":"info","ts":"2026-09-09T06:41:19.646039Z","caller":"traceutil/trace.go:171","msg":"trace[625212203] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-2ckhw; range_end:; response_count:1; response_revision:8889; }","duration":"254.121092ms","start":"2026-09-09T06:41:19.391901Z","end":"2026-09-09T06:41:19.646022Z","steps":["trace[625212203] 'agreement among raft nodes before linearized reading' (duration: 253.898528ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:41:20.572343Z","caller":"traceutil/trace.go:171","msg":"trace[38005015] linearizableReadLoop","detail":"{readStateIndex:9221; appliedIndex:9220; }","duration":"130.423623ms","start":"2026-09-09T06:41:20.441899Z","end":"2026-09-09T06:41:20.572323Z","steps":["trace[38005015] 'read index received' (duration: 130.28514ms)","trace[38005015] 'applied index is now lower than readState.Index' (duration: 137.423µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:41:20.572407Z","caller":"traceutil/trace.go:171","msg":"trace[2137963778] transaction","detail":"{read_only:false; response_revision:8894; number_of_response:1; }","duration":"206.10309ms","start":"2026-09-09T06:41:20.366271Z","end":"2026-09-09T06:41:20.572374Z","steps":["trace[2137963778] 'process raft request' (duration: 205.900835ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:41:20.572572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.655307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-09T06:41:20.572662Z","caller":"traceutil/trace.go:171","msg":"trace[326212503] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:8894; }","duration":"130.79494ms","start":"2026-09-09T06:41:20.441848Z","end":"2026-09-09T06:41:20.572643Z","steps":["trace[326212503] 'agreement among raft nodes before linearized reading' (duration: 130.579076ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:41:20.612591Z","caller":"traceutil/trace.go:171","msg":"trace[163255690] transaction","detail":"{read_only:false; response_revision:8895; number_of_response:1; }","duration":"147.266467ms","start":"2026-09-09T06:41:20.465301Z","end":"2026-09-09T06:41:20.612568Z","steps":["trace[163255690] 'process raft request' (duration: 122.28205ms)","trace[163255690] 'compare' (duration: 24.814064ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:41:34.779733Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000248e.wal"} {"level":"info","ts":"2026-09-09T06:41:37.225274Z","caller":"traceutil/trace.go:171","msg":"trace[1670433037] transaction","detail":"{read_only:false; response_revision:9046; number_of_response:1; }","duration":"127.904988ms","start":"2026-09-09T06:41:37.097339Z","end":"2026-09-09T06:41:37.225244Z","steps":["trace[1670433037] 'process raft request' (duration: 90.848356ms)","trace[1670433037] 'compare' (duration: 36.842978ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:41:37.225285Z","caller":"traceutil/trace.go:171","msg":"trace[577557460] transaction","detail":"{read_only:false; response_revision:9047; number_of_response:1; }","duration":"127.475639ms","start":"2026-09-09T06:41:37.097777Z","end":"2026-09-09T06:41:37.225252Z","steps":["trace[577557460] 'process raft request' (duration: 127.381857ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:42:37.734953Z","caller":"traceutil/trace.go:171","msg":"trace[1447762928] linearizableReadLoop","detail":"{readStateIndex:9994; appliedIndex:9992; }","duration":"156.289898ms","start":"2026-09-09T06:42:37.578639Z","end":"2026-09-09T06:42:37.734929Z","steps":["trace[1447762928] 'read index received' (duration: 94.374581ms)","trace[1447762928] 'applied index is now lower than readState.Index' (duration: 61.914097ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:42:37.735017Z","caller":"traceutil/trace.go:171","msg":"trace[307007475] transaction","detail":"{read_only:false; response_revision:9646; number_of_response:1; }","duration":"162.147982ms","start":"2026-09-09T06:42:37.572833Z","end":"2026-09-09T06:42:37.734981Z","steps":["trace[307007475] 'process raft request' (duration: 100.103152ms)","trace[307007475] 'compare' (duration: 61.797705ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:42:37.735108Z","caller":"traceutil/trace.go:171","msg":"trace[540062222] transaction","detail":"{read_only:false; response_revision:9647; number_of_response:1; }","duration":"162.049408ms","start":"2026-09-09T06:42:37.573015Z","end":"2026-09-09T06:42:37.735064Z","steps":["trace[540062222] 'process raft request' (duration: 161.864785ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:42:37.735236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.555093ms","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-09T06:42:37.735289Z","caller":"traceutil/trace.go:171","msg":"trace[1516672952] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:9647; }","duration":"156.675925ms","start":"2026-09-09T06:42:37.578599Z","end":"2026-09-09T06:42:37.735275Z","steps":["trace[1516672952] 'agreement among raft nodes before linearized reading' (duration: 156.477761ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:42:38.976886Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"43ce62d0a10d51da","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-09T06:42:38.984793Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-09-09T06:42:38.984885Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-09-09T06:44:34.634634Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8037} {"level":"info","ts":"2026-09-09T06:44:34.700581Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8037,"took":"63.463849ms","hash":616066946,"current-db-size-bytes":56868864,"current-db-size":"57 MB","current-db-size-in-use-bytes":28786688,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-09-09T06:44:34.700661Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":616066946,"revision":8037,"compact-revision":5876} {"level":"warn","ts":"2026-09-09T06:46:18.963084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.281862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-09-09T06:46:18.963212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.31206ms","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-09T06:46:18.963235Z","caller":"traceutil/trace.go:171","msg":"trace[657447333] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:11475; }","duration":"123.503025ms","start":"2026-09-09T06:46:18.83971Z","end":"2026-09-09T06:46:18.963213Z","steps":["trace[657447333] 'count revisions from in-memory index tree' (duration: 123.204239ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:46:18.963262Z","caller":"traceutil/trace.go:171","msg":"trace[1634792931] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:11475; }","duration":"105.459813ms","start":"2026-09-09T06:46:18.857786Z","end":"2026-09-09T06:46:18.963246Z","steps":["trace[1634792931] 'range keys from in-memory index tree' (duration: 105.104416ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:46:18.963366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.814287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-09T06:46:18.963434Z","caller":"traceutil/trace.go:171","msg":"trace[1453907674] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:11475; }","duration":"110.988021ms","start":"2026-09-09T06:46:18.852428Z","end":"2026-09-09T06:46:18.963416Z","steps":["trace[1453907674] 'range keys from in-memory index tree' (duration: 110.676584ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:46:18.963657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.767651ms","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-09-09T06:46:18.96388Z","caller":"traceutil/trace.go:171","msg":"trace[1349709655] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:11475; }","duration":"116.17991ms","start":"2026-09-09T06:46:18.847571Z","end":"2026-09-09T06:46:18.963751Z","steps":["trace[1349709655] 'range keys from in-memory index tree' (duration: 115.18981ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:49:34.641683Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10487} {"level":"info","ts":"2026-09-09T06:49:34.728942Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10487,"took":"84.968956ms","hash":765263483,"current-db-size-bytes":56868864,"current-db-size":"57 MB","current-db-size-in-use-bytes":27521024,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-09T06:49:34.729014Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":765263483,"revision":10487,"compact-revision":8037} {"level":"info","ts":"2026-09-09T06:52:26.942671Z","caller":"traceutil/trace.go:171","msg":"trace[1773199245] transaction","detail":"{read_only:false; response_revision:13961; number_of_response:1; }","duration":"111.460918ms","start":"2026-09-09T06:52:26.831169Z","end":"2026-09-09T06:52:26.94263Z","steps":["trace[1773199245] 'process raft request' (duration: 111.287924ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:53:23.382617Z","caller":"traceutil/trace.go:171","msg":"trace[1543167557] transaction","detail":"{read_only:false; response_revision:14460; number_of_response:1; }","duration":"109.175388ms","start":"2026-09-09T06:53:23.273418Z","end":"2026-09-09T06:53:23.382594Z","steps":["trace[1543167557] 'process raft request' (duration: 12.71099ms)","trace[1543167557] 'compare' (duration: 96.218143ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:53:23.382522Z","caller":"traceutil/trace.go:171","msg":"trace[1356693287] linearizableReadLoop","detail":"{readStateIndex:14971; appliedIndex:14970; }","duration":"102.104247ms","start":"2026-09-09T06:53:23.280394Z","end":"2026-09-09T06:53:23.382498Z","steps":["trace[1356693287] 'read index received' (duration: 5.734191ms)","trace[1356693287] 'applied index is now lower than readState.Index' (duration: 96.369096ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:53:23.38485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.401387ms","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-09T06:53:23.385461Z","caller":"traceutil/trace.go:171","msg":"trace[428414091] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:14460; }","duration":"105.071631ms","start":"2026-09-09T06:53:23.280356Z","end":"2026-09-09T06:53:23.385039Z","steps":["trace[428414091] 'agreement among raft nodes before linearized reading' (duration: 102.69554ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:54:34.648023Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12754} {"level":"info","ts":"2026-09-09T06:54:34.734903Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12754,"took":"84.346203ms","hash":3046425616,"current-db-size-bytes":56868864,"current-db-size":"57 MB","current-db-size-in-use-bytes":30113792,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-09T06:54:34.734979Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3046425616,"revision":12754,"compact-revision":10487} {"level":"info","ts":"2026-09-09T06:59:34.654611Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15195} {"level":"info","ts":"2026-09-09T06:59:34.75462Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15195,"took":"96.712113ms","hash":3776784299,"current-db-size-bytes":56868864,"current-db-size":"57 MB","current-db-size-in-use-bytes":31604736,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-09T06:59:34.754691Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3776784299,"revision":15195,"compact-revision":12754} {"level":"info","ts":"2026-09-09T07:00:33.335903Z","caller":"traceutil/trace.go:171","msg":"trace[1823923143] transaction","detail":"{read_only:false; response_revision:17881; number_of_response:1; }","duration":"271.362342ms","start":"2026-09-09T07:00:33.064515Z","end":"2026-09-09T07:00:33.335877Z","steps":["trace[1823923143] 'process raft request' (duration: 271.208179ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T07:00:33.460579Z","caller":"traceutil/trace.go:171","msg":"trace[1479117695] linearizableReadLoop","detail":"{readStateIndex:18500; appliedIndex:18499; }","duration":"351.46333ms","start":"2026-09-09T07:00:33.109098Z","end":"2026-09-09T07:00:33.460562Z","steps":["trace[1479117695] 'read index received' (duration: 227.082464ms)","trace[1479117695] 'applied index is now lower than readState.Index' (duration: 124.380146ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T07:00:33.460656Z","caller":"traceutil/trace.go:171","msg":"trace[306708820] transaction","detail":"{read_only:false; response_revision:17882; number_of_response:1; }","duration":"358.142979ms","start":"2026-09-09T07:00:33.102457Z","end":"2026-09-09T07:00:33.4606Z","steps":["trace[306708820] 'process raft request' (duration: 345.61261ms)","trace[306708820] 'compare' (duration: 12.374095ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T07:00:33.460805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T07:00:33.102409Z","time spent":"358.316262ms","remote":"127.0.0.1:58034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T07:00:33.461021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.891079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-09-09T07:00:33.461123Z","caller":"traceutil/trace.go:171","msg":"trace[668693799] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17882; }","duration":"352.039503ms","start":"2026-09-09T07:00:33.109063Z","end":"2026-09-09T07:00:33.461103Z","steps":["trace[668693799] 'agreement among raft nodes before linearized reading' (duration: 351.588953ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T07:00:33.461232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T07:00:33.109051Z","time spent":"352.164555ms","remote":"127.0.0.1:58034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-09T07:00:33.59024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.669627ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-09-09T07:00:33.590485Z","caller":"traceutil/trace.go:171","msg":"trace[559160903] linearizableReadLoop","detail":"{readStateIndex:18501; appliedIndex:18500; }","duration":"129.83907ms","start":"2026-09-09T07:00:33.460625Z","end":"2026-09-09T07:00:33.590464Z","steps":["trace[559160903] 'read index received' (duration: 31.871µs)","trace[559160903] 'applied index is now lower than readState.Index' (duration: 129.805769ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T07:00:33.591323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.107496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-6hklq\" ","response":"range_response_count:1 size:20763"} {"level":"info","ts":"2026-09-09T07:00:33.59137Z","caller":"traceutil/trace.go:171","msg":"trace[955822024] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-6hklq; range_end:; response_count:1; response_revision:17882; }","duration":"160.196549ms","start":"2026-09-09T07:00:33.431161Z","end":"2026-09-09T07:00:33.591357Z","steps":["trace[955822024] 'agreement among raft nodes before linearized reading' (duration: 160.019995ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T07:00:33.591359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.454781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-09T07:00:33.591434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.111281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-09T07:00:33.591495Z","caller":"traceutil/trace.go:171","msg":"trace[660293245] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17882; }","duration":"391.216643ms","start":"2026-09-09T07:00:33.20026Z","end":"2026-09-09T07:00:33.591476Z","steps":["trace[660293245] 'agreement among raft nodes before linearized reading' (duration: 391.117491ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T07:00:33.591513Z","caller":"traceutil/trace.go:171","msg":"trace[2086132590] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:17882; }","duration":"324.672036ms","start":"2026-09-09T07:00:33.266794Z","end":"2026-09-09T07:00:33.591466Z","steps":["trace[2086132590] 'agreement among raft nodes before linearized reading' (duration: 324.4007ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T07:00:33.591533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T07:00:33.200238Z","time spent":"391.285575ms","remote":"127.0.0.1:57798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-09T07:00:33.591578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T07:00:33.26677Z","time spent":"324.795138ms","remote":"127.0.0.1:41668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":28,"request content":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true "} {"level":"info","ts":"2026-09-09T07:00:33.592008Z","caller":"traceutil/trace.go:171","msg":"trace[1808490307] transaction","detail":"{read_only:false; response_revision:17883; number_of_response:1; }","duration":"126.774535ms","start":"2026-09-09T07:00:33.465218Z","end":"2026-09-09T07:00:33.591993Z","steps":["trace[1808490307] 'process raft request' (duration: 125.133642ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T07:00:33.608243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.423745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-09T07:00:33.608333Z","caller":"traceutil/trace.go:171","msg":"trace[301412383] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:17883; }","duration":"106.557678ms","start":"2026-09-09T07:00:33.501749Z","end":"2026-09-09T07:00:33.608307Z","steps":["trace[301412383] 'agreement among raft nodes before linearized reading' (duration: 90.223689ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T07:03:16.569705Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"43ce62d0a10d51da","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-09T07:03:16.576165Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-09-09T07:03:16.576675Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-09-09T07:04:34.661453Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17417} {"level":"info","ts":"2026-09-09T07:04:34.766853Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17417,"took":"101.437998ms","hash":230382273,"current-db-size-bytes":56868864,"current-db-size":"57 MB","current-db-size-in-use-bytes":34942976,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-09-09T07:04:34.766922Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":230382273,"revision":17417,"compact-revision":15195} {"level":"info","ts":"2026-09-09T07:06:04.010303Z","caller":"traceutil/trace.go:171","msg":"trace[1780614085] transaction","detail":"{read_only:false; response_revision:20526; number_of_response:1; }","duration":"108.937648ms","start":"2026-09-09T07:06:03.901338Z","end":"2026-09-09T07:06:04.010276Z","steps":["trace[1780614085] 'process raft request' (duration: 108.747984ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T07:06:09.57318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.021162ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-09T07:06:09.573338Z","caller":"traceutil/trace.go:171","msg":"trace[1944290724] linearizableReadLoop","detail":"{readStateIndex:21260; appliedIndex:21259; }","duration":"144.873772ms","start":"2026-09-09T07:06:09.428443Z","end":"2026-09-09T07:06:09.573317Z","steps":["trace[1944290724] 'read index received' (duration: 17.498963ms)","trace[1944290724] 'applied index is now lower than readState.Index' (duration: 127.372929ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T07:06:09.573498Z","caller":"traceutil/trace.go:171","msg":"trace[671882501] transaction","detail":"{read_only:false; response_revision:20554; number_of_response:1; }","duration":"241.197362ms","start":"2026-09-09T07:06:09.33225Z","end":"2026-09-09T07:06:09.573448Z","steps":["trace[671882501] 'process raft request' (duration: 113.789902ms)","trace[671882501] 'compare' (duration: 126.885969ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T07:06:09.573563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.111347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-09-09T07:06:09.573612Z","caller":"traceutil/trace.go:171","msg":"trace[455009665] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20554; }","duration":"145.203689ms","start":"2026-09-09T07:06:09.428391Z","end":"2026-09-09T07:06:09.573595Z","steps":["trace[455009665] 'agreement among raft nodes before linearized reading' (duration: 144.984125ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T07:06:09.785312Z","caller":"traceutil/trace.go:171","msg":"trace[1154604347] linearizableReadLoop","detail":"{readStateIndex:21261; appliedIndex:21260; }","duration":"142.825109ms","start":"2026-09-09T07:06:09.642468Z","end":"2026-09-09T07:06:09.785293Z","steps":["trace[1154604347] 'read index received' (duration: 142.675066ms)","trace[1154604347] 'applied index is now lower than readState.Index' (duration: 149.223µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T07:06:09.785394Z","caller":"traceutil/trace.go:171","msg":"trace[1303618398] transaction","detail":"{read_only:false; response_revision:20555; number_of_response:1; }","duration":"207.141607ms","start":"2026-09-09T07:06:09.578205Z","end":"2026-09-09T07:06:09.785347Z","steps":["trace[1303618398] 'process raft request' (duration: 206.977314ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T07:06:09.785551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.061083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-09-09T07:06:09.78564Z","caller":"traceutil/trace.go:171","msg":"trace[1356399759] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20555; }","duration":"143.202307ms","start":"2026-09-09T07:06:09.642413Z","end":"2026-09-09T07:06:09.785616Z","steps":["trace[1356399759] 'agreement among raft nodes before linearized reading' (duration: 142.951081ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T07:06:09.946813Z","caller":"traceutil/trace.go:171","msg":"trace[565241862] transaction","detail":"{read_only:false; response_revision:20556; number_of_response:1; }","duration":"157.151283ms","start":"2026-09-09T07:06:09.789626Z","end":"2026-09-09T07:06:09.946778Z","steps":["trace[565241862] 'process raft request' (duration: 131.320134ms)","trace[565241862] 'compare' (duration: 25.683556ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T07:06:10.156286Z","caller":"traceutil/trace.go:171","msg":"trace[1570013081] transaction","detail":"{read_only:false; response_revision:20557; number_of_response:1; }","duration":"125.762686ms","start":"2026-09-09T07:06:10.030487Z","end":"2026-09-09T07:06:10.15625Z","steps":["trace[1570013081] 'process raft request' (duration: 82.848833ms)","trace[1570013081] 'compare' (duration: 42.7598ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T07:06:10.828883Z","caller":"traceutil/trace.go:171","msg":"trace[2124947998] linearizableReadLoop","detail":"{readStateIndex:21264; appliedIndex:21263; }","duration":"147.719062ms","start":"2026-09-09T07:06:10.681114Z","end":"2026-09-09T07:06:10.828833Z","steps":["trace[2124947998] 'read index received' (duration: 147.576159ms)","trace[2124947998] 'applied index is now lower than readState.Index' (duration: 141.803µs)"],"step_count":2} {"level":"warn","ts":"2026-09-09T07:06:10.829083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.950247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-09T07:06:10.829139Z","caller":"traceutil/trace.go:171","msg":"trace[1538533946] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20558; }","duration":"148.053369ms","start":"2026-09-09T07:06:10.681069Z","end":"2026-09-09T07:06:10.829122Z","steps":["trace[1538533946] 'agreement among raft nodes before linearized reading' (duration: 147.895286ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T07:06:10.82905Z","caller":"traceutil/trace.go:171","msg":"trace[328963840] transaction","detail":"{read_only:false; response_revision:20558; number_of_response:1; }","duration":"166.666236ms","start":"2026-09-09T07:06:10.662306Z","end":"2026-09-09T07:06:10.828972Z","steps":["trace[328963840] 'process raft request' (duration: 166.37483ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T07:06:10.982422Z","caller":"traceutil/trace.go:171","msg":"trace[456993712] transaction","detail":"{read_only:false; response_revision:20559; number_of_response:1; }","duration":"221.025782ms","start":"2026-09-09T07:06:10.76136Z","end":"2026-09-09T07:06:10.982386Z","steps":["trace[456993712] 'process raft request' (duration: 167.965043ms)","trace[456993712] 'compare' (duration: 52.846135ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T07:06:10.982546Z","caller":"traceutil/trace.go:171","msg":"trace[1705013072] linearizableReadLoop","detail":"{readStateIndex:21265; appliedIndex:21264; }","duration":"153.552327ms","start":"2026-09-09T07:06:10.828944Z","end":"2026-09-09T07:06:10.982497Z","steps":["trace[1705013072] 'read index received' (duration: 100.385046ms)","trace[1705013072] 'applied index is now lower than readState.Index' (duration: 53.163691ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T07:06:10.982974Z","caller":"traceutil/trace.go:171","msg":"trace[1523022761] transaction","detail":"{read_only:false; response_revision:20560; number_of_response:1; }","duration":"149.207694ms","start":"2026-09-09T07:06:10.833748Z","end":"2026-09-09T07:06:10.982956Z","steps":["trace[1523022761] 'process raft request' (duration: 148.99439ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T07:06:10.983281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.778769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-09-09T07:06:10.983339Z","caller":"traceutil/trace.go:171","msg":"trace[1261418765] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:20560; }","duration":"128.881502ms","start":"2026-09-09T07:06:10.85444Z","end":"2026-09-09T07:06:10.983322Z","steps":["trace[1261418765] 'agreement among raft nodes before linearized reading' (duration: 128.463723ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T07:06:10.983303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.575401ms","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-09T07:06:10.983486Z","caller":"traceutil/trace.go:171","msg":"trace[1667486246] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20559; }","duration":"289.772435ms","start":"2026-09-09T07:06:10.693637Z","end":"2026-09-09T07:06:10.983409Z","steps":["trace[1667486246] 'agreement among raft nodes before linearized reading' (duration: 288.973448ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T07:09:34.668077Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19935} {"level":"info","ts":"2026-09-09T07:09:34.787925Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19935,"took":"116.12242ms","hash":298812570,"current-db-size-bytes":56868864,"current-db-size":"57 MB","current-db-size-in-use-bytes":30494720,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-09T07:09:34.787994Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":298812570,"revision":19935,"compact-revision":17417} {"level":"info","ts":"2026-09-09T07:09:58.93906Z","caller":"traceutil/trace.go:171","msg":"trace[1539275659] linearizableReadLoop","detail":"{readStateIndex:23719; appliedIndex:23718; }","duration":"103.022471ms","start":"2026-09-09T07:09:58.836012Z","end":"2026-09-09T07:09:58.939035Z","steps":["trace[1539275659] 'read index received' (duration: 99.532027ms)","trace[1539275659] 'applied index is now lower than readState.Index' (duration: 3.489264ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T07:09:58.939133Z","caller":"traceutil/trace.go:171","msg":"trace[637457522] transaction","detail":"{read_only:false; response_revision:22939; number_of_response:1; }","duration":"124.885357ms","start":"2026-09-09T07:09:58.814216Z","end":"2026-09-09T07:09:58.939102Z","steps":["trace[637457522] 'process raft request' (duration: 121.339142ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T07:09:58.939667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.635055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-09T07:09:58.939712Z","caller":"traceutil/trace.go:171","msg":"trace[1370548375] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:22939; }","duration":"103.720947ms","start":"2026-09-09T07:09:58.835979Z","end":"2026-09-09T07:09:58.9397Z","steps":["trace[1370548375] 'agreement among raft nodes before linearized reading' (duration: 103.163125ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T07:09:59.263696Z","caller":"traceutil/trace.go:171","msg":"trace[1846818604] transaction","detail":"{read_only:false; response_revision:22940; number_of_response:1; }","duration":"209.324802ms","start":"2026-09-09T07:09:59.054339Z","end":"2026-09-09T07:09:59.263663Z","steps":["trace[1846818604] 'process raft request' (duration: 207.585766ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T07:09:59.26384Z","caller":"traceutil/trace.go:171","msg":"trace[1214119202] transaction","detail":"{read_only:false; response_revision:22941; number_of_response:1; }","duration":"146.822723ms","start":"2026-09-09T07:09:59.116979Z","end":"2026-09-09T07:09:59.263802Z","steps":["trace[1214119202] 'process raft request' (duration: 146.68223ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T07:09:59.264153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.929148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-09-09T07:09:59.264211Z","caller":"traceutil/trace.go:171","msg":"trace[211249394] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:22941; }","duration":"204.030441ms","start":"2026-09-09T07:09:59.060165Z","end":"2026-09-09T07:09:59.264196Z","steps":["trace[211249394] 'agreement among raft nodes before linearized reading' (duration: 203.866337ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T07:09:59.263972Z","caller":"traceutil/trace.go:171","msg":"trace[905089154] linearizableReadLoop","detail":"{readStateIndex:23720; appliedIndex:23719; }","duration":"203.572111ms","start":"2026-09-09T07:09:59.060214Z","end":"2026-09-09T07:09:59.263786Z","steps":["trace[905089154] 'read index received' (duration: 201.682891ms)","trace[905089154] 'applied index is now lower than readState.Index' (duration: 1.88716ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T07:14:34.675607Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22196} {"level":"info","ts":"2026-09-09T07:14:34.788437Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22196,"took":"108.814815ms","hash":2827151190,"current-db-size-bytes":56868864,"current-db-size":"57 MB","current-db-size-in-use-bytes":42237952,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-09-09T07:14:34.788526Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2827151190,"revision":22196,"compact-revision":19935} {"level":"info","ts":"2026-09-09T07:19:34.683246Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25304} {"level":"info","ts":"2026-09-09T07:19:34.838571Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25304,"took":"149.620003ms","hash":2704528735,"current-db-size-bytes":56868864,"current-db-size":"57 MB","current-db-size-in-use-bytes":29675520,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-09T07:19:34.838652Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2704528735,"revision":25304,"compact-revision":22196}