{"level":"warn","ts":"2026-10-16T09:14:30.204354Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-16T09:14:30.20449Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.14:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.14:2380","--initial-cluster=instance=https://162.253.55.14:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.14:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.14:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-10-16T09:14:30.2046Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-16T09:14:30.204623Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.14:2380"]} {"level":"info","ts":"2026-10-16T09:14:30.204658Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-16T09:14:30.206153Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.14:2379"]} {"level":"info","ts":"2026-10-16T09:14:30.20637Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.14:2380"],"listen-peer-urls":["https://162.253.55.14:2380"],"advertise-client-urls":["https://162.253.55.14:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.14:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.14:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-10-16T09:14:30.210054Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.294461ms"} {"level":"info","ts":"2026-10-16T09:14:30.217912Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5db0a1b394e67b72","cluster-id":"cfda6ccfe21bda40"} {"level":"info","ts":"2026-10-16T09:14:30.218038Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5db0a1b394e67b72 switched to configuration voters=()"} {"level":"info","ts":"2026-10-16T09:14:30.21808Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5db0a1b394e67b72 became follower at term 0"} {"level":"info","ts":"2026-10-16T09:14:30.218109Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5db0a1b394e67b72 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-16T09:14:30.218134Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5db0a1b394e67b72 became follower at term 1"} {"level":"info","ts":"2026-10-16T09:14:30.218205Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5db0a1b394e67b72 switched to configuration voters=(6751073634097724274)"} {"level":"warn","ts":"2026-10-16T09:14:30.225634Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-16T09:14:30.227439Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-16T09:14:30.228903Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-10-16T09:14:30.232035Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5db0a1b394e67b72","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-16T09:14:30.2325Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-16T09:14:30.232629Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-16T09:14:30.232646Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-16T09:14:30.232496Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5db0a1b394e67b72","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-16T09:14:30.235465Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5db0a1b394e67b72 switched to configuration voters=(6751073634097724274)"} {"level":"info","ts":"2026-10-16T09:14:30.235746Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cfda6ccfe21bda40","local-member-id":"5db0a1b394e67b72","added-peer-id":"5db0a1b394e67b72","added-peer-peer-urls":["https://162.253.55.14:2380"]} {"level":"info","ts":"2026-10-16T09:14:30.239326Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-16T09:14:30.239507Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.14:2380"} {"level":"info","ts":"2026-10-16T09:14:30.239595Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.14:2380"} {"level":"info","ts":"2026-10-16T09:14:30.239638Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5db0a1b394e67b72","initial-advertise-peer-urls":["https://162.253.55.14:2380"],"listen-peer-urls":["https://162.253.55.14:2380"],"advertise-client-urls":["https://162.253.55.14:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.14:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-16T09:14:30.239662Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-16T09:14:30.618605Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5db0a1b394e67b72 is starting a new election at term 1"} {"level":"info","ts":"2026-10-16T09:14:30.618667Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5db0a1b394e67b72 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-16T09:14:30.618697Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5db0a1b394e67b72 received MsgPreVoteResp from 5db0a1b394e67b72 at term 1"} {"level":"info","ts":"2026-10-16T09:14:30.618712Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5db0a1b394e67b72 became candidate at term 2"} {"level":"info","ts":"2026-10-16T09:14:30.61872Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5db0a1b394e67b72 received MsgVoteResp from 5db0a1b394e67b72 at term 2"} {"level":"info","ts":"2026-10-16T09:14:30.618734Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5db0a1b394e67b72 became leader at term 2"} {"level":"info","ts":"2026-10-16T09:14:30.618743Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5db0a1b394e67b72 elected leader 5db0a1b394e67b72 at term 2"} {"level":"info","ts":"2026-10-16T09:14:30.619725Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5db0a1b394e67b72","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.14:2379]}","request-path":"/0/members/5db0a1b394e67b72/attributes","cluster-id":"cfda6ccfe21bda40","publish-timeout":"7s"} {"level":"info","ts":"2026-10-16T09:14:30.619838Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-16T09:14:30.62012Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-16T09:14:30.620324Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-16T09:14:30.620924Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-16T09:14:30.620965Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-16T09:14:30.621315Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cfda6ccfe21bda40","local-member-id":"5db0a1b394e67b72","cluster-version":"3.5"} {"level":"info","ts":"2026-10-16T09:14:30.621498Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-16T09:14:30.621539Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-16T09:14:30.624389Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-16T09:14:30.624754Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.14:2379"} {"level":"warn","ts":"2026-10-16T09:15:14.048713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.121315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-16T09:15:14.048807Z","caller":"traceutil/trace.go:171","msg":"trace[601447915] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:541; }","duration":"154.279609ms","start":"2026-10-16T09:15:13.894509Z","end":"2026-10-16T09:15:14.048789Z","steps":["trace[601447915] 'agreement among raft nodes before linearized reading' (duration: 154.097264ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:15:14.048794Z","caller":"traceutil/trace.go:171","msg":"trace[940149809] linearizableReadLoop","detail":"{readStateIndex:562; appliedIndex:561; }","duration":"153.875738ms","start":"2026-10-16T09:15:13.894541Z","end":"2026-10-16T09:15:14.048416Z","steps":["trace[940149809] 'read index received' (duration: 147.137221ms)","trace[940149809] 'applied index is now lower than readState.Index' (duration: 6.736807ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T09:15:20.904906Z","caller":"traceutil/trace.go:171","msg":"trace[1267756228] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"136.3193ms","start":"2026-10-16T09:15:20.768527Z","end":"2026-10-16T09:15:20.904846Z","steps":["trace[1267756228] 'process raft request' (duration: 136.108194ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:15:21.300228Z","caller":"traceutil/trace.go:171","msg":"trace[1977116475] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"187.352669ms","start":"2026-10-16T09:15:21.112848Z","end":"2026-10-16T09:15:21.300201Z","steps":["trace[1977116475] 'process raft request' (duration: 186.821764ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:15:36.319995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.395612ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-16T09:15:36.320107Z","caller":"traceutil/trace.go:171","msg":"trace[430742065] transaction","detail":"{read_only:false; response_revision:812; number_of_response:1; }","duration":"158.334782ms","start":"2026-10-16T09:15:36.161754Z","end":"2026-10-16T09:15:36.320089Z","steps":["trace[430742065] 'process raft request' (duration: 22.899401ms)","trace[430742065] 'compare' (duration: 134.30876ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T09:16:23.26152Z","caller":"traceutil/trace.go:171","msg":"trace[1869691626] transaction","detail":"{read_only:false; response_revision:1359; number_of_response:1; }","duration":"103.142697ms","start":"2026-10-16T09:16:23.158336Z","end":"2026-10-16T09:16:23.261479Z","steps":["trace[1869691626] 'process raft request' (duration: 89.905746ms)","trace[1869691626] 'compare' (duration: 13.050656ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T09:16:55.353964Z","caller":"traceutil/trace.go:171","msg":"trace[536653799] transaction","detail":"{read_only:false; response_revision:1906; number_of_response:1; }","duration":"164.549077ms","start":"2026-10-16T09:16:55.189346Z","end":"2026-10-16T09:16:55.353895Z","steps":["trace[536653799] 'process raft request' (duration: 164.245409ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:16:55.593013Z","caller":"traceutil/trace.go:171","msg":"trace[513595252] linearizableReadLoop","detail":"{readStateIndex:1984; appliedIndex:1983; }","duration":"169.813014ms","start":"2026-10-16T09:16:55.423107Z","end":"2026-10-16T09:16:55.59292Z","steps":["trace[513595252] 'read index received' (duration: 169.518636ms)","trace[513595252] 'applied index is now lower than readState.Index' (duration: 293.378µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T09:16:55.593137Z","caller":"traceutil/trace.go:171","msg":"trace[1965054712] transaction","detail":"{read_only:false; response_revision:1907; number_of_response:1; }","duration":"230.56212ms","start":"2026-10-16T09:16:55.362541Z","end":"2026-10-16T09:16:55.593103Z","steps":["trace[1965054712] 'process raft request' (duration: 230.21416ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:16:55.593251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.131032ms","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-10-16T09:16:55.593304Z","caller":"traceutil/trace.go:171","msg":"trace[1134036291] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1907; }","duration":"170.232915ms","start":"2026-10-16T09:16:55.423057Z","end":"2026-10-16T09:16:55.59329Z","steps":["trace[1134036291] 'agreement among raft nodes before linearized reading' (duration: 170.068251ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:17:08.707158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.015319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-cluster-operator-54f767cff8-drq7n\" ","response":"range_response_count:1 size:5854"} {"level":"info","ts":"2026-10-16T09:17:08.707253Z","caller":"traceutil/trace.go:171","msg":"trace[2054027945] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-cluster-operator-54f767cff8-drq7n; range_end:; response_count:1; response_revision:2016; }","duration":"139.160693ms","start":"2026-10-16T09:17:08.568069Z","end":"2026-10-16T09:17:08.707229Z","steps":["trace[2054027945] 'range keys from in-memory index tree' (duration: 138.814713ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:17:09.011438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.528591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-16T09:17:09.011511Z","caller":"traceutil/trace.go:171","msg":"trace[498644394] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2020; }","duration":"113.632184ms","start":"2026-10-16T09:17:08.897863Z","end":"2026-10-16T09:17:09.011495Z","steps":["trace[498644394] 'agreement among raft nodes before linearized reading' (duration: 113.439819ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:17:11.815414Z","caller":"traceutil/trace.go:171","msg":"trace[2103716189] transaction","detail":"{read_only:false; response_revision:2042; number_of_response:1; }","duration":"127.527759ms","start":"2026-10-16T09:17:11.68785Z","end":"2026-10-16T09:17:11.815378Z","steps":["trace[2103716189] 'process raft request' (duration: 127.323104ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:17:12.442717Z","caller":"traceutil/trace.go:171","msg":"trace[1158025305] transaction","detail":"{read_only:false; response_revision:2047; number_of_response:1; }","duration":"186.921619ms","start":"2026-10-16T09:17:12.255778Z","end":"2026-10-16T09:17:12.4427Z","steps":["trace[1158025305] 'process raft request' (duration: 186.770565ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:17:12.443784Z","caller":"traceutil/trace.go:171","msg":"trace[1465623603] transaction","detail":"{read_only:false; response_revision:2048; number_of_response:1; }","duration":"167.87238ms","start":"2026-10-16T09:17:12.275882Z","end":"2026-10-16T09:17:12.443754Z","steps":["trace[1465623603] 'process raft request' (duration: 167.719446ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:17:51.423093Z","caller":"traceutil/trace.go:171","msg":"trace[74173496] transaction","detail":"{read_only:false; response_revision:2259; number_of_response:1; }","duration":"143.349352ms","start":"2026-10-16T09:17:51.279713Z","end":"2026-10-16T09:17:51.423063Z","steps":["trace[74173496] 'process raft request' (duration: 143.146786ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:17:51.678983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.508101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T09:17:51.679074Z","caller":"traceutil/trace.go:171","msg":"trace[230773580] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:2259; }","duration":"210.662265ms","start":"2026-10-16T09:17:51.468396Z","end":"2026-10-16T09:17:51.679059Z","steps":["trace[230773580] 'count revisions from in-memory index tree' (duration: 210.383477ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:18:36.254369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.630481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-metrics\" ","response":"range_response_count:1 size:1449"} {"level":"info","ts":"2026-10-16T09:18:36.254483Z","caller":"traceutil/trace.go:171","msg":"trace[1142891019] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-metrics; range_end:; response_count:1; response_revision:2672; }","duration":"187.778355ms","start":"2026-10-16T09:18:36.066678Z","end":"2026-10-16T09:18:36.254457Z","steps":["trace[1142891019] 'range keys from in-memory index tree' (duration: 187.442275ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:19:09.252529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.682135ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-10-16T09:19:09.253267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.063276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-16T09:19:09.253462Z","caller":"traceutil/trace.go:171","msg":"trace[1606454558] linearizableReadLoop","detail":"{readStateIndex:2988; appliedIndex:2987; }","duration":"179.668025ms","start":"2026-10-16T09:19:09.073189Z","end":"2026-10-16T09:19:09.252857Z","steps":["trace[1606454558] 'read index received' (duration: 42.60146ms)","trace[1606454558] 'applied index is now lower than readState.Index' (duration: 137.063465ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T09:19:09.253459Z","caller":"traceutil/trace.go:171","msg":"trace[953704575] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2874; }","duration":"180.20129ms","start":"2026-10-16T09:19:09.073136Z","end":"2026-10-16T09:19:09.253337Z","steps":["trace[953704575] 'agreement among raft nodes before linearized reading' (duration: 179.771268ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:19:09.253831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.31899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T09:19:09.253924Z","caller":"traceutil/trace.go:171","msg":"trace[828282897] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:2874; }","duration":"151.480735ms","start":"2026-10-16T09:19:09.102429Z","end":"2026-10-16T09:19:09.25391Z","steps":["trace[828282897] 'agreement among raft nodes before linearized reading' (duration: 151.30263ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:23:49.005815Z","caller":"traceutil/trace.go:171","msg":"trace[826416457] transaction","detail":"{read_only:false; response_revision:5281; number_of_response:1; }","duration":"128.899051ms","start":"2026-10-16T09:23:48.876884Z","end":"2026-10-16T09:23:49.005783Z","steps":["trace[826416457] 'process raft request' (duration: 102.098463ms)","trace[826416457] 'compare' (duration: 26.628213ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T09:23:49.008606Z","caller":"traceutil/trace.go:171","msg":"trace[1942140641] transaction","detail":"{read_only:false; response_revision:5282; number_of_response:1; }","duration":"111.473828ms","start":"2026-10-16T09:23:48.897099Z","end":"2026-10-16T09:23:49.008573Z","steps":["trace[1942140641] 'process raft request' (duration: 111.237941ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:24:02.683681Z","caller":"traceutil/trace.go:171","msg":"trace[640301968] transaction","detail":"{read_only:false; response_revision:5531; number_of_response:1; }","duration":"185.231641ms","start":"2026-10-16T09:24:02.498429Z","end":"2026-10-16T09:24:02.683661Z","steps":["trace[640301968] 'process raft request' (duration: 185.113628ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:24:02.698705Z","caller":"traceutil/trace.go:171","msg":"trace[827647915] linearizableReadLoop","detail":"{readStateIndex:5742; appliedIndex:5740; }","duration":"147.714282ms","start":"2026-10-16T09:24:02.550949Z","end":"2026-10-16T09:24:02.698663Z","steps":["trace[827647915] 'read index received' (duration: 132.645823ms)","trace[827647915] 'applied index is now lower than readState.Index' (duration: 15.064669ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T09:24:02.699104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.937918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T09:24:02.699097Z","caller":"traceutil/trace.go:171","msg":"trace[1037425482] transaction","detail":"{read_only:false; response_revision:5532; number_of_response:1; }","duration":"194.613906ms","start":"2026-10-16T09:24:02.504451Z","end":"2026-10-16T09:24:02.699064Z","steps":["trace[1037425482] 'process raft request' (duration: 194.075271ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:24:02.699175Z","caller":"traceutil/trace.go:171","msg":"trace[1652499607] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5532; }","duration":"148.239146ms","start":"2026-10-16T09:24:02.550915Z","end":"2026-10-16T09:24:02.699154Z","steps":["trace[1652499607] 'agreement among raft nodes before linearized reading' (duration: 147.938718ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:24:10.327047Z","caller":"traceutil/trace.go:171","msg":"trace[939812493] linearizableReadLoop","detail":"{readStateIndex:5909; appliedIndex:5908; }","duration":"131.328058ms","start":"2026-10-16T09:24:10.195655Z","end":"2026-10-16T09:24:10.326983Z","steps":["trace[939812493] 'read index received' (duration: 131.065081ms)","trace[939812493] 'applied index is now lower than readState.Index' (duration: 261.677µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T09:24:10.327088Z","caller":"traceutil/trace.go:171","msg":"trace[1949288702] transaction","detail":"{read_only:false; response_revision:5695; number_of_response:1; }","duration":"183.256774ms","start":"2026-10-16T09:24:10.143799Z","end":"2026-10-16T09:24:10.327055Z","steps":["trace[1949288702] 'process raft request' (duration: 183.019198ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:24:10.327527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.846353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-39348749-b1d3-418d-996d-6c92c5c9c5cb\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-10-16T09:24:10.32759Z","caller":"traceutil/trace.go:171","msg":"trace[64011392] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-39348749-b1d3-418d-996d-6c92c5c9c5cb; range_end:; response_count:1; response_revision:5695; }","duration":"131.954615ms","start":"2026-10-16T09:24:10.19562Z","end":"2026-10-16T09:24:10.327574Z","steps":["trace[64011392] 'agreement among raft nodes before linearized reading' (duration: 131.523153ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:24:30.658911Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2993} {"level":"info","ts":"2026-10-16T09:24:30.733401Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2993,"took":"73.189205ms","hash":1266697074,"current-db-size-bytes":57565184,"current-db-size":"58 MB","current-db-size-in-use-bytes":32571392,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-16T09:24:30.733448Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1266697074,"revision":2993,"compact-revision":-1} {"level":"info","ts":"2026-10-16T09:29:30.6675Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5915} {"level":"info","ts":"2026-10-16T09:29:30.75468Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5915,"took":"84.682298ms","hash":210482498,"current-db-size-bytes":57565184,"current-db-size":"58 MB","current-db-size-in-use-bytes":26304512,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-16T09:29:30.75473Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":210482498,"revision":5915,"compact-revision":2993} {"level":"info","ts":"2026-10-16T09:30:33.3673Z","caller":"traceutil/trace.go:171","msg":"trace[1371630952] transaction","detail":"{read_only:false; response_revision:8615; number_of_response:1; }","duration":"306.814075ms","start":"2026-10-16T09:30:33.060461Z","end":"2026-10-16T09:30:33.367276Z","steps":["trace[1371630952] 'process raft request' (duration: 306.675661ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:30:33.367441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T09:30:33.060431Z","time spent":"306.941108ms","remote":"127.0.0.1:57080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T09:30:33.368792Z","caller":"traceutil/trace.go:171","msg":"trace[1845176949] transaction","detail":"{read_only:false; response_revision:8616; number_of_response:1; }","duration":"226.766578ms","start":"2026-10-16T09:30:33.142Z","end":"2026-10-16T09:30:33.368767Z","steps":["trace[1845176949] 'process raft request' (duration: 226.631505ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:30:45.507991Z","caller":"traceutil/trace.go:171","msg":"trace[1066619783] transaction","detail":"{read_only:false; response_revision:8682; number_of_response:1; }","duration":"101.868274ms","start":"2026-10-16T09:30:45.406096Z","end":"2026-10-16T09:30:45.507964Z","steps":["trace[1066619783] 'process raft request' (duration: 101.667739ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:30:45.520356Z","caller":"traceutil/trace.go:171","msg":"trace[1444803229] transaction","detail":"{read_only:false; response_revision:8683; number_of_response:1; }","duration":"109.76646ms","start":"2026-10-16T09:30:45.410565Z","end":"2026-10-16T09:30:45.520331Z","steps":["trace[1444803229] 'process raft request' (duration: 109.609106ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:30:51.531226Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002347.wal"} {"level":"info","ts":"2026-10-16T09:31:14.754249Z","caller":"traceutil/trace.go:171","msg":"trace[123762945] transaction","detail":"{read_only:false; response_revision:8895; number_of_response:1; }","duration":"289.785438ms","start":"2026-10-16T09:31:14.464435Z","end":"2026-10-16T09:31:14.754221Z","steps":["trace[123762945] 'process raft request' (duration: 289.642225ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:31:14.754818Z","caller":"traceutil/trace.go:171","msg":"trace[1431866380] linearizableReadLoop","detail":"{readStateIndex:9213; appliedIndex:9213; }","duration":"202.682789ms","start":"2026-10-16T09:31:14.552123Z","end":"2026-10-16T09:31:14.754806Z","steps":["trace[1431866380] 'read index received' (duration: 202.678359ms)","trace[1431866380] 'applied index is now lower than readState.Index' (duration: 3.61µs)"],"step_count":2} {"level":"warn","ts":"2026-10-16T09:31:14.755132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.008648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T09:31:14.755183Z","caller":"traceutil/trace.go:171","msg":"trace[1759749047] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8895; }","duration":"203.113431ms","start":"2026-10-16T09:31:14.552055Z","end":"2026-10-16T09:31:14.755169Z","steps":["trace[1759749047] 'agreement among raft nodes before linearized reading' (duration: 203.039329ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:31:18.021239Z","caller":"traceutil/trace.go:171","msg":"trace[50090283] transaction","detail":"{read_only:false; response_revision:8909; number_of_response:1; }","duration":"385.188665ms","start":"2026-10-16T09:31:17.636024Z","end":"2026-10-16T09:31:18.021212Z","steps":["trace[50090283] 'process raft request' (duration: 385.107033ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:31:18.021273Z","caller":"traceutil/trace.go:171","msg":"trace[1177978527] transaction","detail":"{read_only:false; response_revision:8908; number_of_response:1; }","duration":"385.475493ms","start":"2026-10-16T09:31:17.635764Z","end":"2026-10-16T09:31:18.021239Z","steps":["trace[1177978527] 'process raft request' (duration: 385.128863ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:31:18.021406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T09:31:17.635998Z","time spent":"385.320678ms","remote":"127.0.0.1:57080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T09:31:18.021484Z","caller":"traceutil/trace.go:171","msg":"trace[4411816] linearizableReadLoop","detail":"{readStateIndex:9228; appliedIndex:9224; }","duration":"341.033074ms","start":"2026-10-16T09:31:17.680415Z","end":"2026-10-16T09:31:18.021448Z","steps":["trace[4411816] 'read index received' (duration: 29.709565ms)","trace[4411816] 'applied index is now lower than readState.Index' (duration: 311.322129ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T09:31:18.021459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T09:31:17.635739Z","time spent":"385.605356ms","remote":"127.0.0.1:57080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T09:31:18.021627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.205028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T09:31:18.021711Z","caller":"traceutil/trace.go:171","msg":"trace[1463520551] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:8910; }","duration":"341.309981ms","start":"2026-10-16T09:31:17.680387Z","end":"2026-10-16T09:31:18.021697Z","steps":["trace[1463520551] 'agreement among raft nodes before linearized reading' (duration: 341.192608ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:31:18.021498Z","caller":"traceutil/trace.go:171","msg":"trace[974565497] transaction","detail":"{read_only:false; response_revision:8910; number_of_response:1; }","duration":"385.415411ms","start":"2026-10-16T09:31:17.636066Z","end":"2026-10-16T09:31:18.021481Z","steps":["trace[974565497] 'process raft request' (duration: 385.110182ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:31:18.021743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T09:31:17.680375Z","time spent":"341.359352ms","remote":"127.0.0.1:58968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true "} {"level":"warn","ts":"2026-10-16T09:31:18.021791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T09:31:17.636041Z","time spent":"385.713739ms","remote":"127.0.0.1:57080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T09:31:18.021856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.054625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-10-16T09:31:18.021859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.859345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-10-16T09:31:18.021903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.083766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T09:31:18.021946Z","caller":"traceutil/trace.go:171","msg":"trace[1033747912] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8910; }","duration":"333.164858ms","start":"2026-10-16T09:31:17.688739Z","end":"2026-10-16T09:31:18.021904Z","steps":["trace[1033747912] 'agreement among raft nodes before linearized reading' (duration: 332.900331ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:31:18.02196Z","caller":"traceutil/trace.go:171","msg":"trace[1573173443] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8910; }","duration":"333.159377ms","start":"2026-10-16T09:31:17.688791Z","end":"2026-10-16T09:31:18.02195Z","steps":["trace[1573173443] 'agreement among raft nodes before linearized reading' (duration: 333.078135ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:31:18.021989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T09:31:17.68878Z","time spent":"333.202529ms","remote":"127.0.0.1:57260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-10-16T09:31:18.022005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T09:31:17.688718Z","time spent":"333.26756ms","remote":"127.0.0.1:57080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-16T09:31:18.021993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.742146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-16T09:31:18.022049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.000007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-vw4z2\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-10-16T09:31:18.022081Z","caller":"traceutil/trace.go:171","msg":"trace[942936426] range","detail":"{range_begin:/registry/pods/openstack/keepalived-vw4z2; range_end:; response_count:1; response_revision:8910; }","duration":"110.060879ms","start":"2026-10-16T09:31:17.912012Z","end":"2026-10-16T09:31:18.022073Z","steps":["trace[942936426] 'agreement among raft nodes before linearized reading' (duration: 109.974646ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:31:18.021948Z","caller":"traceutil/trace.go:171","msg":"trace[1550743052] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8910; }","duration":"160.003288ms","start":"2026-10-16T09:31:17.861907Z","end":"2026-10-16T09:31:18.021911Z","steps":["trace[1550743052] 'agreement among raft nodes before linearized reading' (duration: 159.816393ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:31:18.022086Z","caller":"traceutil/trace.go:171","msg":"trace[1329351493] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:8910; }","duration":"188.895941ms","start":"2026-10-16T09:31:17.833164Z","end":"2026-10-16T09:31:18.02206Z","steps":["trace[1329351493] 'agreement among raft nodes before linearized reading' (duration: 188.651465ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:31:26.163766Z","caller":"traceutil/trace.go:171","msg":"trace[1365057891] transaction","detail":"{read_only:false; response_revision:8951; number_of_response:1; }","duration":"109.38768ms","start":"2026-10-16T09:31:26.05435Z","end":"2026-10-16T09:31:26.163738Z","steps":["trace[1365057891] 'process raft request' (duration: 109.216696ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:31:26.411164Z","caller":"traceutil/trace.go:171","msg":"trace[1332493478] linearizableReadLoop","detail":"{readStateIndex:9274; appliedIndex:9272; }","duration":"288.663537ms","start":"2026-10-16T09:31:26.122486Z","end":"2026-10-16T09:31:26.411149Z","steps":["trace[1332493478] 'read index received' (duration: 41.377185ms)","trace[1332493478] 'applied index is now lower than readState.Index' (duration: 247.285592ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T09:31:26.411243Z","caller":"traceutil/trace.go:171","msg":"trace[1724925023] transaction","detail":"{read_only:false; response_revision:8952; number_of_response:1; }","duration":"351.609893ms","start":"2026-10-16T09:31:26.059626Z","end":"2026-10-16T09:31:26.411236Z","steps":["trace[1724925023] 'process raft request' (duration: 306.837805ms)","trace[1724925023] 'compare' (duration: 44.386868ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T09:31:26.411308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T09:31:26.05959Z","time spent":"351.664725ms","remote":"127.0.0.1:57080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T09:31:26.411383Z","caller":"traceutil/trace.go:171","msg":"trace[1367504929] transaction","detail":"{read_only:false; response_revision:8953; number_of_response:1; }","duration":"351.659245ms","start":"2026-10-16T09:31:26.05972Z","end":"2026-10-16T09:31:26.411379Z","steps":["trace[1367504929] 'process raft request' (duration: 351.398448ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:31:26.411408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T09:31:26.059685Z","time spent":"351.711006ms","remote":"127.0.0.1:57080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T09:31:26.41142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.917924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-16T09:31:26.411499Z","caller":"traceutil/trace.go:171","msg":"trace[651075181] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8953; }","duration":"289.046168ms","start":"2026-10-16T09:31:26.122435Z","end":"2026-10-16T09:31:26.411481Z","steps":["trace[651075181] 'agreement among raft nodes before linearized reading' (duration: 288.841933ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:31:26.411501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.93306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-10-16T09:31:26.41154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.803538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-vw4z2\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-10-16T09:31:26.411559Z","caller":"traceutil/trace.go:171","msg":"trace[386179] range","detail":"{range_begin:/registry/pods/openstack/keepalived-vw4z2; range_end:; response_count:1; response_revision:8953; }","duration":"262.863379ms","start":"2026-10-16T09:31:26.148691Z","end":"2026-10-16T09:31:26.411554Z","steps":["trace[386179] 'agreement among raft nodes before linearized reading' (duration: 262.819858ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:31:26.411576Z","caller":"traceutil/trace.go:171","msg":"trace[1660815018] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8953; }","duration":"287.008292ms","start":"2026-10-16T09:31:26.124551Z","end":"2026-10-16T09:31:26.411559Z","steps":["trace[1660815018] 'agreement among raft nodes before linearized reading' (duration: 286.805847ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:31:27.222573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.818768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-16T09:31:27.222658Z","caller":"traceutil/trace.go:171","msg":"trace[458753377] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8958; }","duration":"107.960062ms","start":"2026-10-16T09:31:27.114679Z","end":"2026-10-16T09:31:27.22264Z","steps":["trace[458753377] 'range keys from in-memory index tree' (duration: 107.603712ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:32:32.822984Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5db0a1b394e67b72","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-16T09:32:32.830006Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-16T09:32:32.830127Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-16T09:34:30.677734Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8085} {"level":"info","ts":"2026-10-16T09:34:30.752333Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8085,"took":"72.58734ms","hash":1709572718,"current-db-size-bytes":57565184,"current-db-size":"58 MB","current-db-size-in-use-bytes":28139520,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-16T09:34:30.752396Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1709572718,"revision":8085,"compact-revision":5915} {"level":"info","ts":"2026-10-16T09:38:17.033982Z","caller":"traceutil/trace.go:171","msg":"trace[802561059] transaction","detail":"{read_only:false; response_revision:12147; number_of_response:1; }","duration":"100.504302ms","start":"2026-10-16T09:38:16.933437Z","end":"2026-10-16T09:38:17.033942Z","steps":["trace[802561059] 'process raft request' (duration: 100.130282ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:38:37.529708Z","caller":"traceutil/trace.go:171","msg":"trace[2062293003] linearizableReadLoop","detail":"{readStateIndex:12687; appliedIndex:12686; }","duration":"164.934053ms","start":"2026-10-16T09:38:37.364757Z","end":"2026-10-16T09:38:37.529691Z","steps":["trace[2062293003] 'read index received' (duration: 164.748448ms)","trace[2062293003] 'applied index is now lower than readState.Index' (duration: 184.485µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T09:38:37.529793Z","caller":"traceutil/trace.go:171","msg":"trace[2059997036] transaction","detail":"{read_only:false; response_revision:12260; number_of_response:1; }","duration":"290.70259ms","start":"2026-10-16T09:38:37.239064Z","end":"2026-10-16T09:38:37.529766Z","steps":["trace[2059997036] 'process raft request' (duration: 290.493794ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:38:37.529873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.100928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:19921"} {"level":"info","ts":"2026-10-16T09:38:37.529903Z","caller":"traceutil/trace.go:171","msg":"trace[1023285697] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:12260; }","duration":"165.17513ms","start":"2026-10-16T09:38:37.364719Z","end":"2026-10-16T09:38:37.529894Z","steps":["trace[1023285697] 'agreement among raft nodes before linearized reading' (duration: 165.066037ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:39:30.683522Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10449} {"level":"info","ts":"2026-10-16T09:39:30.761386Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10449,"took":"74.879728ms","hash":451672131,"current-db-size-bytes":57565184,"current-db-size":"58 MB","current-db-size-in-use-bytes":25796608,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-16T09:39:30.761449Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":451672131,"revision":10449,"compact-revision":8085} {"level":"info","ts":"2026-10-16T09:44:30.695819Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12623} {"level":"info","ts":"2026-10-16T09:44:30.774132Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12623,"took":"76.037269ms","hash":267086886,"current-db-size-bytes":57565184,"current-db-size":"58 MB","current-db-size-in-use-bytes":27754496,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-16T09:44:30.774206Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":267086886,"revision":12623,"compact-revision":10449} {"level":"warn","ts":"2026-10-16T09:45:05.74267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.112695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T09:45:05.742752Z","caller":"traceutil/trace.go:171","msg":"trace[1464316309] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14854; }","duration":"191.240689ms","start":"2026-10-16T09:45:05.551494Z","end":"2026-10-16T09:45:05.742734Z","steps":["trace[1464316309] 'range keys from in-memory index tree' (duration: 191.002662ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:45:23.444157Z","caller":"traceutil/trace.go:171","msg":"trace[1089768577] transaction","detail":"{read_only:false; response_revision:14956; number_of_response:1; }","duration":"197.49644ms","start":"2026-10-16T09:45:23.246628Z","end":"2026-10-16T09:45:23.444125Z","steps":["trace[1089768577] 'process raft request' (duration: 183.740262ms)","trace[1089768577] 'compare' (duration: 13.582603ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T09:45:23.444387Z","caller":"traceutil/trace.go:171","msg":"trace[1224601951] linearizableReadLoop","detail":"{readStateIndex:15481; appliedIndex:15479; }","duration":"180.801361ms","start":"2026-10-16T09:45:23.263557Z","end":"2026-10-16T09:45:23.444359Z","steps":["trace[1224601951] 'read index received' (duration: 14.362845ms)","trace[1224601951] 'applied index is now lower than readState.Index' (duration: 166.437226ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T09:45:23.444519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.939605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T09:45:23.444558Z","caller":"traceutil/trace.go:171","msg":"trace[2033588574] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:14957; }","duration":"181.045778ms","start":"2026-10-16T09:45:23.2635Z","end":"2026-10-16T09:45:23.444545Z","steps":["trace[2033588574] 'agreement among raft nodes before linearized reading' (duration: 180.958005ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:45:23.444547Z","caller":"traceutil/trace.go:171","msg":"trace[414446435] transaction","detail":"{read_only:false; response_revision:14957; number_of_response:1; }","duration":"170.729184ms","start":"2026-10-16T09:45:23.273768Z","end":"2026-10-16T09:45:23.444497Z","steps":["trace[414446435] 'process raft request' (duration: 170.296512ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:47:07.455999Z","caller":"traceutil/trace.go:171","msg":"trace[887175972] linearizableReadLoop","detail":"{readStateIndex:16614; appliedIndex:16613; }","duration":"157.859041ms","start":"2026-10-16T09:47:07.298114Z","end":"2026-10-16T09:47:07.455973Z","steps":["trace[887175972] 'read index received' (duration: 157.660065ms)","trace[887175972] 'applied index is now lower than readState.Index' (duration: 198.056µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T09:47:07.45639Z","caller":"traceutil/trace.go:171","msg":"trace[1077678906] transaction","detail":"{read_only:false; response_revision:16055; number_of_response:1; }","duration":"165.005877ms","start":"2026-10-16T09:47:07.291346Z","end":"2026-10-16T09:47:07.456352Z","steps":["trace[1077678906] 'process raft request' (duration: 164.488633ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:47:07.45667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.567979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-10-16T09:47:07.456711Z","caller":"traceutil/trace.go:171","msg":"trace[392697576] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16055; }","duration":"158.665543ms","start":"2026-10-16T09:47:07.298037Z","end":"2026-10-16T09:47:07.456703Z","steps":["trace[392697576] 'agreement among raft nodes before linearized reading' (duration: 158.517509ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:47:07.581592Z","caller":"traceutil/trace.go:171","msg":"trace[1000549681] linearizableReadLoop","detail":"{readStateIndex:16615; appliedIndex:16614; }","duration":"125.48516ms","start":"2026-10-16T09:47:07.45608Z","end":"2026-10-16T09:47:07.581565Z","steps":["trace[1000549681] 'read index received' (duration: 86.513798ms)","trace[1000549681] 'applied index is now lower than readState.Index' (duration: 38.970272ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T09:47:07.581773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.663852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T09:47:07.581806Z","caller":"traceutil/trace.go:171","msg":"trace[126895211] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16056; }","duration":"282.752424ms","start":"2026-10-16T09:47:07.299041Z","end":"2026-10-16T09:47:07.581794Z","steps":["trace[126895211] 'agreement among raft nodes before linearized reading' (duration: 282.683582ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:47:07.581892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.439653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-z7m2q\" ","response":"range_response_count:1 size:9790"} {"level":"info","ts":"2026-10-16T09:47:07.581858Z","caller":"traceutil/trace.go:171","msg":"trace[122366715] transaction","detail":"{read_only:false; response_revision:16056; number_of_response:1; }","duration":"242.997421ms","start":"2026-10-16T09:47:07.338789Z","end":"2026-10-16T09:47:07.581786Z","steps":["trace[122366715] 'process raft request' (duration: 203.859375ms)","trace[122366715] 'compare' (duration: 38.762526ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T09:47:07.58194Z","caller":"traceutil/trace.go:171","msg":"trace[1307850245] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-z7m2q; range_end:; response_count:1; response_revision:16056; }","duration":"159.533567ms","start":"2026-10-16T09:47:07.422394Z","end":"2026-10-16T09:47:07.581928Z","steps":["trace[1307850245] 'agreement among raft nodes before linearized reading' (duration: 159.235849ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:47:07.582087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.950459ms","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-10-16T09:47:07.582216Z","caller":"traceutil/trace.go:171","msg":"trace[116459964] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:16056; }","duration":"283.112944ms","start":"2026-10-16T09:47:07.299083Z","end":"2026-10-16T09:47:07.582196Z","steps":["trace[116459964] 'agreement among raft nodes before linearized reading' (duration: 282.61519ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:47:07.582295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.395685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-10-16T09:47:07.58234Z","caller":"traceutil/trace.go:171","msg":"trace[873926583] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:16056; }","duration":"250.471067ms","start":"2026-10-16T09:47:07.331855Z","end":"2026-10-16T09:47:07.582326Z","steps":["trace[873926583] 'agreement among raft nodes before linearized reading' (duration: 249.965273ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:47:07.583701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.409967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-10-16T09:47:07.583817Z","caller":"traceutil/trace.go:171","msg":"trace[1766064722] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16056; }","duration":"281.574901ms","start":"2026-10-16T09:47:07.302225Z","end":"2026-10-16T09:47:07.5838Z","steps":["trace[1766064722] 'agreement among raft nodes before linearized reading' (duration: 279.445183ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:49:30.701875Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14625} {"level":"info","ts":"2026-10-16T09:49:30.772452Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14625,"took":"68.594144ms","hash":4114603673,"current-db-size-bytes":57565184,"current-db-size":"58 MB","current-db-size-in-use-bytes":31531008,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-16T09:49:30.772499Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4114603673,"revision":14625,"compact-revision":12623} {"level":"info","ts":"2026-10-16T09:53:15.305083Z","caller":"traceutil/trace.go:171","msg":"trace[1715141869] transaction","detail":"{read_only:false; response_revision:18746; number_of_response:1; }","duration":"170.002895ms","start":"2026-10-16T09:53:15.135047Z","end":"2026-10-16T09:53:15.30505Z","steps":["trace[1715141869] 'process raft request' (duration: 169.800919ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:53:15.305209Z","caller":"traceutil/trace.go:171","msg":"trace[459849424] transaction","detail":"{read_only:false; response_revision:18747; number_of_response:1; }","duration":"128.478772ms","start":"2026-10-16T09:53:15.176698Z","end":"2026-10-16T09:53:15.305177Z","steps":["trace[459849424] 'process raft request' (duration: 128.304537ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:53:15.694579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.650438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T09:53:15.694665Z","caller":"traceutil/trace.go:171","msg":"trace[1566908700] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18748; }","duration":"140.799421ms","start":"2026-10-16T09:53:15.553849Z","end":"2026-10-16T09:53:15.694648Z","steps":["trace[1566908700] 'range keys from in-memory index tree' (duration: 140.565715ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:53:15.694964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.241246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T09:53:15.695007Z","caller":"traceutil/trace.go:171","msg":"trace[1951487896] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:18748; }","duration":"248.330758ms","start":"2026-10-16T09:53:15.446665Z","end":"2026-10-16T09:53:15.694996Z","steps":["trace[1951487896] 'count revisions from in-memory index tree' (duration: 248.162843ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:53:15.69501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.919954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-16T09:53:15.695059Z","caller":"traceutil/trace.go:171","msg":"trace[433370185] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18748; }","duration":"189.039387ms","start":"2026-10-16T09:53:15.506006Z","end":"2026-10-16T09:53:15.695045Z","steps":["trace[433370185] 'range keys from in-memory index tree' (duration: 188.723819ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:53:15.695068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.66391ms","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-10-16T09:53:15.695139Z","caller":"traceutil/trace.go:171","msg":"trace[1316637039] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18748; }","duration":"183.782573ms","start":"2026-10-16T09:53:15.511337Z","end":"2026-10-16T09:53:15.69512Z","steps":["trace[1316637039] 'range keys from in-memory index tree' (duration: 183.525116ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:53:16.105302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.27996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-16T09:53:16.10539Z","caller":"traceutil/trace.go:171","msg":"trace[67865136] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:18751; }","duration":"259.413463ms","start":"2026-10-16T09:53:15.845956Z","end":"2026-10-16T09:53:16.105369Z","steps":["trace[67865136] 'range keys from in-memory index tree' (duration: 259.124114ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:53:16.105302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.239013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-kk9mp\" ","response":"range_response_count:1 size:22096"} {"level":"info","ts":"2026-10-16T09:53:16.105619Z","caller":"traceutil/trace.go:171","msg":"trace[794317447] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-kk9mp; range_end:; response_count:1; response_revision:18751; }","duration":"205.583093ms","start":"2026-10-16T09:53:15.899991Z","end":"2026-10-16T09:53:16.105574Z","steps":["trace[794317447] 'range keys from in-memory index tree' (duration: 204.977516ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:53:21.010852Z","caller":"traceutil/trace.go:171","msg":"trace[1741262012] transaction","detail":"{read_only:false; response_revision:18788; number_of_response:1; }","duration":"100.494283ms","start":"2026-10-16T09:53:20.91033Z","end":"2026-10-16T09:53:21.010824Z","steps":["trace[1741262012] 'process raft request' (duration: 43.557297ms)","trace[1741262012] 'compare' (duration: 56.785192ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T09:53:21.013958Z","caller":"traceutil/trace.go:171","msg":"trace[816406333] transaction","detail":"{read_only:false; response_revision:18789; number_of_response:1; }","duration":"103.30942ms","start":"2026-10-16T09:53:20.910626Z","end":"2026-10-16T09:53:21.013936Z","steps":["trace[816406333] 'process raft request' (duration: 102.492288ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:53:21.014251Z","caller":"traceutil/trace.go:171","msg":"trace[126742077] transaction","detail":"{read_only:false; response_revision:18790; number_of_response:1; }","duration":"100.3877ms","start":"2026-10-16T09:53:20.913849Z","end":"2026-10-16T09:53:21.014237Z","steps":["trace[126742077] 'process raft request' (duration: 99.360881ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:53:24.493356Z","caller":"traceutil/trace.go:171","msg":"trace[1535000237] linearizableReadLoop","detail":"{readStateIndex:19543; appliedIndex:19541; }","duration":"100.0109ms","start":"2026-10-16T09:53:24.393318Z","end":"2026-10-16T09:53:24.493329Z","steps":["trace[1535000237] 'read index received' (duration: 17.746228ms)","trace[1535000237] 'applied index is now lower than readState.Index' (duration: 82.263572ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T09:53:24.493416Z","caller":"traceutil/trace.go:171","msg":"trace[1886789675] transaction","detail":"{read_only:false; response_revision:18890; number_of_response:1; }","duration":"103.051714ms","start":"2026-10-16T09:53:24.390337Z","end":"2026-10-16T09:53:24.493388Z","steps":["trace[1886789675] 'process raft request' (duration: 102.843048ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:53:24.493885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.554515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:16 size:112180"} {"level":"info","ts":"2026-10-16T09:53:24.493956Z","caller":"traceutil/trace.go:171","msg":"trace[551286337] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:16; response_revision:18890; }","duration":"100.664778ms","start":"2026-10-16T09:53:24.393273Z","end":"2026-10-16T09:53:24.493938Z","steps":["trace[551286337] 'agreement among raft nodes before linearized reading' (duration: 100.219446ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:54:04.022784Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5db0a1b394e67b72","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-16T09:54:04.028997Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-16T09:54:04.029126Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-16T09:54:30.70805Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17034} {"level":"info","ts":"2026-10-16T09:54:30.800634Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17034,"took":"88.518414ms","hash":3748143112,"current-db-size-bytes":57565184,"current-db-size":"58 MB","current-db-size-in-use-bytes":33693696,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-16T09:54:30.800681Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3748143112,"revision":17034,"compact-revision":14625} {"level":"info","ts":"2026-10-16T09:59:16.717573Z","caller":"traceutil/trace.go:171","msg":"trace[576081641] transaction","detail":"{read_only:false; response_revision:21528; number_of_response:1; }","duration":"317.157032ms","start":"2026-10-16T09:59:16.400391Z","end":"2026-10-16T09:59:16.717548Z","steps":["trace[576081641] 'process raft request' (duration: 316.989357ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:59:16.717751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T09:59:16.40037Z","time spent":"317.302705ms","remote":"127.0.0.1:57080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T09:59:16.742924Z","caller":"traceutil/trace.go:171","msg":"trace[2066526810] transaction","detail":"{read_only:false; response_revision:21529; number_of_response:1; }","duration":"212.02759ms","start":"2026-10-16T09:59:16.530874Z","end":"2026-10-16T09:59:16.742902Z","steps":["trace[2066526810] 'process raft request' (duration: 211.862875ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:59:16.743124Z","caller":"traceutil/trace.go:171","msg":"trace[1539281216] transaction","detail":"{read_only:false; response_revision:21530; number_of_response:1; }","duration":"110.458457ms","start":"2026-10-16T09:59:16.632634Z","end":"2026-10-16T09:59:16.743093Z","steps":["trace[1539281216] 'process raft request' (duration: 110.227041ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:59:16.743248Z","caller":"traceutil/trace.go:171","msg":"trace[605217992] linearizableReadLoop","detail":"{readStateIndex:22270; appliedIndex:22268; }","duration":"191.242368ms","start":"2026-10-16T09:59:16.551962Z","end":"2026-10-16T09:59:16.743204Z","steps":["trace[605217992] 'read index received' (duration: 165.429558ms)","trace[605217992] 'applied index is now lower than readState.Index' (duration: 25.8113ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T09:59:16.743275Z","caller":"traceutil/trace.go:171","msg":"trace[313770827] transaction","detail":"{read_only:false; response_revision:21531; number_of_response:1; }","duration":"108.435652ms","start":"2026-10-16T09:59:16.634818Z","end":"2026-10-16T09:59:16.743253Z","steps":["trace[313770827] 'process raft request' (duration: 108.242896ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:59:16.743339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.360852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T09:59:16.743375Z","caller":"traceutil/trace.go:171","msg":"trace[2124678264] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21531; }","duration":"191.443115ms","start":"2026-10-16T09:59:16.55192Z","end":"2026-10-16T09:59:16.743363Z","steps":["trace[2124678264] 'agreement among raft nodes before linearized reading' (duration: 191.367063ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:59:30.71332Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19550} {"level":"info","ts":"2026-10-16T09:59:30.836376Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19550,"took":"118.969561ms","hash":2091113637,"current-db-size-bytes":57565184,"current-db-size":"58 MB","current-db-size-in-use-bytes":29769728,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-16T09:59:30.836459Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2091113637,"revision":19550,"compact-revision":17034} {"level":"info","ts":"2026-10-16T09:59:32.904055Z","caller":"traceutil/trace.go:171","msg":"trace[1147881186] transaction","detail":"{read_only:false; response_revision:21612; number_of_response:1; }","duration":"101.351838ms","start":"2026-10-16T09:59:32.802664Z","end":"2026-10-16T09:59:32.904016Z","steps":["trace[1147881186] 'process raft request' (duration: 101.194573ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:59:33.176306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.086385ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-16T09:59:33.176457Z","caller":"traceutil/trace.go:171","msg":"trace[277021646] transaction","detail":"{read_only:false; response_revision:21614; number_of_response:1; }","duration":"349.648325ms","start":"2026-10-16T09:59:32.826791Z","end":"2026-10-16T09:59:33.17644Z","steps":["trace[277021646] 'process raft request' (duration: 349.597263ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T09:59:33.176538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T09:59:32.826774Z","time spent":"349.731087ms","remote":"127.0.0.1:57080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T09:59:33.176601Z","caller":"traceutil/trace.go:171","msg":"trace[1267749899] transaction","detail":"{read_only:false; response_revision:21613; number_of_response:1; }","duration":"372.927095ms","start":"2026-10-16T09:59:32.803631Z","end":"2026-10-16T09:59:33.176558Z","steps":["trace[1267749899] 'process raft request' (duration: 153.48767ms)","trace[1267749899] 'compare' (duration: 218.996203ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T09:59:33.176813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T09:59:32.803592Z","time spent":"373.093909ms","remote":"127.0.0.1:57080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T09:59:33.498059Z","caller":"traceutil/trace.go:171","msg":"trace[1400873325] linearizableReadLoop","detail":"{readStateIndex:22360; appliedIndex:22359; }","duration":"101.79913ms","start":"2026-10-16T09:59:33.396233Z","end":"2026-10-16T09:59:33.498032Z","steps":["trace[1400873325] 'read index received' (duration: 101.329447ms)","trace[1400873325] 'applied index is now lower than readState.Index' (duration: 468.803µs)"],"step_count":2} {"level":"warn","ts":"2026-10-16T09:59:33.498349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.093487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1991"} {"level":"info","ts":"2026-10-16T09:59:33.498411Z","caller":"traceutil/trace.go:171","msg":"trace[1878905315] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:21615; }","duration":"102.1755ms","start":"2026-10-16T09:59:33.396215Z","end":"2026-10-16T09:59:33.498391Z","steps":["trace[1878905315] 'agreement among raft nodes before linearized reading' (duration: 101.950333ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T09:59:33.4987Z","caller":"traceutil/trace.go:171","msg":"trace[1696339042] transaction","detail":"{read_only:false; response_revision:21615; number_of_response:1; }","duration":"255.417493ms","start":"2026-10-16T09:59:33.242736Z","end":"2026-10-16T09:59:33.498153Z","steps":["trace[1696339042] 'process raft request' (duration: 254.897349ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T10:00:03.534823Z","caller":"traceutil/trace.go:171","msg":"trace[291710612] transaction","detail":"{read_only:false; response_revision:22047; number_of_response:1; }","duration":"104.769921ms","start":"2026-10-16T10:00:03.430022Z","end":"2026-10-16T10:00:03.534792Z","steps":["trace[291710612] 'process raft request' (duration: 104.582745ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T10:03:34.213733Z","caller":"traceutil/trace.go:171","msg":"trace[96930157] transaction","detail":"{read_only:false; response_revision:24189; number_of_response:1; }","duration":"113.003478ms","start":"2026-10-16T10:03:34.100698Z","end":"2026-10-16T10:03:34.213701Z","steps":["trace[96930157] 'process raft request' (duration: 94.294923ms)","trace[96930157] 'compare' (duration: 18.54979ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T10:03:34.216212Z","caller":"traceutil/trace.go:171","msg":"trace[1325516057] transaction","detail":"{read_only:false; response_revision:24190; number_of_response:1; }","duration":"113.751128ms","start":"2026-10-16T10:03:34.102441Z","end":"2026-10-16T10:03:34.216192Z","steps":["trace[1325516057] 'process raft request' (duration: 113.477561ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T10:04:30.729188Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21601} {"level":"info","ts":"2026-10-16T10:04:30.875706Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21601,"took":"143.946859ms","hash":2789064922,"current-db-size-bytes":57565184,"current-db-size":"58 MB","current-db-size-in-use-bytes":43012096,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-10-16T10:04:30.875762Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2789064922,"revision":21601,"compact-revision":19550} {"level":"info","ts":"2026-10-16T10:05:47.014438Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006757.wal"} {"level":"info","ts":"2026-10-16T10:07:06.200407Z","caller":"traceutil/trace.go:171","msg":"trace[429334690] transaction","detail":"{read_only:false; response_revision:26307; number_of_response:1; }","duration":"109.513397ms","start":"2026-10-16T10:07:06.090859Z","end":"2026-10-16T10:07:06.200372Z","steps":["trace[429334690] 'process raft request' (duration: 107.668816ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T10:07:11.728552Z","caller":"traceutil/trace.go:171","msg":"trace[617908512] transaction","detail":"{read_only:false; response_revision:26346; number_of_response:1; }","duration":"285.079542ms","start":"2026-10-16T10:07:11.443417Z","end":"2026-10-16T10:07:11.728496Z","steps":["trace[617908512] 'process raft request' (duration: 205.129981ms)","trace[617908512] 'compare' (duration: 79.731675ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T10:07:11.995195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.429869ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-16T10:07:11.995352Z","caller":"traceutil/trace.go:171","msg":"trace[1228274819] transaction","detail":"{read_only:false; response_revision:26347; number_of_response:1; }","duration":"378.279999ms","start":"2026-10-16T10:07:11.617038Z","end":"2026-10-16T10:07:11.995318Z","steps":["trace[1228274819] 'process raft request' (duration: 236.588382ms)","trace[1228274819] 'compare' (duration: 141.271445ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T10:07:11.995449Z","caller":"traceutil/trace.go:171","msg":"trace[2145465623] linearizableReadLoop","detail":"{readStateIndex:27218; appliedIndex:27217; }","duration":"270.885069ms","start":"2026-10-16T10:07:11.724539Z","end":"2026-10-16T10:07:11.995424Z","steps":["trace[2145465623] 'read index received' (duration: 129.256933ms)","trace[2145465623] 'applied index is now lower than readState.Index' (duration: 141.626196ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T10:07:11.995538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T10:07:11.617015Z","time spent":"378.410092ms","remote":"127.0.0.1:57080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T10:07:11.995551Z","caller":"traceutil/trace.go:171","msg":"trace[1738037232] transaction","detail":"{read_only:false; response_revision:26348; number_of_response:1; }","duration":"207.25467ms","start":"2026-10-16T10:07:11.788135Z","end":"2026-10-16T10:07:11.99539Z","steps":["trace[1738037232] 'process raft request' (duration: 207.171958ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T10:07:11.995732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.220769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0\" ","response":"range_response_count:1 size:1852"} {"level":"info","ts":"2026-10-16T10:07:11.995794Z","caller":"traceutil/trace.go:171","msg":"trace[910415270] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0; range_end:; response_count:1; response_revision:26348; }","duration":"271.314371ms","start":"2026-10-16T10:07:11.72446Z","end":"2026-10-16T10:07:11.995774Z","steps":["trace[910415270] 'agreement among raft nodes before linearized reading' (duration: 271.129666ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T10:07:11.995883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.228029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-10-16T10:07:11.995942Z","caller":"traceutil/trace.go:171","msg":"trace[1168515904] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26348; }","duration":"254.336702ms","start":"2026-10-16T10:07:11.741592Z","end":"2026-10-16T10:07:11.995929Z","steps":["trace[1168515904] 'agreement among raft nodes before linearized reading' (duration: 254.154557ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T10:07:11.995917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.720832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-16T10:07:11.99599Z","caller":"traceutil/trace.go:171","msg":"trace[903212458] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26348; }","duration":"165.842575ms","start":"2026-10-16T10:07:11.830129Z","end":"2026-10-16T10:07:11.995972Z","steps":["trace[903212458] 'agreement among raft nodes before linearized reading' (duration: 165.639939ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T10:07:11.996053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.298978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T10:07:11.996101Z","caller":"traceutil/trace.go:171","msg":"trace[1141418599] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:26348; }","duration":"136.391561ms","start":"2026-10-16T10:07:11.859695Z","end":"2026-10-16T10:07:11.996087Z","steps":["trace[1141418599] 'agreement among raft nodes before linearized reading' (duration: 136.314359ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T10:07:11.996326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.186231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T10:07:11.996393Z","caller":"traceutil/trace.go:171","msg":"trace[173348101] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0; response_count:0; response_revision:26348; }","duration":"231.396467ms","start":"2026-10-16T10:07:11.764977Z","end":"2026-10-16T10:07:11.996373Z","steps":["trace[173348101] 'agreement among raft nodes before linearized reading' (duration: 230.988816ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T10:07:12.177067Z","caller":"traceutil/trace.go:171","msg":"trace[1395670183] transaction","detail":"{read_only:false; response_revision:26350; number_of_response:1; }","duration":"114.828405ms","start":"2026-10-16T10:07:12.0622Z","end":"2026-10-16T10:07:12.177028Z","steps":["trace[1395670183] 'process raft request' (duration: 105.796295ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T10:08:15.898754Z","caller":"traceutil/trace.go:171","msg":"trace[23714664] linearizableReadLoop","detail":"{readStateIndex:27813; appliedIndex:27812; }","duration":"195.182026ms","start":"2026-10-16T10:08:15.703545Z","end":"2026-10-16T10:08:15.898727Z","steps":["trace[23714664] 'read index received' (duration: 194.893208ms)","trace[23714664] 'applied index is now lower than readState.Index' (duration: 287.868µs)"],"step_count":2} {"level":"warn","ts":"2026-10-16T10:08:15.898893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.32923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T10:08:15.898935Z","caller":"traceutil/trace.go:171","msg":"trace[554770966] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:26928; }","duration":"195.430753ms","start":"2026-10-16T10:08:15.703487Z","end":"2026-10-16T10:08:15.898918Z","steps":["trace[554770966] 'agreement among raft nodes before linearized reading' (duration: 195.339201ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T10:08:15.898975Z","caller":"traceutil/trace.go:171","msg":"trace[136464806] transaction","detail":"{read_only:false; response_revision:26928; number_of_response:1; }","duration":"290.046129ms","start":"2026-10-16T10:08:15.608901Z","end":"2026-10-16T10:08:15.898947Z","steps":["trace[136464806] 'process raft request' (duration: 289.609117ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T10:08:16.317697Z","caller":"traceutil/trace.go:171","msg":"trace[1911028881] linearizableReadLoop","detail":"{readStateIndex:27815; appliedIndex:27814; }","duration":"202.773096ms","start":"2026-10-16T10:08:16.114863Z","end":"2026-10-16T10:08:16.317636Z","steps":["trace[1911028881] 'read index received' (duration: 147.129738ms)","trace[1911028881] 'applied index is now lower than readState.Index' (duration: 55.640948ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T10:08:16.317644Z","caller":"traceutil/trace.go:171","msg":"trace[2044302140] transaction","detail":"{read_only:false; response_revision:26930; number_of_response:1; }","duration":"232.873068ms","start":"2026-10-16T10:08:16.084729Z","end":"2026-10-16T10:08:16.317602Z","steps":["trace[2044302140] 'process raft request' (duration: 177.23048ms)","trace[2044302140] 'compare' (duration: 55.382441ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T10:08:16.317893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.009843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-16T10:08:16.317974Z","caller":"traceutil/trace.go:171","msg":"trace[495562934] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:26931; }","duration":"203.139536ms","start":"2026-10-16T10:08:16.114813Z","end":"2026-10-16T10:08:16.317953Z","steps":["trace[495562934] 'agreement among raft nodes before linearized reading' (duration: 202.977492ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T10:08:16.317953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.437096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-16T10:08:16.318057Z","caller":"traceutil/trace.go:171","msg":"trace[1164422015] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:26931; }","duration":"126.639761ms","start":"2026-10-16T10:08:16.191391Z","end":"2026-10-16T10:08:16.318031Z","steps":["trace[1164422015] 'agreement among raft nodes before linearized reading' (duration: 126.450096ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T10:08:16.677208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.390276ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-16T10:08:16.677339Z","caller":"traceutil/trace.go:171","msg":"trace[1476560463] linearizableReadLoop","detail":"{readStateIndex:27817; appliedIndex:27816; }","duration":"269.449129ms","start":"2026-10-16T10:08:16.407855Z","end":"2026-10-16T10:08:16.677304Z","steps":["trace[1476560463] 'read index received' (duration: 79.796246ms)","trace[1476560463] 'applied index is now lower than readState.Index' (duration: 189.651433ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T10:08:16.677451Z","caller":"traceutil/trace.go:171","msg":"trace[784155027] transaction","detail":"{read_only:false; response_revision:26932; number_of_response:1; }","duration":"269.627525ms","start":"2026-10-16T10:08:16.407812Z","end":"2026-10-16T10:08:16.677439Z","steps":["trace[784155027] 'process raft request' (duration: 79.92464ms)","trace[784155027] 'compare' (duration: 189.173521ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T10:08:16.677697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.838781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-16T10:08:16.67773Z","caller":"traceutil/trace.go:171","msg":"trace[559255623] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26932; }","duration":"269.913553ms","start":"2026-10-16T10:08:16.407807Z","end":"2026-10-16T10:08:16.677721Z","steps":["trace[559255623] 'agreement among raft nodes before linearized reading' (duration: 269.750638ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T10:08:16.677833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.111749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T10:08:16.677864Z","caller":"traceutil/trace.go:171","msg":"trace[1113895537] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26932; }","duration":"125.178041ms","start":"2026-10-16T10:08:16.552675Z","end":"2026-10-16T10:08:16.677853Z","steps":["trace[1113895537] 'agreement among raft nodes before linearized reading' (duration: 125.13702ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T10:08:16.67803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.996767ms","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-10-16T10:08:16.678094Z","caller":"traceutil/trace.go:171","msg":"trace[1584507355] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26932; }","duration":"116.10755ms","start":"2026-10-16T10:08:16.561971Z","end":"2026-10-16T10:08:16.678078Z","steps":["trace[1584507355] 'agreement among raft nodes before linearized reading' (duration: 115.911684ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T10:08:16.80216Z","caller":"traceutil/trace.go:171","msg":"trace[732383261] linearizableReadLoop","detail":"{readStateIndex:27819; appliedIndex:27817; }","duration":"108.661275ms","start":"2026-10-16T10:08:16.693471Z","end":"2026-10-16T10:08:16.802133Z","steps":["trace[732383261] 'read index received' (duration: 63.951148ms)","trace[732383261] 'applied index is now lower than readState.Index' (duration: 44.708997ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T10:08:16.802236Z","caller":"traceutil/trace.go:171","msg":"trace[183927190] transaction","detail":"{read_only:false; response_revision:26934; number_of_response:1; }","duration":"120.247334ms","start":"2026-10-16T10:08:16.681944Z","end":"2026-10-16T10:08:16.802191Z","steps":["trace[183927190] 'process raft request' (duration: 108.16453ms)","trace[183927190] 'compare' (duration: 11.9217ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T10:08:16.802475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.40993ms","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-10-16T10:08:16.80256Z","caller":"traceutil/trace.go:171","msg":"trace[1759775334] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26934; }","duration":"107.539254ms","start":"2026-10-16T10:08:16.695002Z","end":"2026-10-16T10:08:16.802541Z","steps":["trace[1759775334] 'agreement among raft nodes before linearized reading' (duration: 107.318308ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T10:08:16.802933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.419526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-16T10:08:16.802977Z","caller":"traceutil/trace.go:171","msg":"trace[608602512] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26934; }","duration":"109.527819ms","start":"2026-10-16T10:08:16.693436Z","end":"2026-10-16T10:08:16.802964Z","steps":["trace[608602512] 'agreement among raft nodes before linearized reading' (duration: 108.831569ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T10:09:30.738179Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24762} {"level":"info","ts":"2026-10-16T10:09:30.889038Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24762,"took":"145.605116ms","hash":3862873515,"current-db-size-bytes":58830848,"current-db-size":"59 MB","current-db-size-in-use-bytes":35983360,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-16T10:09:30.889111Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3862873515,"revision":24762,"compact-revision":21601} {"level":"info","ts":"2026-10-16T10:11:53.183485Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5db0a1b394e67b72","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-16T10:11:53.188862Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-16T10:11:53.189005Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-16T10:14:30.744785Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27655} {"level":"info","ts":"2026-10-16T10:14:30.902631Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27655,"took":"153.6812ms","hash":3168752747,"current-db-size-bytes":58830848,"current-db-size":"59 MB","current-db-size-in-use-bytes":34783232,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-10-16T10:14:30.90272Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3168752747,"revision":27655,"compact-revision":24762} {"level":"info","ts":"2026-10-16T10:19:30.751338Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30356} {"level":"info","ts":"2026-10-16T10:19:30.909246Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30356,"took":"154.028418ms","hash":1838689485,"current-db-size-bytes":58830848,"current-db-size":"59 MB","current-db-size-in-use-bytes":30781440,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-16T10:19:30.909307Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1838689485,"revision":30356,"compact-revision":27655} {"level":"info","ts":"2026-10-16T10:24:30.757922Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32612} {"level":"info","ts":"2026-10-16T10:24:30.922268Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32612,"took":"161.254656ms","hash":1368132928,"current-db-size-bytes":58830848,"current-db-size":"59 MB","current-db-size-in-use-bytes":30429184,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-16T10:24:30.922336Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1368132928,"revision":32612,"compact-revision":30356} {"level":"info","ts":"2026-10-16T10:29:30.762972Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34843} {"level":"info","ts":"2026-10-16T10:29:30.894555Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34843,"took":"127.352623ms","hash":4084998810,"current-db-size-bytes":58830848,"current-db-size":"59 MB","current-db-size-in-use-bytes":31715328,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-16T10:29:30.89461Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4084998810,"revision":34843,"compact-revision":32612} {"level":"info","ts":"2026-10-16T10:33:04.976309Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5db0a1b394e67b72","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-16T10:33:04.982085Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-16T10:33:04.982241Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-16T10:34:30.772041Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37136} {"level":"info","ts":"2026-10-16T10:34:30.925369Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37136,"took":"148.831445ms","hash":1530360126,"current-db-size-bytes":59682816,"current-db-size":"60 MB","current-db-size-in-use-bytes":32907264,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-16T10:34:30.925564Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1530360126,"revision":37136,"compact-revision":34843} {"level":"info","ts":"2026-10-16T10:39:30.787455Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39414} {"level":"info","ts":"2026-10-16T10:39:30.92664Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39414,"took":"135.178879ms","hash":3126256429,"current-db-size-bytes":60108800,"current-db-size":"60 MB","current-db-size-in-use-bytes":31346688,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-16T10:39:30.926709Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3126256429,"revision":39414,"compact-revision":37136}